Dec 7 11:56:00 TEST racoon: INFO: @(#)ipsec-tools 0.8.2 (http://ipsec-tools.sourceforge.net)
Dec 7 11:56:00 TEST racoon: INFO: @(#)This product linked OpenSSL 1.0.1u-freebsd 22 Sep 2016 (http://www.openssl.org/)
Dec 7 11:56:00 TEST racoon: INFO: Reading configuration from "/usr/local/etc/racoon/racoon.conf"
Dec 7 11:56:00 TEST racoon: DEBUG2: lifetime = 86400
Dec 7 11:56:00 TEST racoon: DEBUG2: lifebyte = 0
Dec 7 11:56:00 TEST racoon: DEBUG2: encklen=0
Dec 7 11:56:00 TEST racoon: DEBUG2: p:1 t:1
Dec 7 11:56:00 TEST racoon: DEBUG2: 3DES-CBC(5)
Dec 7 11:56:00 TEST racoon: DEBUG2: SHA(2)
Dec 7 11:56:00 TEST racoon: DEBUG2: 1024-bit MODP group(2)
Dec 7 11:56:00 TEST racoon: DEBUG2: pre-shared key(1)
Dec 7 11:56:00 TEST racoon: DEBUG2:
Dec 7 11:56:00 TEST racoon: DEBUG2: Etype mismatch: got 2, expected 4.
Dec 7 11:56:00 TEST racoon: DEBUG: hmac(modp1024)
Dec 7 11:56:00 TEST racoon: DEBUG: no check of compression algorithm; not supported in sadb message.
Dec 7 11:56:00 TEST racoon: DEBUG: getsainfo params: loc='ANONYMOUS' rmt='ANONYMOUS' peer='NULL' client='NULL' id=0
Dec 7 11:56:00 TEST racoon: DEBUG2: parse successed.
Dec 7 11:56:00 TEST racoon: INFO: 10.1.1.99[4500] used for NAT-T
Dec 7 11:56:00 TEST racoon: INFO: 10.1.1.99[4500] used as isakmp port (fd=6)
Dec 7 11:56:00 TEST racoon: INFO: 10.1.1.99[500] used as isakmp port (fd=7)
Dec 7 11:56:00 TEST racoon: INFO: 10.1.1.99[7001] used as isakmp port (fd=8)
Dec 7 11:56:00 TEST racoon: DEBUG: pk_recv: retry[0] recv()
Dec 7 11:56:00 TEST racoon: DEBUG: got pfkey X_SPDDUMP message
Dec 7 11:56:00 TEST racoon: DEBUG2: 02120000 0b000100 01000000 e3020000 03000500 11200000 100206a5 c3954646 00000000 00000000 03000600 11200000 10020000 0a010163 00000000 00000000 03001200 02000100 02000000 00000000 08003200 01020000
Dec 7 11:56:00 TEST racoon: DEBUG: pk_recv: retry[0] recv()
Dec 7 11:56:00 TEST racoon: DEBUG: got pfkey X_SPDDUMP message
Dec 7 11:56:00 TEST racoon: DEBUG2: 02120000 0b000100 00000000 e3020000 03000500 11200000 10020000 0a010163 00000000 00000000 03000600 11200000 100206a5 c3954646 00000000 00000000 03001200 02000200 01000000 00000000 08003200 01020000
Dec 7 11:56:00 TEST racoon: DEBUG: sub:0x7fffffffe610: 10.1.1.99/32[0] 195.195.195.195/32[1701] proto=udp dir=out reqid=0
Dec 7 11:56:00 TEST racoon: DEBUG: db :0x80205d490: 195.195.195.195/32[1701] 10.1.1.99/32[0] proto=udp dir=in reqid=0
Dec 7 11:56:25 TEST racoon: DEBUG: pk_recv: retry[0] recv()
Dec 7 11:56:25 TEST racoon: DEBUG: got pfkey ACQUIRE message
Dec 7 11:56:25 TEST racoon: DEBUG2: 02060003 2f000000 02000000 00000000 03000500 11200000 10020000 0a010163 00000000 00000000 03000600 11200000 100206a5 c3954646 00000000 00000000 02001200 02000200 01000000 00000000 25000d00 20000000 00070000 00000000 0001c001 00000000 01000000 01000000 00000000 00000000 00000000 00000000 00000000 00000000 80510100 00000000 80700000 00000000 00000000 00000000 000b0000 00000000 00010008 00000000 01000000 01000000 00000000 00000000 00000000 00000000 00000000 00000000 80510100 00000000 80700000 00000000 00000000 00000000 000c0000 00000000 00010001 00000000 01000000 01000000 00000000 00000000 00000000 00000000 00000000 00000000 80510100 00000000 80700000 00000000 00000000 00000000 00160000 00000000 00010001 00000000 01000000 01000000 00000000 00000000 00000000 00000000 00000000 00000000 80510100 00000000 80700000 00000000 00000000 00000000
Dec 7 11:56:25 TEST racoon: DEBUG: suitable outbound SP found: 10.1.1.99/32[0] 195.195.195.195/32[1701] proto=udp dir=out reqid=0.
Dec 7 11:56:25 TEST racoon: DEBUG: sub:0x7fffffffe610: 195.195.195.195/32[1701] 10.1.1.99/32[0] proto=udp dir=in reqid=0
Dec 7 11:56:25 TEST racoon: DEBUG: db :0x80205d490: 195.195.195.195/32[1701] 10.1.1.99/32[0] proto=udp dir=in reqid=0
Dec 7 11:56:25 TEST racoon: DEBUG: suitable inbound SP found: 195.195.195.195/32[1701] 10.1.1.99/32[0] proto=udp dir=in reqid=0.
Dec 7 11:56:25 TEST racoon: DEBUG: new acquire 10.1.1.99/32[0] 195.195.195.195/32[1701] proto=udp dir=out reqid=0
Dec 7 11:56:25 TEST racoon: [195.195.195.195] DEBUG2: Checking remote conf "195.195.195.195[500]" 195.195.195.195[500].
Dec 7 11:56:25 TEST racoon: DEBUG2: enumrmconf: "195.195.195.195[500]" matches.
Dec 7 11:56:25 TEST racoon: [195.195.195.195] DEBUG: configuration "195.195.195.195[500]" selected.
Dec 7 11:56:25 TEST racoon: DEBUG: getsainfo params: loc='10.1.1.99' rmt='195.195.195.195' peer='NULL' client='NULL' id=0
Dec 7 11:56:25 TEST racoon: DEBUG: evaluating sainfo: loc='ANONYMOUS', rmt='ANONYMOUS', peer='ANY', id=0, reqid=0
Dec 7 11:56:25 TEST racoon: DEBUG: check and compare ids : values matched (ANONYMOUS)
Dec 7 11:56:25 TEST racoon: DEBUG: check and compare ids : values matched (ANONYMOUS)
Dec 7 11:56:25 TEST racoon: DEBUG: selected sainfo: loc='ANONYMOUS', rmt='ANONYMOUS', peer='ANY', id=0, reqid=0
Dec 7 11:56:25 TEST racoon: DEBUG: (proto_id=ESP spisize=4 spi=00000000 spi_p=00000000 encmode=Transport reqid=0:0)
Dec 7 11:56:25 TEST racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-md5)
Dec 7 11:56:25 TEST racoon: DEBUG: (trns_id=3DES encklen=0 authtype=hmac-sha)
Dec 7 11:56:25 TEST racoon: DEBUG: in post_acquire
Dec 7 11:56:25 TEST racoon: [195.195.195.195] DEBUG2: Checking remote conf "195.195.195.195[500]" 195.195.195.195[500].
Dec 7 11:56:25 TEST racoon: DEBUG2: enumrmconf: "195.195.195.195[500]" matches.
Dec 7 11:56:25 TEST racoon: [195.195.195.195] DEBUG: configuration "195.195.195.195[500]" selected.
Dec 7 11:56:25 TEST racoon: DEBUG2: getph1: start
Dec 7 11:56:25 TEST racoon: DEBUG2: local: 10.1.1.99[0]
Dec 7 11:56:25 TEST racoon: DEBUG2: remote: 195.195.195.195[1701]
Dec 7 11:56:25 TEST racoon: DEBUG2: no match
Dec 7 11:56:25 TEST racoon: INFO: IPsec-SA request for 195.195.195.195 queued due to no phase1 found.
Dec 7 11:56:25 TEST racoon: DEBUG: ===
Dec 7 11:56:25 TEST racoon: INFO: initiate new phase 1 negotiation: 10.1.1.99[500]<=>195.195.195.195[500]
Dec 7 11:56:25 TEST racoon: INFO: begin Identity Protection mode.
Dec 7 11:56:25 TEST racoon: DEBUG: new cookie: 66a0d6f56c2082ef
Dec 7 11:56:25 TEST racoon: DEBUG: add payload of len 52, next type 13
Dec 7 11:56:25 TEST racoon: DEBUG: add payload of len 16, next type 13
Dec 7 11:56:25 TEST racoon: DEBUG: add payload of len 16, next type 0
Dec 7 11:56:25 TEST racoon: DEBUG: 124 bytes from 10.1.1.99[500] to 195.195.195.195[500]
Dec 7 11:56:25 TEST racoon: DEBUG: sockname 10.1.1.99[500]
Dec 7 11:56:25 TEST racoon: DEBUG: send packet from 10.1.1.99[500]
Dec 7 11:56:25 TEST racoon: DEBUG: send packet to 195.195.195.195[500]
Dec 7 11:56:25 TEST racoon: DEBUG: 1 times of 124 bytes message will be sent to 195.195.195.195[500]
Dec 7 11:56:25 TEST racoon: DEBUG: 66a0d6f5 6c2082ef 00000000 00000000 01100200 00000000 0000007c 0d000038 00000001 00000001 0000002c 01010001 00000024 01010000 800b0001 000c0004 00015180 80010005 80030001 80020002 80040002 0d000014 4a131c81 07035845 5c5728f2 0e95452f 00000014 afcad713 68a1f1c9 6b8696fc 77570100
Dec 7 11:56:25 TEST racoon: DEBUG: resend phase1 packet 66a0d6f56c2082ef:0000000000000000
Dec 7 11:56:25 TEST racoon: DEBUG: ===
Dec 7 11:56:25 TEST racoon: DEBUG: 128 bytes message received from 195.195.195.195[500] to 10.1.1.99[500]
Dec 7 11:56:25 TEST racoon: DEBUG: 66a0d6f5 6c2082ef 43283910 6a57a86f 01100200 00000000 00000080 0d000038 00000001 00000001 0000002c 01010001 00000024 01010000 80010005 80020002 80040002 80030001 800b0001 000c0004 00015180 0d000014 4a131c81 07035845 5c5728f2 0e95452f 00000018 4048b7d5 6ebce885 25e7de7f 00d6c2d3 c0000000
Dec 7 11:56:25 TEST racoon: DEBUG: begin.
Dec 7 11:56:25 TEST racoon: DEBUG: seen nptype=1(sa)
Dec 7 11:56:25 TEST racoon: DEBUG: seen nptype=13(vid)
Dec 7 11:56:25 TEST racoon: DEBUG: seen nptype=13(vid)
Dec 7 11:56:25 TEST racoon: DEBUG: succeed.
Dec 7 11:56:25 TEST racoon: INFO: received Vendor ID: RFC 3947
Dec 7 11:56:25 TEST racoon: INFO: received broken Microsoft ID: FRAGMENTATION
Dec 7 11:56:25 TEST racoon: [195.195.195.195] INFO: Selected NAT-T version: RFC 3947
Dec 7 11:56:25 TEST racoon: DEBUG: total SA len=52
Dec 7 11:56:25 TEST racoon: DEBUG: 00000001 00000001 0000002c 01010001 00000024 01010000 80010005 80020002 80040002 80030001 800b0001 000c0004 00015180
Dec 7 11:56:25 TEST racoon: DEBUG: begin.
Dec 7 11:56:25 TEST racoon: DEBUG: seen nptype=2(prop)
Dec 7 11:56:25 TEST racoon: DEBUG: succeed.
Dec 7 11:56:25 TEST racoon: DEBUG: proposal #1 len=44
Dec 7 11:56:25 TEST racoon: DEBUG: begin.
Dec 7 11:56:25 TEST racoon: DEBUG: seen nptype=3(trns)
Dec 7 11:56:25 TEST racoon: DEBUG: succeed.
Dec 7 11:56:25 TEST racoon: DEBUG: transform #1 len=36
Dec 7 11:56:25 TEST racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC
Dec 7 11:56:25 TEST racoon: DEBUG: encryption(3des)
Dec 7 11:56:25 TEST racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 7 11:56:25 TEST racoon: DEBUG: hash(sha1)
Dec 7 11:56:25 TEST racoon: DEBUG: type=Group Description, flag=0x8000, lorv=1024-bit MODP group
Dec 7 11:56:25 TEST racoon: DEBUG: hmac(modp1024)
Dec 7 11:56:25 TEST racoon: DEBUG: type=Authentication Method, flag=0x8000, lorv=pre-shared key
Dec 7 11:56:25 TEST racoon: DEBUG: type=Life Type, flag=0x8000, lorv=seconds
Dec 7 11:56:25 TEST racoon: DEBUG: type=Life Duration, flag=0x0000, lorv=4
Dec 7 11:56:25 TEST racoon: DEBUG: pair 1:
Dec 7 11:56:25 TEST racoon: DEBUG: 0x802024c80: next=0x0 tnext=0x0
Dec 7 11:56:25 TEST racoon: DEBUG: proposal #1: 1 transform
Dec 7 11:56:25 TEST racoon: DEBUG: type=Encryption Algorithm, flag=0x8000, lorv=3DES-CBC
Dec 7 11:56:25 TEST racoon: DEBUG: type=Hash Algorithm, flag=0x8000, lorv=SHA
Dec 7 11:56:25 TEST racoon: DEBUG: type=Group Description, flag=0x8000, lorv=1024-bit MODP group
Dec 7 11:56:25 TEST racoon: DEBUG: type=Authentication Method, flag=0x8000, lorv=pre-shared key
Dec 7 11:56:25 TEST racoon: DEBUG: type=Life Type, flag=0x8000, lorv=seconds
Dec 7 11:56:25 TEST racoon: DEBUG: type=Life Duration, flag=0x0000, lorv=4
Dec 7 11:56:25 TEST racoon: DEBUG: prop#=1, prot-id=ISAKMP, spi-size=0, #trns=1
Dec 7 11:56:25 TEST racoon: DEBUG: trns#=1, trns-id=IKE
Dec 7 11:56:25 TEST racoon: DEBUG: lifetime = 86400
Dec 7 11:56:25 TEST racoon: DEBUG: lifebyte = 0
Dec 7 11:56:25 TEST racoon: DEBUG: enctype = 3DES-CBC
Dec 7 11:56:25 TEST racoon: DEBUG: encklen = 0
Dec 7 11:56:25 TEST racoon: DEBUG: hashtype = SHA
Dec 7 11:56:25 TEST racoon: DEBUG: authmethod = pre-shared key
Dec 7 11:56:25 TEST racoon: DEBUG: dh_group = 1024-bit MODP group
Dec 7 11:56:25 TEST racoon: DEBUG2: checkisakmpsa: authmethod: 1 / 1
Dec 7 11:56:25 TEST racoon: DEBUG: an acceptable proposal found.
Dec 7 11:56:25 TEST racoon: DEBUG: hmac(modp1024)
Dec 7 11:56:25 TEST racoon: DEBUG: agreed on pre-shared key auth.
Dec 7 11:56:25 TEST racoon: DEBUG: ===
Dec 7 11:56:25 TEST racoon: DEBUG: compute DH's private.
Dec 7 11:56:25 TEST racoon: DEBUG: 7e833060 4ba1995e 8880a4aa 46f46948 460b2607 3338d315 4600d0b6 d411bf75 d64cf786 d6c2edda 91643af6 de8196f2 66a697c3 5f1fa9ff 10ee6bd8 5bed0f87 19363893 679ff1a4 b7fce14b 8e3ba7f5 f36dc7f5 72f823fd f7c3d7ee 14cf0ef3 cc76b0ad 9a5eeed8 94b2bb04 f59eb9f1 461daa03 0b54d33f d690c5c2 ceaa422a
Dec 7 11:56:25 TEST racoon: DEBUG: compute DH's public.
Dec 7 11:56:25 TEST racoon: DEBUG: e9184f2d b4981706 5aa96cee 335bb2b5 f4fae353 7c5f3c53 43ede4d9 95e65b62 33bc95db 8e849470 de82c54a 7d9e4348 b9252661 10f1580f 2342d97d ae753aa2 b937691c 0b2333d7 bb1628d5 80209263 77a22e8e 0f5a4032 3479753f a0c78ce5 f94a213a 72cbe115 d5f22c7f 4364985c 922863c8 e24ca3e7 80a3c35f 56080782
Dec 7 11:56:25 TEST racoon: [195.195.195.195] INFO: Hashing 195.195.195.195[500] with algo #2
Dec 7 11:56:25 TEST racoon: DEBUG: hash(sha1)
Dec 7 11:56:25 TEST racoon: [10.1.1.99] INFO: Hashing 10.1.1.99[500] with algo #2
Dec 7 11:56:25 TEST racoon: DEBUG: hash(sha1)
Dec 7 11:56:25 TEST racoon: INFO: Adding remote and local NAT-D payloads.
Dec 7 11:56:25 TEST racoon: DEBUG: add payload of len 128, next type 10
Dec 7 11:56:25 TEST racoon: DEBUG: add payload of len 16, next type 20
Dec 7 11:56:25 TEST racoon: DEBUG: add payload of len 20, next type 20
Dec 7 11:56:25 TEST racoon: DEBUG: add payload of len 20, next type 0
Dec 7 11:56:25 TEST racoon: DEBUG: 228 bytes from 10.1.1.99[500] to 195.195.195.195[500]
Dec 7 11:56:25 TEST racoon: DEBUG: sockname 10.1.1.99[500]
Dec 7 11:56:25 TEST racoon: DEBUG: send packet from 10.1.1.99[500]
Dec 7 11:56:25 TEST racoon: DEBUG: send packet to 195.195.195.195[500]
Dec 7 11:56:25 TEST racoon: DEBUG: 1 times of 228 bytes message will be sent to 195.195.195.195[500]
Dec 7 11:56:25 TEST racoon: DEBUG: 66a0d6f5 6c2082ef 43283910 6a57a86f 04100200 00000000 000000e4 0a000084 e9184f2d b4981706 5aa96cee 335bb2b5 f4fae353 7c5f3c53 43ede4d9 95e65b62 33bc95db 8e849470 de82c54a 7d9e4348 b9252661 10f1580f 2342d97d ae753aa2 b937691c 0b2333d7 bb1628d5 80209263 77a22e8e 0f5a4032 3479753f a0c78ce5 f94a213a 72cbe115 d5f22c7f 4364985c 922863c8 e24ca3e7 80a3c35f 56080782 14000014 b6d967cc ce38f972 3454d6f3 519b5c91 14000018 501f19da e02f8274 a1e5e24c 13167b81 4483aa45 00000018 8eae0e35 6b1a0d84 3101f4c0 7dd26788 8b5013b0
Dec 7 11:56:25 TEST racoon: DEBUG: resend phase1 packet 66a0d6f56c2082ef:432839106a57a86f
Dec 7 11:56:25 TEST racoon: DEBUG: ===
Dec 7 11:56:25 TEST racoon: DEBUG: 304 bytes message received from 195.195.195.195[500] to 10.1.1.99[500]
Dec 7 11:56:25 TEST racoon: DEBUG: 66a0d6f5 6c2082ef 43283910 6a57a86f 04100200 00000000 00000130 0a000084 0697fd65 ce264b84 8cb6048c c516bbb1 948ffdac ef4af5ec 3e7883c5 eab9c8b2 b12e0b22 3c150d20 0d828fa3 51f489e2 31e53b9c 24165699 678e5239 21738492 4131fc6e edf2a0ef 8e1e402c af22e5c2 f1d1b5a7 f34a0ef0 cd1504b1 0bbf0fe3 53b15f52 6b580443 b8d26365 a15b88e2 c5b6eb8b fa4df061 610ec4bb 2ea60dc2 0d000018 34062827 a24df96a 4355f65e 39ee441a f756c9d0 0d000014 12f5f28c 457168a9 702d9fe2 74cc0100 0d00000c 09002689 dfd6b712 0d000014 b6ef9e0d 6a56a86f c2999911 d809b519 14000014 1f07f70e aa6514d3 b0fa9654 2a500100 14000018 ec1c2e12 e6e19871 b00dde08 8a01c1cc 97d7a755 00000018 501f19da e02f8274 a1e5e24c 13167b81 4483aa45
Dec 7 11:56:25 TEST racoon: DEBUG: begin.
Dec 7 11:56:25 TEST racoon: DEBUG: seen nptype=4(ke)
Dec 7 11:56:25 TEST racoon: DEBUG: seen nptype=10(nonce)
Dec 7 11:56:25 TEST racoon: DEBUG: seen nptype=13(vid)
Dec 7 11:56:25 TEST last message repeated 3 times
Dec 7 11:56:25 TEST racoon: DEBUG: seen nptype=20(nat-d)
Dec 7 11:56:25 TEST racoon: DEBUG: seen nptype=20(nat-d)
Dec 7 11:56:25 TEST racoon: DEBUG: succeed.
Dec 7 11:56:25 TEST racoon: INFO: received Vendor ID: CISCO-UNITY
Dec 7 11:56:25 TEST racoon: INFO: received Vendor ID: draft-ietf-ipsra-isakmp-xauth-06.txt
Dec 7 11:56:25 TEST racoon: DEBUG: received unknown Vendor ID
Dec 7 11:56:25 TEST racoon: DEBUG: b6ef9e0d 6a56a86f c2999911 d809b519
Dec 7 11:56:25 TEST racoon: DEBUG: received unknown Vendor ID
Dec 7 11:56:25 TEST racoon: DEBUG: 1f07f70e aa6514d3 b0fa9654 2a500100
Dec 7 11:56:25 TEST racoon: [10.1.1.99] INFO: Hashing 10.1.1.99[500] with algo #2
Dec 7 11:56:25 TEST racoon: DEBUG: hash(sha1)
Dec 7 11:56:25 TEST racoon: INFO: NAT-D payload #0 doesn't match
Dec 7 11:56:25 TEST racoon: [195.195.195.195] INFO: Hashing 195.195.195.195[500] with algo #2
Dec 7 11:56:25 TEST racoon: DEBUG: hash(sha1)
Dec 7 11:56:25 TEST racoon: INFO: NAT-D payload #1 verified
Dec 7 11:56:25 TEST racoon: INFO: NAT detected: ME
Dec 7 11:56:25 TEST racoon: INFO: KA list add: 10.1.1.99[4500]->195.195.195.195[4500]
Dec 7 11:56:25 TEST racoon: DEBUG: ===
Dec 7 11:56:25 TEST racoon: DEBUG: compute DH's shared.
Dec 7 11:56:25 TEST racoon: DEBUG: 9f41feb5 e0b5290a fab77914 d15ddec5 014c1470 df6180bc 4117d239 1c5d2e47 49d58c02 15fd7f6c b51690fb 9e1d5bb4 9a112ba3 8a8ab929 5e555355 d02c3633 3bcad860 bea160e4 1e637a09 1df53e38 26629074 b6170e2a 8fd73b8e 28716835 750af95e 3447ef52 a3708187 4df7d19f 566ed023 f9dbf90a fe949b1d 9af9e6ce
Dec 7 11:56:25 TEST racoon: DEBUG: the psk found.
Dec 7 11:56:25 TEST racoon: DEBUG2: psk:
Dec 7 11:56:25 TEST racoon: DEBUG2: 47746864 73715f59 467b
Dec 7 11:56:25 TEST racoon: DEBUG: nonce 1:
Dec 7 11:56:25 TEST racoon: DEBUG: b6d967cc ce38f972 3454d6f3 519b5c91
Dec 7 11:56:25 TEST racoon: DEBUG: nonce 2:
Dec 7 11:56:25 TEST racoon: DEBUG: 34062827 a24df96a 4355f65e 39ee441a f756c9d0
Dec 7 11:56:25 TEST racoon: DEBUG: hmac(hmac_sha1)
Dec 7 11:56:25 TEST racoon: DEBUG: SKEYID computed:
Dec 7 11:56:25 TEST racoon: DEBUG: bac88b95 787a4d50 3fafc78c 0df0a7fc b58da95b
Dec 7 11:56:25 TEST racoon: DEBUG: hmac(hmac_sha1)
Dec 7 11:56:25 TEST racoon: DEBUG: SKEYID_d computed:
Dec 7 11:56:25 TEST racoon: DEBUG: af37d5ec e31e482d 9d8fa9dc 057df2e6 4a8ad62c
Dec 7 11:56:25 TEST racoon: DEBUG: hmac(hmac_sha1)
Dec 7 11:56:25 TEST racoon: DEBUG: SKEYID_a computed:
Dec 7 11:56:25 TEST racoon: DEBUG: 46a03274 6dd820b6 d923cdfe edb6be17 58af3f6e
Dec 7 11:56:25 TEST racoon: DEBUG: hmac(hmac_sha1)
Dec 7 11:56:25 TEST racoon: DEBUG: SKEYID_e computed:
Dec 7 11:56:25 TEST racoon: DEBUG: ebc534db bcc9810e c256a283 1efa5489 e688f8bb
Dec 7 11:56:25 TEST racoon: DEBUG: encryption(3des)
Dec 7 11:56:25 TEST racoon: DEBUG: hash(sha1)
Dec 7 11:56:25 TEST racoon: DEBUG: len(SKEYID_e) < len(Ka) (20 < 24), generating long key (Ka = K1 | K2 | ...)
Dec 7 11:56:25 TEST racoon: DEBUG: hmac(hmac_sha1)
Dec 7 11:56:25 TEST racoon: DEBUG: compute intermediate encryption key K1
Dec 7 11:56:25 TEST racoon: DEBUG: 00
Dec 7 11:56:25 TEST racoon: DEBUG: 48377391 0973c37c d4555ce2 0b903861 4bea38fb
Dec 7 11:56:25 TEST racoon: DEBUG: hmac(hmac_sha1)
Dec 7 11:56:25 TEST racoon: DEBUG: compute intermediate encryption key K2
Dec 7 11:56:25 TEST racoon: DEBUG: 48377391 0973c37c d4555ce2 0b903861 4bea38fb
Dec 7 11:56:25 TEST racoon: DEBUG: 523c8d61 7dc238a9 190e03dc ec61273c 517960a9
Dec 7 11:56:25 TEST racoon: DEBUG: final encryption key computed:
Dec 7 11:56:25 TEST racoon: DEBUG: 48377391 0973c37c d4555ce2 0b903861 4bea38fb 523c8d61
Dec 7 11:56:25 TEST racoon: DEBUG: hash(sha1)
Dec 7 11:56:25 TEST racoon: DEBUG: encryption(3des)
Dec 7 11:56:25 TEST racoon: DEBUG: IV computed:
Dec 7 11:56:25 TEST racoon: DEBUG: 4b7fa81a 57f28564
Dec 7 11:56:25 TEST racoon: DEBUG: use ID type of IPv4_address
Dec 7 11:56:25 TEST racoon: DEBUG: HASH with:
Dec 7 11:56:25 TEST racoon: DEBUG: e9184f2d b4981706 5aa96cee 335bb2b5 f4fae353 7c5f3c53 43ede4d9 95e65b62 33bc95db 8e849470 de82c54a 7d9e4348 b9252661 10f1580f 2342d97d ae753aa2 b937691c 0b2333d7 bb1628d5 80209263 77a22e8e 0f5a4032 3479753f a0c78ce5 f94a213a 72cbe115 d5f22c7f 4364985c 922863c8 e24ca3e7 80a3c35f 56080782 0697fd65 ce264b84 8cb6048c c516bbb1 948ffdac ef4af5ec 3e7883c5 eab9c8b2 b12e0b22 3c150d20 0d828fa3 51f489e2 31e53b9c 24165699 678e5239 21738492 4131fc6e edf2a0ef 8e1e402c af22e5c2 f1d1b5a7 f34a0ef0 cd1504b1 0bbf0fe3 53b15f52 6b580443 b8d26365 a15b88e2 c5b6eb8b fa4df061 610ec4bb 2ea60dc2 66a0d6f5 6c2082ef 43283910 6a57a86f 00000001 00000001 0000002c 01010001 00000024 01010000 800b0001 000c0004 00015180 80010005 80030001 80020002 80040002 011101f4 0a010163
Dec 7 11:56:25 TEST racoon: DEBUG: hmac(hmac_sha1)
Dec 7 11:56:25 TEST racoon: DEBUG: HASH (init) computed:
Dec 7 11:56:25 TEST racoon: DEBUG: d30781ba 6fdf851a dc3c6c46 4c8d3116 2b6613bd
Dec 7 11:56:25 TEST racoon: DEBUG: add payload of len 8, next type 8
Dec 7 11:56:25 TEST racoon: DEBUG: add payload of len 20, next type 0
Dec 7 11:56:25 TEST racoon: DEBUG: begin encryption.
Dec 7 11:56:25 TEST racoon: DEBUG: encryption(3des)
Dec 7 11:56:25 TEST racoon: DEBUG: pad length = 4
Dec 7 11:56:25 TEST racoon: DEBUG: 0800000c 011101f4 0a010163 00000018 d30781ba 6fdf851a dc3c6c46 4c8d3116 2b6613bd 00000004
Dec 7 11:56:25 TEST racoon: DEBUG: encryption(3des)
Dec 7 11:56:25 TEST racoon: DEBUG: with key:
Dec 7 11:56:25 TEST racoon: DEBUG: 48377391 0973c37c d4555ce2 0b903861 4bea38fb 523c8d61
Dec 7 11:56:25 TEST racoon: DEBUG: encrypted payload by IV:
Dec 7 11:56:25 TEST racoon: DEBUG: 4b7fa81a 57f28564
Dec 7 11:56:25 TEST racoon: DEBUG: save IV for next:
Dec 7 11:56:25 TEST racoon: DEBUG: aa67e249 97c7eab4
Dec 7 11:56:25 TEST racoon: DEBUG: encrypted.
Dec 7 11:56:25 TEST racoon: DEBUG: Adding NON-ESP marker
Dec 7 11:56:25 TEST racoon: DEBUG: 72 bytes from 10.1.1.99[4500] to 195.195.195.195[4500]
Dec 7 11:56:25 TEST racoon: DEBUG: sockname 10.1.1.99[4500]
Dec 7 11:56:25 TEST racoon: DEBUG: send packet from 10.1.1.99[4500]
Dec 7 11:56:25 TEST racoon: DEBUG: send packet to 195.195.195.195[4500]
Dec 7 11:56:25 TEST racoon: DEBUG: 1 times of 72 bytes message will be sent to 195.195.195.195[4500]
Dec 7 11:56:25 TEST racoon: DEBUG: 00000000 66a0d6f5 6c2082ef 43283910 6a57a86f 05100201 00000000 00000044 71c1106d d9490a4f f73aa5e3 741e1ef4 f30d3576 1a842e10 25a89743 6dafbbbc aa67e249 97c7eab4
Dec 7 11:56:25 TEST racoon: DEBUG: resend phase1 packet 66a0d6f56c2082ef:432839106a57a86f
Dec 7 11:56:25 TEST racoon: DEBUG: ===
Dec 7 11:56:25 TEST racoon: DEBUG: 84 bytes message received from 195.195.195.195[4500] to 10.1.1.99[4500]
Dec 7 11:56:25 TEST racoon: DEBUG: 66a0d6f5 6c2082ef 43283910 6a57a86f 05100201 00000000 00000054 bc73a297 9daf7ef9 d463323f a1b7cdbf 2b02ebac a9579c87 6efd0cd8 8b1a3e8b 9ed2da5c 9430dc67 afb3fe1a 929918c0 a9bf12cc fee1ec4d
Dec 7 11:56:25 TEST racoon: DEBUG: begin decryption.
Dec 7 11:56:25 TEST racoon: DEBUG: encryption(3des)
Dec 7 11:56:25 TEST racoon: DEBUG: IV was saved for next processing:
Dec 7 11:56:25 TEST racoon: DEBUG: a9bf12cc fee1ec4d
Dec 7 11:56:25 TEST racoon: DEBUG: encryption(3des)
Dec 7 11:56:25 TEST racoon: DEBUG: with key:
Dec 7 11:56:25 TEST racoon: DEBUG: 48377391 0973c37c d4555ce2 0b903861 4bea38fb 523c8d61
Dec 7 11:56:25 TEST racoon: DEBUG: decrypted payload by IV:
Dec 7 11:56:25 TEST racoon: DEBUG: aa67e249 97c7eab4
Dec 7 11:56:25 TEST racoon: DEBUG: decrypted payload, but not trimed.
Dec 7 11:56:25 TEST racoon: DEBUG: 0800000c 01110000 c3954646 0d000018 3cec26d3 a8b6316a ae978b24 1e7d7f22 0c460ab0 00000014 afcad713 68a1f1c9 6b8696fc 77570100
Dec 7 11:56:25 TEST racoon: DEBUG: padding len=0
Dec 7 11:56:25 TEST racoon: DEBUG: skip to trim padding.
Dec 7 11:56:25 TEST racoon: DEBUG: decrypted.
Dec 7 11:56:25 TEST racoon: DEBUG: 66a0d6f5 6c2082ef 43283910 6a57a86f 05100201 00000000 00000054 0800000c 01110000 c3954646 0d000018 3cec26d3 a8b6316a ae978b24 1e7d7f22 0c460ab0 00000014 afcad713 68a1f1c9 6b8696fc 77570100
Dec 7 11:56:25 TEST racoon: DEBUG: begin.
Dec 7 11:56:25 TEST racoon: DEBUG: seen nptype=5(id)
Dec 7 11:56:25 TEST racoon: DEBUG: seen nptype=8(hash)
Dec 7 11:56:25 TEST racoon: DEBUG: seen nptype=13(vid)
Dec 7 11:56:25 TEST racoon: DEBUG: succeed.
Dec 7 11:56:25 TEST racoon: INFO: received Vendor ID: DPD
Dec 7 11:56:25 TEST racoon: DEBUG: remote supports DPD
Dec 7 11:56:25 TEST racoon: WARNING: port 4500 expected, but 0
Dec 7 11:56:25 TEST racoon: DEBUG: HASH received:
Dec 7 11:56:25 TEST racoon: DEBUG: 3cec26d3 a8b6316a ae978b24 1e7d7f22 0c460ab0
Dec 7 11:56:25 TEST racoon: DEBUG: HASH with:
Dec 7 11:56:25 TEST racoon: DEBUG: 0697fd65 ce264b84 8cb6048c c516bbb1 948ffdac ef4af5ec 3e7883c5 eab9c8b2 b12e0b22 3c150d20 0d828fa3 51f489e2 31e53b9c 24165699 678e5239 21738492 4131fc6e edf2a0ef 8e1e402c af22e5c2 f1d1b5a7 f34a0ef0 cd1504b1 0bbf0fe3 53b15f52 6b580443 b8d26365 a15b88e2 c5b6eb8b fa4df061 610ec4bb 2ea60dc2 e9184f2d b4981706 5aa96cee 335bb2b5 f4fae353 7c5f3c53 43ede4d9 95e65b62 33bc95db 8e849470 de82c54a 7d9e4348 b9252661 10f1580f 2342d97d ae753aa2 b937691c 0b2333d7 bb1628d5 80209263 77a22e8e 0f5a4032 3479753f a0c78ce5 f94a213a 72cbe115 d5f22c7f 4364985c 922863c8 e24ca3e7 80a3c35f 56080782 43283910 6a57a86f 66a0d6f5 6c2082ef 00000001 00000001 0000002c 01010001 00000024 01010000 800b0001 000c0004 00015180 80010005 80030001 80020002 80040002 01110000 c3954646[CODE]