Вот лог от реального компа с Win 7 на борту. Даже серый IP видно.
Dec 20 12:00:00 proxy newsyslog[40866]: logfile turned over due to size>100K
Dec 20 12:14:48 proxy racoon: DEBUG: ===
Dec 20 12:14:48 proxy racoon: DEBUG: 384 bytes message received from YYY.YYY.YYY.YYY[500] to XXX.XXX.XXX.XXX[500]
Dec 20 12:14:48 proxy racoon: DEBUG: fa6975cc b8108e36 00000000 00000000 01100200 00000000 00000180 0d0000d4 00000001 00000001 000000c8 01010005 03000028 01010000 80010007 800e0100 80020002 80040014 80030001 800b0001 000c0004 00007080 03000028 02010000 80010007 800e0080 80020002 80040013 80030001 800b0001 000c0004 00007080 03000028 03010000 80010007 800e0100 80020002 8004000e 80030001 800b0001 000c0004 00007080 03000024 04010000 80010005 80020002 8004000e 80030001 800b0001 000c0004 00007080 00000024 05010000 80010005 80020002 80040002 80030001 800b0001 000c0004 00007080 0d000018 1e2b5169 05991c7d 7c96fcbf b587e461 00000008 0d000014 4a131c81 07035845 5c5728f2 0e95452f 0d000014 90cb8091 3ebb696e 086381b5 ec427b1f 0d000014 4048b7d5 6ebce885 25e7de7f 00d6c2d3 0d000014 fb1de3cd f341b7ea 16b7e5be 0855f120 0d000014 26244d38 eddb61b3 172a36e3 d0cfb819 00000014 e3a5966a 76379fe7 07228231 e5ce8652
Dec 20 12:14:48 proxy racoon: DEBUG: ===
Dec 20 12:14:48 proxy racoon: INFO: respond new phase 1 negotiation: XXX.XXX.XXX.XXX[500]<=>YYY.YYY.YYY.YYY[500]
Dec 20 12:14:48 proxy racoon: INFO: begin Identity Protection mode.
Dec 20 12:14:48 proxy racoon: DEBUG: begin.
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=1(sa)
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=13(vid)
Dec 20 12:14:48 proxy last message repeated 6 times
Dec 20 12:14:48 proxy racoon: DEBUG: succeed.
Dec 20 12:14:48 proxy racoon: INFO: received broken Microsoft ID: MS NT5 ISAKMPOAKLEY
Dec 20 12:14:48 proxy racoon: INFO: received Vendor ID: RFC 3947
Dec 20 12:14:48 proxy racoon: INFO: received Vendor ID: draft-ietf-ipsec-nat-t-ike-02
Dec 20 12:14:48 proxy racoon: INFO: received Vendor ID: FRAGMENTATION
Dec 20 12:14:48 proxy racoon: DEBUG: received unknown Vendor ID
Dec 20 12:14:48 proxy racoon: DEBUG: fb1de3cd f341b7ea 16b7e5be 0855f120
Dec 20 12:14:48 proxy racoon: DEBUG: received unknown Vendor ID
Dec 20 12:14:48 proxy racoon: DEBUG: 26244d38 eddb61b3 172a36e3 d0cfb819
Dec 20 12:14:48 proxy racoon: DEBUG: received unknown Vendor ID
Dec 20 12:14:48 proxy racoon: DEBUG: e3a5966a 76379fe7 07228231 e5ce8652
Dec 20 12:14:48 proxy racoon: [YYY.YYY.YYY.YYY] INFO: Selected NAT-T version: RFC 3947
Dec 20 12:14:48 proxy racoon: DEBUG: total SA len=208
Dec 20 12:14:48 proxy racoon: DEBUG: 00000001 00000001 000000c8 01010005 03000028 01010000 80010007 800e0100 80020002 80040014 80030001 800b0001 000c0004 00007080 03000028 02010000 80010007 800e0080 80020002 80040013 80030001 800b0001 000c0004 00007080 03000028 03010000 80010007 800e0100 80020002 8004000e 80030001 800b0001 000c0004 00007080 03000024 04010000 80010005 80020002 8004000e 80030001 800b0001 000c0004 00007080 00000024 05010000 80010005 80020002 80040002 80030001 800b0001 000c0004 00007080
Dec 20 12:14:48 proxy racoon: DEBUG: begin.
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=2(prop)
Dec 20 12:14:48 proxy racoon: DEBUG: succeed.
Dec 20 12:14:48 proxy racoon: DEBUG: proposal #1 len=200
Dec 20 12:14:48 proxy racoon: DEBUG: begin.
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=3(trns)
Dec 20 12:14:48 proxy last message repeated 4 times
Dec 20 12:14:48 proxy racoon: DEBUG: succeed.
Dec 20 12:14:48 proxy racoon: DEBUG: transform #1 len=40
Dec 20 12:14:48 proxy racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=AES-CBC
Dec 20 12:14:48 proxy racoon: DEBUG: encryption(aes)
Dec 20 12:14:48 proxy racoon: DEBUG: type=Key Length, flag=0x8000, lorv=256
Dec 20 12:14:48 proxy racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 20 12:14:48 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: type=Group Description, flag=0x8000, lorv=20
Dec 20 12:14:48 proxy racoon: ERROR: invalid DH group 20.
Dec 20 12:14:48 proxy racoon: DEBUG: transform #2 len=40
Dec 20 12:14:48 proxy racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=AES-CBC
Dec 20 12:14:48 proxy racoon: DEBUG: encryption(aes)
Dec 20 12:14:48 proxy racoon: DEBUG: type=Key Length, flag=0x8000, lorv=128
Dec 20 12:14:48 proxy racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 20 12:14:48 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: type=Group Description, flag=0x8000, lorv=19
Dec 20 12:14:48 proxy racoon: ERROR: invalid DH group 19.
Dec 20 12:14:48 proxy racoon: DEBUG: transform #3 len=40
Dec 20 12:14:48 proxy racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=AES-CBC
Dec 20 12:14:48 proxy racoon: DEBUG: encryption(aes)
Dec 20 12:14:48 proxy racoon: DEBUG: type=Key Length, flag=0x8000, lorv=256
Dec 20 12:14:48 proxy racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 20 12:14:48 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: type=Group Description, flag=0x8000, lorv=2048-bit MODP group
Dec 20 12:14:48 proxy racoon: DEBUG: hmac(modp2048)
Dec 20 12:14:48 proxy racoon: DEBUG: type=Authentication Method, flag=0x8000, lorv=pre-shared key
Dec 20 12:14:48 proxy racoon: DEBUG: type=Life Type, flag=0x8000, lorv=seconds
Dec 20 12:14:48 proxy racoon: DEBUG: type=Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:48 proxy racoon: DEBUG: transform #4 len=36
Dec 20 12:14:48 proxy racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC
Dec 20 12:14:48 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:48 proxy racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 20 12:14:48 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: type=Group Description, flag=0x8000, lorv=2048-bit MODP group
Dec 20 12:14:48 proxy racoon: DEBUG: hmac(modp2048)
Dec 20 12:14:48 proxy racoon: DEBUG: type=Authentication Method, flag=0x8000, lorv=pre-shared key
Dec 20 12:14:48 proxy racoon: DEBUG: type=Life Type, flag=0x8000, lorv=seconds
Dec 20 12:14:48 proxy racoon: DEBUG: type=Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:48 proxy racoon: DEBUG: transform #5 len=36
Dec 20 12:14:48 proxy racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC
Dec 20 12:14:48 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:48 proxy racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 20 12:14:48 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: type=Group Description, flag=0x8000, lorv=1024-bit MODP group
Dec 20 12:14:48 proxy racoon: DEBUG: hmac(modp1024)
Dec 20 12:14:48 proxy racoon: DEBUG: type=Authentication Method, flag=0x8000, lorv=pre-shared key
Dec 20 12:14:48 proxy racoon: DEBUG: type=Life Type, flag=0x8000, lorv=seconds
Dec 20 12:14:48 proxy racoon: DEBUG: type=Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:48 proxy racoon: DEBUG: pair 1:
Dec 20 12:14:48 proxy racoon: DEBUG: 0x285173c0: next=0x0 tnext=0x285173e0
Dec 20 12:14:48 proxy racoon: DEBUG: 0x285173e0: next=0x0 tnext=0x285173f0
Dec 20 12:14:48 proxy racoon: DEBUG: 0x285173f0: next=0x0 tnext=0x0
Dec 20 12:14:48 proxy racoon: DEBUG: proposal #1: 3 transform
Dec 20 12:14:48 proxy racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=AES-CBC
Dec 20 12:14:48 proxy racoon: DEBUG: type=Key Length, flag=0x8000, lorv=256
Dec 20 12:14:48 proxy racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 20 12:14:48 proxy racoon: DEBUG: type=Group Description, flag=0x8000, lorv=2048-bit MODP group
Dec 20 12:14:48 proxy racoon: DEBUG: type=Authentication Method, flag=0x8000, lorv=pre-shared key
Dec 20 12:14:48 proxy racoon: DEBUG: type=Life Type, flag=0x8000, lorv=seconds
Dec 20 12:14:48 proxy racoon: DEBUG: type=Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:48 proxy racoon: DEBUG: prop#=1, prot-id=ISAKMP, spi-size=0, #trns=5
Dec 20 12:14:48 proxy racoon: DEBUG: trns#=3, trns-id=IKE
Dec 20 12:14:48 proxy racoon: DEBUG: lifetime = 28800
Dec 20 12:14:48 proxy racoon: DEBUG: lifebyte = 0
Dec 20 12:14:48 proxy racoon: DEBUG: enctype = AES-CBC
Dec 20 12:14:48 proxy racoon: DEBUG: encklen = 256
Dec 20 12:14:48 proxy racoon: DEBUG: hashtype = SHA
Dec 20 12:14:48 proxy racoon: DEBUG: authmethod = pre-shared key
Dec 20 12:14:48 proxy racoon: DEBUG: dh_group = 2048-bit MODP group
Dec 20 12:14:48 proxy racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC
Dec 20 12:14:48 proxy racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 20 12:14:48 proxy racoon: DEBUG: type=Group Description, flag=0x8000, lorv=2048-bit MODP group
Dec 20 12:14:48 proxy racoon: DEBUG: type=Authentication Method, flag=0x8000, lorv=pre-shared key
Dec 20 12:14:48 proxy racoon: DEBUG: type=Life Type, flag=0x8000, lorv=seconds
Dec 20 12:14:48 proxy racoon: DEBUG: type=Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:48 proxy racoon: DEBUG: prop#=1, prot-id=ISAKMP, spi-size=0, #trns=5
Dec 20 12:14:48 proxy racoon: DEBUG: trns#=4, trns-id=IKE
Dec 20 12:14:48 proxy racoon: DEBUG: lifetime = 28800
Dec 20 12:14:48 proxy racoon: DEBUG: lifebyte = 0
Dec 20 12:14:48 proxy racoon: DEBUG: enctype = 3DES-CBC
Dec 20 12:14:48 proxy racoon: DEBUG: encklen = 0
Dec 20 12:14:48 proxy racoon: DEBUG: hashtype = SHA
Dec 20 12:14:48 proxy racoon: DEBUG: authmethod = pre-shared key
Dec 20 12:14:48 proxy racoon: DEBUG: dh_group = 2048-bit MODP group
Dec 20 12:14:48 proxy racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC
Dec 20 12:14:48 proxy racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 20 12:14:48 proxy racoon: DEBUG: type=Group Description, flag=0x8000, lorv=1024-bit MODP group
Dec 20 12:14:48 proxy racoon: DEBUG: type=Authentication Method, flag=0x8000, lorv=pre-shared key
Dec 20 12:14:48 proxy racoon: DEBUG: type=Life Type, flag=0x8000, lorv=seconds
Dec 20 12:14:48 proxy racoon: DEBUG: type=Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:48 proxy racoon: DEBUG: prop#=1, prot-id=ISAKMP, spi-size=0, #trns=5
Dec 20 12:14:48 proxy racoon: DEBUG: trns#=5, trns-id=IKE
Dec 20 12:14:48 proxy racoon: DEBUG: lifetime = 28800
Dec 20 12:14:48 proxy racoon: DEBUG: lifebyte = 0
Dec 20 12:14:48 proxy racoon: DEBUG: enctype = 3DES-CBC
Dec 20 12:14:48 proxy racoon: DEBUG: encklen = 0
Dec 20 12:14:48 proxy racoon: DEBUG: hashtype = SHA
Dec 20 12:14:48 proxy racoon: DEBUG: authmethod = pre-shared key
Dec 20 12:14:48 proxy racoon: DEBUG: dh_group = 1024-bit MODP group
Dec 20 12:14:48 proxy racoon: DEBUG: an acceptable proposal found.
Dec 20 12:14:48 proxy racoon: DEBUG: hmac(modp1024)
Dec 20 12:14:48 proxy racoon: DEBUG: agreed on pre-shared key auth.
Dec 20 12:14:48 proxy racoon: DEBUG: ===
Dec 20 12:14:48 proxy racoon: DEBUG: new cookie: 2ec90ca982855fc3
Dec 20 12:14:48 proxy racoon: DEBUG: add payload of len 52, next type 13
Dec 20 12:14:48 proxy racoon: DEBUG: add payload of len 16, next type 0
Dec 20 12:14:48 proxy racoon: DEBUG: 104 bytes from XXX.XXX.XXX.XXX[500] to YYY.YYY.YYY.YYY[500]
Dec 20 12:14:48 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[500]
Dec 20 12:14:48 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[500]
Dec 20 12:14:48 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[500]
Dec 20 12:14:48 proxy racoon: DEBUG: 1 times of 104 bytes message will be sent to YYY.YYY.YYY.YYY[500]
Dec 20 12:14:48 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 01100200 00000000 00000068 0d000038 00000001 00000001 0000002c 01010001 00000024 05010000 80010005 80020002 80040002 80030001 800b0001 000c0004 00007080 00000014 4a131c81 07035845 5c5728f2 0e95452f
Dec 20 12:14:48 proxy racoon: DEBUG: resend phase1 packet fa6975ccb8108e36:2ec90ca982855fc3
Dec 20 12:14:48 proxy racoon: DEBUG: ===
Dec 20 12:14:48 proxy racoon: DEBUG: 260 bytes message received from YYY.YYY.YYY.YYY[500] to XXX.XXX.XXX.XXX[500]
Dec 20 12:14:48 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 04100200 00000000 00000104 0a000084 3ab1d855 f2503a82 81dbf344 09e4d2a5 e822c409 255751fe 8ab897f6 33f10cee 6a8330cb b8985565 d26b089d e4e8bf9d 70aecb3a 269fc1a4 3274d7b5 36774634 7a65af2c 2b6a4bbb 349c09cc bdd2ccf6 86fe8045 7459d1e5 8355bf53 0f348068 25d6fc80 69047118 43ad7652 560b9d85 879ad1fa 43119ab6 c03059b8 5a18453b 14000034 c3d3aa4a d26d4fac 59c2fa5e b772f462 b3664cad 158ce780 44c10d67 b17a43ce fc6fdb37 f709cb54 a26c5a95 c7fb93f4 14000018 31e76a4d 8f4d405c ddf022d2 93060dcd bda04492 00000018 df1b99ca b8e0aa1d 7089609b 7ed3c4fa 2deab5ed
Dec 20 12:14:48 proxy racoon: DEBUG: begin.
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=4(ke)
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=10(nonce)
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=20(nat-d)
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=20(nat-d)
Dec 20 12:14:48 proxy racoon: DEBUG: succeed.
Dec 20 12:14:48 proxy racoon: [XXX.XXX.XXX.XXX] INFO: Hashing XXX.XXX.XXX.XXX[500] with algo #2
Dec 20 12:14:48 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:48 proxy racoon: INFO: NAT-D payload #0 verified
Dec 20 12:14:48 proxy racoon: [YYY.YYY.YYY.YYY] INFO: Hashing YYY.YYY.YYY.YYY[500] with algo #2
Dec 20 12:14:48 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:48 proxy racoon: INFO: NAT-D payload #1 doesn't match
Dec 20 12:14:48 proxy racoon: INFO: NAT detected: PEER
Dec 20 12:14:48 proxy racoon: DEBUG: ===
Dec 20 12:14:48 proxy racoon: DEBUG: compute DH's private.
Dec 20 12:14:48 proxy racoon: DEBUG: 6297508b fdb9e576 e5678291 250ed41b c2d471db 647e901f 38d0f482 60ba1bb9 78f7d376 b88135e2 7dbda9a2 99c13e40 11652ac1 83e86a5e c1c52fb3 2c644f43 5acf7b1b a07e5f0f 9a1656c1 e4d3c66d 3c6aa512 0e017bea 1876e870 5d992e13 ad012e89 af1ffe12 48c7e820 13fe9468 0fdfea85 06648c0f 6710b8d0 b1b25c60
Dec 20 12:14:48 proxy racoon: DEBUG: compute DH's public.
Dec 20 12:14:48 proxy racoon: DEBUG: c777b6a8 9d8c5104 c10f86fc d61d0062 4e6651f5 687837d1 ccc7fc5d b9344964 cb5600f2 d73ac121 791d29c0 2b9112fa b8750563 a4220482 23aa2685 50395d4a b8e4e9cd 3a5e8aad 1616d3dd 377b7ece 8dac4f54 102c0381 6ab8c223 c7f96f1e a14cd536 11297bb6 1ba5cfc8 297f89e0 11b386fb ad738eb6 7985aa73 3ae0fd68
Dec 20 12:14:48 proxy racoon: [YYY.YYY.YYY.YYY] INFO: Hashing YYY.YYY.YYY.YYY[500] with algo #2
Dec 20 12:14:48 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:48 proxy racoon: [XXX.XXX.XXX.XXX] INFO: Hashing XXX.XXX.XXX.XXX[500] with algo #2
Dec 20 12:14:48 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:48 proxy racoon: INFO: Adding remote and local NAT-D payloads.
Dec 20 12:14:48 proxy racoon: DEBUG: add payload of len 128, next type 10
Dec 20 12:14:48 proxy racoon: DEBUG: add payload of len 16, next type 20
Dec 20 12:14:48 proxy racoon: DEBUG: add payload of len 20, next type 20
Dec 20 12:14:48 proxy racoon: DEBUG: add payload of len 20, next type 0
Dec 20 12:14:48 proxy racoon: DEBUG: 228 bytes from XXX.XXX.XXX.XXX[500] to YYY.YYY.YYY.YYY[500]
Dec 20 12:14:48 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[500]
Dec 20 12:14:48 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[500]
Dec 20 12:14:48 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[500]
Dec 20 12:14:48 proxy racoon: DEBUG: 1 times of 228 bytes message will be sent to YYY.YYY.YYY.YYY[500]
Dec 20 12:14:48 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 04100200 00000000 000000e4 0a000084 c777b6a8 9d8c5104 c10f86fc d61d0062 4e6651f5 687837d1 ccc7fc5d b9344964 cb5600f2 d73ac121 791d29c0 2b9112fa b8750563 a4220482 23aa2685 50395d4a b8e4e9cd 3a5e8aad 1616d3dd 377b7ece 8dac4f54 102c0381 6ab8c223 c7f96f1e a14cd536 11297bb6 1ba5cfc8 297f89e0 11b386fb ad738eb6 7985aa73 3ae0fd68 14000014 ea3864e8 2ad7fc02 751dbcce aa43b06c 14000018 6ca18f6a a7ac32af febfdeb6 11281b95 f7226589 00000018 31e76a4d 8f4d405c ddf022d2 93060dcd bda04492
Dec 20 12:14:48 proxy racoon: DEBUG: resend phase1 packet fa6975ccb8108e36:2ec90ca982855fc3
Dec 20 12:14:48 proxy racoon: DEBUG: compute DH's shared.
Dec 20 12:14:48 proxy racoon: DEBUG: 940177dd feddcb1b 6ecb06ad 6dc7e819 54a00ef3 8b76e6fa 6e81cd45 c0b3cebc 282bf5c1 afc95466 5e7c566d 83cf20a4 b163f8f9 1dc0a282 f0bb7712 774eacb2 0c99eb8e 3b114e6f 551bc670 fdaff3ac 1209f8d5 08080351 2380e5c9 7697f83b b7a79ceb 44b1c299 a1021790 959adae9 59262d07 d8f78854 4e512acf a6266525
Dec 20 12:14:48 proxy racoon: DEBUG: the psk found.
Dec 20 12:14:48 proxy racoon: DEBUG: nonce 1:
Dec 20 12:14:48 proxy racoon: DEBUG: c3d3aa4a d26d4fac 59c2fa5e b772f462 b3664cad 158ce780 44c10d67 b17a43ce fc6fdb37 f709cb54 a26c5a95 c7fb93f4
Dec 20 12:14:48 proxy racoon: DEBUG: nonce 2:
Dec 20 12:14:48 proxy racoon: DEBUG: ea3864e8 2ad7fc02 751dbcce aa43b06c
Dec 20 12:14:48 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: SKEYID computed:
Dec 20 12:14:48 proxy racoon: DEBUG: 01d35946 5aa7de9b a9576c90 64fb7261 becaeece
Dec 20 12:14:48 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: SKEYID_d computed:
Dec 20 12:14:48 proxy racoon: DEBUG: 40c6d700 cb96ec57 aa653334 b6135d97 8798844b
Dec 20 12:14:48 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: SKEYID_a computed:
Dec 20 12:14:48 proxy racoon: DEBUG: 928bbd2e 736ca2bc b8ea0d16 50588d32 8def4ace
Dec 20 12:14:48 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: SKEYID_e computed:
Dec 20 12:14:48 proxy racoon: DEBUG: 7fd96bcd 973cfdd7 8f3c3ddf 613675ba 2126de14
Dec 20 12:14:48 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:48 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: len(SKEYID_e) < len(Ka) (20 < 24), generating long key (Ka = K1 | K2 | ...)
Dec 20 12:14:48 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: compute intermediate encryption key K1
Dec 20 12:14:48 proxy racoon: DEBUG: 00
Dec 20 12:14:48 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492
Dec 20 12:14:48 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: compute intermediate encryption key K2
Dec 20 12:14:48 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492
Dec 20 12:14:48 proxy racoon: DEBUG: 66febc63 51833c8b 84bf3f9f 7bca70d8 b40ae8c8
Dec 20 12:14:48 proxy racoon: DEBUG: final encryption key computed:
Dec 20 12:14:48 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:14:48 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:48 proxy racoon: DEBUG: IV computed:
Dec 20 12:14:48 proxy racoon: DEBUG: db9163cf 3a2abd9b
Dec 20 12:14:48 proxy racoon: DEBUG: ===
Dec 20 12:14:48 proxy racoon: DEBUG: 68 bytes message received from YYY.YYY.YYY.YYY[4500] to XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:48 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 05100201 00000000 00000044 3f06f2d4 f6215d4d 9f8843b4 5cf68e84 7b1970a3 191516d1 e37aa4f8 305ad659 b7987eb2 b0f34296
Dec 20 12:14:48 proxy racoon: INFO: NAT-T: ports changed to: YYY.YYY.YYY.YYY[4500]<->XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:48 proxy racoon: INFO: KA list add: XXX.XXX.XXX.XXX[4500]->YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:48 proxy racoon: DEBUG: begin decryption.
Dec 20 12:14:48 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:48 proxy racoon: DEBUG: IV was saved for next processing:
Dec 20 12:14:48 proxy racoon: DEBUG: b7987eb2 b0f34296
Dec 20 12:14:48 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:48 proxy racoon: DEBUG: with key:
Dec 20 12:14:48 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:14:48 proxy racoon: DEBUG: decrypted payload by IV:
Dec 20 12:14:48 proxy racoon: DEBUG: db9163cf 3a2abd9b
Dec 20 12:14:48 proxy racoon: DEBUG: decrypted payload, but not trimed.
Dec 20 12:14:48 proxy racoon: DEBUG: 0800000c 01000000 c0a80182 00000018 2ff81724 121bce31 9dffa041 aa4408c8 e98d2e0a 00000000
Dec 20 12:14:48 proxy racoon: DEBUG: padding len=0
Dec 20 12:14:48 proxy racoon: DEBUG: skip to trim padding.
Dec 20 12:14:48 proxy racoon: DEBUG: decrypted.
Dec 20 12:14:48 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 05100201 00000000 00000044 0800000c 01000000 c0a80182 00000018 2ff81724 121bce31 9dffa041 aa4408c8 e98d2e0a 00000000
Dec 20 12:14:48 proxy racoon: DEBUG: begin.
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=5(id)
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=8(hash)
Dec 20 12:14:48 proxy racoon: DEBUG: succeed.
Dec 20 12:14:48 proxy racoon: [YYY.YYY.YYY.YYY] DEBUG: getrmconf_by_ph1: remote YYY.YYY.YYY.YYY[4500], identity 192.168.1.130.
Dec 20 12:14:48 proxy racoon: [YYY.YYY.YYY.YYY] DEBUG: configuration "anonymous" selected.
Dec 20 12:14:48 proxy racoon: [YYY.YYY.YYY.YYY] DEBUG: getrmconf_by_ph1: remote YYY.YYY.YYY.YYY[4500], identity 192.168.1.130.
Dec 20 12:14:48 proxy racoon: [YYY.YYY.YYY.YYY] DEBUG: configuration "anonymous" selected.
Dec 20 12:14:48 proxy racoon: DEBUG: HASH received:
Dec 20 12:14:48 proxy racoon: DEBUG: 2ff81724 121bce31 9dffa041 aa4408c8 e98d2e0a
Dec 20 12:14:48 proxy racoon: DEBUG: HASH with:
Dec 20 12:14:48 proxy racoon: DEBUG: 3ab1d855 f2503a82 81dbf344 09e4d2a5 e822c409 255751fe 8ab897f6 33f10cee 6a8330cb b8985565 d26b089d e4e8bf9d 70aecb3a 269fc1a4 3274d7b5 36774634 7a65af2c 2b6a4bbb 349c09cc bdd2ccf6 86fe8045 7459d1e5 8355bf53 0f348068 25d6fc80 69047118 43ad7652 560b9d85 879ad1fa 43119ab6 c03059b8 5a18453b c777b6a8 9d8c5104 c10f86fc d61d0062 4e6651f5 687837d1 ccc7fc5d b9344964 cb5600f2 d73ac121 791d29c0 2b9112fa b8750563 a4220482 23aa2685 50395d4a b8e4e9cd 3a5e8aad 1616d3dd 377b7ece 8dac4f54 102c0381 6ab8c223 c7f96f1e a14cd536 11297bb6 1ba5cfc8 297f89e0 11b386fb ad738eb6 7985aa73 3ae0fd68 fa6975cc b8108e36 2ec90ca9 82855fc3 00000001 00000001 000000c8 01010005 03000028 01010000 80010007 800e0100 80020002 80040014 80030001 800b0001 000c0004 00007080 03000028 02010000 80010007 800e0080 80020002 80040013 80030001 800b0001 000c0004 00007080 03000028 03010000 80010007 800e0100 80020002 8004000e 80030001 800b0001 000c0004 00007080 03000024 04010000 80010005 80020002 8004000e 80030001 800b0001 000c000
Dec 20 12:14:48 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: HASH (resp) computed:
Dec 20 12:14:48 proxy racoon: DEBUG: 2ff81724 121bce31 9dffa041 aa4408c8 e98d2e0a
Dec 20 12:14:48 proxy racoon: DEBUG: HASH for PSK validated.
Dec 20 12:14:48 proxy racoon: [YYY.YYY.YYY.YYY] DEBUG: peer's ID
Dec 20 12:14:48 proxy racoon: DEBUG: 01000000 c0a80182
Dec 20 12:14:48 proxy racoon: DEBUG: ===
Dec 20 12:14:48 proxy racoon: DEBUG: use ID type of IPv4_address
Dec 20 12:14:48 proxy racoon: DEBUG: generate HASH_R
Dec 20 12:14:48 proxy racoon: DEBUG: HASH with:
Dec 20 12:14:48 proxy racoon: DEBUG: c777b6a8 9d8c5104 c10f86fc d61d0062 4e6651f5 687837d1 ccc7fc5d b9344964 cb5600f2 d73ac121 791d29c0 2b9112fa b8750563 a4220482 23aa2685 50395d4a b8e4e9cd 3a5e8aad 1616d3dd 377b7ece 8dac4f54 102c0381 6ab8c223 c7f96f1e a14cd536 11297bb6 1ba5cfc8 297f89e0 11b386fb ad738eb6 7985aa73 3ae0fd68 3ab1d855 f2503a82 81dbf344 09e4d2a5 e822c409 255751fe 8ab897f6 33f10cee 6a8330cb b8985565 d26b089d e4e8bf9d 70aecb3a 269fc1a4 3274d7b5 36774634 7a65af2c 2b6a4bbb 349c09cc bdd2ccf6 86fe8045 7459d1e5 8355bf53 0f348068 25d6fc80 69047118 43ad7652 560b9d85 879ad1fa 43119ab6 c03059b8 5a18453b 2ec90ca9 82855fc3 fa6975cc b8108e36 00000001 00000001 000000c8 01010005 03000028 01010000 80010007 800e0100 80020002 80040014 80030001 800b0001 000c0004 00007080 03000028 02010000 80010007 800e0080 80020002 80040013 80030001 800b0001 000c0004 00007080 03000028 03010000 80010007 800e0100 80020002 8004000e 80030001 800b0001 000c0004 00007080 03000024 04010000 80010005 80020002 8004000e 80030001 800b0001 000c000
Dec 20 12:14:48 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: HASH (resp) computed:
Dec 20 12:14:48 proxy racoon: DEBUG: 5a4d377e 6858eddd 4b7d6599 0f00be70 61b6f1b7
Dec 20 12:14:48 proxy racoon: DEBUG: add payload of len 8, next type 8
Dec 20 12:14:48 proxy racoon: DEBUG: add payload of len 20, next type 0
Dec 20 12:14:48 proxy racoon: DEBUG: begin encryption.
Dec 20 12:14:48 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:48 proxy racoon: DEBUG: pad length = 4
Dec 20 12:14:48 proxy racoon: DEBUG: 0800000c 011101f4 d5a08b84 00000018 5a4d377e 6858eddd 4b7d6599 0f00be70 61b6f1b7 00000004
Dec 20 12:14:48 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:48 proxy racoon: DEBUG: with key:
Dec 20 12:14:48 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:14:48 proxy racoon: DEBUG: encrypted payload by IV:
Dec 20 12:14:48 proxy racoon: DEBUG: b7987eb2 b0f34296
Dec 20 12:14:48 proxy racoon: DEBUG: save IV for next:
Dec 20 12:14:48 proxy racoon: DEBUG: 9e88afc8 f154547f
Dec 20 12:14:48 proxy racoon: DEBUG: encrypted.
Dec 20 12:14:48 proxy racoon: DEBUG: Adding NON-ESP marker
Dec 20 12:14:48 proxy racoon: DEBUG: 72 bytes from XXX.XXX.XXX.XXX[4500] to YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:48 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:48 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:48 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:48 proxy racoon: DEBUG: 1 times of 72 bytes message will be sent to YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:48 proxy racoon: DEBUG: 00000000 fa6975cc b8108e36 2ec90ca9 82855fc3 05100201 00000000 00000044 4dc301b7 976470fc f6ac575a 0d14001a d0ea2bcb e5a8e70e 7583573a fb46df45 9e88afc8 f154547f
Dec 20 12:14:48 proxy racoon: INFO: ISAKMP-SA established XXX.XXX.XXX.XXX[4500]-YYY.YYY.YYY.YYY[4500] spi:fa6975ccb8108e36:2ec90ca982855fc3
Dec 20 12:14:48 proxy racoon: DEBUG: ===
Dec 20 12:14:48 proxy racoon: DEBUG: ===
Dec 20 12:14:48 proxy racoon: DEBUG: 220 bytes message received from YYY.YYY.YYY.YYY[4500] to XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:48 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 08102001 00000001 000000dc f845f1d9 6a295096 0aa7c111 178fd0e8 c7bb991a 73a31f4c dca1e0e7 537170e9 5e8ea52c ab10c942 4469f83d c7a03df0 50925b26 927ff53a b86011b8 ac0f3f35 8907163e f26bb738 6769158b 55359b6c 740026bb afa93173 58b4c3d5 4701e50b 12b07a18 c8840b4c cb486257 93ed30f3 b5a3eb57 b6a47414 c6c28f49 bd8d7441 4be4f1af 7923e9d9 a923a4a9 df2cf431 bcdc0c75 e45dccbb d9aecc0c 31ec2df0 996a3e25 1ec0fa25 a3d7e48c 3b7f94e4 96df481d 209eb356 350e8b12 c14b0199
Dec 20 12:14:48 proxy racoon: DEBUG: compute IV for phase2
Dec 20 12:14:48 proxy racoon: DEBUG: phase1 last IV:
Dec 20 12:14:48 proxy racoon: DEBUG: 9e88afc8 f154547f 00000001
Dec 20 12:14:48 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:48 proxy racoon: DEBUG: phase2 IV computed:
Dec 20 12:14:48 proxy racoon: DEBUG: cb9f45f5 0af2a3a4
Dec 20 12:14:48 proxy racoon: DEBUG: ===
Dec 20 12:14:48 proxy racoon: INFO: respond new phase 2 negotiation: XXX.XXX.XXX.XXX[4500]<=>YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:48 proxy racoon: DEBUG: begin decryption.
Dec 20 12:14:48 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:48 proxy racoon: DEBUG: IV was saved for next processing:
Dec 20 12:14:48 proxy racoon: DEBUG: 350e8b12 c14b0199
Dec 20 12:14:48 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:48 proxy racoon: DEBUG: with key:
Dec 20 12:14:48 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:14:48 proxy racoon: DEBUG: decrypted payload by IV:
Dec 20 12:14:48 proxy racoon: DEBUG: cb9f45f5 0af2a3a4
Dec 20 12:14:48 proxy racoon: DEBUG: decrypted payload, but not trimed.
Dec 20 12:14:48 proxy racoon: DEBUG: 01000018 e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84 00000000
Dec 20 12:14:48 proxy racoon: DEBUG: padding len=0
Dec 20 12:14:48 proxy racoon: DEBUG: skip to trim padding.
Dec 20 12:14:48 proxy racoon: DEBUG: decrypted.
Dec 20 12:14:48 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 08102001 00000001 000000dc 01000018 e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84 00000000
Dec 20 12:14:48 proxy racoon: DEBUG: begin.
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=8(hash)
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=1(sa)
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=10(nonce)
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=5(id)
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=5(id)
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=21(nat-oa)
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=21(nat-oa)
Dec 20 12:14:48 proxy racoon: DEBUG: succeed.
Dec 20 12:14:48 proxy racoon: DEBUG: received IDci2:
Dec 20 12:14:48 proxy racoon: DEBUG: 011106a5 c0a80182
Dec 20 12:14:48 proxy racoon: DEBUG: received IDcr2:
Dec 20 12:14:48 proxy racoon: DEBUG: 011106a5 d5a08b84
Dec 20 12:14:48 proxy racoon: DEBUG: HASH(1) validate:
Dec 20 12:14:48 proxy racoon: DEBUG: e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b
Dec 20 12:14:48 proxy racoon: DEBUG: HASH with:
Dec 20 12:14:48 proxy racoon: DEBUG: 00000001 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84
Dec 20 12:14:48 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: HASH computed:
Dec 20 12:14:48 proxy racoon: DEBUG: e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b
Dec 20 12:14:48 proxy racoon: DEBUG: getsainfo params: loc='XXX.XXX.XXX.XXX' rmt='192.168.1.130' peer='192.168.1.130' client='YYY.YYY.YYY.YYY' id=0
Dec 20 12:14:48 proxy racoon: DEBUG: evaluating sainfo: loc='ANONYMOUS', rmt='ANONYMOUS', peer='ANY', id=0
Dec 20 12:14:48 proxy racoon: DEBUG: check and compare ids : values matched (ANONYMOUS)
Dec 20 12:14:48 proxy racoon: DEBUG: check and compare ids : values matched (ANONYMOUS)
Dec 20 12:14:48 proxy racoon: DEBUG: selected sainfo: loc='ANONYMOUS', rmt='ANONYMOUS', peer='ANY', id=0
Dec 20 12:14:48 proxy racoon: DEBUG: Either family (2 - 2), types (1 - 1) of ID from initiator differ or matching sainfo has no id_i defined for the peer. Not filling iph2->sa_src and iph2->sa_dst.
Dec 20 12:14:48 proxy racoon: DEBUG: get src address from ID payload 192.168.1.130[1701] prefixlen=32 ul_proto=17
Dec 20 12:14:48 proxy racoon: DEBUG: get dst address from ID payload XXX.XXX.XXX.XXX[1701] prefixlen=32 ul_proto=17
Dec 20 12:14:48 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:48 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:48 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:48 proxy racoon: DEBUG: db: 0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:48 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /0: 0.0.0.0[1701]
Dec 20 12:14:48 proxy racoon: DEBUG: 0x28547148 masked with /0: 0.0.0.0[0]
Dec 20 12:14:48 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:14:48 proxy racoon: DEBUG: 0x28547148 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:14:48 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:48 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:48 proxy racoon: INFO: Update the generated policy : 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:48 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:48 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:48 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:48 proxy racoon: DEBUG: db: 0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:48 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:48 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:48 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:48 proxy racoon: DEBUG: db: 0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:48 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:14:48 proxy racoon: DEBUG: 0x285473c8 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:14:48 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /0: 0.0.0.0[1701]
Dec 20 12:14:48 proxy racoon: DEBUG: 0x285473c8 masked with /0: 0.0.0.0[0]
Dec 20 12:14:48 proxy racoon: DEBUG: suitable SP found:XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:48 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:14:48 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-md5)
Dec 20 12:14:48 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-sha)
Dec 20 12:14:48 proxy racoon: DEBUG: total SA len=60
Dec 20 12:14:48 proxy racoon: DEBUG: 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090
Dec 20 12:14:48 proxy racoon: DEBUG: begin.
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=2(prop)
Dec 20 12:14:48 proxy racoon: DEBUG: succeed.
Dec 20 12:14:48 proxy racoon: DEBUG: proposal #1 len=52
Dec 20 12:14:48 proxy racoon: DEBUG: begin.
Dec 20 12:14:48 proxy racoon: DEBUG: seen nptype=3(trns)
Dec 20 12:14:48 proxy racoon: DEBUG: succeed.
Dec 20 12:14:48 proxy racoon: DEBUG: transform #1 len=40
Dec 20 12:14:48 proxy racoon: DEBUG: type=Encryption Mode, flag=0x8000, lorv=UDP-Transport
Dec 20 12:14:48 proxy racoon: DEBUG: UDP encapsulation requested
Dec 20 12:14:48 proxy racoon: DEBUG: type=Authentication Algorithm, flag=0x8000, lorv=hmac-sha
Dec 20 12:14:48 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=seconds
Dec 20 12:14:48 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:48 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=kilobytes
Dec 20 12:14:48 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:48 proxy racoon: DEBUG: pair 1:
Dec 20 12:14:48 proxy racoon: DEBUG: 0x28517510: next=0x0 tnext=0x0
Dec 20 12:14:48 proxy racoon: DEBUG: proposal #1: 1 transform
Dec 20 12:14:48 proxy racoon: DEBUG: begin compare proposals.
Dec 20 12:14:48 proxy racoon: DEBUG: pair[1]: 0x28517510
Dec 20 12:14:48 proxy racoon: DEBUG: 0x28517510: next=0x0 tnext=0x0
Dec 20 12:14:48 proxy racoon: DEBUG: prop#=1 prot-id=ESP spi-size=4 #trns=1 trns#=1 trns-id=NULL
Dec 20 12:14:48 proxy racoon: DEBUG: type=Encryption Mode, flag=0x8000, lorv=UDP-Transport
Dec 20 12:14:48 proxy racoon: DEBUG: type=Authentication Algorithm, flag=0x8000, lorv=hmac-sha
Dec 20 12:14:48 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=seconds
Dec 20 12:14:48 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:48 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=kilobytes
Dec 20 12:14:48 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:48 proxy racoon: DEBUG: peer's single bundle:
Dec 20 12:14:48 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=f591bc4d spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:14:48 proxy racoon: DEBUG: (trns_id=NULL encklen=0 authtype=hmac-sha)
Dec 20 12:14:48 proxy racoon: DEBUG: my single bundle:
Dec 20 12:14:48 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:14:48 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-md5)
Dec 20 12:14:48 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-sha)
Dec 20 12:14:48 proxy racoon: INFO: Adjusting my encmode UDP-Transport->Transport
Dec 20 12:14:48 proxy racoon: INFO: Adjusting peer's encmode UDP-Transport(4)->Transport(2)
Dec 20 12:14:48 proxy racoon: WARNING: trns_id mismatched: my:3DES peer:NULL
Dec 20 12:14:48 proxy racoon: WARNING: trns_id mismatched: my:3DES peer:NULL
Dec 20 12:14:48 proxy racoon: ERROR: not matched
Dec 20 12:14:48 proxy racoon: ERROR: no suitable policy found.
Dec 20 12:14:48 proxy racoon: [YYY.YYY.YYY.YYY] ERROR: no proposal chosen.
Dec 20 12:14:48 proxy racoon: [YYY.YYY.YYY.YYY] ERROR: failed to pre-process ph2 packet (side: 1, status: 1).
Dec 20 12:14:48 proxy racoon: DEBUG: compute IV for phase2
Dec 20 12:14:48 proxy racoon: DEBUG: phase1 last IV:
Dec 20 12:14:48 proxy racoon: DEBUG: 9e88afc8 f154547f 82e89e50
Dec 20 12:14:48 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:48 proxy racoon: DEBUG: phase2 IV computed:
Dec 20 12:14:48 proxy racoon: DEBUG: f0998314 e35449b7
Dec 20 12:14:48 proxy racoon: DEBUG: HASH with:
Dec 20 12:14:48 proxy racoon: DEBUG: 82e89e50 0000000c 00000001 0100000e
Dec 20 12:14:48 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:14:48 proxy racoon: DEBUG: HASH computed:
Dec 20 12:14:48 proxy racoon: DEBUG: c7078f93 bf82e6a0 31d98da9 6d5deb60 c1a62129
Dec 20 12:14:48 proxy racoon: DEBUG: begin encryption.
Dec 20 12:14:48 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:48 proxy racoon: DEBUG: pad length = 4
Dec 20 12:14:48 proxy racoon: DEBUG: 0b000018 c7078f93 bf82e6a0 31d98da9 6d5deb60 c1a62129 0000000c 00000001 0100000e 00000004
Dec 20 12:14:48 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:48 proxy racoon: DEBUG: with key:
Dec 20 12:14:48 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:14:48 proxy racoon: DEBUG: encrypted payload by IV:
Dec 20 12:14:48 proxy racoon: DEBUG: f0998314 e35449b7
Dec 20 12:14:48 proxy racoon: DEBUG: save IV for next:
Dec 20 12:14:48 proxy racoon: DEBUG: d7a95dd6 ba319ba7
Dec 20 12:14:48 proxy racoon: DEBUG: encrypted.
Dec 20 12:14:48 proxy racoon: DEBUG: Adding NON-ESP marker
Dec 20 12:14:48 proxy racoon: DEBUG: 72 bytes from XXX.XXX.XXX.XXX[4500] to YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:48 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:48 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:48 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:48 proxy racoon: DEBUG: 1 times of 72 bytes message will be sent to YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:48 proxy racoon: DEBUG: 00000000 fa6975cc b8108e36 2ec90ca9 82855fc3 08100501 82e89e50 00000044 8c8f52cb c7525af8 a063505c 8839aff0 09402a99 51f441e1 87637d13 fa6f78d1 d7a95dd6 ba319ba7
Dec 20 12:14:48 proxy racoon: DEBUG: sendto Information notify.
Dec 20 12:14:48 proxy racoon: DEBUG: IV freed
Dec 20 12:14:48 proxy racoon: DEBUG: sub:0xbfbfe37c: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:48 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:48 proxy racoon: DEBUG: sub:0xbfbfe37c: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:48 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:48 proxy racoon: DEBUG: sub:0xbfbfe37c: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:48 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:48 proxy racoon: DEBUG: sub:0xbfbfe37c: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:48 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:48 proxy racoon: DEBUG: IV freed
Dec 20 12:14:49 proxy racoon: DEBUG: ===
Dec 20 12:14:49 proxy racoon: DEBUG: 220 bytes message received from YYY.YYY.YYY.YYY[4500] to XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:49 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 08102001 00000001 000000dc f845f1d9 6a295096 0aa7c111 178fd0e8 c7bb991a 73a31f4c dca1e0e7 537170e9 5e8ea52c ab10c942 4469f83d c7a03df0 50925b26 927ff53a b86011b8 ac0f3f35 8907163e f26bb738 6769158b 55359b6c 740026bb afa93173 58b4c3d5 4701e50b 12b07a18 c8840b4c cb486257 93ed30f3 b5a3eb57 b6a47414 c6c28f49 bd8d7441 4be4f1af 7923e9d9 a923a4a9 df2cf431 bcdc0c75 e45dccbb d9aecc0c 31ec2df0 996a3e25 1ec0fa25 a3d7e48c 3b7f94e4 96df481d 209eb356 350e8b12 c14b0199
Dec 20 12:14:49 proxy racoon: DEBUG: compute IV for phase2
Dec 20 12:14:49 proxy racoon: DEBUG: phase1 last IV:
Dec 20 12:14:49 proxy racoon: DEBUG: 9e88afc8 f154547f 00000001
Dec 20 12:14:49 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:49 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:49 proxy racoon: DEBUG: phase2 IV computed:
Dec 20 12:14:49 proxy racoon: DEBUG: cb9f45f5 0af2a3a4
Dec 20 12:14:49 proxy racoon: DEBUG: ===
Dec 20 12:14:49 proxy racoon: INFO: respond new phase 2 negotiation: XXX.XXX.XXX.XXX[4500]<=>YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:49 proxy racoon: DEBUG: begin decryption.
Dec 20 12:14:49 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:49 proxy racoon: DEBUG: IV was saved for next processing:
Dec 20 12:14:49 proxy racoon: DEBUG: 350e8b12 c14b0199
Dec 20 12:14:49 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:49 proxy racoon: DEBUG: with key:
Dec 20 12:14:49 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:14:49 proxy racoon: DEBUG: decrypted payload by IV:
Dec 20 12:14:49 proxy racoon: DEBUG: cb9f45f5 0af2a3a4
Dec 20 12:14:49 proxy racoon: DEBUG: decrypted payload, but not trimed.
Dec 20 12:14:49 proxy racoon: DEBUG: 01000018 e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84 00000000
Dec 20 12:14:49 proxy racoon: DEBUG: padding len=0
Dec 20 12:14:49 proxy racoon: DEBUG: skip to trim padding.
Dec 20 12:14:49 proxy racoon: DEBUG: decrypted.
Dec 20 12:14:49 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 08102001 00000001 000000dc 01000018 e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84 00000000
Dec 20 12:14:49 proxy racoon: DEBUG: begin.
Dec 20 12:14:49 proxy racoon: DEBUG: seen nptype=8(hash)
Dec 20 12:14:49 proxy racoon: DEBUG: seen nptype=1(sa)
Dec 20 12:14:49 proxy racoon: DEBUG: seen nptype=10(nonce)
Dec 20 12:14:49 proxy racoon: DEBUG: seen nptype=5(id)
Dec 20 12:14:49 proxy racoon: DEBUG: seen nptype=5(id)
Dec 20 12:14:49 proxy racoon: DEBUG: seen nptype=21(nat-oa)
Dec 20 12:14:49 proxy racoon: DEBUG: seen nptype=21(nat-oa)
Dec 20 12:14:49 proxy racoon: DEBUG: succeed.
Dec 20 12:14:49 proxy racoon: DEBUG: received IDci2:
Dec 20 12:14:49 proxy racoon: DEBUG: 011106a5 c0a80182
Dec 20 12:14:49 proxy racoon: DEBUG: received IDcr2:
Dec 20 12:14:49 proxy racoon: DEBUG: 011106a5 d5a08b84
Dec 20 12:14:49 proxy racoon: DEBUG: HASH(1) validate:
Dec 20 12:14:49 proxy racoon: DEBUG: e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b
Dec 20 12:14:49 proxy racoon: DEBUG: HASH with:
Dec 20 12:14:49 proxy racoon: DEBUG: 00000001 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84
Dec 20 12:14:49 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:14:49 proxy racoon: DEBUG: HASH computed:
Dec 20 12:14:49 proxy racoon: DEBUG: e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b
Dec 20 12:14:49 proxy racoon: DEBUG: getsainfo params: loc='XXX.XXX.XXX.XXX' rmt='192.168.1.130' peer='192.168.1.130' client='YYY.YYY.YYY.YYY' id=0
Dec 20 12:14:49 proxy racoon: DEBUG: evaluating sainfo: loc='ANONYMOUS', rmt='ANONYMOUS', peer='ANY', id=0
Dec 20 12:14:49 proxy racoon: DEBUG: check and compare ids : values matched (ANONYMOUS)
Dec 20 12:14:49 proxy racoon: DEBUG: check and compare ids : values matched (ANONYMOUS)
Dec 20 12:14:49 proxy racoon: DEBUG: selected sainfo: loc='ANONYMOUS', rmt='ANONYMOUS', peer='ANY', id=0
Dec 20 12:14:49 proxy racoon: DEBUG: Either family (2 - 2), types (1 - 1) of ID from initiator differ or matching sainfo has no id_i defined for the peer. Not filling iph2->sa_src and iph2->sa_dst.
Dec 20 12:14:49 proxy racoon: DEBUG: get src address from ID payload 192.168.1.130[1701] prefixlen=32 ul_proto=17
Dec 20 12:14:49 proxy racoon: DEBUG: get dst address from ID payload XXX.XXX.XXX.XXX[1701] prefixlen=32 ul_proto=17
Dec 20 12:14:49 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:49 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:49 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:49 proxy racoon: DEBUG: db: 0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:49 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /0: 0.0.0.0[1701]
Dec 20 12:14:49 proxy racoon: DEBUG: 0x28547148 masked with /0: 0.0.0.0[0]
Dec 20 12:14:49 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:14:49 proxy racoon: DEBUG: 0x28547148 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:14:49 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:49 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:49 proxy racoon: INFO: Update the generated policy : 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:49 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:49 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:49 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:49 proxy racoon: DEBUG: db: 0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:49 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:49 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:49 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:49 proxy racoon: DEBUG: db: 0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:49 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:14:49 proxy racoon: DEBUG: 0x285473c8 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:14:49 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /0: 0.0.0.0[1701]
Dec 20 12:14:49 proxy racoon: DEBUG: 0x285473c8 masked with /0: 0.0.0.0[0]
Dec 20 12:14:49 proxy racoon: DEBUG: suitable SP found:XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:49 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:14:49 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-md5)
Dec 20 12:14:49 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-sha)
Dec 20 12:14:49 proxy racoon: DEBUG: total SA len=60
Dec 20 12:14:49 proxy racoon: DEBUG: 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090
Dec 20 12:14:49 proxy racoon: DEBUG: begin.
Dec 20 12:14:49 proxy racoon: DEBUG: seen nptype=2(prop)
Dec 20 12:14:49 proxy racoon: DEBUG: succeed.
Dec 20 12:14:49 proxy racoon: DEBUG: proposal #1 len=52
Dec 20 12:14:49 proxy racoon: DEBUG: begin.
Dec 20 12:14:49 proxy racoon: DEBUG: seen nptype=3(trns)
Dec 20 12:14:49 proxy racoon: DEBUG: succeed.
Dec 20 12:14:49 proxy racoon: DEBUG: transform #1 len=40
Dec 20 12:14:49 proxy racoon: DEBUG: type=Encryption Mode, flag=0x8000, lorv=UDP-Transport
Dec 20 12:14:49 proxy racoon: DEBUG: UDP encapsulation requested
Dec 20 12:14:49 proxy racoon: DEBUG: type=Authentication Algorithm, flag=0x8000, lorv=hmac-sha
Dec 20 12:14:49 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=seconds
Dec 20 12:14:49 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:49 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=kilobytes
Dec 20 12:14:49 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:49 proxy racoon: DEBUG: pair 1:
Dec 20 12:14:49 proxy racoon: DEBUG: 0x28517510: next=0x0 tnext=0x0
Dec 20 12:14:49 proxy racoon: DEBUG: proposal #1: 1 transform
Dec 20 12:14:49 proxy racoon: DEBUG: begin compare proposals.
Dec 20 12:14:49 proxy racoon: DEBUG: pair[1]: 0x28517510
Dec 20 12:14:49 proxy racoon: DEBUG: 0x28517510: next=0x0 tnext=0x0
Dec 20 12:14:49 proxy racoon: DEBUG: prop#=1 prot-id=ESP spi-size=4 #trns=1 trns#=1 trns-id=NULL
Dec 20 12:14:49 proxy racoon: DEBUG: type=Encryption Mode, flag=0x8000, lorv=UDP-Transport
Dec 20 12:14:49 proxy racoon: DEBUG: type=Authentication Algorithm, flag=0x8000, lorv=hmac-sha
Dec 20 12:14:49 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=seconds
Dec 20 12:14:49 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:49 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=kilobytes
Dec 20 12:14:49 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:49 proxy racoon: DEBUG: peer's single bundle:
Dec 20 12:14:49 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=f591bc4d spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:14:49 proxy racoon: DEBUG: (trns_id=NULL encklen=0 authtype=hmac-sha)
Dec 20 12:14:49 proxy racoon: DEBUG: my single bundle:
Dec 20 12:14:49 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:14:49 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-md5)
Dec 20 12:14:49 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-sha)
Dec 20 12:14:49 proxy racoon: INFO: Adjusting my encmode UDP-Transport->Transport
Dec 20 12:14:49 proxy racoon: INFO: Adjusting peer's encmode UDP-Transport(4)->Transport(2)
Dec 20 12:14:49 proxy racoon: WARNING: trns_id mismatched: my:3DES peer:NULL
Dec 20 12:14:49 proxy racoon: WARNING: trns_id mismatched: my:3DES peer:NULL
Dec 20 12:14:49 proxy racoon: ERROR: not matched
Dec 20 12:14:49 proxy racoon: ERROR: no suitable policy found.
Dec 20 12:14:49 proxy racoon: [YYY.YYY.YYY.YYY] ERROR: no proposal chosen.
Dec 20 12:14:49 proxy racoon: [YYY.YYY.YYY.YYY] ERROR: failed to pre-process ph2 packet (side: 1, status: 1).
Dec 20 12:14:49 proxy racoon: DEBUG: compute IV for phase2
Dec 20 12:14:49 proxy racoon: DEBUG: phase1 last IV:
Dec 20 12:14:49 proxy racoon: DEBUG: 9e88afc8 f154547f 9ff10fa8
Dec 20 12:14:49 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:49 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:49 proxy racoon: DEBUG: phase2 IV computed:
Dec 20 12:14:49 proxy racoon: DEBUG: 94d3b8c4 25089566
Dec 20 12:14:49 proxy racoon: DEBUG: HASH with:
Dec 20 12:14:49 proxy racoon: DEBUG: 9ff10fa8 0000000c 00000001 0100000e
Dec 20 12:14:49 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:14:49 proxy racoon: DEBUG: HASH computed:
Dec 20 12:14:49 proxy racoon: DEBUG: 60189b86 0b7649b5 1c469090 b165cf96 69054ffc
Dec 20 12:14:49 proxy racoon: DEBUG: begin encryption.
Dec 20 12:14:49 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:49 proxy racoon: DEBUG: pad length = 4
Dec 20 12:14:49 proxy racoon: DEBUG: 0b000018 60189b86 0b7649b5 1c469090 b165cf96 69054ffc 0000000c 00000001 0100000e 00000004
Dec 20 12:14:49 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:49 proxy racoon: DEBUG: with key:
Dec 20 12:14:49 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:14:49 proxy racoon: DEBUG: encrypted payload by IV:
Dec 20 12:14:49 proxy racoon: DEBUG: 94d3b8c4 25089566
Dec 20 12:14:49 proxy racoon: DEBUG: save IV for next:
Dec 20 12:14:49 proxy racoon: DEBUG: fc30454f e52a0710
Dec 20 12:14:49 proxy racoon: DEBUG: encrypted.
Dec 20 12:14:49 proxy racoon: DEBUG: Adding NON-ESP marker
Dec 20 12:14:49 proxy racoon: DEBUG: 72 bytes from XXX.XXX.XXX.XXX[4500] to YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:49 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:49 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:49 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:49 proxy racoon: DEBUG: 1 times of 72 bytes message will be sent to YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:49 proxy racoon: DEBUG: 00000000 fa6975cc b8108e36 2ec90ca9 82855fc3 08100501 9ff10fa8 00000044 1352be32 00e66e85 757fa24f e1d47641 4322c144 92129ff3 ad6cf195 6db60ce1 fc30454f e52a0710
Dec 20 12:14:49 proxy racoon: DEBUG: sendto Information notify.
Dec 20 12:14:49 proxy racoon: DEBUG: IV freed
Dec 20 12:14:49 proxy racoon: DEBUG: sub:0xbfbfe37c: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:49 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:49 proxy racoon: DEBUG: sub:0xbfbfe37c: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:49 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:49 proxy racoon: DEBUG: sub:0xbfbfe37c: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:49 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:49 proxy racoon: DEBUG: sub:0xbfbfe37c: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:49 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:49 proxy racoon: DEBUG: IV freed
Dec 20 12:14:51 proxy racoon: DEBUG: ===
Dec 20 12:14:51 proxy racoon: DEBUG: 220 bytes message received from YYY.YYY.YYY.YYY[4500] to XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:51 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 08102001 00000001 000000dc f845f1d9 6a295096 0aa7c111 178fd0e8 c7bb991a 73a31f4c dca1e0e7 537170e9 5e8ea52c ab10c942 4469f83d c7a03df0 50925b26 927ff53a b86011b8 ac0f3f35 8907163e f26bb738 6769158b 55359b6c 740026bb afa93173 58b4c3d5 4701e50b 12b07a18 c8840b4c cb486257 93ed30f3 b5a3eb57 b6a47414 c6c28f49 bd8d7441 4be4f1af 7923e9d9 a923a4a9 df2cf431 bcdc0c75 e45dccbb d9aecc0c 31ec2df0 996a3e25 1ec0fa25 a3d7e48c 3b7f94e4 96df481d 209eb356 350e8b12 c14b0199
Dec 20 12:14:51 proxy racoon: DEBUG: compute IV for phase2
Dec 20 12:14:51 proxy racoon: DEBUG: phase1 last IV:
Dec 20 12:14:51 proxy racoon: DEBUG: 9e88afc8 f154547f 00000001
Dec 20 12:14:51 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:51 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:51 proxy racoon: DEBUG: phase2 IV computed:
Dec 20 12:14:51 proxy racoon: DEBUG: cb9f45f5 0af2a3a4
Dec 20 12:14:51 proxy racoon: DEBUG: ===
Dec 20 12:14:51 proxy racoon: INFO: respond new phase 2 negotiation: XXX.XXX.XXX.XXX[4500]<=>YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:51 proxy racoon: DEBUG: begin decryption.
Dec 20 12:14:51 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:51 proxy racoon: DEBUG: IV was saved for next processing:
Dec 20 12:14:51 proxy racoon: DEBUG: 350e8b12 c14b0199
Dec 20 12:14:51 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:51 proxy racoon: DEBUG: with key:
Dec 20 12:14:51 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:14:51 proxy racoon: DEBUG: decrypted payload by IV:
Dec 20 12:14:51 proxy racoon: DEBUG: cb9f45f5 0af2a3a4
Dec 20 12:14:51 proxy racoon: DEBUG: decrypted payload, but not trimed.
Dec 20 12:14:51 proxy racoon: DEBUG: 01000018 e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84 00000000
Dec 20 12:14:51 proxy racoon: DEBUG: padding len=0
Dec 20 12:14:51 proxy racoon: DEBUG: skip to trim padding.
Dec 20 12:14:51 proxy racoon: DEBUG: decrypted.
Dec 20 12:14:51 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 08102001 00000001 000000dc 01000018 e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84 00000000
Dec 20 12:14:51 proxy racoon: DEBUG: begin.
Dec 20 12:14:51 proxy racoon: DEBUG: seen nptype=8(hash)
Dec 20 12:14:51 proxy racoon: DEBUG: seen nptype=1(sa)
Dec 20 12:14:51 proxy racoon: DEBUG: seen nptype=10(nonce)
Dec 20 12:14:51 proxy racoon: DEBUG: seen nptype=5(id)
Dec 20 12:14:51 proxy racoon: DEBUG: seen nptype=5(id)
Dec 20 12:14:51 proxy racoon: DEBUG: seen nptype=21(nat-oa)
Dec 20 12:14:51 proxy racoon: DEBUG: seen nptype=21(nat-oa)
Dec 20 12:14:51 proxy racoon: DEBUG: succeed.
Dec 20 12:14:51 proxy racoon: DEBUG: received IDci2:
Dec 20 12:14:51 proxy racoon: DEBUG: 011106a5 c0a80182
Dec 20 12:14:51 proxy racoon: DEBUG: received IDcr2:
Dec 20 12:14:51 proxy racoon: DEBUG: 011106a5 d5a08b84
Dec 20 12:14:51 proxy racoon: DEBUG: HASH(1) validate:
Dec 20 12:14:51 proxy racoon: DEBUG: e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b
Dec 20 12:14:51 proxy racoon: DEBUG: HASH with:
Dec 20 12:14:51 proxy racoon: DEBUG: 00000001 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84
Dec 20 12:14:51 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:14:51 proxy racoon: DEBUG: HASH computed:
Dec 20 12:14:51 proxy racoon: DEBUG: e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b
Dec 20 12:14:51 proxy racoon: DEBUG: getsainfo params: loc='XXX.XXX.XXX.XXX' rmt='192.168.1.130' peer='192.168.1.130' client='YYY.YYY.YYY.YYY' id=0
Dec 20 12:14:51 proxy racoon: DEBUG: evaluating sainfo: loc='ANONYMOUS', rmt='ANONYMOUS', peer='ANY', id=0
Dec 20 12:14:51 proxy racoon: DEBUG: check and compare ids : values matched (ANONYMOUS)
Dec 20 12:14:51 proxy racoon: DEBUG: check and compare ids : values matched (ANONYMOUS)
Dec 20 12:14:51 proxy racoon: DEBUG: selected sainfo: loc='ANONYMOUS', rmt='ANONYMOUS', peer='ANY', id=0
Dec 20 12:14:51 proxy racoon: DEBUG: Either family (2 - 2), types (1 - 1) of ID from initiator differ or matching sainfo has no id_i defined for the peer. Not filling iph2->sa_src and iph2->sa_dst.
Dec 20 12:14:51 proxy racoon: DEBUG: get src address from ID payload 192.168.1.130[1701] prefixlen=32 ul_proto=17
Dec 20 12:14:51 proxy racoon: DEBUG: get dst address from ID payload XXX.XXX.XXX.XXX[1701] prefixlen=32 ul_proto=17
Dec 20 12:14:51 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:51 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:51 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:51 proxy racoon: DEBUG: db: 0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:51 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /0: 0.0.0.0[1701]
Dec 20 12:14:51 proxy racoon: DEBUG: 0x28547148 masked with /0: 0.0.0.0[0]
Dec 20 12:14:51 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:14:51 proxy racoon: DEBUG: 0x28547148 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:14:51 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:51 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:51 proxy racoon: INFO: Update the generated policy : 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:51 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:51 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:51 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:51 proxy racoon: DEBUG: db: 0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:51 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:51 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:51 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:51 proxy racoon: DEBUG: db: 0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:51 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:14:51 proxy racoon: DEBUG: 0x285473c8 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:14:51 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /0: 0.0.0.0[1701]
Dec 20 12:14:51 proxy racoon: DEBUG: 0x285473c8 masked with /0: 0.0.0.0[0]
Dec 20 12:14:51 proxy racoon: DEBUG: suitable SP found:XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:51 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:14:51 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-md5)
Dec 20 12:14:51 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-sha)
Dec 20 12:14:51 proxy racoon: DEBUG: total SA len=60
Dec 20 12:14:51 proxy racoon: DEBUG: 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090
Dec 20 12:14:51 proxy racoon: DEBUG: begin.
Dec 20 12:14:51 proxy racoon: DEBUG: seen nptype=2(prop)
Dec 20 12:14:51 proxy racoon: DEBUG: succeed.
Dec 20 12:14:51 proxy racoon: DEBUG: proposal #1 len=52
Dec 20 12:14:51 proxy racoon: DEBUG: begin.
Dec 20 12:14:51 proxy racoon: DEBUG: seen nptype=3(trns)
Dec 20 12:14:51 proxy racoon: DEBUG: succeed.
Dec 20 12:14:51 proxy racoon: DEBUG: transform #1 len=40
Dec 20 12:14:51 proxy racoon: DEBUG: type=Encryption Mode, flag=0x8000, lorv=UDP-Transport
Dec 20 12:14:51 proxy racoon: DEBUG: UDP encapsulation requested
Dec 20 12:14:51 proxy racoon: DEBUG: type=Authentication Algorithm, flag=0x8000, lorv=hmac-sha
Dec 20 12:14:51 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=seconds
Dec 20 12:14:51 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:51 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=kilobytes
Dec 20 12:14:51 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:51 proxy racoon: DEBUG: pair 1:
Dec 20 12:14:51 proxy racoon: DEBUG: 0x28517510: next=0x0 tnext=0x0
Dec 20 12:14:51 proxy racoon: DEBUG: proposal #1: 1 transform
Dec 20 12:14:51 proxy racoon: DEBUG: begin compare proposals.
Dec 20 12:14:51 proxy racoon: DEBUG: pair[1]: 0x28517510
Dec 20 12:14:51 proxy racoon: DEBUG: 0x28517510: next=0x0 tnext=0x0
Dec 20 12:14:51 proxy racoon: DEBUG: prop#=1 prot-id=ESP spi-size=4 #trns=1 trns#=1 trns-id=NULL
Dec 20 12:14:51 proxy racoon: DEBUG: type=Encryption Mode, flag=0x8000, lorv=UDP-Transport
Dec 20 12:14:51 proxy racoon: DEBUG: type=Authentication Algorithm, flag=0x8000, lorv=hmac-sha
Dec 20 12:14:51 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=seconds
Dec 20 12:14:51 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:51 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=kilobytes
Dec 20 12:14:51 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:51 proxy racoon: DEBUG: peer's single bundle:
Dec 20 12:14:51 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=f591bc4d spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:14:51 proxy racoon: DEBUG: (trns_id=NULL encklen=0 authtype=hmac-sha)
Dec 20 12:14:51 proxy racoon: DEBUG: my single bundle:
Dec 20 12:14:51 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:14:51 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-md5)
Dec 20 12:14:51 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-sha)
Dec 20 12:14:51 proxy racoon: INFO: Adjusting my encmode UDP-Transport->Transport
Dec 20 12:14:51 proxy racoon: INFO: Adjusting peer's encmode UDP-Transport(4)->Transport(2)
Dec 20 12:14:51 proxy racoon: WARNING: trns_id mismatched: my:3DES peer:NULL
Dec 20 12:14:51 proxy racoon: WARNING: trns_id mismatched: my:3DES peer:NULL
Dec 20 12:14:51 proxy racoon: ERROR: not matched
Dec 20 12:14:51 proxy racoon: ERROR: no suitable policy found.
Dec 20 12:14:51 proxy racoon: [YYY.YYY.YYY.YYY] ERROR: no proposal chosen.
Dec 20 12:14:51 proxy racoon: [YYY.YYY.YYY.YYY] ERROR: failed to pre-process ph2 packet (side: 1, status: 1).
Dec 20 12:14:51 proxy racoon: DEBUG: compute IV for phase2
Dec 20 12:14:51 proxy racoon: DEBUG: phase1 last IV:
Dec 20 12:14:51 proxy racoon: DEBUG: 9e88afc8 f154547f 8249b08e
Dec 20 12:14:51 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:51 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:51 proxy racoon: DEBUG: phase2 IV computed:
Dec 20 12:14:51 proxy racoon: DEBUG: 2612a69a 831e94ea
Dec 20 12:14:51 proxy racoon: DEBUG: HASH with:
Dec 20 12:14:51 proxy racoon: DEBUG: 8249b08e 0000000c 00000001 0100000e
Dec 20 12:14:51 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:14:51 proxy racoon: DEBUG: HASH computed:
Dec 20 12:14:51 proxy racoon: DEBUG: 247549ca f00c9d0d 848275e3 10c763be 2a1ab93f
Dec 20 12:14:51 proxy racoon: DEBUG: begin encryption.
Dec 20 12:14:51 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:51 proxy racoon: DEBUG: pad length = 4
Dec 20 12:14:51 proxy racoon: DEBUG: 0b000018 247549ca f00c9d0d 848275e3 10c763be 2a1ab93f 0000000c 00000001 0100000e 00000004
Dec 20 12:14:51 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:51 proxy racoon: DEBUG: with key:
Dec 20 12:14:51 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:14:51 proxy racoon: DEBUG: encrypted payload by IV:
Dec 20 12:14:51 proxy racoon: DEBUG: 2612a69a 831e94ea
Dec 20 12:14:51 proxy racoon: DEBUG: save IV for next:
Dec 20 12:14:51 proxy racoon: DEBUG: 9adbf3a5 433350c2
Dec 20 12:14:51 proxy racoon: DEBUG: encrypted.
Dec 20 12:14:51 proxy racoon: DEBUG: Adding NON-ESP marker
Dec 20 12:14:51 proxy racoon: DEBUG: 72 bytes from XXX.XXX.XXX.XXX[4500] to YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:51 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:51 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:51 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:51 proxy racoon: DEBUG: 1 times of 72 bytes message will be sent to YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:51 proxy racoon: DEBUG: 00000000 fa6975cc b8108e36 2ec90ca9 82855fc3 08100501 8249b08e 00000044 fc84f0ef da16e6df b03e4b0b 73b549c6 763a8497 d7bdf8ae 51f62ef8 acdaa62b 9adbf3a5 433350c2
Dec 20 12:14:51 proxy racoon: DEBUG: sendto Information notify.
Dec 20 12:14:51 proxy racoon: DEBUG: IV freed
Dec 20 12:14:51 proxy racoon: DEBUG: sub:0xbfbfe37c: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:51 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:51 proxy racoon: DEBUG: sub:0xbfbfe37c: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:51 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:51 proxy racoon: DEBUG: sub:0xbfbfe37c: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:51 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:51 proxy racoon: DEBUG: sub:0xbfbfe37c: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:51 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:51 proxy racoon: DEBUG: IV freed
Dec 20 12:14:52 proxy racoon: DEBUG: KA: XXX.XXX.XXX.XXX[4500]->YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:52 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:52 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:52 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:52 proxy racoon: DEBUG: 1 times of 1 bytes message will be sent to YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:52 proxy racoon: DEBUG: ff
Dec 20 12:14:55 proxy racoon: DEBUG: ===
Dec 20 12:14:55 proxy racoon: DEBUG: 220 bytes message received from YYY.YYY.YYY.YYY[4500] to XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:55 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 08102001 00000001 000000dc f845f1d9 6a295096 0aa7c111 178fd0e8 c7bb991a 73a31f4c dca1e0e7 537170e9 5e8ea52c ab10c942 4469f83d c7a03df0 50925b26 927ff53a b86011b8 ac0f3f35 8907163e f26bb738 6769158b 55359b6c 740026bb afa93173 58b4c3d5 4701e50b 12b07a18 c8840b4c cb486257 93ed30f3 b5a3eb57 b6a47414 c6c28f49 bd8d7441 4be4f1af 7923e9d9 a923a4a9 df2cf431 bcdc0c75 e45dccbb d9aecc0c 31ec2df0 996a3e25 1ec0fa25 a3d7e48c 3b7f94e4 96df481d 209eb356 350e8b12 c14b0199
Dec 20 12:14:55 proxy racoon: DEBUG: compute IV for phase2
Dec 20 12:14:55 proxy racoon: DEBUG: phase1 last IV:
Dec 20 12:14:55 proxy racoon: DEBUG: 9e88afc8 f154547f 00000001
Dec 20 12:14:55 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:55 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:55 proxy racoon: DEBUG: phase2 IV computed:
Dec 20 12:14:55 proxy racoon: DEBUG: cb9f45f5 0af2a3a4
Dec 20 12:14:55 proxy racoon: DEBUG: ===
Dec 20 12:14:55 proxy racoon: INFO: respond new phase 2 negotiation: XXX.XXX.XXX.XXX[4500]<=>YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:55 proxy racoon: DEBUG: begin decryption.
Dec 20 12:14:55 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:55 proxy racoon: DEBUG: IV was saved for next processing:
Dec 20 12:14:55 proxy racoon: DEBUG: 350e8b12 c14b0199
Dec 20 12:14:55 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:55 proxy racoon: DEBUG: with key:
Dec 20 12:14:55 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:14:55 proxy racoon: DEBUG: decrypted payload by IV:
Dec 20 12:14:55 proxy racoon: DEBUG: cb9f45f5 0af2a3a4
Dec 20 12:14:55 proxy racoon: DEBUG: decrypted payload, but not trimed.
Dec 20 12:14:55 proxy racoon: DEBUG: 01000018 e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84 00000000
Dec 20 12:14:55 proxy racoon: DEBUG: padding len=0
Dec 20 12:14:55 proxy racoon: DEBUG: skip to trim padding.
Dec 20 12:14:55 proxy racoon: DEBUG: decrypted.
Dec 20 12:14:55 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 08102001 00000001 000000dc 01000018 e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84 00000000
Dec 20 12:14:55 proxy racoon: DEBUG: begin.
Dec 20 12:14:55 proxy racoon: DEBUG: seen nptype=8(hash)
Dec 20 12:14:55 proxy racoon: DEBUG: seen nptype=1(sa)
Dec 20 12:14:55 proxy racoon: DEBUG: seen nptype=10(nonce)
Dec 20 12:14:55 proxy racoon: DEBUG: seen nptype=5(id)
Dec 20 12:14:55 proxy racoon: DEBUG: seen nptype=5(id)
Dec 20 12:14:55 proxy racoon: DEBUG: seen nptype=21(nat-oa)
Dec 20 12:14:55 proxy racoon: DEBUG: seen nptype=21(nat-oa)
Dec 20 12:14:55 proxy racoon: DEBUG: succeed.
Dec 20 12:14:55 proxy racoon: DEBUG: received IDci2:
Dec 20 12:14:55 proxy racoon: DEBUG: 011106a5 c0a80182
Dec 20 12:14:55 proxy racoon: DEBUG: received IDcr2:
Dec 20 12:14:55 proxy racoon: DEBUG: 011106a5 d5a08b84
Dec 20 12:14:55 proxy racoon: DEBUG: HASH(1) validate:
Dec 20 12:14:55 proxy racoon: DEBUG: e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b
Dec 20 12:14:55 proxy racoon: DEBUG: HASH with:
Dec 20 12:14:55 proxy racoon: DEBUG: 00000001 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84
Dec 20 12:14:55 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:14:55 proxy racoon: DEBUG: HASH computed:
Dec 20 12:14:55 proxy racoon: DEBUG: e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b
Dec 20 12:14:55 proxy racoon: DEBUG: getsainfo params: loc='XXX.XXX.XXX.XXX' rmt='192.168.1.130' peer='192.168.1.130' client='YYY.YYY.YYY.YYY' id=0
Dec 20 12:14:55 proxy racoon: DEBUG: evaluating sainfo: loc='ANONYMOUS', rmt='ANONYMOUS', peer='ANY', id=0
Dec 20 12:14:55 proxy racoon: DEBUG: check and compare ids : values matched (ANONYMOUS)
Dec 20 12:14:55 proxy racoon: DEBUG: check and compare ids : values matched (ANONYMOUS)
Dec 20 12:14:55 proxy racoon: DEBUG: selected sainfo: loc='ANONYMOUS', rmt='ANONYMOUS', peer='ANY', id=0
Dec 20 12:14:55 proxy racoon: DEBUG: Either family (2 - 2), types (1 - 1) of ID from initiator differ or matching sainfo has no id_i defined for the peer. Not filling iph2->sa_src and iph2->sa_dst.
Dec 20 12:14:55 proxy racoon: DEBUG: get src address from ID payload 192.168.1.130[1701] prefixlen=32 ul_proto=17
Dec 20 12:14:55 proxy racoon: DEBUG: get dst address from ID payload XXX.XXX.XXX.XXX[1701] prefixlen=32 ul_proto=17
Dec 20 12:14:55 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:55 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:55 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:55 proxy racoon: DEBUG: db: 0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:55 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /0: 0.0.0.0[1701]
Dec 20 12:14:55 proxy racoon: DEBUG: 0x28547148 masked with /0: 0.0.0.0[0]
Dec 20 12:14:55 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:14:55 proxy racoon: DEBUG: 0x28547148 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:14:55 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:55 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:55 proxy racoon: INFO: Update the generated policy : 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:55 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:55 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:55 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:55 proxy racoon: DEBUG: db: 0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:55 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:55 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:55 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:55 proxy racoon: DEBUG: db: 0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:55 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:14:55 proxy racoon: DEBUG: 0x285473c8 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:14:55 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /0: 0.0.0.0[1701]
Dec 20 12:14:55 proxy racoon: DEBUG: 0x285473c8 masked with /0: 0.0.0.0[0]
Dec 20 12:14:55 proxy racoon: DEBUG: suitable SP found:XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:55 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:14:55 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-md5)
Dec 20 12:14:55 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-sha)
Dec 20 12:14:55 proxy racoon: DEBUG: total SA len=60
Dec 20 12:14:55 proxy racoon: DEBUG: 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090
Dec 20 12:14:55 proxy racoon: DEBUG: begin.
Dec 20 12:14:55 proxy racoon: DEBUG: seen nptype=2(prop)
Dec 20 12:14:55 proxy racoon: DEBUG: succeed.
Dec 20 12:14:55 proxy racoon: DEBUG: proposal #1 len=52
Dec 20 12:14:55 proxy racoon: DEBUG: begin.
Dec 20 12:14:55 proxy racoon: DEBUG: seen nptype=3(trns)
Dec 20 12:14:55 proxy racoon: DEBUG: succeed.
Dec 20 12:14:55 proxy racoon: DEBUG: transform #1 len=40
Dec 20 12:14:55 proxy racoon: DEBUG: type=Encryption Mode, flag=0x8000, lorv=UDP-Transport
Dec 20 12:14:55 proxy racoon: DEBUG: UDP encapsulation requested
Dec 20 12:14:55 proxy racoon: DEBUG: type=Authentication Algorithm, flag=0x8000, lorv=hmac-sha
Dec 20 12:14:55 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=seconds
Dec 20 12:14:55 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:55 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=kilobytes
Dec 20 12:14:55 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:55 proxy racoon: DEBUG: pair 1:
Dec 20 12:14:55 proxy racoon: DEBUG: 0x28517510: next=0x0 tnext=0x0
Dec 20 12:14:55 proxy racoon: DEBUG: proposal #1: 1 transform
Dec 20 12:14:55 proxy racoon: DEBUG: begin compare proposals.
Dec 20 12:14:55 proxy racoon: DEBUG: pair[1]: 0x28517510
Dec 20 12:14:55 proxy racoon: DEBUG: 0x28517510: next=0x0 tnext=0x0
Dec 20 12:14:55 proxy racoon: DEBUG: prop#=1 prot-id=ESP spi-size=4 #trns=1 trns#=1 trns-id=NULL
Dec 20 12:14:55 proxy racoon: DEBUG: type=Encryption Mode, flag=0x8000, lorv=UDP-Transport
Dec 20 12:14:55 proxy racoon: DEBUG: type=Authentication Algorithm, flag=0x8000, lorv=hmac-sha
Dec 20 12:14:55 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=seconds
Dec 20 12:14:55 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:55 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=kilobytes
Dec 20 12:14:55 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:14:55 proxy racoon: DEBUG: peer's single bundle:
Dec 20 12:14:55 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=f591bc4d spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:14:55 proxy racoon: DEBUG: (trns_id=NULL encklen=0 authtype=hmac-sha)
Dec 20 12:14:55 proxy racoon: DEBUG: my single bundle:
Dec 20 12:14:55 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:14:55 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-md5)
Dec 20 12:14:55 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-sha)
Dec 20 12:14:55 proxy racoon: INFO: Adjusting my encmode UDP-Transport->Transport
Dec 20 12:14:55 proxy racoon: INFO: Adjusting peer's encmode UDP-Transport(4)->Transport(2)
Dec 20 12:14:55 proxy racoon: WARNING: trns_id mismatched: my:3DES peer:NULL
Dec 20 12:14:55 proxy racoon: WARNING: trns_id mismatched: my:3DES peer:NULL
Dec 20 12:14:55 proxy racoon: ERROR: not matched
Dec 20 12:14:55 proxy racoon: ERROR: no suitable policy found.
Dec 20 12:14:55 proxy racoon: [YYY.YYY.YYY.YYY] ERROR: no proposal chosen.
Dec 20 12:14:55 proxy racoon: [YYY.YYY.YYY.YYY] ERROR: failed to pre-process ph2 packet (side: 1, status: 1).
Dec 20 12:14:55 proxy racoon: DEBUG: compute IV for phase2
Dec 20 12:14:55 proxy racoon: DEBUG: phase1 last IV:
Dec 20 12:14:55 proxy racoon: DEBUG: 9e88afc8 f154547f c2d3ce93
Dec 20 12:14:55 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:14:55 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:55 proxy racoon: DEBUG: phase2 IV computed:
Dec 20 12:14:55 proxy racoon: DEBUG: 3935a77e 537cb3b7
Dec 20 12:14:55 proxy racoon: DEBUG: HASH with:
Dec 20 12:14:55 proxy racoon: DEBUG: c2d3ce93 0000000c 00000001 0100000e
Dec 20 12:14:55 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:14:55 proxy racoon: DEBUG: HASH computed:
Dec 20 12:14:55 proxy racoon: DEBUG: fa98730f 88e30edc bb3b9819 af692547 1d1d6c14
Dec 20 12:14:55 proxy racoon: DEBUG: begin encryption.
Dec 20 12:14:55 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:55 proxy racoon: DEBUG: pad length = 4
Dec 20 12:14:55 proxy racoon: DEBUG: 0b000018 fa98730f 88e30edc bb3b9819 af692547 1d1d6c14 0000000c 00000001 0100000e 00000004
Dec 20 12:14:55 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:14:55 proxy racoon: DEBUG: with key:
Dec 20 12:14:55 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:14:55 proxy racoon: DEBUG: encrypted payload by IV:
Dec 20 12:14:55 proxy racoon: DEBUG: 3935a77e 537cb3b7
Dec 20 12:14:55 proxy racoon: DEBUG: save IV for next:
Dec 20 12:14:55 proxy racoon: DEBUG: 4401e427 61e4a7ea
Dec 20 12:14:55 proxy racoon: DEBUG: encrypted.
Dec 20 12:14:55 proxy racoon: DEBUG: Adding NON-ESP marker
Dec 20 12:14:55 proxy racoon: DEBUG: 72 bytes from XXX.XXX.XXX.XXX[4500] to YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:55 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:55 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[4500]
Dec 20 12:14:55 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:55 proxy racoon: DEBUG: 1 times of 72 bytes message will be sent to YYY.YYY.YYY.YYY[4500]
Dec 20 12:14:55 proxy racoon: DEBUG: 00000000 fa6975cc b8108e36 2ec90ca9 82855fc3 08100501 c2d3ce93 00000044 7a68d153 09bdb8a4 3acbfe28 0bf0243d 23d98654 3ceb2535 9941f7fb d957fec9 4401e427 61e4a7ea
Dec 20 12:14:55 proxy racoon: DEBUG: sendto Information notify.
Dec 20 12:14:55 proxy racoon: DEBUG: IV freed
Dec 20 12:14:55 proxy racoon: DEBUG: sub:0xbfbfe37c: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:55 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:55 proxy racoon: DEBUG: sub:0xbfbfe37c: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:55 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:55 proxy racoon: DEBUG: sub:0xbfbfe37c: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:55 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:14:55 proxy racoon: DEBUG: sub:0xbfbfe37c: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:14:55 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:14:55 proxy racoon: DEBUG: IV freed
Dec 20 12:15:02 proxy racoon: DEBUG: KA: XXX.XXX.XXX.XXX[4500]->YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:02 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:02 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:02 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:02 proxy racoon: DEBUG: 1 times of 1 bytes message will be sent to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:02 proxy racoon: DEBUG: ff
Dec 20 12:15:03 proxy racoon: DEBUG: ===
Dec 20 12:15:03 proxy racoon: DEBUG: 220 bytes message received from YYY.YYY.YYY.YYY[4500] to XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:03 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 08102001 00000001 000000dc f845f1d9 6a295096 0aa7c111 178fd0e8 c7bb991a 73a31f4c dca1e0e7 537170e9 5e8ea52c ab10c942 4469f83d c7a03df0 50925b26 927ff53a b86011b8 ac0f3f35 8907163e f26bb738 6769158b 55359b6c 740026bb afa93173 58b4c3d5 4701e50b 12b07a18 c8840b4c cb486257 93ed30f3 b5a3eb57 b6a47414 c6c28f49 bd8d7441 4be4f1af 7923e9d9 a923a4a9 df2cf431 bcdc0c75 e45dccbb d9aecc0c 31ec2df0 996a3e25 1ec0fa25 a3d7e48c 3b7f94e4 96df481d 209eb356 350e8b12 c14b0199
Dec 20 12:15:03 proxy racoon: DEBUG: compute IV for phase2
Dec 20 12:15:03 proxy racoon: DEBUG: phase1 last IV:
Dec 20 12:15:03 proxy racoon: DEBUG: 9e88afc8 f154547f 00000001
Dec 20 12:15:03 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:03 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:03 proxy racoon: DEBUG: phase2 IV computed:
Dec 20 12:15:03 proxy racoon: DEBUG: cb9f45f5 0af2a3a4
Dec 20 12:15:03 proxy racoon: DEBUG: ===
Dec 20 12:15:03 proxy racoon: INFO: respond new phase 2 negotiation: XXX.XXX.XXX.XXX[4500]<=>YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:03 proxy racoon: DEBUG: begin decryption.
Dec 20 12:15:03 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:03 proxy racoon: DEBUG: IV was saved for next processing:
Dec 20 12:15:03 proxy racoon: DEBUG: 350e8b12 c14b0199
Dec 20 12:15:03 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:03 proxy racoon: DEBUG: with key:
Dec 20 12:15:03 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:15:03 proxy racoon: DEBUG: decrypted payload by IV:
Dec 20 12:15:03 proxy racoon: DEBUG: cb9f45f5 0af2a3a4
Dec 20 12:15:03 proxy racoon: DEBUG: decrypted payload, but not trimed.
Dec 20 12:15:03 proxy racoon: DEBUG: 01000018 e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84 00000000
Dec 20 12:15:03 proxy racoon: DEBUG: padding len=0
Dec 20 12:15:03 proxy racoon: DEBUG: skip to trim padding.
Dec 20 12:15:03 proxy racoon: DEBUG: decrypted.
Dec 20 12:15:03 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 08102001 00000001 000000dc 01000018 e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84 00000000
Dec 20 12:15:03 proxy racoon: DEBUG: begin.
Dec 20 12:15:03 proxy racoon: DEBUG: seen nptype=8(hash)
Dec 20 12:15:03 proxy racoon: DEBUG: seen nptype=1(sa)
Dec 20 12:15:03 proxy racoon: DEBUG: seen nptype=10(nonce)
Dec 20 12:15:03 proxy racoon: DEBUG: seen nptype=5(id)
Dec 20 12:15:03 proxy racoon: DEBUG: seen nptype=5(id)
Dec 20 12:15:03 proxy racoon: DEBUG: seen nptype=21(nat-oa)
Dec 20 12:15:03 proxy racoon: DEBUG: seen nptype=21(nat-oa)
Dec 20 12:15:03 proxy racoon: DEBUG: succeed.
Dec 20 12:15:03 proxy racoon: DEBUG: received IDci2:
Dec 20 12:15:03 proxy racoon: DEBUG: 011106a5 c0a80182
Dec 20 12:15:03 proxy racoon: DEBUG: received IDcr2:
Dec 20 12:15:03 proxy racoon: DEBUG: 011106a5 d5a08b84
Dec 20 12:15:03 proxy racoon: DEBUG: HASH(1) validate:
Dec 20 12:15:03 proxy racoon: DEBUG: e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b
Dec 20 12:15:03 proxy racoon: DEBUG: HASH with:
Dec 20 12:15:03 proxy racoon: DEBUG: 00000001 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84
Dec 20 12:15:03 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:15:03 proxy racoon: DEBUG: HASH computed:
Dec 20 12:15:03 proxy racoon: DEBUG: e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b
Dec 20 12:15:03 proxy racoon: DEBUG: getsainfo params: loc='XXX.XXX.XXX.XXX' rmt='192.168.1.130' peer='192.168.1.130' client='YYY.YYY.YYY.YYY' id=0
Dec 20 12:15:03 proxy racoon: DEBUG: evaluating sainfo: loc='ANONYMOUS', rmt='ANONYMOUS', peer='ANY', id=0
Dec 20 12:15:03 proxy racoon: DEBUG: check and compare ids : values matched (ANONYMOUS)
Dec 20 12:15:03 proxy racoon: DEBUG: check and compare ids : values matched (ANONYMOUS)
Dec 20 12:15:03 proxy racoon: DEBUG: selected sainfo: loc='ANONYMOUS', rmt='ANONYMOUS', peer='ANY', id=0
Dec 20 12:15:03 proxy racoon: DEBUG: Either family (2 - 2), types (1 - 1) of ID from initiator differ or matching sainfo has no id_i defined for the peer. Not filling iph2->sa_src and iph2->sa_dst.
Dec 20 12:15:03 proxy racoon: DEBUG: get src address from ID payload 192.168.1.130[1701] prefixlen=32 ul_proto=17
Dec 20 12:15:03 proxy racoon: DEBUG: get dst address from ID payload XXX.XXX.XXX.XXX[1701] prefixlen=32 ul_proto=17
Dec 20 12:15:03 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:03 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:03 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:03 proxy racoon: DEBUG: db: 0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:03 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /0: 0.0.0.0[1701]
Dec 20 12:15:03 proxy racoon: DEBUG: 0x28547148 masked with /0: 0.0.0.0[0]
Dec 20 12:15:03 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:15:03 proxy racoon: DEBUG: 0x28547148 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:15:03 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:03 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:15:03 proxy racoon: INFO: Update the generated policy : 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:03 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:03 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:03 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:03 proxy racoon: DEBUG: db: 0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:03 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:03 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:15:03 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:03 proxy racoon: DEBUG: db: 0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:15:03 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:15:03 proxy racoon: DEBUG: 0x285473c8 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:15:03 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /0: 0.0.0.0[1701]
Dec 20 12:15:03 proxy racoon: DEBUG: 0x285473c8 masked with /0: 0.0.0.0[0]
Dec 20 12:15:03 proxy racoon: DEBUG: suitable SP found:XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:03 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:15:03 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-md5)
Dec 20 12:15:03 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-sha)
Dec 20 12:15:03 proxy racoon: DEBUG: total SA len=60
Dec 20 12:15:03 proxy racoon: DEBUG: 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090
Dec 20 12:15:03 proxy racoon: DEBUG: begin.
Dec 20 12:15:03 proxy racoon: DEBUG: seen nptype=2(prop)
Dec 20 12:15:03 proxy racoon: DEBUG: succeed.
Dec 20 12:15:03 proxy racoon: DEBUG: proposal #1 len=52
Dec 20 12:15:03 proxy racoon: DEBUG: begin.
Dec 20 12:15:03 proxy racoon: DEBUG: seen nptype=3(trns)
Dec 20 12:15:03 proxy racoon: DEBUG: succeed.
Dec 20 12:15:03 proxy racoon: DEBUG: transform #1 len=40
Dec 20 12:15:03 proxy racoon: DEBUG: type=Encryption Mode, flag=0x8000, lorv=UDP-Transport
Dec 20 12:15:03 proxy racoon: DEBUG: UDP encapsulation requested
Dec 20 12:15:03 proxy racoon: DEBUG: type=Authentication Algorithm, flag=0x8000, lorv=hmac-sha
Dec 20 12:15:03 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=seconds
Dec 20 12:15:03 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:03 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=kilobytes
Dec 20 12:15:03 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:03 proxy racoon: DEBUG: pair 1:
Dec 20 12:15:03 proxy racoon: DEBUG: 0x28517510: next=0x0 tnext=0x0
Dec 20 12:15:03 proxy racoon: DEBUG: proposal #1: 1 transform
Dec 20 12:15:03 proxy racoon: DEBUG: begin compare proposals.
Dec 20 12:15:03 proxy racoon: DEBUG: pair[1]: 0x28517510
Dec 20 12:15:03 proxy racoon: DEBUG: 0x28517510: next=0x0 tnext=0x0
Dec 20 12:15:03 proxy racoon: DEBUG: prop#=1 prot-id=ESP spi-size=4 #trns=1 trns#=1 trns-id=NULL
Dec 20 12:15:03 proxy racoon: DEBUG: type=Encryption Mode, flag=0x8000, lorv=UDP-Transport
Dec 20 12:15:03 proxy racoon: DEBUG: type=Authentication Algorithm, flag=0x8000, lorv=hmac-sha
Dec 20 12:15:03 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=seconds
Dec 20 12:15:03 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:03 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=kilobytes
Dec 20 12:15:03 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:03 proxy racoon: DEBUG: peer's single bundle:
Dec 20 12:15:03 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=f591bc4d spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:15:03 proxy racoon: DEBUG: (trns_id=NULL encklen=0 authtype=hmac-sha)
Dec 20 12:15:03 proxy racoon: DEBUG: my single bundle:
Dec 20 12:15:03 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:15:03 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-md5)
Dec 20 12:15:03 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-sha)
Dec 20 12:15:03 proxy racoon: INFO: Adjusting my encmode UDP-Transport->Transport
Dec 20 12:15:03 proxy racoon: INFO: Adjusting peer's encmode UDP-Transport(4)->Transport(2)
Dec 20 12:15:03 proxy racoon: WARNING: trns_id mismatched: my:3DES peer:NULL
Dec 20 12:15:03 proxy racoon: WARNING: trns_id mismatched: my:3DES peer:NULL
Dec 20 12:15:03 proxy racoon: ERROR: not matched
Dec 20 12:15:03 proxy racoon: ERROR: no suitable policy found.
Dec 20 12:15:03 proxy racoon: [YYY.YYY.YYY.YYY] ERROR: no proposal chosen.
Dec 20 12:15:03 proxy racoon: [YYY.YYY.YYY.YYY] ERROR: failed to pre-process ph2 packet (side: 1, status: 1).
Dec 20 12:15:03 proxy racoon: DEBUG: compute IV for phase2
Dec 20 12:15:03 proxy racoon: DEBUG: phase1 last IV:
Dec 20 12:15:03 proxy racoon: DEBUG: 9e88afc8 f154547f afbdf0ea
Dec 20 12:15:03 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:03 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:03 proxy racoon: DEBUG: phase2 IV computed:
Dec 20 12:15:03 proxy racoon: DEBUG: 9d16d8a4 8953d9a2
Dec 20 12:15:03 proxy racoon: DEBUG: HASH with:
Dec 20 12:15:03 proxy racoon: DEBUG: afbdf0ea 0000000c 00000001 0100000e
Dec 20 12:15:03 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:15:03 proxy racoon: DEBUG: HASH computed:
Dec 20 12:15:03 proxy racoon: DEBUG: ff14ced4 c55ea53b b3589559 bf32dd98 0a3966d3
Dec 20 12:15:03 proxy racoon: DEBUG: begin encryption.
Dec 20 12:15:03 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:03 proxy racoon: DEBUG: pad length = 4
Dec 20 12:15:03 proxy racoon: DEBUG: 0b000018 ff14ced4 c55ea53b b3589559 bf32dd98 0a3966d3 0000000c 00000001 0100000e 00000004
Dec 20 12:15:03 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:03 proxy racoon: DEBUG: with key:
Dec 20 12:15:03 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:15:03 proxy racoon: DEBUG: encrypted payload by IV:
Dec 20 12:15:03 proxy racoon: DEBUG: 9d16d8a4 8953d9a2
Dec 20 12:15:03 proxy racoon: DEBUG: save IV for next:
Dec 20 12:15:03 proxy racoon: DEBUG: 89428d06 73483791
Dec 20 12:15:03 proxy racoon: DEBUG: encrypted.
Dec 20 12:15:03 proxy racoon: DEBUG: Adding NON-ESP marker
Dec 20 12:15:03 proxy racoon: DEBUG: 72 bytes from XXX.XXX.XXX.XXX[4500] to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:03 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:03 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:03 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:03 proxy racoon: DEBUG: 1 times of 72 bytes message will be sent to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:03 proxy racoon: DEBUG: 00000000 fa6975cc b8108e36 2ec90ca9 82855fc3 08100501 afbdf0ea 00000044 38a0b775 2ba1187e 4500901a b7daa29f 603c7ff4 2b5a7d4d 93f13ea7 ae3f2c17 89428d06 73483791
Dec 20 12:15:03 proxy racoon: DEBUG: sendto Information notify.
Dec 20 12:15:03 proxy racoon: DEBUG: IV freed
Dec 20 12:15:03 proxy racoon: DEBUG: sub:0xbfbfe37c: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:03 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:03 proxy racoon: DEBUG: sub:0xbfbfe37c: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:03 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:15:03 proxy racoon: DEBUG: sub:0xbfbfe37c: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:03 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:03 proxy racoon: DEBUG: sub:0xbfbfe37c: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:03 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:15:03 proxy racoon: DEBUG: IV freed
Dec 20 12:15:12 proxy racoon: DEBUG: KA: XXX.XXX.XXX.XXX[4500]->YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:12 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:12 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:12 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:12 proxy racoon: DEBUG: 1 times of 1 bytes message will be sent to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:12 proxy racoon: DEBUG: ff
Dec 20 12:15:19 proxy racoon: DEBUG: ===
Dec 20 12:15:19 proxy racoon: DEBUG: 220 bytes message received from YYY.YYY.YYY.YYY[4500] to XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:19 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 08102001 00000001 000000dc f845f1d9 6a295096 0aa7c111 178fd0e8 c7bb991a 73a31f4c dca1e0e7 537170e9 5e8ea52c ab10c942 4469f83d c7a03df0 50925b26 927ff53a b86011b8 ac0f3f35 8907163e f26bb738 6769158b 55359b6c 740026bb afa93173 58b4c3d5 4701e50b 12b07a18 c8840b4c cb486257 93ed30f3 b5a3eb57 b6a47414 c6c28f49 bd8d7441 4be4f1af 7923e9d9 a923a4a9 df2cf431 bcdc0c75 e45dccbb d9aecc0c 31ec2df0 996a3e25 1ec0fa25 a3d7e48c 3b7f94e4 96df481d 209eb356 350e8b12 c14b0199
Dec 20 12:15:19 proxy racoon: DEBUG: compute IV for phase2
Dec 20 12:15:19 proxy racoon: DEBUG: phase1 last IV:
Dec 20 12:15:19 proxy racoon: DEBUG: 9e88afc8 f154547f 00000001
Dec 20 12:15:19 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:19 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:19 proxy racoon: DEBUG: phase2 IV computed:
Dec 20 12:15:19 proxy racoon: DEBUG: cb9f45f5 0af2a3a4
Dec 20 12:15:19 proxy racoon: DEBUG: ===
Dec 20 12:15:19 proxy racoon: INFO: respond new phase 2 negotiation: XXX.XXX.XXX.XXX[4500]<=>YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:19 proxy racoon: DEBUG: begin decryption.
Dec 20 12:15:19 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:19 proxy racoon: DEBUG: IV was saved for next processing:
Dec 20 12:15:19 proxy racoon: DEBUG: 350e8b12 c14b0199
Dec 20 12:15:19 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:19 proxy racoon: DEBUG: with key:
Dec 20 12:15:19 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:15:19 proxy racoon: DEBUG: decrypted payload by IV:
Dec 20 12:15:19 proxy racoon: DEBUG: cb9f45f5 0af2a3a4
Dec 20 12:15:19 proxy racoon: DEBUG: decrypted payload, but not trimed.
Dec 20 12:15:19 proxy racoon: DEBUG: 01000018 e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84 00000000
Dec 20 12:15:19 proxy racoon: DEBUG: padding len=0
Dec 20 12:15:19 proxy racoon: DEBUG: skip to trim padding.
Dec 20 12:15:19 proxy racoon: DEBUG: decrypted.
Dec 20 12:15:19 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 08102001 00000001 000000dc 01000018 e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84 00000000
Dec 20 12:15:19 proxy racoon: DEBUG: begin.
Dec 20 12:15:19 proxy racoon: DEBUG: seen nptype=8(hash)
Dec 20 12:15:19 proxy racoon: DEBUG: seen nptype=1(sa)
Dec 20 12:15:19 proxy racoon: DEBUG: seen nptype=10(nonce)
Dec 20 12:15:19 proxy racoon: DEBUG: seen nptype=5(id)
Dec 20 12:15:19 proxy racoon: DEBUG: seen nptype=5(id)
Dec 20 12:15:19 proxy racoon: DEBUG: seen nptype=21(nat-oa)
Dec 20 12:15:19 proxy racoon: DEBUG: seen nptype=21(nat-oa)
Dec 20 12:15:19 proxy racoon: DEBUG: succeed.
Dec 20 12:15:19 proxy racoon: DEBUG: received IDci2:
Dec 20 12:15:19 proxy racoon: DEBUG: 011106a5 c0a80182
Dec 20 12:15:19 proxy racoon: DEBUG: received IDcr2:
Dec 20 12:15:19 proxy racoon: DEBUG: 011106a5 d5a08b84
Dec 20 12:15:19 proxy racoon: DEBUG: HASH(1) validate:
Dec 20 12:15:19 proxy racoon: DEBUG: e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b
Dec 20 12:15:19 proxy racoon: DEBUG: HASH with:
Dec 20 12:15:19 proxy racoon: DEBUG: 00000001 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84
Dec 20 12:15:19 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:15:19 proxy racoon: DEBUG: HASH computed:
Dec 20 12:15:19 proxy racoon: DEBUG: e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b
Dec 20 12:15:19 proxy racoon: DEBUG: getsainfo params: loc='XXX.XXX.XXX.XXX' rmt='192.168.1.130' peer='192.168.1.130' client='YYY.YYY.YYY.YYY' id=0
Dec 20 12:15:19 proxy racoon: DEBUG: evaluating sainfo: loc='ANONYMOUS', rmt='ANONYMOUS', peer='ANY', id=0
Dec 20 12:15:19 proxy racoon: DEBUG: check and compare ids : values matched (ANONYMOUS)
Dec 20 12:15:19 proxy racoon: DEBUG: check and compare ids : values matched (ANONYMOUS)
Dec 20 12:15:19 proxy racoon: DEBUG: selected sainfo: loc='ANONYMOUS', rmt='ANONYMOUS', peer='ANY', id=0
Dec 20 12:15:19 proxy racoon: DEBUG: Either family (2 - 2), types (1 - 1) of ID from initiator differ or matching sainfo has no id_i defined for the peer. Not filling iph2->sa_src and iph2->sa_dst.
Dec 20 12:15:19 proxy racoon: DEBUG: get src address from ID payload 192.168.1.130[1701] prefixlen=32 ul_proto=17
Dec 20 12:15:19 proxy racoon: DEBUG: get dst address from ID payload XXX.XXX.XXX.XXX[1701] prefixlen=32 ul_proto=17
Dec 20 12:15:19 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:19 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:19 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:19 proxy racoon: DEBUG: db: 0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:19 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /0: 0.0.0.0[1701]
Dec 20 12:15:19 proxy racoon: DEBUG: 0x28547148 masked with /0: 0.0.0.0[0]
Dec 20 12:15:19 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:15:19 proxy racoon: DEBUG: 0x28547148 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:15:19 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:19 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:15:19 proxy racoon: INFO: Update the generated policy : 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:19 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:19 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:19 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:19 proxy racoon: DEBUG: db: 0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:19 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:19 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:15:19 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:19 proxy racoon: DEBUG: db: 0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:15:19 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:15:19 proxy racoon: DEBUG: 0x285473c8 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:15:19 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /0: 0.0.0.0[1701]
Dec 20 12:15:19 proxy racoon: DEBUG: 0x285473c8 masked with /0: 0.0.0.0[0]
Dec 20 12:15:19 proxy racoon: DEBUG: suitable SP found:XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:19 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:15:19 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-md5)
Dec 20 12:15:19 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-sha)
Dec 20 12:15:19 proxy racoon: DEBUG: total SA len=60
Dec 20 12:15:19 proxy racoon: DEBUG: 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090
Dec 20 12:15:19 proxy racoon: DEBUG: begin.
Dec 20 12:15:19 proxy racoon: DEBUG: seen nptype=2(prop)
Dec 20 12:15:19 proxy racoon: DEBUG: succeed.
Dec 20 12:15:19 proxy racoon: DEBUG: proposal #1 len=52
Dec 20 12:15:19 proxy racoon: DEBUG: begin.
Dec 20 12:15:19 proxy racoon: DEBUG: seen nptype=3(trns)
Dec 20 12:15:19 proxy racoon: DEBUG: succeed.
Dec 20 12:15:19 proxy racoon: DEBUG: transform #1 len=40
Dec 20 12:15:19 proxy racoon: DEBUG: type=Encryption Mode, flag=0x8000, lorv=UDP-Transport
Dec 20 12:15:19 proxy racoon: DEBUG: UDP encapsulation requested
Dec 20 12:15:19 proxy racoon: DEBUG: type=Authentication Algorithm, flag=0x8000, lorv=hmac-sha
Dec 20 12:15:19 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=seconds
Dec 20 12:15:19 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:19 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=kilobytes
Dec 20 12:15:19 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:19 proxy racoon: DEBUG: pair 1:
Dec 20 12:15:19 proxy racoon: DEBUG: 0x28517510: next=0x0 tnext=0x0
Dec 20 12:15:19 proxy racoon: DEBUG: proposal #1: 1 transform
Dec 20 12:15:19 proxy racoon: DEBUG: begin compare proposals.
Dec 20 12:15:19 proxy racoon: DEBUG: pair[1]: 0x28517510
Dec 20 12:15:19 proxy racoon: DEBUG: 0x28517510: next=0x0 tnext=0x0
Dec 20 12:15:19 proxy racoon: DEBUG: prop#=1 prot-id=ESP spi-size=4 #trns=1 trns#=1 trns-id=NULL
Dec 20 12:15:19 proxy racoon: DEBUG: type=Encryption Mode, flag=0x8000, lorv=UDP-Transport
Dec 20 12:15:19 proxy racoon: DEBUG: type=Authentication Algorithm, flag=0x8000, lorv=hmac-sha
Dec 20 12:15:19 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=seconds
Dec 20 12:15:19 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:19 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=kilobytes
Dec 20 12:15:19 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:19 proxy racoon: DEBUG: peer's single bundle:
Dec 20 12:15:19 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=f591bc4d spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:15:19 proxy racoon: DEBUG: (trns_id=NULL encklen=0 authtype=hmac-sha)
Dec 20 12:15:19 proxy racoon: DEBUG: my single bundle:
Dec 20 12:15:19 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:15:19 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-md5)
Dec 20 12:15:19 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-sha)
Dec 20 12:15:19 proxy racoon: INFO: Adjusting my encmode UDP-Transport->Transport
Dec 20 12:15:19 proxy racoon: INFO: Adjusting peer's encmode UDP-Transport(4)->Transport(2)
Dec 20 12:15:19 proxy racoon: WARNING: trns_id mismatched: my:3DES peer:NULL
Dec 20 12:15:19 proxy racoon: WARNING: trns_id mismatched: my:3DES peer:NULL
Dec 20 12:15:19 proxy racoon: ERROR: not matched
Dec 20 12:15:19 proxy racoon: ERROR: no suitable policy found.
Dec 20 12:15:19 proxy racoon: [YYY.YYY.YYY.YYY] ERROR: no proposal chosen.
Dec 20 12:15:19 proxy racoon: [YYY.YYY.YYY.YYY] ERROR: failed to pre-process ph2 packet (side: 1, status: 1).
Dec 20 12:15:19 proxy racoon: DEBUG: compute IV for phase2
Dec 20 12:15:19 proxy racoon: DEBUG: phase1 last IV:
Dec 20 12:15:19 proxy racoon: DEBUG: 9e88afc8 f154547f b36cdb40
Dec 20 12:15:19 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:19 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:19 proxy racoon: DEBUG: phase2 IV computed:
Dec 20 12:15:19 proxy racoon: DEBUG: 557cc9aa 1afd948e
Dec 20 12:15:19 proxy racoon: DEBUG: HASH with:
Dec 20 12:15:19 proxy racoon: DEBUG: b36cdb40 0000000c 00000001 0100000e
Dec 20 12:15:19 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:15:19 proxy racoon: DEBUG: HASH computed:
Dec 20 12:15:19 proxy racoon: DEBUG: 603730ab 5afa4627 bda5f581 b2708b90 1ab8da51
Dec 20 12:15:19 proxy racoon: DEBUG: begin encryption.
Dec 20 12:15:19 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:19 proxy racoon: DEBUG: pad length = 4
Dec 20 12:15:19 proxy racoon: DEBUG: 0b000018 603730ab 5afa4627 bda5f581 b2708b90 1ab8da51 0000000c 00000001 0100000e 00000004
Dec 20 12:15:19 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:19 proxy racoon: DEBUG: with key:
Dec 20 12:15:19 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:15:19 proxy racoon: DEBUG: encrypted payload by IV:
Dec 20 12:15:19 proxy racoon: DEBUG: 557cc9aa 1afd948e
Dec 20 12:15:19 proxy racoon: DEBUG: save IV for next:
Dec 20 12:15:19 proxy racoon: DEBUG: 9a1191d7 006ad142
Dec 20 12:15:19 proxy racoon: DEBUG: encrypted.
Dec 20 12:15:19 proxy racoon: DEBUG: Adding NON-ESP marker
Dec 20 12:15:19 proxy racoon: DEBUG: 72 bytes from XXX.XXX.XXX.XXX[4500] to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:19 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:19 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:19 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:19 proxy racoon: DEBUG: 1 times of 72 bytes message will be sent to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:19 proxy racoon: DEBUG: 00000000 fa6975cc b8108e36 2ec90ca9 82855fc3 08100501 b36cdb40 00000044 41d293a7 8249c26f 6fc6666d 6ca7e2e5 5b11fae6 3f3517e1 f63b4ab0 942dd011 9a1191d7 006ad142
Dec 20 12:15:19 proxy racoon: DEBUG: sendto Information notify.
Dec 20 12:15:19 proxy racoon: DEBUG: IV freed
Dec 20 12:15:19 proxy racoon: DEBUG: sub:0xbfbfe37c: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:19 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:19 proxy racoon: DEBUG: sub:0xbfbfe37c: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:19 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:15:19 proxy racoon: DEBUG: sub:0xbfbfe37c: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:19 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:19 proxy racoon: DEBUG: sub:0xbfbfe37c: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:19 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:15:19 proxy racoon: DEBUG: IV freed
Dec 20 12:15:22 proxy racoon: DEBUG: KA: XXX.XXX.XXX.XXX[4500]->YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:22 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:22 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:22 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:22 proxy racoon: DEBUG: 1 times of 1 bytes message will be sent to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:22 proxy racoon: DEBUG: ff
Dec 20 12:15:32 proxy racoon: DEBUG: KA: XXX.XXX.XXX.XXX[4500]->YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:32 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:32 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:32 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:32 proxy racoon: DEBUG: 1 times of 1 bytes message will be sent to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:32 proxy racoon: DEBUG: ff
Dec 20 12:15:35 proxy racoon: DEBUG: ===
Dec 20 12:15:35 proxy racoon: DEBUG: 220 bytes message received from YYY.YYY.YYY.YYY[4500] to XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:35 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 08102001 00000001 000000dc f845f1d9 6a295096 0aa7c111 178fd0e8 c7bb991a 73a31f4c dca1e0e7 537170e9 5e8ea52c ab10c942 4469f83d c7a03df0 50925b26 927ff53a b86011b8 ac0f3f35 8907163e f26bb738 6769158b 55359b6c 740026bb afa93173 58b4c3d5 4701e50b 12b07a18 c8840b4c cb486257 93ed30f3 b5a3eb57 b6a47414 c6c28f49 bd8d7441 4be4f1af 7923e9d9 a923a4a9 df2cf431 bcdc0c75 e45dccbb d9aecc0c 31ec2df0 996a3e25 1ec0fa25 a3d7e48c 3b7f94e4 96df481d 209eb356 350e8b12 c14b0199
Dec 20 12:15:35 proxy racoon: DEBUG: compute IV for phase2
Dec 20 12:15:35 proxy racoon: DEBUG: phase1 last IV:
Dec 20 12:15:35 proxy racoon: DEBUG: 9e88afc8 f154547f 00000001
Dec 20 12:15:36 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:36 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:36 proxy racoon: DEBUG: phase2 IV computed:
Dec 20 12:15:36 proxy racoon: DEBUG: cb9f45f5 0af2a3a4
Dec 20 12:15:36 proxy racoon: DEBUG: ===
Dec 20 12:15:36 proxy racoon: INFO: respond new phase 2 negotiation: XXX.XXX.XXX.XXX[4500]<=>YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:36 proxy racoon: DEBUG: begin decryption.
Dec 20 12:15:36 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:36 proxy racoon: DEBUG: IV was saved for next processing:
Dec 20 12:15:36 proxy racoon: DEBUG: 350e8b12 c14b0199
Dec 20 12:15:36 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:36 proxy racoon: DEBUG: with key:
Dec 20 12:15:36 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:15:36 proxy racoon: DEBUG: decrypted payload by IV:
Dec 20 12:15:36 proxy racoon: DEBUG: cb9f45f5 0af2a3a4
Dec 20 12:15:36 proxy racoon: DEBUG: decrypted payload, but not trimed.
Dec 20 12:15:36 proxy racoon: DEBUG: 01000018 e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84 00000000
Dec 20 12:15:36 proxy racoon: DEBUG: padding len=0
Dec 20 12:15:36 proxy racoon: DEBUG: skip to trim padding.
Dec 20 12:15:36 proxy racoon: DEBUG: decrypted.
Dec 20 12:15:36 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 08102001 00000001 000000dc 01000018 e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84 00000000
Dec 20 12:15:36 proxy racoon: DEBUG: begin.
Dec 20 12:15:36 proxy racoon: DEBUG: seen nptype=8(hash)
Dec 20 12:15:36 proxy racoon: DEBUG: seen nptype=1(sa)
Dec 20 12:15:36 proxy racoon: DEBUG: seen nptype=10(nonce)
Dec 20 12:15:36 proxy racoon: DEBUG: seen nptype=5(id)
Dec 20 12:15:36 proxy racoon: DEBUG: seen nptype=5(id)
Dec 20 12:15:36 proxy racoon: DEBUG: seen nptype=21(nat-oa)
Dec 20 12:15:36 proxy racoon: DEBUG: seen nptype=21(nat-oa)
Dec 20 12:15:36 proxy racoon: DEBUG: succeed.
Dec 20 12:15:36 proxy racoon: DEBUG: received IDci2:
Dec 20 12:15:36 proxy racoon: DEBUG: 011106a5 c0a80182
Dec 20 12:15:36 proxy racoon: DEBUG: received IDcr2:
Dec 20 12:15:36 proxy racoon: DEBUG: 011106a5 d5a08b84
Dec 20 12:15:36 proxy racoon: DEBUG: HASH(1) validate:
Dec 20 12:15:36 proxy racoon: DEBUG: e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b
Dec 20 12:15:36 proxy racoon: DEBUG: HASH with:
Dec 20 12:15:36 proxy racoon: DEBUG: 00000001 0a000040 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090 05000034 f8734eb1 cf2dfbb5 ec7e1ec7 c039ba83 a8e2e395 29436515 2101946c badb4cd1 ef32483a 96b9f8ec 503e3950 84c11db5 0500000c 011106a5 c0a80182 1500000c 011106a5 d5a08b84 1500000c 01000000 c0a80182 0000000c 01000000 d5a08b84
Dec 20 12:15:36 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:15:36 proxy racoon: DEBUG: HASH computed:
Dec 20 12:15:36 proxy racoon: DEBUG: e01f51db 8ee2ea30 624ac805 6b2fa347 dfedaf7b
Dec 20 12:15:36 proxy racoon: DEBUG: getsainfo params: loc='XXX.XXX.XXX.XXX' rmt='192.168.1.130' peer='192.168.1.130' client='YYY.YYY.YYY.YYY' id=0
Dec 20 12:15:36 proxy racoon: DEBUG: evaluating sainfo: loc='ANONYMOUS', rmt='ANONYMOUS', peer='ANY', id=0
Dec 20 12:15:36 proxy racoon: DEBUG: check and compare ids : values matched (ANONYMOUS)
Dec 20 12:15:36 proxy racoon: DEBUG: check and compare ids : values matched (ANONYMOUS)
Dec 20 12:15:36 proxy racoon: DEBUG: selected sainfo: loc='ANONYMOUS', rmt='ANONYMOUS', peer='ANY', id=0
Dec 20 12:15:36 proxy racoon: DEBUG: Either family (2 - 2), types (1 - 1) of ID from initiator differ or matching sainfo has no id_i defined for the peer. Not filling iph2->sa_src and iph2->sa_dst.
Dec 20 12:15:36 proxy racoon: DEBUG: get src address from ID payload 192.168.1.130[1701] prefixlen=32 ul_proto=17
Dec 20 12:15:36 proxy racoon: DEBUG: get dst address from ID payload XXX.XXX.XXX.XXX[1701] prefixlen=32 ul_proto=17
Dec 20 12:15:36 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:36 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:36 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:36 proxy racoon: DEBUG: db: 0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:36 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /0: 0.0.0.0[1701]
Dec 20 12:15:36 proxy racoon: DEBUG: 0x28547148 masked with /0: 0.0.0.0[0]
Dec 20 12:15:36 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:15:36 proxy racoon: DEBUG: 0x28547148 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:15:36 proxy racoon: DEBUG: sub:0xbfbfe2a4: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:36 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:15:36 proxy racoon: INFO: Update the generated policy : 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:36 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:36 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:36 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:36 proxy racoon: DEBUG: db: 0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:36 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:36 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:15:36 proxy racoon: DEBUG: sub:0xbfbfe2a4: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:36 proxy racoon: DEBUG: db: 0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:15:36 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:15:36 proxy racoon: DEBUG: 0x285473c8 masked with /32: XXX.XXX.XXX.XXX[1701]
Dec 20 12:15:36 proxy racoon: DEBUG: 0xbfbfe2a4 masked with /0: 0.0.0.0[1701]
Dec 20 12:15:36 proxy racoon: DEBUG: 0x285473c8 masked with /0: 0.0.0.0[0]
Dec 20 12:15:36 proxy racoon: DEBUG: suitable SP found:XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:36 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:15:36 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-md5)
Dec 20 12:15:36 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-sha)
Dec 20 12:15:36 proxy racoon: DEBUG: total SA len=60
Dec 20 12:15:36 proxy racoon: DEBUG: 00000001 00000001 00000034 01030401 f591bc4d 00000028 010b0000 80040004 80050002 80010001 00020004 00000e10 80010002 00020004 0003d090
Dec 20 12:15:36 proxy racoon: DEBUG: begin.
Dec 20 12:15:36 proxy racoon: DEBUG: seen nptype=2(prop)
Dec 20 12:15:36 proxy racoon: DEBUG: succeed.
Dec 20 12:15:36 proxy racoon: DEBUG: proposal #1 len=52
Dec 20 12:15:36 proxy racoon: DEBUG: begin.
Dec 20 12:15:36 proxy racoon: DEBUG: seen nptype=3(trns)
Dec 20 12:15:36 proxy racoon: DEBUG: succeed.
Dec 20 12:15:36 proxy racoon: DEBUG: transform #1 len=40
Dec 20 12:15:36 proxy racoon: DEBUG: type=Encryption Mode, flag=0x8000, lorv=UDP-Transport
Dec 20 12:15:36 proxy racoon: DEBUG: UDP encapsulation requested
Dec 20 12:15:36 proxy racoon: DEBUG: type=Authentication Algorithm, flag=0x8000, lorv=hmac-sha
Dec 20 12:15:36 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=seconds
Dec 20 12:15:36 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:36 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=kilobytes
Dec 20 12:15:36 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:36 proxy racoon: DEBUG: pair 1:
Dec 20 12:15:36 proxy racoon: DEBUG: 0x28517510: next=0x0 tnext=0x0
Dec 20 12:15:36 proxy racoon: DEBUG: proposal #1: 1 transform
Dec 20 12:15:36 proxy racoon: DEBUG: begin compare proposals.
Dec 20 12:15:36 proxy racoon: DEBUG: pair[1]: 0x28517510
Dec 20 12:15:36 proxy racoon: DEBUG: 0x28517510: next=0x0 tnext=0x0
Dec 20 12:15:36 proxy racoon: DEBUG: prop#=1 prot-id=ESP spi-size=4 #trns=1 trns#=1 trns-id=NULL
Dec 20 12:15:36 proxy racoon: DEBUG: type=Encryption Mode, flag=0x8000, lorv=UDP-Transport
Dec 20 12:15:36 proxy racoon: DEBUG: type=Authentication Algorithm, flag=0x8000, lorv=hmac-sha
Dec 20 12:15:36 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=seconds
Dec 20 12:15:36 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:36 proxy racoon: DEBUG: type=SA Life Type, flag=0x8000, lorv=kilobytes
Dec 20 12:15:36 proxy racoon: DEBUG: type=SA Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:36 proxy racoon: DEBUG: peer's single bundle:
Dec 20 12:15:36 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=f591bc4d spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:15:36 proxy racoon: DEBUG: (trns_id=NULL encklen=0 authtype=hmac-sha)
Dec 20 12:15:36 proxy racoon: DEBUG: my single bundle:
Dec 20 12:15:36 proxy racoon: DEBUG: (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=UDP-Transport reqid=0:0)
Dec 20 12:15:36 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-md5)
Dec 20 12:15:36 proxy racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-sha)
Dec 20 12:15:36 proxy racoon: INFO: Adjusting my encmode UDP-Transport->Transport
Dec 20 12:15:36 proxy racoon: INFO: Adjusting peer's encmode UDP-Transport(4)->Transport(2)
Dec 20 12:15:36 proxy racoon: WARNING: trns_id mismatched: my:3DES peer:NULL
Dec 20 12:15:36 proxy racoon: WARNING: trns_id mismatched: my:3DES peer:NULL
Dec 20 12:15:36 proxy racoon: ERROR: not matched
Dec 20 12:15:36 proxy racoon: ERROR: no suitable policy found.
Dec 20 12:15:36 proxy racoon: [YYY.YYY.YYY.YYY] ERROR: no proposal chosen.
Dec 20 12:15:36 proxy racoon: [YYY.YYY.YYY.YYY] ERROR: failed to pre-process ph2 packet (side: 1, status: 1).
Dec 20 12:15:36 proxy racoon: DEBUG: compute IV for phase2
Dec 20 12:15:36 proxy racoon: DEBUG: phase1 last IV:
Dec 20 12:15:36 proxy racoon: DEBUG: 9e88afc8 f154547f 995236ba
Dec 20 12:15:36 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:36 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:36 proxy racoon: DEBUG: phase2 IV computed:
Dec 20 12:15:36 proxy racoon: DEBUG: 95afd506 af97cd5e
Dec 20 12:15:36 proxy racoon: DEBUG: HASH with:
Dec 20 12:15:36 proxy racoon: DEBUG: 995236ba 0000000c 00000001 0100000e
Dec 20 12:15:36 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:15:36 proxy racoon: DEBUG: HASH computed:
Dec 20 12:15:36 proxy racoon: DEBUG: 6fa63aed 209e982d d17502f8 39e27550 1928f795
Dec 20 12:15:36 proxy racoon: DEBUG: begin encryption.
Dec 20 12:15:36 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:36 proxy racoon: DEBUG: pad length = 4
Dec 20 12:15:36 proxy racoon: DEBUG: 0b000018 6fa63aed 209e982d d17502f8 39e27550 1928f795 0000000c 00000001 0100000e 00000004
Dec 20 12:15:36 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:36 proxy racoon: DEBUG: with key:
Dec 20 12:15:36 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:15:36 proxy racoon: DEBUG: encrypted payload by IV:
Dec 20 12:15:36 proxy racoon: DEBUG: 95afd506 af97cd5e
Dec 20 12:15:36 proxy racoon: DEBUG: save IV for next:
Dec 20 12:15:36 proxy racoon: DEBUG: 53935f43 0edc9899
Dec 20 12:15:36 proxy racoon: DEBUG: encrypted.
Dec 20 12:15:36 proxy racoon: DEBUG: Adding NON-ESP marker
Dec 20 12:15:36 proxy racoon: DEBUG: 72 bytes from XXX.XXX.XXX.XXX[4500] to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:36 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:36 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:36 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:36 proxy racoon: DEBUG: 1 times of 72 bytes message will be sent to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:36 proxy racoon: DEBUG: 00000000 fa6975cc b8108e36 2ec90ca9 82855fc3 08100501 995236ba 00000044 56121cd0 6a6c47ac 89bb7fec d129ab5c 3da581d8 6e33f69e b7baa580 1814814b 53935f43 0edc9899
Dec 20 12:15:36 proxy racoon: DEBUG: sendto Information notify.
Dec 20 12:15:36 proxy racoon: DEBUG: IV freed
Dec 20 12:15:36 proxy racoon: DEBUG: sub:0xbfbfe37c: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:36 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:36 proxy racoon: DEBUG: sub:0xbfbfe37c: 192.168.1.130/32[1701] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:36 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:15:36 proxy racoon: DEBUG: sub:0xbfbfe37c: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:36 proxy racoon: DEBUG: db :0x28547148: 0.0.0.0/0[0] XXX.XXX.XXX.XXX/32[1701] proto=udp dir=in
Dec 20 12:15:36 proxy racoon: DEBUG: sub:0xbfbfe37c: XXX.XXX.XXX.XXX/32[1701] 192.168.1.130/32[1701] proto=udp dir=out
Dec 20 12:15:36 proxy racoon: DEBUG: db :0x285473c8: XXX.XXX.XXX.XXX/32[1701] 0.0.0.0/0[0] proto=udp dir=out
Dec 20 12:15:36 proxy racoon: DEBUG: IV freed
Dec 20 12:15:42 proxy racoon: DEBUG: KA: XXX.XXX.XXX.XXX[4500]->YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:42 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:42 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:42 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:42 proxy racoon: DEBUG: 1 times of 1 bytes message will be sent to YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:42 proxy racoon: DEBUG: ff
Dec 20 12:15:52 proxy racoon: DEBUG: ===
Dec 20 12:15:52 proxy racoon: DEBUG: 84 bytes message received from YYY.YYY.YYY.YYY[4500] to XXX.XXX.XXX.XXX[4500]
Dec 20 12:15:52 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 08100501 adde450f 00000054 151480ab f9d1eef3 884b2ae4 ed5c350e d74c3d80 6b6e13ca 0a8d41d8 200e2d0a b2cdde08 74c85ea8 75454737 a7ee9504 130ce206 3f5340b0
Dec 20 12:15:52 proxy racoon: DEBUG: receive Information.
Dec 20 12:15:52 proxy racoon: DEBUG: compute IV for phase2
Dec 20 12:15:52 proxy racoon: DEBUG: phase1 last IV:
Dec 20 12:15:52 proxy racoon: DEBUG: 9e88afc8 f154547f adde450f
Dec 20 12:15:52 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:52 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:52 proxy racoon: DEBUG: phase2 IV computed:
Dec 20 12:15:52 proxy racoon: DEBUG: c07b5268 4b37ecbc
Dec 20 12:15:52 proxy racoon: DEBUG: begin decryption.
Dec 20 12:15:52 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:52 proxy racoon: DEBUG: IV was saved for next processing:
Dec 20 12:15:52 proxy racoon: DEBUG: 130ce206 3f5340b0
Dec 20 12:15:52 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:52 proxy racoon: DEBUG: with key:
Dec 20 12:15:52 proxy racoon: DEBUG: 6d1961b4 215951d7 01c1eddc 21cf45fa 59094492 66febc63
Dec 20 12:15:52 proxy racoon: DEBUG: decrypted payload by IV:
Dec 20 12:15:52 proxy racoon: DEBUG: c07b5268 4b37ecbc
Dec 20 12:15:52 proxy racoon: DEBUG: decrypted payload, but not trimed.
Dec 20 12:15:52 proxy racoon: DEBUG: 0c000018 a20476c2 31aa0117 d415d4c5 8a6fae8a 5da93275 0000001c 00000001 01100001 fa6975cc b8108e36 2ec90ca9 82855fc3 00000000
Dec 20 12:15:52 proxy racoon: DEBUG: padding len=0
Dec 20 12:15:52 proxy racoon: DEBUG: skip to trim padding.
Dec 20 12:15:52 proxy racoon: DEBUG: decrypted.
Dec 20 12:15:52 proxy racoon: DEBUG: fa6975cc b8108e36 2ec90ca9 82855fc3 08100501 adde450f 00000054 0c000018 a20476c2 31aa0117 d415d4c5 8a6fae8a 5da93275 0000001c 00000001 01100001 fa6975cc b8108e36 2ec90ca9 82855fc3 00000000
Dec 20 12:15:52 proxy racoon: DEBUG: IV freed
Dec 20 12:15:52 proxy racoon: DEBUG: HASH with:
Dec 20 12:15:52 proxy racoon: DEBUG: adde450f 0000001c 00000001 01100001 fa6975cc b8108e36 2ec90ca9 82855fc3
Dec 20 12:15:52 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:15:52 proxy racoon: DEBUG: HASH computed:
Dec 20 12:15:52 proxy racoon: DEBUG: a20476c2 31aa0117 d415d4c5 8a6fae8a 5da93275
Dec 20 12:15:52 proxy racoon: DEBUG: hash validated.
Dec 20 12:15:52 proxy racoon: DEBUG: begin.
Dec 20 12:15:52 proxy racoon: DEBUG: seen nptype=8(hash)
Dec 20 12:15:52 proxy racoon: DEBUG: seen nptype=12(delete)
Dec 20 12:15:52 proxy racoon: DEBUG: succeed.
Dec 20 12:15:52 proxy racoon: [YYY.YYY.YYY.YYY] DEBUG: delete payload for protocol ISAKMP
Dec 20 12:15:52 proxy racoon: INFO: ISAKMP-SA expired XXX.XXX.XXX.XXX[4500]-YYY.YYY.YYY.YYY[4500] spi:fa6975ccb8108e36:2ec90ca982855fc3
Dec 20 12:15:52 proxy racoon: INFO: ISAKMP-SA deleted XXX.XXX.XXX.XXX[4500]-YYY.YYY.YYY.YYY[4500] spi:fa6975ccb8108e36:2ec90ca982855fc3
Dec 20 12:15:52 proxy racoon: INFO: KA remove: XXX.XXX.XXX.XXX[4500]->YYY.YYY.YYY.YYY[4500]
Dec 20 12:15:52 proxy racoon: DEBUG: KA tree dump: XXX.XXX.XXX.XXX[4500]->YYY.YYY.YYY.YYY[4500] (in_use=1)
Dec 20 12:15:52 proxy racoon: DEBUG: KA removing this one...
Dec 20 12:15:52 proxy racoon: DEBUG: IV freed
Dec 20 12:15:52 proxy racoon: DEBUG: purged SAs.
Dec 20 12:15:52 proxy racoon: DEBUG: ===
Dec 20 12:15:52 proxy racoon: DEBUG: 384 bytes message received from YYY.YYY.YYY.YYY[500] to XXX.XXX.XXX.XXX[500]
Dec 20 12:15:52 proxy racoon: DEBUG: 1c7e1dd1 4ffc68c8 00000000 00000000 01100200 00000000 00000180 0d0000d4 00000001 00000001 000000c8 01010005 03000028 01010000 80010007 800e0100 80020002 80040014 80030001 800b0001 000c0004 00007080 03000028 02010000 80010007 800e0080 80020002 80040013 80030001 800b0001 000c0004 00007080 03000028 03010000 80010007 800e0100 80020002 8004000e 80030001 800b0001 000c0004 00007080 03000024 04010000 80010005 80020002 8004000e 80030001 800b0001 000c0004 00007080 00000024 05010000 80010005 80020002 80040002 80030001 800b0001 000c0004 00007080 0d000018 1e2b5169 05991c7d 7c96fcbf b587e461 00000008 0d000014 4a131c81 07035845 5c5728f2 0e95452f 0d000014 90cb8091 3ebb696e 086381b5 ec427b1f 0d000014 4048b7d5 6ebce885 25e7de7f 00d6c2d3 0d000014 fb1de3cd f341b7ea 16b7e5be 0855f120 0d000014 26244d38 eddb61b3 172a36e3 d0cfb819 00000014 e3a5966a 76379fe7 07228231 e5ce8652
Dec 20 12:15:52 proxy racoon: DEBUG: ===
Dec 20 12:15:52 proxy racoon: INFO: respond new phase 1 negotiation: XXX.XXX.XXX.XXX[500]<=>YYY.YYY.YYY.YYY[500]
Dec 20 12:15:52 proxy racoon: INFO: begin Identity Protection mode.
Dec 20 12:15:52 proxy racoon: DEBUG: begin.
Dec 20 12:15:52 proxy racoon: DEBUG: seen nptype=1(sa)
Dec 20 12:15:52 proxy racoon: DEBUG: seen nptype=13(vid)
Dec 20 12:15:52 proxy last message repeated 6 times
Dec 20 12:15:52 proxy racoon: DEBUG: succeed.
Dec 20 12:15:52 proxy racoon: INFO: received broken Microsoft ID: MS NT5 ISAKMPOAKLEY
Dec 20 12:15:52 proxy racoon: INFO: received Vendor ID: RFC 3947
Dec 20 12:15:52 proxy racoon: INFO: received Vendor ID: draft-ietf-ipsec-nat-t-ike-02
Dec 20 12:15:52 proxy racoon: INFO: received Vendor ID: FRAGMENTATION
Dec 20 12:15:52 proxy racoon: DEBUG: received unknown Vendor ID
Dec 20 12:15:52 proxy racoon: DEBUG: fb1de3cd f341b7ea 16b7e5be 0855f120
Dec 20 12:15:52 proxy racoon: DEBUG: received unknown Vendor ID
Dec 20 12:15:52 proxy racoon: DEBUG: 26244d38 eddb61b3 172a36e3 d0cfb819
Dec 20 12:15:52 proxy racoon: DEBUG: received unknown Vendor ID
Dec 20 12:15:52 proxy racoon: DEBUG: e3a5966a 76379fe7 07228231 e5ce8652
Dec 20 12:15:52 proxy racoon: [YYY.YYY.YYY.YYY] INFO: Selected NAT-T version: RFC 3947
Dec 20 12:15:52 proxy racoon: DEBUG: total SA len=208
Dec 20 12:15:52 proxy racoon: DEBUG: 00000001 00000001 000000c8 01010005 03000028 01010000 80010007 800e0100 80020002 80040014 80030001 800b0001 000c0004 00007080 03000028 02010000 80010007 800e0080 80020002 80040013 80030001 800b0001 000c0004 00007080 03000028 03010000 80010007 800e0100 80020002 8004000e 80030001 800b0001 000c0004 00007080 03000024 04010000 80010005 80020002 8004000e 80030001 800b0001 000c0004 00007080 00000024 05010000 80010005 80020002 80040002 80030001 800b0001 000c0004 00007080
Dec 20 12:15:52 proxy racoon: DEBUG: begin.
Dec 20 12:15:52 proxy racoon: DEBUG: seen nptype=2(prop)
Dec 20 12:15:52 proxy racoon: DEBUG: succeed.
Dec 20 12:15:52 proxy racoon: DEBUG: proposal #1 len=200
Dec 20 12:15:52 proxy racoon: DEBUG: begin.
Dec 20 12:15:52 proxy racoon: DEBUG: seen nptype=3(trns)
Dec 20 12:15:52 proxy last message repeated 4 times
Dec 20 12:15:52 proxy racoon: DEBUG: succeed.
Dec 20 12:15:52 proxy racoon: DEBUG: transform #1 len=40
Dec 20 12:15:52 proxy racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=AES-CBC
Dec 20 12:15:52 proxy racoon: DEBUG: encryption(aes)
Dec 20 12:15:52 proxy racoon: DEBUG: type=Key Length, flag=0x8000, lorv=256
Dec 20 12:15:52 proxy racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 20 12:15:52 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:52 proxy racoon: DEBUG: type=Group Description, flag=0x8000, lorv=20
Dec 20 12:15:52 proxy racoon: ERROR: invalid DH group 20.
Dec 20 12:15:52 proxy racoon: DEBUG: transform #2 len=40
Dec 20 12:15:52 proxy racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=AES-CBC
Dec 20 12:15:52 proxy racoon: DEBUG: encryption(aes)
Dec 20 12:15:52 proxy racoon: DEBUG: type=Key Length, flag=0x8000, lorv=128
Dec 20 12:15:52 proxy racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 20 12:15:52 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:52 proxy racoon: DEBUG: type=Group Description, flag=0x8000, lorv=19
Dec 20 12:15:52 proxy racoon: ERROR: invalid DH group 19.
Dec 20 12:15:52 proxy racoon: DEBUG: transform #3 len=40
Dec 20 12:15:52 proxy racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=AES-CBC
Dec 20 12:15:52 proxy racoon: DEBUG: encryption(aes)
Dec 20 12:15:52 proxy racoon: DEBUG: type=Key Length, flag=0x8000, lorv=256
Dec 20 12:15:52 proxy racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 20 12:15:52 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:52 proxy racoon: DEBUG: type=Group Description, flag=0x8000, lorv=2048-bit MODP group
Dec 20 12:15:52 proxy racoon: DEBUG: hmac(modp2048)
Dec 20 12:15:52 proxy racoon: DEBUG: type=Authentication Method, flag=0x8000, lorv=pre-shared key
Dec 20 12:15:52 proxy racoon: DEBUG: type=Life Type, flag=0x8000, lorv=seconds
Dec 20 12:15:52 proxy racoon: DEBUG: type=Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:52 proxy racoon: DEBUG: transform #4 len=36
Dec 20 12:15:52 proxy racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC
Dec 20 12:15:52 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:52 proxy racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 20 12:15:52 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:52 proxy racoon: DEBUG: type=Group Description, flag=0x8000, lorv=2048-bit MODP group
Dec 20 12:15:52 proxy racoon: DEBUG: hmac(modp2048)
Dec 20 12:15:52 proxy racoon: DEBUG: type=Authentication Method, flag=0x8000, lorv=pre-shared key
Dec 20 12:15:52 proxy racoon: DEBUG: type=Life Type, flag=0x8000, lorv=seconds
Dec 20 12:15:52 proxy racoon: DEBUG: type=Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:52 proxy racoon: DEBUG: transform #5 len=36
Dec 20 12:15:52 proxy racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC
Dec 20 12:15:52 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:52 proxy racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 20 12:15:52 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:52 proxy racoon: DEBUG: type=Group Description, flag=0x8000, lorv=1024-bit MODP group
Dec 20 12:15:52 proxy racoon: DEBUG: hmac(modp1024)
Dec 20 12:15:52 proxy racoon: DEBUG: type=Authentication Method, flag=0x8000, lorv=pre-shared key
Dec 20 12:15:52 proxy racoon: DEBUG: type=Life Type, flag=0x8000, lorv=seconds
Dec 20 12:15:52 proxy racoon: DEBUG: type=Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:52 proxy racoon: DEBUG: pair 1:
Dec 20 12:15:52 proxy racoon: DEBUG: 0x285173e0: next=0x0 tnext=0x28517460
Dec 20 12:15:52 proxy racoon: DEBUG: 0x28517460: next=0x0 tnext=0x28517470
Dec 20 12:15:52 proxy racoon: DEBUG: 0x28517470: next=0x0 tnext=0x0
Dec 20 12:15:52 proxy racoon: DEBUG: proposal #1: 3 transform
Dec 20 12:15:52 proxy racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=AES-CBC
Dec 20 12:15:52 proxy racoon: DEBUG: type=Key Length, flag=0x8000, lorv=256
Dec 20 12:15:52 proxy racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 20 12:15:52 proxy racoon: DEBUG: type=Group Description, flag=0x8000, lorv=2048-bit MODP group
Dec 20 12:15:52 proxy racoon: DEBUG: type=Authentication Method, flag=0x8000, lorv=pre-shared key
Dec 20 12:15:52 proxy racoon: DEBUG: type=Life Type, flag=0x8000, lorv=seconds
Dec 20 12:15:52 proxy racoon: DEBUG: type=Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:52 proxy racoon: DEBUG: prop#=1, prot-id=ISAKMP, spi-size=0, #trns=5
Dec 20 12:15:52 proxy racoon: DEBUG: trns#=3, trns-id=IKE
Dec 20 12:15:52 proxy racoon: DEBUG: lifetime = 28800
Dec 20 12:15:52 proxy racoon: DEBUG: lifebyte = 0
Dec 20 12:15:52 proxy racoon: DEBUG: enctype = AES-CBC
Dec 20 12:15:52 proxy racoon: DEBUG: encklen = 256
Dec 20 12:15:52 proxy racoon: DEBUG: hashtype = SHA
Dec 20 12:15:52 proxy racoon: DEBUG: authmethod = pre-shared key
Dec 20 12:15:52 proxy racoon: DEBUG: dh_group = 2048-bit MODP group
Dec 20 12:15:52 proxy racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC
Dec 20 12:15:52 proxy racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 20 12:15:52 proxy racoon: DEBUG: type=Group Description, flag=0x8000, lorv=2048-bit MODP group
Dec 20 12:15:52 proxy racoon: DEBUG: type=Authentication Method, flag=0x8000, lorv=pre-shared key
Dec 20 12:15:52 proxy racoon: DEBUG: type=Life Type, flag=0x8000, lorv=seconds
Dec 20 12:15:52 proxy racoon: DEBUG: type=Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:52 proxy racoon: DEBUG: prop#=1, prot-id=ISAKMP, spi-size=0, #trns=5
Dec 20 12:15:52 proxy racoon: DEBUG: trns#=4, trns-id=IKE
Dec 20 12:15:52 proxy racoon: DEBUG: lifetime = 28800
Dec 20 12:15:52 proxy racoon: DEBUG: lifebyte = 0
Dec 20 12:15:52 proxy racoon: DEBUG: enctype = 3DES-CBC
Dec 20 12:15:52 proxy racoon: DEBUG: encklen = 0
Dec 20 12:15:52 proxy racoon: DEBUG: hashtype = SHA
Dec 20 12:15:52 proxy racoon: DEBUG: authmethod = pre-shared key
Dec 20 12:15:52 proxy racoon: DEBUG: dh_group = 2048-bit MODP group
Dec 20 12:15:52 proxy racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC
Dec 20 12:15:52 proxy racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 20 12:15:52 proxy racoon: DEBUG: type=Group Description, flag=0x8000, lorv=1024-bit MODP group
Dec 20 12:15:52 proxy racoon: DEBUG: type=Authentication Method, flag=0x8000, lorv=pre-shared key
Dec 20 12:15:52 proxy racoon: DEBUG: type=Life Type, flag=0x8000, lorv=seconds
Dec 20 12:15:52 proxy racoon: DEBUG: type=Life Duration, flag=0x0000, lorv=4
Dec 20 12:15:52 proxy racoon: DEBUG: prop#=1, prot-id=ISAKMP, spi-size=0, #trns=5
Dec 20 12:15:52 proxy racoon: DEBUG: trns#=5, trns-id=IKE
Dec 20 12:15:52 proxy racoon: DEBUG: lifetime = 28800
Dec 20 12:15:52 proxy racoon: DEBUG: lifebyte = 0
Dec 20 12:15:52 proxy racoon: DEBUG: enctype = 3DES-CBC
Dec 20 12:15:52 proxy racoon: DEBUG: encklen = 0
Dec 20 12:15:52 proxy racoon: DEBUG: hashtype = SHA
Dec 20 12:15:52 proxy racoon: DEBUG: authmethod = pre-shared key
Dec 20 12:15:52 proxy racoon: DEBUG: dh_group = 1024-bit MODP group
Dec 20 12:15:52 proxy racoon: DEBUG: an acceptable proposal found.
Dec 20 12:15:52 proxy racoon: DEBUG: hmac(modp1024)
Dec 20 12:15:52 proxy racoon: DEBUG: agreed on pre-shared key auth.
Dec 20 12:15:52 proxy racoon: DEBUG: ===
Dec 20 12:15:52 proxy racoon: DEBUG: new cookie: 3d61b2bfde8ac120
Dec 20 12:15:52 proxy racoon: DEBUG: add payload of len 52, next type 13
Dec 20 12:15:52 proxy racoon: DEBUG: add payload of len 16, next type 0
Dec 20 12:15:52 proxy racoon: DEBUG: 104 bytes from XXX.XXX.XXX.XXX[500] to YYY.YYY.YYY.YYY[500]
Dec 20 12:15:52 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[500]
Dec 20 12:15:52 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[500]
Dec 20 12:15:52 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[500]
Dec 20 12:15:52 proxy racoon: DEBUG: 1 times of 104 bytes message will be sent to YYY.YYY.YYY.YYY[500]
Dec 20 12:15:52 proxy racoon: DEBUG: 1c7e1dd1 4ffc68c8 3d61b2bf de8ac120 01100200 00000000 00000068 0d000038 00000001 00000001 0000002c 01010001 00000024 05010000 80010005 80020002 80040002 80030001 800b0001 000c0004 00007080 00000014 4a131c81 07035845 5c5728f2 0e95452f
Dec 20 12:15:52 proxy racoon: DEBUG: resend phase1 packet 1c7e1dd14ffc68c8:3d61b2bfde8ac120
Dec 20 12:15:52 proxy racoon: DEBUG: ===
Dec 20 12:15:52 proxy racoon: DEBUG: 260 bytes message received from YYY.YYY.YYY.YYY[500] to XXX.XXX.XXX.XXX[500]
Dec 20 12:15:52 proxy racoon: DEBUG: 1c7e1dd1 4ffc68c8 3d61b2bf de8ac120 04100200 00000000 00000104 0a000084 13f28670 e733c9ec 67b21423 0b472c20 acec3b5d cfd5f11c 28e34bcf 71765a59 95a1f2b0 69feff3c e28326a8 51da2e9d e3fdec2e ccb17a67 bc250b8a f4bf81ee da61287a d8cc2894 2557742d d9aa06ed cf8e1e7e cb37c518 57d05135 54942889 90d20bbc cdd71775 c09b7ac2 98b0e86a b3682a4e 7b457773 4750a3d6 ddf5f062 14000034 9c008c61 87675075 22410026 e9e33600 d96c5174 00323490 acc686a7 c44b8883 a9b841bb 4d54d45b d91b72fb b1d0d94d 14000018 7b995f1b 6c71a6bf 4c8a02e5 e7d6e5f5 63fc7104 00000018 6f7db51a 41b2a24b 91d1a578 0d6f12a7 494074c4
Dec 20 12:15:52 proxy racoon: DEBUG: begin.
Dec 20 12:15:52 proxy racoon: DEBUG: seen nptype=4(ke)
Dec 20 12:15:52 proxy racoon: DEBUG: seen nptype=10(nonce)
Dec 20 12:15:52 proxy racoon: DEBUG: seen nptype=20(nat-d)
Dec 20 12:15:52 proxy racoon: DEBUG: seen nptype=20(nat-d)
Dec 20 12:15:52 proxy racoon: DEBUG: succeed.
Dec 20 12:15:52 proxy racoon: [XXX.XXX.XXX.XXX] INFO: Hashing XXX.XXX.XXX.XXX[500] with algo #2
Dec 20 12:15:52 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:52 proxy racoon: INFO: NAT-D payload #0 verified
Dec 20 12:15:52 proxy racoon: [YYY.YYY.YYY.YYY] INFO: Hashing YYY.YYY.YYY.YYY[500] with algo #2
Dec 20 12:15:52 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:52 proxy racoon: INFO: NAT-D payload #1 doesn't match
Dec 20 12:15:52 proxy racoon: INFO: NAT detected: PEER
Dec 20 12:15:52 proxy racoon: DEBUG: ===
Dec 20 12:15:52 proxy racoon: DEBUG: compute DH's private.
Dec 20 12:15:52 proxy racoon: DEBUG: 7d49d8fb abe5563a b70bd6c3 5022eac9 c6bc2001 a70fae0d 9e73216e 301401e8 7214b631 c148ed23 a7750de1 7d642152 050c008f 254128ae d04a5c38 7e2a5ffc 40ef8186 2ffc6d67 341af7f9 d0033c01 238d82ec d671f763 fa38e5ac 97303448 3c467a0e 93dff11d 2c3e92e1 f4841cf8 899ab65c 7db3f05b c87533db d66998e3
Dec 20 12:15:52 proxy racoon: DEBUG: compute DH's public.
Dec 20 12:15:52 proxy racoon: DEBUG: 5d193b54 30efc3b9 82863caf 580e0de9 3a5f2e63 eeeea781 6aac174c c5215f62 376f5b35 e03a568e ec4f1aff 72278d03 0234454d 16aea8b1 b04c0379 4aa2b391 04d2c417 5f16cab4 d219ccc2 06355abf bcc7211c 21ed0d8c 01d5a563 1bafde27 b2a53f2b 82438da9 5c525984 b691e329 8eba6daf 6ccaa25c 18866ee8 ad8dcf90
Dec 20 12:15:52 proxy racoon: [YYY.YYY.YYY.YYY] INFO: Hashing YYY.YYY.YYY.YYY[500] with algo #2
Dec 20 12:15:52 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:52 proxy racoon: [XXX.XXX.XXX.XXX] INFO: Hashing XXX.XXX.XXX.XXX[500] with algo #2
Dec 20 12:15:52 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:52 proxy racoon: INFO: Adding remote and local NAT-D payloads.
Dec 20 12:15:52 proxy racoon: DEBUG: add payload of len 128, next type 10
Dec 20 12:15:52 proxy racoon: DEBUG: add payload of len 16, next type 20
Dec 20 12:15:52 proxy racoon: DEBUG: add payload of len 20, next type 20
Dec 20 12:15:52 proxy racoon: DEBUG: add payload of len 20, next type 0
Dec 20 12:15:52 proxy racoon: DEBUG: 228 bytes from XXX.XXX.XXX.XXX[500] to YYY.YYY.YYY.YYY[500]
Dec 20 12:15:52 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[500]
Dec 20 12:15:52 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[500]
Dec 20 12:15:52 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[500]
Dec 20 12:15:52 proxy racoon: DEBUG: 1 times of 228 bytes message will be sent to YYY.YYY.YYY.YYY[500]
Dec 20 12:15:52 proxy racoon: DEBUG: 1c7e1dd1 4ffc68c8 3d61b2bf de8ac120 04100200 00000000 000000e4 0a000084 5d193b54 30efc3b9 82863caf 580e0de9 3a5f2e63 eeeea781 6aac174c c5215f62 376f5b35 e03a568e ec4f1aff 72278d03 0234454d 16aea8b1 b04c0379 4aa2b391 04d2c417 5f16cab4 d219ccc2 06355abf bcc7211c 21ed0d8c 01d5a563 1bafde27 b2a53f2b 82438da9 5c525984 b691e329 8eba6daf 6ccaa25c 18866ee8 ad8dcf90 14000014 d3eb85f6 904e6111 61a48040 604c531e 14000018 8a135bfc af8bc3d7 1b8dfaac a0514be5 fb226e26 00000018 7b995f1b 6c71a6bf 4c8a02e5 e7d6e5f5 63fc7104
Dec 20 12:15:52 proxy racoon: DEBUG: resend phase1 packet 1c7e1dd14ffc68c8:3d61b2bfde8ac120
Dec 20 12:15:52 proxy racoon: DEBUG: compute DH's shared.
Dec 20 12:15:52 proxy racoon: DEBUG: adbda99a b41b308a 5b9547ec 20e8875d 43fbcc34 88408b98 15a84202 bb9cf0b4 70029f52 4f80439b 9dfa3490 755cbccb a9bb3b49 9d7c0f33 7f87dbe0 5b03ef40 f1dcba0e 2302f57f 90bd5c3f 72a4e256 800111de 62e94a74 0590ca50 6956002b d8d2a766 0babe66e 9f962aa5 00975d33 b870bb5d b14d6496 74874b39 65bfd99e
Dec 20 12:15:52 proxy racoon: DEBUG: the psk found.
Dec 20 12:15:52 proxy racoon: DEBUG: nonce 1:
Dec 20 12:15:52 proxy racoon: DEBUG: 9c008c61 87675075 22410026 e9e33600 d96c5174 00323490 acc686a7 c44b8883 a9b841bb 4d54d45b d91b72fb b1d0d94d
Dec 20 12:15:52 proxy racoon: DEBUG: nonce 2:
Dec 20 12:15:52 proxy racoon: DEBUG: d3eb85f6 904e6111 61a48040 604c531e
Dec 20 12:15:52 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:15:52 proxy racoon: DEBUG: SKEYID computed:
Dec 20 12:15:52 proxy racoon: DEBUG: e8930422 33d8a0f0 6efb3d43 11924196 77d437e5
Dec 20 12:15:52 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:15:52 proxy racoon: DEBUG: SKEYID_d computed:
Dec 20 12:15:52 proxy racoon: DEBUG: 45f432dd b900549b c7d20e92 a51fb24c c2efae37
Dec 20 12:15:52 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:15:52 proxy racoon: DEBUG: SKEYID_a computed:
Dec 20 12:15:52 proxy racoon: DEBUG: d7f69ca4 9daed0fc 716d76fa 60eb8e60 19558ce5
Dec 20 12:15:52 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:15:52 proxy racoon: DEBUG: SKEYID_e computed:
Dec 20 12:15:52 proxy racoon: DEBUG: 07d02adb 5deaf5c2 a58a062d 13181da7 6c4a8300
Dec 20 12:15:52 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:52 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:52 proxy racoon: DEBUG: len(SKEYID_e) < len(Ka) (20 < 24), generating long key (Ka = K1 | K2 | ...)
Dec 20 12:15:52 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:15:52 proxy racoon: DEBUG: compute intermediate encryption key K1
Dec 20 12:15:52 proxy racoon: DEBUG: 00
Dec 20 12:15:52 proxy racoon: DEBUG: 1922a669 fb091a20 6689f254 952aacd2 8574d7d8
Dec 20 12:15:52 proxy racoon: DEBUG: hmac(hmac_sha1)
Dec 20 12:15:52 proxy racoon: DEBUG: compute intermediate encryption key K2
Dec 20 12:15:52 proxy racoon: DEBUG: 1922a669 fb091a20 6689f254 952aacd2 8574d7d8
Dec 20 12:15:52 proxy racoon: DEBUG: 05c46dee 7ccf8cfc abdf2695 b663738d aeb83f0e
Dec 20 12:15:52 proxy racoon: DEBUG: final encryption key computed:
Dec 20 12:15:52 proxy racoon: DEBUG: 1922a669 fb091a20 6689f254 952aacd2 8574d7d8 05c46dee
Dec 20 12:15:52 proxy racoon: DEBUG: hash(sha1)
Dec 20 12:15:52 proxy racoon: DEBUG: encryption(3des)
Dec 20 12:15:52 proxy racoon: DEBUG: IV computed:
Dec 20 12:15:52 proxy racoon: DEBUG: 4fef2bed 3467288f
Dec 20 12:16:12 proxy racoon: DEBUG: 228 bytes from XXX.XXX.XXX.XXX[500] to YYY.YYY.YYY.YYY[500]
Dec 20 12:16:12 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[500]
Dec 20 12:16:12 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[500]
Dec 20 12:16:12 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[500]
Dec 20 12:16:12 proxy racoon: DEBUG: 1 times of 228 bytes message will be sent to YYY.YYY.YYY.YYY[500]
Dec 20 12:16:12 proxy racoon: DEBUG: 1c7e1dd1 4ffc68c8 3d61b2bf de8ac120 04100200 00000000 000000e4 0a000084 5d193b54 30efc3b9 82863caf 580e0de9 3a5f2e63 eeeea781 6aac174c c5215f62 376f5b35 e03a568e ec4f1aff 72278d03 0234454d 16aea8b1 b04c0379 4aa2b391 04d2c417 5f16cab4 d219ccc2 06355abf bcc7211c 21ed0d8c 01d5a563 1bafde27 b2a53f2b 82438da9 5c525984 b691e329 8eba6daf 6ccaa25c 18866ee8 ad8dcf90 14000014 d3eb85f6 904e6111 61a48040 604c531e 14000018 8a135bfc af8bc3d7 1b8dfaac a0514be5 fb226e26 00000018 7b995f1b 6c71a6bf 4c8a02e5 e7d6e5f5 63fc7104
Dec 20 12:16:12 proxy racoon: DEBUG: resend phase1 packet 1c7e1dd14ffc68c8:3d61b2bfde8ac120
Dec 20 12:16:32 proxy racoon: DEBUG: 228 bytes from XXX.XXX.XXX.XXX[500] to YYY.YYY.YYY.YYY[500]
Dec 20 12:16:32 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[500]
Dec 20 12:16:32 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[500]
Dec 20 12:16:32 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[500]
Dec 20 12:16:32 proxy racoon: DEBUG: 1 times of 228 bytes message will be sent to YYY.YYY.YYY.YYY[500]
Dec 20 12:16:32 proxy racoon: DEBUG: 1c7e1dd1 4ffc68c8 3d61b2bf de8ac120 04100200 00000000 000000e4 0a000084 5d193b54 30efc3b9 82863caf 580e0de9 3a5f2e63 eeeea781 6aac174c c5215f62 376f5b35 e03a568e ec4f1aff 72278d03 0234454d 16aea8b1 b04c0379 4aa2b391 04d2c417 5f16cab4 d219ccc2 06355abf bcc7211c 21ed0d8c 01d5a563 1bafde27 b2a53f2b 82438da9 5c525984 b691e329 8eba6daf 6ccaa25c 18866ee8 ad8dcf90 14000014 d3eb85f6 904e6111 61a48040 604c531e 14000018 8a135bfc af8bc3d7 1b8dfaac a0514be5 fb226e26 00000018 7b995f1b 6c71a6bf 4c8a02e5 e7d6e5f5 63fc7104
Dec 20 12:16:32 proxy racoon: DEBUG: resend phase1 packet 1c7e1dd14ffc68c8:3d61b2bfde8ac120
Dec 20 12:16:52 proxy racoon: DEBUG: 228 bytes from XXX.XXX.XXX.XXX[500] to YYY.YYY.YYY.YYY[500]
Dec 20 12:16:52 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[500]
Dec 20 12:16:52 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[500]
Dec 20 12:16:52 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[500]
Dec 20 12:16:52 proxy racoon: DEBUG: 1 times of 228 bytes message will be sent to YYY.YYY.YYY.YYY[500]
Dec 20 12:16:52 proxy racoon: DEBUG: 1c7e1dd1 4ffc68c8 3d61b2bf de8ac120 04100200 00000000 000000e4 0a000084 5d193b54 30efc3b9 82863caf 580e0de9 3a5f2e63 eeeea781 6aac174c c5215f62 376f5b35 e03a568e ec4f1aff 72278d03 0234454d 16aea8b1 b04c0379 4aa2b391 04d2c417 5f16cab4 d219ccc2 06355abf bcc7211c 21ed0d8c 01d5a563 1bafde27 b2a53f2b 82438da9 5c525984 b691e329 8eba6daf 6ccaa25c 18866ee8 ad8dcf90 14000014 d3eb85f6 904e6111 61a48040 604c531e 14000018 8a135bfc af8bc3d7 1b8dfaac a0514be5 fb226e26 00000018 7b995f1b 6c71a6bf 4c8a02e5 e7d6e5f5 63fc7104
Dec 20 12:16:52 proxy racoon: DEBUG: resend phase1 packet 1c7e1dd14ffc68c8:3d61b2bfde8ac120
Dec 20 12:17:12 proxy racoon: DEBUG: 228 bytes from XXX.XXX.XXX.XXX[500] to YYY.YYY.YYY.YYY[500]
Dec 20 12:17:12 proxy racoon: DEBUG: sockname XXX.XXX.XXX.XXX[500]
Dec 20 12:17:12 proxy racoon: DEBUG: send packet from XXX.XXX.XXX.XXX[500]
Dec 20 12:17:12 proxy racoon: DEBUG: send packet to YYY.YYY.YYY.YYY[500]
Dec 20 12:17:12 proxy racoon: DEBUG: 1 times of 228 bytes message will be sent to YYY.YYY.YYY.YYY[500]
Dec 20 12:17:12 proxy racoon: DEBUG: 1c7e1dd1 4ffc68c8 3d61b2bf de8ac120 04100200 00000000 000000e4 0a000084 5d193b54 30efc3b9 82863caf 580e0de9 3a5f2e63 eeeea781 6aac174c c5215f62 376f5b35 e03a568e ec4f1aff 72278d03 0234454d 16aea8b1 b04c0379 4aa2b391 04d2c417 5f16cab4 d219ccc2 06355abf bcc7211c 21ed0d8c 01d5a563 1bafde27 b2a53f2b 82438da9 5c525984 b691e329 8eba6daf 6ccaa25c 18866ee8 ad8dcf90 14000014 d3eb85f6 904e6111 61a48040 604c531e 14000018 8a135bfc af8bc3d7 1b8dfaac a0514be5 fb226e26 00000018 7b995f1b 6c71a6bf 4c8a02e5 e7d6e5f5 63fc7104
Dec 20 12:17:12 proxy racoon: DEBUG: resend phase1 packet 1c7e1dd14ffc68c8:3d61b2bfde8ac120
Dec 20 12:17:32 proxy racoon: ERROR: phase1 negotiation failed due to time up. 1c7e1dd14ffc68c8:3d61b2bfde8ac120
Dec 20 12:17:32 proxy racoon: DEBUG: IV freed