"Secure' logs attached... Andreas Steffen wrote:
> Could you enable restricted debugging by setting > > plutodebug="control klips" > > and sending me the log? > > Regards > > Andreas > > Nilesh Trivedi wrote: > > Hi, > > > > Please note that I'm having problems subscribing to the mailing list > > so would appreciate if you could reply back to me in person as well. > > > > I have everything that's neccessary (including the recompiled kernel > > module ipsec.o) to do protocol-port selector config. however while > > just playing around with Automatic keying (no certificates yet) on the > > > > freeswan-1.99, x509 patch version 0.9.34 I get the following errors; > > (along with the configs.) > > > > Note: This works fine if I comment the leftprotoport and > > rightprotoport > > out...without these lines I can get both the ISAKMP as well as IPSEC > > SA's established fine but ofcourse I need to test the port > > combinations > > which doesn't seem to work ?? > > > > Any help would be greatly appreciated...thanks in advance!! > > > > Regards, > > > > Nilesh. > > > > Config + Logs -------------------------->>>>>>> > > > >>>[EMAIL PROTECTED] log]# service ipsec start > >>>ipsec_setup: Starting FreeS/WAN IPsec 1.99... > >>>[EMAIL PROTECTED] log]# ipsec verify > >>>Checking your system to see if IPsec got installed and started > > > > correctly > > > >>>Version check and ipsec on-path [OK] > >>>Checking for KLIPS support in kernel [OK] > >>>Checking for RSA private key (/etc/ipsec.secrets) [OK] > >>>Checking that pluto is running [OK] > >>>DNS checks. > >>>Looking for forward key for dcm-3.cisco.com [FAILED] > > > > > >>>Does the machine have at least one non-private address [OK] > >>>[EMAIL PROTECTED] log]# > >>>[EMAIL PROTECTED] log]# > >>>[EMAIL PROTECTED] log]# > >>>[EMAIL PROTECTED] log]# ipsec look > >>>dcm-3.cisco.com Mon Aug 18 16:07:10 EST 2003 > >>>ipsec0->eth0 mtu=16260(1500)->1500 > >>>Destination Gateway Genmask Flags MSS Window > > > > irtt > > > >>>Iface > >>>0.0.0.0 172.18.195.1 0.0.0.0 UG 40 > > > > 0 0 > > > >>>eth0 > >>>172.18.195.0 0.0.0.0 255.255.255.0 U 40 > > > > 0 0 > > > >>>eth0 > >>>172.18.195.0 0.0.0.0 255.255.255.0 U 40 > > > > 0 0 > > > >>>ipsec0 > >>>[EMAIL PROTECTED] log]# ipsec auto --up sample > >>>104 "sample" #1: STATE_MAIN_I1: initiate > >>>106 "sample" #1: STATE_MAIN_I2: sent MI2, expecting MR2 > >>>108 "sample" #1: STATE_MAIN_I3: sent MI3, expecting MR3 > >>>004 "sample" #1: STATE_MAIN_I4: ISAKMP SA established > >>>112 "sample" #2: STATE_QUICK_I1: initiate > >>>003 ERROR: "sample" #2: pfkey write() of SADB_X_ADDFLOW message 11 > > > > for > > > >>>flow [EMAIL PROTECTED] failed. Errno 22: Invalid argument > >>>032 "sample" #2: STATE_QUICK_I1: internal error > >>>010 "sample" #2: STATE_QUICK_I1: retransmission; will wait 20s for > >>>response > >>>003 ERROR: "sample" #2: pfkey write() of SADB_X_ADDFLOW message 20 > > > > for > > > >>>flow [EMAIL PROTECTED] failed. Errno 22: Invalid argument > >>>032 "sample" #2: STATE_QUICK_I1: internal error > >>>010 "sample" #2: STATE_QUICK_I1: retransmission; will wait 40s for > >>>response > >>>003 ERROR: "sample" #2: pfkey write() of SADB_X_ADDFLOW message 29 > > > > for > > > >>>flow [EMAIL PROTECTED] failed. Errno 22: Invalid argument > >>>032 "sample" #2: STATE_QUICK_I1: internal error > >>>031 "sample" #2: max number of retransmissions (2) reached > >>>STATE_QUICK_I1. No acceptable response to our first Quick Mode > > > > message: > > > >>>perhaps peer likes no proposal > >>>000 "sample" #2: starting keying attempt 2 of an unlimited number, > > > > but > > > >>>releasing whack > >>>[EMAIL PROTECTED] log]# ipsec auto --status > >>>000 interface ipsec0/eth0 172.18.195.217 > >>>000 > >>>000 "sample": > >>> > > > > 172.18.195.217:17/5099---172.18.195.1...172.18.195.1---172.18.195.216:17/5099 > > > > > >>>000 "sample": CAs: '%any'...'%any' > >>>000 "sample": ike_life: 3600s; ipsec_life: 28800s; rekey_margin: > > > > 540s; > > > >>>rekey_fuzz: 100%; keyingtries: 0 > >>>000 "sample": policy: > > > > RSASIG+ENCRYPT+TUNNEL+PFS+DISABLEARRIVALCHECK; > > > >>>interface: eth0; unrouted > >>>000 "sample": newest ISAKMP SA: #1; newest IPsec SA: #0; eroute > > > > owner: > > > >>>#0 > >>>000 > >>>000 #3: "sample" STATE_QUICK_I1 (sent QI1, expecting QR1); > >>>EVENT_RETRANSMIT in 27s > >>>000 #1: "sample" STATE_MAIN_I4 (ISAKMP SA established); > > > > EVENT_SA_REPLACE > > > >>>in 2653s; newest ISAKMP > >>>000 > >>> > >>>=========================================================== > >>> > >>> My config looks like (I'm trying to do Auto keying first and then > > > > verify > > > >>>X.509 if this works) > >>> > >>>conn sample > >>> # Left security gateway, subnet behind it, next hop toward > > > > right. > > > >>> left=172.18.195.217 > >>> #leftcert=/etc/ipsec.d/freeswan-cert.pem > >>> #leftsubnet=255.255.255.0/24 > >>> [EMAIL PROTECTED] > >>> > >>> leftrsasigkey=0sAQPEYWpENMlD/Petlaq3FDdriWaZKmBC9 > >>> leftnexthop=%defaultroute > >>> # Right security gateway, subnet behind it, next hop toward > > > > left. > > > >>> right=172.18.195.216 > >>> leftprotoport=udp/5099 > >>> rightprotoport=udp/5099 > >>> #rightcert=/etc/ipsec.d/client-cert.pem > >>> [EMAIL PROTECTED] > >>> > >>> rightrsasigkey=0sAQO4NXeNXkNG... > >>> rightnexthop=%defaultroute > >>> #rightnexthop=172.18.195.216 > >>> # To authorize this connection, but not actually start it, > > > > at > > > >>>startup, > >>> # uncomment this. > >>> auto=add > > > > > > _______________________________________________ > > FreeS/WAN Users mailing list > > [EMAIL PROTECTED] > > https://mj2.freeswan.org/cgi-bin/mj_wwwusr > > -- > ======================================================================= > Andreas Steffen e-mail: [EMAIL PROTECTED] > strongSec GmbH home: http://www.strongsec.com > Alter Zürichweg 20 phone: +41 1 730 80 64 > CH-8952 Schlieren (Switzerland) fax: +41 1 730 80 65 > ==========================================[strong internet security]===
Aug 19 15:41:46 dcm-2 pluto[10428]: | finish_pfkey_msg: SADB_DELETE message 94 for Delete SA [EMAIL PROTECTED] Aug 19 15:41:46 dcm-2 pluto[10428]: | 02 04 00 03 0a 00 00 00 5e 00 00 00 bc 28 00 00 Aug 19 15:41:46 dcm-2 pluto[10428]: | 02 00 01 00 0e 2d 40 17 00 01 00 00 00 00 00 00 Aug 19 15:41:46 dcm-2 pluto[10428]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:41:46 dcm-2 pluto[10428]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:41:46 dcm-2 pluto[10428]: | 02 00 13 eb ac 12 c3 d8 00 00 00 00 00 00 00 00 Aug 19 15:41:46 dcm-2 pluto[10428]: | pfkey_get: SADB_DELETE message 94 Aug 19 15:41:46 dcm-2 pluto[10428]: | state transition function for STATE_QUICK_I1 had internal error Aug 19 15:41:46 dcm-2 pluto[10428]: | next event EVENT_RETRANSMIT in 3 seconds for #5 Aug 19 15:41:46 dcm-2 pluto[10428]: | pfkey_async: SADB_X_ADDFLOW len=2, errno=22, satype=9, seq=92, pid=10428 Aug 19 15:41:49 dcm-2 pluto[10428]: | Aug 19 15:41:50 dcm-2 pluto[10428]: | *time to handle event Aug 19 15:41:50 dcm-2 pluto[10428]: | event after this is EVENT_SHUNT_SCAN in 91 seconds Aug 19 15:41:50 dcm-2 pluto[10428]: | handling event EVENT_RETRANSMIT for 172.18.195.217 "sample" #5 Aug 19 15:41:50 dcm-2 pluto[10428]: | sending 380 bytes for EVENT_RETRANSMIT through eth0 to 172.18.195.217:500: Aug 19 15:41:50 dcm-2 pluto[10428]: | d3 4d 55 e0 49 3a 31 83 9d b8 6b 12 80 fe c5 5c Aug 19 15:41:50 dcm-2 pluto[10428]: | 08 10 20 01 4b 28 45 c5 00 00 01 7c e1 6e 88 01 Aug 19 15:41:50 dcm-2 pluto[10428]: | b5 c9 76 b6 5c a3 24 2c bf 06 36 ed db 4e ac 77 Aug 19 15:41:50 dcm-2 pluto[10428]: | 8f 35 06 fb 7c 69 fd 8d 5e 19 72 c0 96 fa 73 f7 Aug 19 15:41:50 dcm-2 pluto[10428]: | 4f a7 27 0f f6 87 bf a1 a5 0c 0b 1d 4f fb 56 8b Aug 19 15:41:50 dcm-2 pluto[10428]: | 88 44 12 b2 7c d3 f0 90 9d 5c 55 6f fd 9d 77 52 Aug 19 15:41:50 dcm-2 pluto[10428]: | 2e 19 52 6a 9e fc 2d 42 a3 15 62 71 ca 95 d1 db Aug 19 15:41:50 dcm-2 pluto[10428]: | 9a 95 15 05 ea 70 67 a9 9a f8 2b f4 e3 6e 41 f2 Aug 19 15:41:50 dcm-2 pluto[10428]: | 29 c2 44 90 40 09 5e d5 78 31 58 94 a5 07 fc 14 Aug 19 15:41:50 dcm-2 pluto[10428]: | c3 4e ca c1 c6 f8 8e d5 55 f5 85 d9 2b 33 ef f6 Aug 19 15:41:50 dcm-2 pluto[10428]: | 85 2b 3d 88 2b 81 7a c3 46 10 05 5b 7f 75 c1 3b Aug 19 15:41:50 dcm-2 pluto[10428]: | 00 8b e3 5b 4b 04 2d dd 73 24 0c ef 38 0f 97 f4 Aug 19 15:41:50 dcm-2 pluto[10428]: | 91 de 1e e6 e0 b0 05 06 c2 52 f5 25 26 bb 23 9c Aug 19 15:41:50 dcm-2 pluto[10428]: | c0 a7 ad d8 cb 94 75 fd 15 a5 33 58 5f 8d 10 53 Aug 19 15:41:51 dcm-2 pluto[10428]: | c7 c3 f0 04 17 17 bf c1 02 a9 e1 b7 e2 b7 22 f5 Aug 19 15:41:51 dcm-2 pluto[10428]: | 9a 4c df fc 57 15 50 1b 3d 1e fd 16 db bf bc e2 Aug 19 15:41:51 dcm-2 pluto[10428]: | a1 d3 5c e5 fb 1e 05 79 f2 0e 7d 2c ae 0f ea 1a Aug 19 15:41:51 dcm-2 pluto[10428]: | 49 12 7f e4 98 7f 1b ac 9f 06 1d 32 c9 90 0e 40 Aug 19 15:41:51 dcm-2 pluto[10428]: | 22 22 89 38 45 19 c9 48 ea 55 7d d2 e8 77 2d 43 Aug 19 15:41:51 dcm-2 pluto[10428]: | 67 53 05 e2 bf f4 9d 1f 4d fa 32 80 c7 d5 4b ec Aug 19 15:41:51 dcm-2 pluto[10428]: | 70 71 24 74 cb e2 13 51 ab 7c cc 8f bd 63 ea cd Aug 19 15:41:51 dcm-2 pluto[10428]: | 10 ba 14 ab 52 bf 76 3d b7 c6 c7 87 59 5f 25 5b Aug 19 15:41:51 dcm-2 pluto[10428]: | ee 38 8b 33 ef f0 1b b5 b9 30 0d 2d a1 4d a8 bc Aug 19 15:41:51 dcm-2 pluto[10428]: | 08 77 c0 5d 78 95 88 28 f0 8d 18 96 Aug 19 15:41:51 dcm-2 pluto[10428]: | inserting event EVENT_RETRANSMIT, timeout in 20 seconds for #5 Aug 19 15:41:51 dcm-2 pluto[10428]: | next event EVENT_RETRANSMIT in 20 seconds for #5 Aug 19 15:41:51 dcm-2 pluto[10428]: | Aug 19 15:41:51 dcm-2 pluto[10428]: | *received 348 bytes from 172.18.195.217:500 on eth0 Aug 19 15:41:51 dcm-2 pluto[10428]: | d3 4d 55 e0 49 3a 31 83 9d b8 6b 12 80 fe c5 5c Aug 19 15:41:51 dcm-2 pluto[10428]: | 08 10 20 01 4b 28 45 c5 00 00 01 5c 8b 24 a2 9a Aug 19 15:41:51 dcm-2 pluto[10428]: | 6b 28 09 e7 65 f5 6d 77 1e ae 85 e0 dd 17 4d 14 Aug 19 15:41:51 dcm-2 pluto[10428]: | 06 ce 0f c0 20 15 1e e4 12 d1 01 d1 2a ca b5 ca Aug 19 15:41:51 dcm-2 pluto[10428]: | 9b f0 df 12 fb d4 13 b6 e2 07 47 0d ca 97 79 b5 Aug 19 15:41:52 dcm-2 pluto[10428]: | 69 db d2 e9 59 ae a3 ef 91 6a 28 fe 30 44 cf 3c Aug 19 15:41:52 dcm-2 pluto[10428]: | e5 55 e4 19 04 a5 4e 12 15 7d 4e 18 c5 e2 f6 82 Aug 19 15:41:52 dcm-2 pluto[10428]: | 27 4d a1 c5 f5 60 7a d1 10 d7 ac 35 be a9 98 a0 Aug 19 15:41:52 dcm-2 pluto[10428]: | 2c ea de 78 a8 3c 40 a3 ac 37 51 37 93 94 40 67 Aug 19 15:41:52 dcm-2 pluto[10428]: | cb 6d d6 25 6b 9e 9a b4 70 4d d3 55 20 c4 48 de Aug 19 15:41:52 dcm-2 pluto[10428]: | 3b ed e8 b4 6e d8 36 06 4e b5 45 b5 a4 45 f0 c7 Aug 19 15:41:52 dcm-2 pluto[10428]: | d9 4c 18 de 69 3e c6 53 c0 5b 6e 6e 8a f7 09 89 Aug 19 15:41:52 dcm-2 pluto[10428]: | 64 51 7f 90 ac 14 fa 02 82 d8 bd 9c b7 0c 42 ab Aug 19 15:41:52 dcm-2 pluto[10428]: | 79 08 48 9c 3a 48 65 5e cd a3 30 29 57 6e 67 da Aug 19 15:41:52 dcm-2 pluto[10428]: | bd 7c 21 2d 2b 79 1c b9 05 ff 77 56 b9 72 2c a5 Aug 19 15:41:52 dcm-2 pluto[10428]: | 49 68 5b f5 e0 77 55 2f fb 7d ba aa cf e0 f1 63 Aug 19 15:41:52 dcm-2 pluto[10428]: | 61 ba bc 97 a9 5e 02 af 54 4b bc b1 51 8b 26 65 Aug 19 15:41:52 dcm-2 pluto[10428]: | d8 9d 9c ca 50 b5 7d a1 47 0a b8 6a 76 ab 01 1d Aug 19 15:41:52 dcm-2 pluto[10428]: | aa 51 44 55 fc 2a c1 ec 66 36 6c 90 e2 1c 77 30 Aug 19 15:41:52 dcm-2 pluto[10428]: | 51 c5 04 ac bc 2d c6 e4 f3 ba 6b 1b 80 46 df 1f Aug 19 15:41:52 dcm-2 pluto[10428]: | a0 46 97 15 9e 2a 81 72 d5 84 49 94 87 56 74 3f Aug 19 15:41:52 dcm-2 pluto[10428]: | 45 a2 34 04 b9 91 6f a6 22 06 c7 3c Aug 19 15:41:52 dcm-2 pluto[10428]: | **parse ISAKMP Message: Aug 19 15:41:52 dcm-2 pluto[10428]: | initiator cookie: Aug 19 15:41:52 dcm-2 pluto[10428]: | d3 4d 55 e0 49 3a 31 83 Aug 19 15:41:52 dcm-2 pluto[10428]: | responder cookie: Aug 19 15:41:52 dcm-2 pluto[10428]: | 9d b8 6b 12 80 fe c5 5c Aug 19 15:41:52 dcm-2 pluto[10428]: | next payload type: ISAKMP_NEXT_HASH Aug 19 15:41:52 dcm-2 pluto[10428]: | ISAKMP version: ISAKMP Version 1.0 Aug 19 15:41:52 dcm-2 pluto[10428]: | exchange type: ISAKMP_XCHG_QUICK Aug 19 15:41:53 dcm-2 pluto[10428]: | flags: ISAKMP_FLAG_ENCRYPTION Aug 19 15:41:53 dcm-2 pluto[10428]: | message ID: 4b 28 45 c5 Aug 19 15:41:53 dcm-2 pluto[10428]: | length: 348 Aug 19 15:41:53 dcm-2 pluto[10428]: | ICOOKIE: d3 4d 55 e0 49 3a 31 83 Aug 19 15:41:53 dcm-2 pluto[10428]: | RCOOKIE: 9d b8 6b 12 80 fe c5 5c Aug 19 15:41:53 dcm-2 pluto[10428]: | peer: ac 12 c3 d9 Aug 19 15:41:53 dcm-2 pluto[10428]: | state hash entry 29 Aug 19 15:41:53 dcm-2 pluto[10428]: | state object #5 found, in STATE_QUICK_I1 Aug 19 15:41:53 dcm-2 pluto[10428]: | received encrypted packet from 172.18.195.217:500 Aug 19 15:41:53 dcm-2 pluto[10428]: | decrypting 320 bytes using algorithm OAKLEY_3DES_CBC Aug 19 15:41:53 dcm-2 pluto[10428]: | decrypted: Aug 19 15:41:53 dcm-2 pluto[10428]: | 01 00 00 14 e2 90 3c 8f ab 1d 63 e8 4f ad b2 d0 Aug 19 15:41:53 dcm-2 pluto[10428]: | 00 f0 9f be 0a 00 00 34 00 00 00 01 00 00 00 01 Aug 19 15:41:53 dcm-2 pluto[10428]: | 00 00 00 28 00 03 04 01 72 9f b5 44 00 00 00 1c Aug 19 15:41:53 dcm-2 pluto[10428]: | 00 03 00 00 80 03 00 05 80 04 00 01 80 01 00 01 Aug 19 15:41:53 dcm-2 pluto[10428]: | 80 02 70 80 80 05 00 01 04 00 00 14 49 34 25 af Aug 19 15:41:53 dcm-2 pluto[10428]: | 5d b7 69 eb 63 82 57 61 18 29 73 5c 05 00 00 c4 Aug 19 15:41:53 dcm-2 pluto[10428]: | f6 bc 45 1d d2 b1 68 ea 78 89 36 b5 48 9b 51 26 Aug 19 15:41:53 dcm-2 pluto[10428]: | 44 6e 74 0e 8e 17 26 a5 f3 c9 55 12 3a 45 08 9e Aug 19 15:41:53 dcm-2 pluto[10428]: | 1b 47 56 3f 05 7f 07 64 5d 9d ef 14 84 7d 6f 26 Aug 19 15:41:53 dcm-2 pluto[10428]: | 5e aa 1c 1f 99 fa 67 34 3f db cf 07 9b d6 92 28 Aug 19 15:41:53 dcm-2 pluto[10428]: | 9f 92 fe 12 a2 3c 0f a4 2f b8 a3 60 3d 57 9c 29 Aug 19 15:41:53 dcm-2 pluto[10428]: | af d5 6a 88 3e b6 ce ef b0 e8 42 e4 e3 29 1c c6 Aug 19 15:41:53 dcm-2 pluto[10428]: | e9 b0 d4 b2 07 ab 8c 69 d7 b4 cd d5 0d a0 3b 1e Aug 19 15:41:53 dcm-2 pluto[10428]: | e8 2b 0a b7 7f e3 79 27 53 3d c6 ef 22 a4 6e fd Aug 19 15:41:54 dcm-2 pluto[10428]: | 0f d7 01 40 90 5b ec 2f d6 89 6d 8d 5f 56 64 c2 Aug 19 15:41:54 dcm-2 pluto[10428]: | ce 42 d9 d8 36 20 e9 b7 9d 66 37 91 23 ad c3 93 Aug 19 15:41:54 dcm-2 pluto[10428]: | df 36 24 8f 0a 95 3f c8 02 ad 5a ca 94 e3 2d d7 Aug 19 15:41:54 dcm-2 pluto[10428]: | c1 2a 22 27 2c 21 69 77 35 b1 b4 27 fd 7d 6b c2 Aug 19 15:41:54 dcm-2 pluto[10428]: | 05 00 00 10 04 11 13 eb ac 12 c3 d8 ff ff ff ff Aug 19 15:41:54 dcm-2 pluto[10428]: | 00 00 00 10 04 11 13 eb ac 12 c3 d9 ff ff ff ff Aug 19 15:41:54 dcm-2 pluto[10428]: | next IV: b9 91 6f a6 22 06 c7 3c Aug 19 15:41:54 dcm-2 pluto[10428]: | ***parse ISAKMP Hash Payload: Aug 19 15:41:54 dcm-2 pluto[10428]: | next payload type: ISAKMP_NEXT_SA Aug 19 15:41:54 dcm-2 pluto[10428]: | length: 20 Aug 19 15:41:54 dcm-2 pluto[10428]: | ***parse ISAKMP Security Association Payload: Aug 19 15:41:54 dcm-2 pluto[10428]: | next payload type: ISAKMP_NEXT_NONCE Aug 19 15:41:54 dcm-2 pluto[10428]: | length: 52 Aug 19 15:41:54 dcm-2 pluto[10428]: | DOI: ISAKMP_DOI_IPSEC Aug 19 15:41:54 dcm-2 pluto[10428]: | ***parse ISAKMP Nonce Payload: Aug 19 15:41:54 dcm-2 pluto[10428]: | next payload type: ISAKMP_NEXT_KE Aug 19 15:41:54 dcm-2 pluto[10428]: | length: 20 Aug 19 15:41:54 dcm-2 pluto[10428]: | ***parse ISAKMP Key Exchange Payload: Aug 19 15:41:54 dcm-2 pluto[10428]: | next payload type: ISAKMP_NEXT_ID Aug 19 15:41:54 dcm-2 pluto[10428]: | length: 196 Aug 19 15:41:54 dcm-2 pluto[10428]: | ***parse ISAKMP Identification Payload (IPsec DOI): Aug 19 15:41:54 dcm-2 pluto[10428]: | next payload type: ISAKMP_NEXT_ID Aug 19 15:41:54 dcm-2 pluto[10428]: | length: 16 Aug 19 15:41:54 dcm-2 pluto[10428]: | ID type: ID_IPV4_ADDR_SUBNET Aug 19 15:41:54 dcm-2 pluto[10428]: | Protocol ID: 17 Aug 19 15:41:54 dcm-2 pluto[10428]: | port: 5099 Aug 19 15:41:54 dcm-2 pluto[10428]: | ***parse ISAKMP Identification Payload (IPsec DOI): Aug 19 15:41:55 dcm-2 pluto[10428]: | next payload type: ISAKMP_NEXT_NONE Aug 19 15:41:55 dcm-2 pluto[10428]: | length: 16 Aug 19 15:41:55 dcm-2 pluto[10428]: | ID type: ID_IPV4_ADDR_SUBNET Aug 19 15:41:55 dcm-2 pluto[10428]: | Protocol ID: 17 Aug 19 15:41:55 dcm-2 pluto[10428]: | port: 5099 Aug 19 15:41:55 dcm-2 pluto[10428]: | **emit ISAKMP Message: Aug 19 15:41:55 dcm-2 pluto[10428]: | initiator cookie: Aug 19 15:41:55 dcm-2 pluto[10428]: | d3 4d 55 e0 49 3a 31 83 Aug 19 15:41:55 dcm-2 pluto[10428]: | responder cookie: Aug 19 15:41:55 dcm-2 pluto[10428]: | 9d b8 6b 12 80 fe c5 5c Aug 19 15:41:55 dcm-2 pluto[10428]: | next payload type: ISAKMP_NEXT_HASH Aug 19 15:41:55 dcm-2 pluto[10428]: | ISAKMP version: ISAKMP Version 1.0 Aug 19 15:41:55 dcm-2 pluto[10428]: | exchange type: ISAKMP_XCHG_QUICK Aug 19 15:41:55 dcm-2 pluto[10428]: | flags: ISAKMP_FLAG_ENCRYPTION Aug 19 15:41:55 dcm-2 pluto[10428]: | message ID: 4b 28 45 c5 Aug 19 15:41:55 dcm-2 pluto[10428]: | HASH(2) computed: Aug 19 15:41:55 dcm-2 pluto[10428]: | e2 90 3c 8f ab 1d 63 e8 4f ad b2 d0 00 f0 9f be Aug 19 15:41:55 dcm-2 pluto[10428]: | ****parse IPsec DOI SIT: Aug 19 15:41:55 dcm-2 pluto[10428]: | IPsec DOI SIT: SIT_IDENTITY_ONLY Aug 19 15:41:55 dcm-2 pluto[10428]: | ****parse ISAKMP Proposal Payload: Aug 19 15:41:55 dcm-2 pluto[10428]: | next payload type: ISAKMP_NEXT_NONE Aug 19 15:41:55 dcm-2 pluto[10428]: | length: 40 Aug 19 15:41:55 dcm-2 pluto[10428]: | proposal number: 0 Aug 19 15:41:55 dcm-2 pluto[10428]: | protocol ID: PROTO_IPSEC_ESP Aug 19 15:41:55 dcm-2 pluto[10428]: | SPI size: 4 Aug 19 15:41:55 dcm-2 pluto[10428]: | number of transforms: 1 Aug 19 15:41:56 dcm-2 pluto[10428]: | parsing 4 raw bytes of ISAKMP Proposal Payload into SPI Aug 19 15:41:56 dcm-2 pluto[10428]: | SPI 72 9f b5 44 Aug 19 15:41:56 dcm-2 pluto[10428]: | *****parse ISAKMP Transform Payload (ESP): Aug 19 15:41:56 dcm-2 pluto[10428]: | next payload type: ISAKMP_NEXT_NONE Aug 19 15:41:56 dcm-2 pluto[10428]: | length: 28 Aug 19 15:41:56 dcm-2 pluto[10428]: | transform number: 0 Aug 19 15:41:56 dcm-2 pluto[10428]: | transform ID: ESP_3DES Aug 19 15:41:56 dcm-2 pluto[10428]: | ******parse ISAKMP IPsec DOI attribute: Aug 19 15:41:56 dcm-2 pluto[10428]: | af+type: GROUP_DESCRIPTION Aug 19 15:41:56 dcm-2 pluto[10428]: | length/value: 5 Aug 19 15:41:56 dcm-2 pluto[10428]: | [5 is OAKLEY_GROUP_MODP1536 (extension)] Aug 19 15:41:56 dcm-2 pluto[10428]: | ******parse ISAKMP IPsec DOI attribute: Aug 19 15:41:56 dcm-2 pluto[10428]: | af+type: ENCAPSULATION_MODE Aug 19 15:41:56 dcm-2 pluto[10428]: | length/value: 1 Aug 19 15:41:56 dcm-2 pluto[10428]: | [1 is ENCAPSULATION_MODE_TUNNEL] Aug 19 15:41:56 dcm-2 pluto[10428]: | ******parse ISAKMP IPsec DOI attribute: Aug 19 15:41:56 dcm-2 pluto[10428]: | af+type: SA_LIFE_TYPE Aug 19 15:41:56 dcm-2 pluto[10428]: | length/value: 1 Aug 19 15:41:56 dcm-2 pluto[10428]: | [1 is SA_LIFE_TYPE_SECONDS] Aug 19 15:41:56 dcm-2 pluto[10428]: | ******parse ISAKMP IPsec DOI attribute: Aug 19 15:41:56 dcm-2 pluto[10428]: | af+type: SA_LIFE_DURATION Aug 19 15:41:56 dcm-2 pluto[10428]: | length/value: 28800 Aug 19 15:41:56 dcm-2 pluto[10428]: | ******parse ISAKMP IPsec DOI attribute: Aug 19 15:41:56 dcm-2 pluto[10428]: | af+type: AUTH_ALGORITHM Aug 19 15:41:56 dcm-2 pluto[10428]: | length/value: 1 Aug 19 15:41:56 dcm-2 pluto[10428]: | [1 is AUTH_ALGORITHM_HMAC_MD5] Aug 19 15:41:57 dcm-2 pluto[10428]: | DH public value received: Aug 19 15:41:57 dcm-2 pluto[10428]: | f6 bc 45 1d d2 b1 68 ea 78 89 36 b5 48 9b 51 26 Aug 19 15:41:57 dcm-2 pluto[10428]: | 44 6e 74 0e 8e 17 26 a5 f3 c9 55 12 3a 45 08 9e Aug 19 15:41:57 dcm-2 pluto[10428]: | 1b 47 56 3f 05 7f 07 64 5d 9d ef 14 84 7d 6f 26 Aug 19 15:41:57 dcm-2 pluto[10428]: | 5e aa 1c 1f 99 fa 67 34 3f db cf 07 9b d6 92 28 Aug 19 15:41:57 dcm-2 pluto[10428]: | 9f 92 fe 12 a2 3c 0f a4 2f b8 a3 60 3d 57 9c 29 Aug 19 15:41:57 dcm-2 pluto[10428]: | af d5 6a 88 3e b6 ce ef b0 e8 42 e4 e3 29 1c c6 Aug 19 15:41:57 dcm-2 pluto[10428]: | e9 b0 d4 b2 07 ab 8c 69 d7 b4 cd d5 0d a0 3b 1e Aug 19 15:41:57 dcm-2 pluto[10428]: | e8 2b 0a b7 7f e3 79 27 53 3d c6 ef 22 a4 6e fd Aug 19 15:41:57 dcm-2 pluto[10428]: | 0f d7 01 40 90 5b ec 2f d6 89 6d 8d 5f 56 64 c2 Aug 19 15:41:57 dcm-2 pluto[10428]: | ce 42 d9 d8 36 20 e9 b7 9d 66 37 91 23 ad c3 93 Aug 19 15:41:57 dcm-2 pluto[10428]: | df 36 24 8f 0a 95 3f c8 02 ad 5a ca 94 e3 2d d7 Aug 19 15:41:57 dcm-2 pluto[10428]: | c1 2a 22 27 2c 21 69 77 35 b1 b4 27 fd 7d 6b c2 Aug 19 15:41:57 dcm-2 pluto[10428]: | DH shared secret: Aug 19 15:41:57 dcm-2 pluto[10428]: | 7d de 5b 1c 3c 11 57 4b b9 3c 0b 16 51 b4 3e 53 Aug 19 15:41:57 dcm-2 pluto[10428]: | b9 6d f5 dc 83 bf 5b 50 bb 9d 4e a1 f8 99 a9 70 Aug 19 15:41:57 dcm-2 pluto[10428]: | 6c 5c 7f 48 ed 46 9e 36 ae 4f 58 da 78 6c 8e 7d Aug 19 15:41:57 dcm-2 pluto[10428]: | ed 8b 3a 02 30 be 12 36 d3 74 be 36 8a f3 e2 10 Aug 19 15:41:57 dcm-2 pluto[10428]: | be 22 6a fb aa 9d 2f 04 81 c7 8f fa 70 34 94 e3 Aug 19 15:41:57 dcm-2 pluto[10428]: | 01 a5 ad 32 bb f8 97 28 a1 d3 d1 04 6b da e3 85 Aug 19 15:41:57 dcm-2 pluto[10428]: | 74 3f 25 75 e5 0c 8b 60 73 ce 94 5c b7 ec 45 88 Aug 19 15:41:57 dcm-2 pluto[10428]: | 81 c3 60 76 ab 9a ee a8 f7 6d 87 24 e7 8d 67 51 Aug 19 15:41:57 dcm-2 pluto[10428]: | 57 a6 d6 1c bc 67 d6 6b cf b5 e6 b7 4c 77 24 1d Aug 19 15:41:57 dcm-2 pluto[10428]: | 89 70 29 e1 9a b5 06 48 d4 44 48 76 79 c1 3a ea Aug 19 15:41:57 dcm-2 pluto[10428]: | 0d dc 81 74 6d 17 4d cd 3a 4d a1 47 e7 5d f5 55 Aug 19 15:41:57 dcm-2 pluto[10428]: | 0b 17 34 cb 57 62 ef 44 84 8e 6b 13 77 e9 68 73 Aug 19 15:41:58 dcm-2 pluto[10428]: | our client is subnet 172.18.195.216/32 Aug 19 15:41:58 dcm-2 pluto[10428]: | our client protocol/port is 17/5099 Aug 19 15:41:58 dcm-2 pluto[10428]: | peer client is subnet 172.18.195.217/32 Aug 19 15:41:58 dcm-2 pluto[10428]: | peer client protocol/port is 17/5099 Aug 19 15:41:58 dcm-2 pluto[10428]: | ***emit ISAKMP Hash Payload: Aug 19 15:41:58 dcm-2 pluto[10428]: | next payload type: ISAKMP_NEXT_NONE Aug 19 15:41:58 dcm-2 pluto[10428]: | emitting 16 zero bytes of HASH into ISAKMP Hash Payload Aug 19 15:41:58 dcm-2 pluto[10428]: | emitting length of ISAKMP Hash Payload: 20 Aug 19 15:41:58 dcm-2 pluto[10428]: | HASH(3) computed: 03 02 bf 4c 5a 32 3b 30 03 52 1d a2 4b 9c c3 60 Aug 19 15:41:58 dcm-2 pluto[10428]: | KEYMAT computed: Aug 19 15:41:58 dcm-2 pluto[10428]: | 17 31 df 9e 1f bc 9a 53 27 e3 62 50 d0 aa c3 c2 Aug 19 15:41:58 dcm-2 pluto[10428]: | f9 5e 3c 69 17 ee 59 0c 09 a1 2f 38 d6 73 e0 3e Aug 19 15:41:58 dcm-2 pluto[10428]: | 2d 85 23 5d f9 d8 7b f5 Aug 19 15:41:58 dcm-2 pluto[10428]: | Peer KEYMAT computed: Aug 19 15:41:58 dcm-2 pluto[10428]: | 68 63 43 b8 b3 00 c6 16 7e 0a eb ce f3 4c 63 2b Aug 19 15:41:58 dcm-2 pluto[10428]: | 40 80 fc 6d 80 5f c7 6b 50 dd 82 9c f3 b5 94 ce Aug 19 15:41:58 dcm-2 pluto[10428]: | b3 49 07 92 cd 37 d0 c3 Aug 19 15:41:58 dcm-2 pluto[10428]: | route owner of "sample" CK_PERMANENT unrouted: NULL; eroute owner: NULL Aug 19 15:41:58 dcm-2 pluto[10428]: | finish_pfkey_msg: SADB_ADD message 95 for Add ESP SA [EMAIL PROTECTED] Aug 19 15:41:58 dcm-2 pluto[10428]: | 02 03 00 03 11 00 00 00 5f 00 00 00 bc 28 00 00 Aug 19 15:41:58 dcm-2 pluto[10428]: | 02 00 01 00 0e 2d 40 17 40 01 02 03 00 00 00 00 Aug 19 15:41:58 dcm-2 pluto[10428]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:41:58 dcm-2 pluto[10428]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:41:58 dcm-2 pluto[10428]: | 02 00 13 eb ac 12 c3 d8 00 00 00 00 00 00 00 00 Aug 19 15:41:58 dcm-2 pluto[10428]: | 03 00 08 00 80 00 00 00 09 a1 2f 38 d6 73 e0 3e Aug 19 15:41:58 dcm-2 pluto[10428]: | 2d 85 23 5d f9 d8 7b f5 04 00 09 00 c0 00 00 00 Aug 19 15:41:59 dcm-2 pluto[10428]: | 17 31 df 9e 1f bc 9a 53 27 e3 62 50 d0 aa c3 c2 Aug 19 15:41:59 dcm-2 pluto[10428]: | f9 5e 3c 69 17 ee 59 0c Aug 19 15:41:59 dcm-2 pluto[10428]: | pfkey_get: SADB_ADD message 95 Aug 19 15:41:59 dcm-2 pluto[10428]: | finish_pfkey_msg: SADB_ADD message 96 for Add IPIP SA [EMAIL PROTECTED] Aug 19 15:41:59 dcm-2 pluto[10428]: | 02 03 00 09 0a 00 00 00 60 00 00 00 bc 28 00 00 Aug 19 15:41:59 dcm-2 pluto[10428]: | 02 00 01 00 00 00 10 15 00 01 00 00 00 00 00 00 Aug 19 15:41:59 dcm-2 pluto[10428]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:41:59 dcm-2 pluto[10428]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:41:59 dcm-2 pluto[10428]: | 02 00 13 eb ac 12 c3 d8 00 00 00 00 00 00 00 00 Aug 19 15:41:59 dcm-2 pluto[10428]: | pfkey_get: SADB_ADD message 96 Aug 19 15:41:59 dcm-2 pluto[10428]: | grouping [EMAIL PROTECTED] and [EMAIL PROTECTED] Aug 19 15:41:59 dcm-2 pluto[10428]: | finish_pfkey_msg: SADB_X_GRPSA message 97 for group [EMAIL PROTECTED] Aug 19 15:41:59 dcm-2 pluto[10428]: | 02 0d 00 09 0d 00 00 00 61 00 00 00 bc 28 00 00 Aug 19 15:41:59 dcm-2 pluto[10428]: | 02 00 01 00 00 00 10 15 00 00 00 00 00 00 00 00 Aug 19 15:41:59 dcm-2 pluto[10428]: | 03 00 06 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:41:59 dcm-2 pluto[10428]: | 00 00 00 00 00 00 00 00 01 00 12 00 03 00 00 00 Aug 19 15:41:59 dcm-2 pluto[10428]: | 02 00 13 00 0e 2d 40 17 00 00 00 00 00 00 00 00 Aug 19 15:41:59 dcm-2 pluto[10428]: | 03 00 14 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:41:59 dcm-2 pluto[10428]: | 00 00 00 00 00 00 00 00 Aug 19 15:41:59 dcm-2 pluto[10428]: | pfkey_get: SADB_X_GRPSA message 97 Aug 19 15:41:59 dcm-2 pluto[10428]: | finish_pfkey_msg: SADB_ADD message 98 for Add ESP SA [EMAIL PROTECTED] Aug 19 15:41:59 dcm-2 pluto[10428]: | 02 03 00 03 11 00 00 00 62 00 00 00 bc 28 00 00 Aug 19 15:41:59 dcm-2 pluto[10428]: | 02 00 01 00 72 9f b5 44 40 01 02 03 00 00 00 00 Aug 19 15:41:59 dcm-2 pluto[10428]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:41:59 dcm-2 pluto[10428]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:41:59 dcm-2 pluto[10428]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:42:00 dcm-2 pluto[10428]: | 03 00 08 00 80 00 00 00 50 dd 82 9c f3 b5 94 ce Aug 19 15:42:00 dcm-2 pluto[10428]: | b3 49 07 92 cd 37 d0 c3 04 00 09 00 c0 00 00 00 Aug 19 15:42:00 dcm-2 pluto[10428]: | 68 63 43 b8 b3 00 c6 16 7e 0a eb ce f3 4c 63 2b Aug 19 15:42:00 dcm-2 pluto[10428]: | 40 80 fc 6d 80 5f c7 6b Aug 19 15:42:00 dcm-2 pluto[10428]: | pfkey_get: SADB_ADD message 98 Aug 19 15:42:00 dcm-2 pluto[10428]: | finish_pfkey_msg: SADB_ADD message 99 for Add IPIP SA [EMAIL PROTECTED] Aug 19 15:42:00 dcm-2 pluto[10428]: | 02 03 00 09 0a 00 00 00 63 00 00 00 bc 28 00 00 Aug 19 15:42:00 dcm-2 pluto[10428]: | 02 00 01 00 00 00 10 16 00 01 00 00 00 00 00 00 Aug 19 15:42:00 dcm-2 pluto[10428]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:42:00 dcm-2 pluto[10428]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:42:00 dcm-2 pluto[10428]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:42:00 dcm-2 pluto[10428]: | pfkey_get: SADB_ADD message 99 Aug 19 15:42:00 dcm-2 pluto[10428]: | grouping [EMAIL PROTECTED] and [EMAIL PROTECTED] Aug 19 15:42:00 dcm-2 pluto[10428]: | finish_pfkey_msg: SADB_X_GRPSA message 100 for group [EMAIL PROTECTED] Aug 19 15:42:00 dcm-2 pluto[10428]: | 02 0d 00 09 0d 00 00 00 64 00 00 00 bc 28 00 00 Aug 19 15:42:00 dcm-2 pluto[10428]: | 02 00 01 00 00 00 10 16 00 00 00 00 00 00 00 00 Aug 19 15:42:00 dcm-2 pluto[10428]: | 03 00 06 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:42:00 dcm-2 pluto[10428]: | 00 00 00 00 00 00 00 00 01 00 12 00 03 00 00 00 Aug 19 15:42:00 dcm-2 pluto[10428]: | 02 00 13 00 72 9f b5 44 00 00 00 00 00 00 00 00 Aug 19 15:42:00 dcm-2 pluto[10428]: | 03 00 14 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:42:00 dcm-2 pluto[10428]: | 00 00 00 00 00 00 00 00 Aug 19 15:42:00 dcm-2 pluto[10428]: | pfkey_get: SADB_X_GRPSA message 100 Aug 19 15:42:00 dcm-2 pluto[10428]: | route owner of "sample" CK_PERMANENT unrouted: NULL; eroute owner: NULL Aug 19 15:42:00 dcm-2 pluto[10428]: | add eroute 172.18.195.216/32:5099 -> 172.18.195.217/32:5099 => [EMAIL PROTECTED]:17 Aug 19 15:42:00 dcm-2 pluto[10428]: | finish_pfkey_msg: SADB_X_ADDFLOW message 101 for flow [EMAIL PROTECTED] Aug 19 15:42:01 dcm-2 pluto[10428]: | 02 0e 00 09 17 00 00 00 65 00 00 00 bc 28 00 00 Aug 19 15:42:01 dcm-2 pluto[10428]: | 02 00 01 00 00 00 10 16 00 00 00 00 00 00 00 00 Aug 19 15:42:01 dcm-2 pluto[10428]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:42:01 dcm-2 pluto[10428]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:42:01 dcm-2 pluto[10428]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:42:01 dcm-2 pluto[10428]: | 03 00 15 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:42:01 dcm-2 pluto[10428]: | 00 00 00 00 00 00 00 00 03 00 16 00 00 00 00 00 Aug 19 15:42:01 dcm-2 pluto[10428]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:42:01 dcm-2 pluto[10428]: | 03 00 17 00 00 00 00 00 02 00 ff ff ff ff ff ff Aug 19 15:42:01 dcm-2 pluto[10428]: | 00 00 00 00 00 00 00 00 03 00 18 00 00 00 00 00 Aug 19 15:42:01 dcm-2 pluto[10428]: | 02 00 ff ff ff ff ff ff 32 31 37 00 00 00 00 00 Aug 19 15:42:01 dcm-2 pluto[10428]: | 01 00 1a 00 11 df 00 00 Aug 19 15:42:01 dcm-2 pluto[10428]: ERROR: "sample" #5: pfkey write() of SADB_X_ADDFLOW message 101 for flow [EMAIL PROTECTED] failed. Errno 22: Invalid argument Aug 19 15:42:01 dcm-2 pluto[10428]: | delete [EMAIL PROTECTED] Aug 19 15:42:01 dcm-2 pluto[10428]: | finish_pfkey_msg: SADB_DELETE message 102 for Delete SA [EMAIL PROTECTED] Aug 19 15:42:01 dcm-2 pluto[10428]: | 02 04 00 03 0a 00 00 00 66 00 00 00 bc 28 00 00 Aug 19 15:42:01 dcm-2 pluto[10428]: | 02 00 01 00 72 9f b5 44 00 01 00 00 00 00 00 00 Aug 19 15:42:01 dcm-2 pluto[10428]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:42:01 dcm-2 pluto[10428]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:42:01 dcm-2 pluto[10428]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:42:01 dcm-2 pluto[10428]: | pfkey_get: SADB_X_ADDFLOW message 101 Aug 19 15:42:01 dcm-2 pluto[10428]: | pfkey_get: SADB_DELETE message 102 Aug 19 15:42:01 dcm-2 pluto[10428]: | delete [EMAIL PROTECTED] Aug 19 15:42:01 dcm-2 pluto[10428]: | finish_pfkey_msg: SADB_DELETE message 103 for Delete SA [EMAIL PROTECTED] Aug 19 15:42:01 dcm-2 pluto[10428]: | 02 04 00 03 0a 00 00 00 67 00 00 00 bc 28 00 00 Aug 19 15:42:01 dcm-2 pluto[10428]: | 02 00 01 00 0e 2d 40 17 00 01 00 00 00 00 00 00 Aug 19 15:42:02 dcm-2 pluto[10428]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:42:02 dcm-2 pluto[10428]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:42:02 dcm-2 pluto[10428]: | 02 00 13 eb ac 12 c3 d8 00 00 00 00 00 00 00 00 Aug 19 15:42:02 dcm-2 pluto[10428]: | pfkey_get: SADB_DELETE message 103 Aug 19 15:42:02 dcm-2 pluto[10428]: | state transition function for STATE_QUICK_I1 had internal error Aug 19 15:42:02 dcm-2 pluto[10428]: | next event EVENT_RETRANSMIT in 9 seconds for #5 Aug 19 15:42:02 dcm-2 pluto[10428]: | pfkey_async: SADB_X_ADDFLOW len=2, errno=22, satype=9, seq=101, pid=10428 Aug 19 15:42:02 dcm-2 pluto[10428]: | Aug 19 15:42:02 dcm-2 pluto[10428]: | *received whack message Aug 19 15:42:02 dcm-2 pluto[10428]: shutting down Aug 19 15:42:02 dcm-2 pluto[10428]: forgetting secrets Aug 19 15:42:02 dcm-2 pluto[10428]: "sample": deleting connection Aug 19 15:42:02 dcm-2 pluto[10428]: "sample" #5: deleting state (STATE_QUICK_I1) Aug 19 15:42:02 dcm-2 pluto[10428]: | ICOOKIE: d3 4d 55 e0 49 3a 31 83 Aug 19 15:42:02 dcm-2 pluto[10428]: | RCOOKIE: 9d b8 6b 12 80 fe c5 5c Aug 19 15:42:02 dcm-2 pluto[10428]: | peer: ac 12 c3 d9 Aug 19 15:42:02 dcm-2 pluto[10428]: | state hash entry 29 Aug 19 15:42:02 dcm-2 pluto[10428]: "sample" #1: deleting state (STATE_MAIN_I4) Aug 19 15:42:02 dcm-2 pluto[10428]: | ICOOKIE: d3 4d 55 e0 49 3a 31 83 Aug 19 15:42:02 dcm-2 pluto[10428]: | RCOOKIE: 9d b8 6b 12 80 fe c5 5c Aug 19 15:42:02 dcm-2 pluto[10428]: | peer: ac 12 c3 d9 Aug 19 15:42:02 dcm-2 pluto[10428]: | state hash entry 29 Aug 19 15:42:02 dcm-2 pluto[10428]: shutting down interface ipsec0/eth0 172.18.195.216 Aug 19 15:42:04 dcm-2 ipsec__plutorun: Starting Pluto subsystem... Aug 19 15:42:04 dcm-2 pluto[11830]: Starting Pluto (FreeS/WAN Version 1.99) Aug 19 15:42:04 dcm-2 pluto[11830]: including X.509 patch with traffic selectors (Version 0.9.34) Aug 19 15:42:04 dcm-2 pluto[11830]: | opening /dev/urandom Aug 19 15:42:04 dcm-2 pluto[11830]: | inserting event EVENT_REINIT_SECRET, timeout in 3600 seconds Aug 19 15:42:04 dcm-2 pluto[11830]: | process 11830 listening for PF_KEY_V2 on file descriptor 6 Aug 19 15:42:04 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_REGISTER message 1 for AH Aug 19 15:42:04 dcm-2 pluto[11830]: | 02 07 00 02 02 00 00 00 01 00 00 00 36 2e 00 00 Aug 19 15:42:04 dcm-2 pluto[11830]: | pfkey_get: SADB_REGISTER message 1 Aug 19 15:42:04 dcm-2 pluto[11830]: | AH registered with kernel. Aug 19 15:42:04 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_REGISTER message 2 for ESP Aug 19 15:42:05 dcm-2 pluto[11830]: | 02 07 00 03 02 00 00 00 02 00 00 00 36 2e 00 00 Aug 19 15:42:05 dcm-2 pluto[11830]: | pfkey_get: SADB_REGISTER message 2 Aug 19 15:42:05 dcm-2 pluto[11830]: | ESP registered with kernel. Aug 19 15:42:05 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_REGISTER message 3 for IPCOMP Aug 19 15:42:05 dcm-2 pluto[11830]: | 02 07 00 0a 02 00 00 00 03 00 00 00 36 2e 00 00 Aug 19 15:42:05 dcm-2 pluto[11830]: | pfkey_get: SADB_REGISTER message 3 Aug 19 15:42:05 dcm-2 pluto[11830]: | IPCOMP registered with kernel. Aug 19 15:42:05 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_REGISTER message 4 for IPIP Aug 19 15:42:05 dcm-2 pluto[11830]: | 02 07 00 09 02 00 00 00 04 00 00 00 36 2e 00 00 Aug 19 15:42:05 dcm-2 pluto[11830]: | pfkey_get: SADB_REGISTER message 4 Aug 19 15:42:05 dcm-2 pluto[11830]: | IPIP registered with kernel. Aug 19 15:42:05 dcm-2 pluto[11830]: | inserting event EVENT_SHUNT_SCAN, timeout in 120 seconds Aug 19 15:42:05 dcm-2 pluto[11830]: Changing to directory '/etc/ipsec.d/cacerts' Aug 19 15:42:05 dcm-2 pluto[11830]: Warning: empty directory Aug 19 15:42:05 dcm-2 pluto[11830]: Changing to directory '/etc/ipsec.d/crls' Aug 19 15:42:05 dcm-2 pluto[11830]: Warning: empty directory Aug 19 15:42:05 dcm-2 pluto[11830]: OpenPGP certificate file '/etc/pgpcert.pgp' not found Aug 19 15:42:05 dcm-2 pluto[11830]: | next event EVENT_SHUNT_SCAN in 120 seconds Aug 19 15:42:05 dcm-2 pluto[11830]: | Aug 19 15:42:05 dcm-2 pluto[11830]: | *received whack message Aug 19 15:42:06 dcm-2 pluto[11830]: | next event EVENT_SHUNT_SCAN in 119 seconds Aug 19 15:42:06 dcm-2 pluto[11830]: | Aug 19 15:42:06 dcm-2 pluto[11830]: | *received whack message Aug 19 15:42:06 dcm-2 pluto[11830]: | next event EVENT_SHUNT_SCAN in 119 seconds Aug 19 15:42:06 dcm-2 pluto[11830]: | Aug 19 15:42:06 dcm-2 pluto[11830]: | *received whack message Aug 19 15:42:06 dcm-2 pluto[11830]: added connection description "sample" Aug 19 15:42:06 dcm-2 pluto[11830]: | [EMAIL PROTECTED]:17/[EMAIL PROTECTED]:17/5099 Aug 19 15:42:06 dcm-2 pluto[11830]: | ike_life: 3600s; ipsec_life: 28800s; rekey_margin: 540s; rekey_fuzz: 100%; keyingtries: 0; policy: RSASIG+ENCRYPT+TUNNEL+PFS+DISABLEARRIVALCHECK Aug 19 15:42:06 dcm-2 pluto[11830]: | next event EVENT_SHUNT_SCAN in 119 seconds Aug 19 15:42:06 dcm-2 pluto[11830]: | Aug 19 15:42:06 dcm-2 pluto[11830]: | *received whack message Aug 19 15:42:06 dcm-2 pluto[11830]: listening for IKE messages Aug 19 15:42:06 dcm-2 pluto[11830]: | found lo with address 127.0.0.1 Aug 19 15:42:06 dcm-2 pluto[11830]: | found eth0 with address 172.18.195.216 Aug 19 15:42:06 dcm-2 pluto[11830]: | found ipsec0 with address 172.18.195.216 Aug 19 15:42:06 dcm-2 pluto[11830]: adding interface ipsec0/eth0 172.18.195.216 Aug 19 15:42:06 dcm-2 pluto[11830]: | IP interface lo 127.0.0.1 has no matching ipsec* interface -- ignored Aug 19 15:42:06 dcm-2 pluto[11830]: | could not open /proc/net/if_inet6 Aug 19 15:42:06 dcm-2 pluto[11830]: loading secrets from "/etc/ipsec.secrets" Aug 19 15:42:07 dcm-2 pluto[11830]: | next event EVENT_SHUNT_SCAN in 118 seconds Aug 19 15:42:09 dcm-2 pluto[11830]: | Aug 19 15:42:09 dcm-2 pluto[11830]: | *received 348 bytes from 172.18.195.217:500 on eth0 Aug 19 15:42:09 dcm-2 pluto[11830]: | ICOOKIE: d3 4d 55 e0 49 3a 31 83 Aug 19 15:42:09 dcm-2 pluto[11830]: | RCOOKIE: 9d b8 6b 12 80 fe c5 5c Aug 19 15:42:09 dcm-2 pluto[11830]: | peer: ac 12 c3 d9 Aug 19 15:42:09 dcm-2 pluto[11830]: | state hash entry 29 Aug 19 15:42:09 dcm-2 pluto[11830]: | state object not found Aug 19 15:42:09 dcm-2 pluto[11830]: | ICOOKIE: d3 4d 55 e0 49 3a 31 83 Aug 19 15:42:09 dcm-2 pluto[11830]: | RCOOKIE: 9d b8 6b 12 80 fe c5 5c Aug 19 15:42:09 dcm-2 pluto[11830]: | peer: ac 12 c3 d9 Aug 19 15:42:10 dcm-2 pluto[11830]: | state hash entry 29 Aug 19 15:42:10 dcm-2 pluto[11830]: | state object not found Aug 19 15:42:10 dcm-2 pluto[11830]: packet from 172.18.195.217:500: Quick Mode message is for a non-existent (expired?) ISAKMP SA Aug 19 15:42:10 dcm-2 pluto[11830]: | next event EVENT_SHUNT_SCAN in 115 seconds Aug 19 15:42:58 dcm-2 pluto[11830]: | Aug 19 15:42:58 dcm-2 pluto[11830]: | *received whack message Aug 19 15:42:58 dcm-2 pluto[11830]: | creating state object #1 at 0x80aa930 Aug 19 15:42:58 dcm-2 pluto[11830]: | ICOOKIE: b6 a4 3b 8d d4 43 82 7c Aug 19 15:42:58 dcm-2 pluto[11830]: | RCOOKIE: 00 00 00 00 00 00 00 00 Aug 19 15:42:58 dcm-2 pluto[11830]: | peer: ac 12 c3 d9 Aug 19 15:42:58 dcm-2 pluto[11830]: | state hash entry 23 Aug 19 15:42:58 dcm-2 pluto[11830]: | inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #1 Aug 19 15:42:58 dcm-2 pluto[11830]: | Queuing pending Quick Mode with 172.18.195.217 "sample" Aug 19 15:42:58 dcm-2 pluto[11830]: "sample" #1: initiating Main Mode Aug 19 15:42:58 dcm-2 pluto[11830]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #1 Aug 19 15:42:58 dcm-2 pluto[11830]: | next event EVENT_RETRANSMIT in 10 seconds for #1 Aug 19 15:42:58 dcm-2 pluto[11830]: | Aug 19 15:42:58 dcm-2 pluto[11830]: | *received 80 bytes from 172.18.195.217:500 on eth0 Aug 19 15:42:58 dcm-2 pluto[11830]: | ICOOKIE: b6 a4 3b 8d d4 43 82 7c Aug 19 15:42:58 dcm-2 pluto[11830]: | RCOOKIE: 01 e6 92 61 41 c2 76 b8 Aug 19 15:42:58 dcm-2 pluto[11830]: | peer: ac 12 c3 d9 Aug 19 15:42:58 dcm-2 pluto[11830]: | state hash entry 14 Aug 19 15:42:58 dcm-2 pluto[11830]: | state object not found Aug 19 15:42:58 dcm-2 pluto[11830]: | ICOOKIE: b6 a4 3b 8d d4 43 82 7c Aug 19 15:42:58 dcm-2 pluto[11830]: | RCOOKIE: 00 00 00 00 00 00 00 00 Aug 19 15:42:58 dcm-2 pluto[11830]: | peer: ac 12 c3 d9 Aug 19 15:42:58 dcm-2 pluto[11830]: | state hash entry 23 Aug 19 15:42:58 dcm-2 pluto[11830]: | state object #1 found, in STATE_MAIN_I1 Aug 19 15:42:58 dcm-2 pluto[11830]: | ICOOKIE: b6 a4 3b 8d d4 43 82 7c Aug 19 15:42:58 dcm-2 pluto[11830]: | RCOOKIE: 00 00 00 00 00 00 00 00 Aug 19 15:42:58 dcm-2 pluto[11830]: | peer: ac 12 c3 d9 Aug 19 15:42:58 dcm-2 pluto[11830]: | state hash entry 23 Aug 19 15:42:58 dcm-2 pluto[11830]: | ICOOKIE: b6 a4 3b 8d d4 43 82 7c Aug 19 15:42:58 dcm-2 pluto[11830]: | RCOOKIE: 01 e6 92 61 41 c2 76 b8 Aug 19 15:42:58 dcm-2 pluto[11830]: | peer: ac 12 c3 d9 Aug 19 15:42:58 dcm-2 pluto[11830]: | state hash entry 14 Aug 19 15:42:58 dcm-2 pluto[11830]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #1 Aug 19 15:42:58 dcm-2 pluto[11830]: | next event EVENT_RETRANSMIT in 10 seconds for #1 Aug 19 15:42:58 dcm-2 pluto[11830]: | Aug 19 15:42:58 dcm-2 pluto[11830]: | *received 244 bytes from 172.18.195.217:500 on eth0 Aug 19 15:42:59 dcm-2 pluto[11830]: | ICOOKIE: b6 a4 3b 8d d4 43 82 7c Aug 19 15:42:59 dcm-2 pluto[11830]: | RCOOKIE: 01 e6 92 61 41 c2 76 b8 Aug 19 15:42:59 dcm-2 pluto[11830]: | peer: ac 12 c3 d9 Aug 19 15:42:59 dcm-2 pluto[11830]: | state hash entry 14 Aug 19 15:42:59 dcm-2 pluto[11830]: | state object #1 found, in STATE_MAIN_I2 Aug 19 15:42:59 dcm-2 pluto[11830]: | signing hash with RSA Key *AQO4NXeNX Aug 19 15:42:59 dcm-2 pluto[11830]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #1 Aug 19 15:42:59 dcm-2 pluto[11830]: | next event EVENT_RETRANSMIT in 10 seconds for #1 Aug 19 15:42:59 dcm-2 pluto[11830]: | Aug 19 15:42:59 dcm-2 pluto[11830]: | *received 332 bytes from 172.18.195.217:500 on eth0 Aug 19 15:42:59 dcm-2 pluto[11830]: | ICOOKIE: b6 a4 3b 8d d4 43 82 7c Aug 19 15:42:59 dcm-2 pluto[11830]: | RCOOKIE: 01 e6 92 61 41 c2 76 b8 Aug 19 15:42:59 dcm-2 pluto[11830]: | peer: ac 12 c3 d9 Aug 19 15:42:59 dcm-2 pluto[11830]: | state hash entry 14 Aug 19 15:42:59 dcm-2 pluto[11830]: | state object #1 found, in STATE_MAIN_I3 Aug 19 15:42:59 dcm-2 pluto[11830]: "sample" #1: Peer ID is ID_FQDN: '@dcm-3.cisco.com' Aug 19 15:42:59 dcm-2 pluto[11830]: | required CA is '%any' Aug 19 15:42:59 dcm-2 pluto[11830]: | key issuer CA is '%any' Aug 19 15:42:59 dcm-2 pluto[11830]: | an RSA Sig check passed with *AQPEYWpEN [preloaded key] Aug 19 15:42:59 dcm-2 pluto[11830]: | inserting event EVENT_SA_REPLACE, timeout in 2742 seconds for #1 Aug 19 15:42:59 dcm-2 pluto[11830]: "sample" #1: ISAKMP SA established Aug 19 15:42:59 dcm-2 pluto[11830]: | unqueuing pending Quick Mode with 172.18.195.217 "sample" Aug 19 15:42:59 dcm-2 pluto[11830]: | duplicating state object #1 Aug 19 15:42:59 dcm-2 pluto[11830]: | creating state object #2 at 0x80ab118 Aug 19 15:42:59 dcm-2 pluto[11830]: | ICOOKIE: b6 a4 3b 8d d4 43 82 7c Aug 19 15:42:59 dcm-2 pluto[11830]: | RCOOKIE: 01 e6 92 61 41 c2 76 b8 Aug 19 15:42:59 dcm-2 pluto[11830]: | peer: ac 12 c3 d9 Aug 19 15:42:59 dcm-2 pluto[11830]: | state hash entry 14 Aug 19 15:42:59 dcm-2 pluto[11830]: | inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #2 Aug 19 15:42:59 dcm-2 pluto[11830]: "sample" #2: initiating Quick Mode RSASIG+ENCRYPT+TUNNEL+PFS+DISABLEARRIVALCHECK Aug 19 15:42:59 dcm-2 pluto[11830]: | generate SPI: eb 08 f7 a8 Aug 19 15:42:59 dcm-2 pluto[11830]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #2 Aug 19 15:42:59 dcm-2 pluto[11830]: | next event EVENT_RETRANSMIT in 10 seconds for #2 Aug 19 15:43:00 dcm-2 pluto[11830]: | Aug 19 15:43:00 dcm-2 pluto[11830]: | *received 348 bytes from 172.18.195.217:500 on eth0 Aug 19 15:43:00 dcm-2 pluto[11830]: | ICOOKIE: b6 a4 3b 8d d4 43 82 7c Aug 19 15:43:00 dcm-2 pluto[11830]: | RCOOKIE: 01 e6 92 61 41 c2 76 b8 Aug 19 15:43:00 dcm-2 pluto[11830]: | peer: ac 12 c3 d9 Aug 19 15:43:00 dcm-2 pluto[11830]: | state hash entry 14 Aug 19 15:43:00 dcm-2 pluto[11830]: | state object #2 found, in STATE_QUICK_I1 Aug 19 15:43:00 dcm-2 pluto[11830]: | our client is subnet 172.18.195.216/32 Aug 19 15:43:00 dcm-2 pluto[11830]: | our client protocol/port is 17/5099 Aug 19 15:43:00 dcm-2 pluto[11830]: | peer client is subnet 172.18.195.217/32 Aug 19 15:43:00 dcm-2 pluto[11830]: | peer client protocol/port is 17/5099 Aug 19 15:43:00 dcm-2 pluto[11830]: | route owner of "sample" CK_PERMANENT unrouted: NULL; eroute owner: NULL Aug 19 15:43:00 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_ADD message 5 for Add ESP SA [EMAIL PROTECTED] Aug 19 15:43:00 dcm-2 pluto[11830]: | 02 03 00 03 11 00 00 00 05 00 00 00 36 2e 00 00 Aug 19 15:43:00 dcm-2 pluto[11830]: | 02 00 01 00 eb 08 f7 a8 40 01 02 03 00 00 00 00 Aug 19 15:43:00 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:43:00 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:00 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d8 00 00 00 00 00 00 00 00 Aug 19 15:43:00 dcm-2 pluto[11830]: | 03 00 08 00 80 00 00 00 fb 5e b5 62 16 4a f9 c0 Aug 19 15:43:00 dcm-2 pluto[11830]: | 70 97 df fc 07 05 09 6a 04 00 09 00 c0 00 00 00 Aug 19 15:43:00 dcm-2 pluto[11830]: | c8 3c 21 fc a8 7b 93 59 b0 bc 2c 3b 40 f4 ab 90 Aug 19 15:43:00 dcm-2 pluto[11830]: | 33 7d a9 99 fd 26 4c 7e Aug 19 15:43:00 dcm-2 pluto[11830]: | pfkey_get: SADB_ADD message 5 Aug 19 15:43:00 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_ADD message 6 for Add IPIP SA [EMAIL PROTECTED] Aug 19 15:43:00 dcm-2 pluto[11830]: | 02 03 00 09 0a 00 00 00 06 00 00 00 36 2e 00 00 Aug 19 15:43:00 dcm-2 pluto[11830]: | 02 00 01 00 00 00 10 01 00 01 00 00 00 00 00 00 Aug 19 15:43:01 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:43:01 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:01 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d8 00 00 00 00 00 00 00 00 Aug 19 15:43:01 dcm-2 pluto[11830]: | pfkey_get: SADB_ADD message 6 Aug 19 15:43:01 dcm-2 pluto[11830]: | grouping [EMAIL PROTECTED] and [EMAIL PROTECTED] Aug 19 15:43:01 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_X_GRPSA message 7 for group [EMAIL PROTECTED] Aug 19 15:43:01 dcm-2 pluto[11830]: | 02 0d 00 09 0d 00 00 00 07 00 00 00 36 2e 00 00 Aug 19 15:43:01 dcm-2 pluto[11830]: | 02 00 01 00 00 00 10 01 00 00 00 00 00 00 00 00 Aug 19 15:43:01 dcm-2 pluto[11830]: | 03 00 06 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:01 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 01 00 12 00 03 00 00 00 Aug 19 15:43:01 dcm-2 pluto[11830]: | 02 00 13 00 eb 08 f7 a8 00 00 00 00 00 00 00 00 Aug 19 15:43:01 dcm-2 pluto[11830]: | 03 00 14 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:01 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 Aug 19 15:43:01 dcm-2 pluto[11830]: | pfkey_get: SADB_X_GRPSA message 7 Aug 19 15:43:01 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_ADD message 8 for Add ESP SA [EMAIL PROTECTED] Aug 19 15:43:01 dcm-2 pluto[11830]: | 02 03 00 03 11 00 00 00 08 00 00 00 36 2e 00 00 Aug 19 15:43:01 dcm-2 pluto[11830]: | 02 00 01 00 72 9f b5 45 40 01 02 03 00 00 00 00 Aug 19 15:43:02 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:02 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:02 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:43:02 dcm-2 pluto[11830]: | 03 00 08 00 80 00 00 00 d3 48 dc c2 c2 44 2d de Aug 19 15:43:02 dcm-2 pluto[11830]: | 3c bd de 96 08 a1 0b 43 04 00 09 00 c0 00 00 00 Aug 19 15:43:02 dcm-2 pluto[11830]: | 51 3c 5b f1 b0 fc d1 eb 5e 96 ad bd f8 a3 c9 07 Aug 19 15:43:02 dcm-2 pluto[11830]: | f3 fa 68 de cc 6a 37 47 Aug 19 15:43:02 dcm-2 pluto[11830]: | pfkey_get: SADB_ADD message 8 Aug 19 15:43:02 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_ADD message 9 for Add IPIP SA [EMAIL PROTECTED] Aug 19 15:43:02 dcm-2 pluto[11830]: | 02 03 00 09 0a 00 00 00 09 00 00 00 36 2e 00 00 Aug 19 15:43:02 dcm-2 pluto[11830]: | 02 00 01 00 00 00 10 02 00 01 00 00 00 00 00 00 Aug 19 15:43:02 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:02 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:02 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:43:02 dcm-2 pluto[11830]: | pfkey_get: SADB_ADD message 9 Aug 19 15:43:02 dcm-2 pluto[11830]: | grouping [EMAIL PROTECTED] and [EMAIL PROTECTED] Aug 19 15:43:02 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_X_GRPSA message 10 for group [EMAIL PROTECTED] Aug 19 15:43:02 dcm-2 pluto[11830]: | 02 0d 00 09 0d 00 00 00 0a 00 00 00 36 2e 00 00 Aug 19 15:43:02 dcm-2 pluto[11830]: | 02 00 01 00 00 00 10 02 00 00 00 00 00 00 00 00 Aug 19 15:43:02 dcm-2 pluto[11830]: | 03 00 06 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:43:02 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 01 00 12 00 03 00 00 00 Aug 19 15:43:02 dcm-2 pluto[11830]: | 02 00 13 00 72 9f b5 45 00 00 00 00 00 00 00 00 Aug 19 15:43:02 dcm-2 pluto[11830]: | 03 00 14 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:43:02 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 Aug 19 15:43:02 dcm-2 pluto[11830]: | pfkey_get: SADB_X_GRPSA message 10 Aug 19 15:43:03 dcm-2 pluto[11830]: | route owner of "sample" CK_PERMANENT unrouted: NULL; eroute owner: NULL Aug 19 15:43:03 dcm-2 pluto[11830]: | add eroute 172.18.195.216/32:5099 -> 172.18.195.217/32:5099 => [EMAIL PROTECTED]:17 Aug 19 15:43:03 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_X_ADDFLOW message 11 for flow [EMAIL PROTECTED] Aug 19 15:43:03 dcm-2 pluto[11830]: | 02 0e 00 09 17 00 00 00 0b 00 00 00 36 2e 00 00 Aug 19 15:43:03 dcm-2 pluto[11830]: | 02 00 01 00 00 00 10 02 00 00 00 00 00 00 00 00 Aug 19 15:43:03 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:03 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:03 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:43:03 dcm-2 pluto[11830]: | 03 00 15 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:03 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 16 00 00 00 00 00 Aug 19 15:43:03 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:43:03 dcm-2 pluto[11830]: | 03 00 17 00 00 00 00 00 02 00 ff ff ff ff ff ff Aug 19 15:43:03 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 18 00 00 00 00 00 Aug 19 15:43:03 dcm-2 pluto[11830]: | 02 00 ff ff ff ff ff ff 32 31 37 00 00 00 00 00 Aug 19 15:43:03 dcm-2 pluto[11830]: | 01 00 1a 00 11 9c 00 00 Aug 19 15:43:03 dcm-2 pluto[11830]: ERROR: "sample" #2: pfkey write() of SADB_X_ADDFLOW message 11 for flow [EMAIL PROTECTED] failed. Errno 22: Invalid argument Aug 19 15:43:03 dcm-2 pluto[11830]: | delete [EMAIL PROTECTED] Aug 19 15:43:03 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_DELETE message 12 for Delete SA [EMAIL PROTECTED] Aug 19 15:43:03 dcm-2 pluto[11830]: | 02 04 00 03 0a 00 00 00 0c 00 00 00 36 2e 00 00 Aug 19 15:43:03 dcm-2 pluto[11830]: | 02 00 01 00 72 9f b5 45 00 01 00 00 00 00 00 00 Aug 19 15:43:03 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:03 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:03 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:43:03 dcm-2 pluto[11830]: | pfkey_get: SADB_X_ADDFLOW message 11 Aug 19 15:43:03 dcm-2 pluto[11830]: | pfkey_get: SADB_DELETE message 12 Aug 19 15:43:04 dcm-2 pluto[11830]: | delete [EMAIL PROTECTED] Aug 19 15:43:04 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_DELETE message 13 for Delete SA [EMAIL PROTECTED] Aug 19 15:43:04 dcm-2 pluto[11830]: | 02 04 00 03 0a 00 00 00 0d 00 00 00 36 2e 00 00 Aug 19 15:43:04 dcm-2 pluto[11830]: | 02 00 01 00 eb 08 f7 a8 00 01 00 00 00 00 00 00 Aug 19 15:43:04 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:43:04 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:04 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d8 00 00 00 00 00 00 00 00 Aug 19 15:43:04 dcm-2 pluto[11830]: | pfkey_get: SADB_DELETE message 13 Aug 19 15:43:04 dcm-2 pluto[11830]: | state transition function for STATE_QUICK_I1 had internal error Aug 19 15:43:04 dcm-2 pluto[11830]: | next event EVENT_RETRANSMIT in 5 seconds for #2 Aug 19 15:43:04 dcm-2 pluto[11830]: | pfkey_async: SADB_X_ADDFLOW len=2, errno=22, satype=9, seq=11, pid=11830 Aug 19 15:43:09 dcm-2 pluto[11830]: | Aug 19 15:43:09 dcm-2 pluto[11830]: | *time to handle event Aug 19 15:43:09 dcm-2 pluto[11830]: | event after this is EVENT_SHUNT_SCAN in 56 seconds Aug 19 15:43:09 dcm-2 pluto[11830]: | handling event EVENT_RETRANSMIT for 172.18.195.217 "sample" #2 Aug 19 15:43:09 dcm-2 pluto[11830]: | inserting event EVENT_RETRANSMIT, timeout in 20 seconds for #2 Aug 19 15:43:09 dcm-2 pluto[11830]: | next event EVENT_RETRANSMIT in 20 seconds for #2 Aug 19 15:43:10 dcm-2 pluto[11830]: | Aug 19 15:43:10 dcm-2 pluto[11830]: | *received 348 bytes from 172.18.195.217:500 on eth0 Aug 19 15:43:10 dcm-2 pluto[11830]: | ICOOKIE: b6 a4 3b 8d d4 43 82 7c Aug 19 15:43:10 dcm-2 pluto[11830]: | RCOOKIE: 01 e6 92 61 41 c2 76 b8 Aug 19 15:43:10 dcm-2 pluto[11830]: | peer: ac 12 c3 d9 Aug 19 15:43:10 dcm-2 pluto[11830]: | state hash entry 14 Aug 19 15:43:10 dcm-2 pluto[11830]: | state object #2 found, in STATE_QUICK_I1 Aug 19 15:43:11 dcm-2 pluto[11830]: | our client is subnet 172.18.195.216/32 Aug 19 15:43:11 dcm-2 pluto[11830]: | our client protocol/port is 17/5099 Aug 19 15:43:11 dcm-2 pluto[11830]: | peer client is subnet 172.18.195.217/32 Aug 19 15:43:11 dcm-2 pluto[11830]: | peer client protocol/port is 17/5099 Aug 19 15:43:11 dcm-2 pluto[11830]: | route owner of "sample" CK_PERMANENT unrouted: NULL; eroute owner: NULL Aug 19 15:43:11 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_ADD message 14 for Add ESP SA [EMAIL PROTECTED] Aug 19 15:43:11 dcm-2 pluto[11830]: | 02 03 00 03 11 00 00 00 0e 00 00 00 36 2e 00 00 Aug 19 15:43:11 dcm-2 pluto[11830]: | 02 00 01 00 eb 08 f7 a8 40 01 02 03 00 00 00 00 Aug 19 15:43:11 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:43:11 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:11 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d8 00 00 00 00 00 00 00 00 Aug 19 15:43:11 dcm-2 pluto[11830]: | 03 00 08 00 80 00 00 00 fb 5e b5 62 16 4a f9 c0 Aug 19 15:43:11 dcm-2 pluto[11830]: | 70 97 df fc 07 05 09 6a 04 00 09 00 c0 00 00 00 Aug 19 15:43:11 dcm-2 pluto[11830]: | c8 3c 21 fc a8 7b 93 59 b0 bc 2c 3b 40 f4 ab 90 Aug 19 15:43:11 dcm-2 pluto[11830]: | 33 7d a9 99 fd 26 4c 7e Aug 19 15:43:11 dcm-2 pluto[11830]: | pfkey_get: SADB_ADD message 14 Aug 19 15:43:11 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_ADD message 15 for Add IPIP SA [EMAIL PROTECTED] Aug 19 15:43:11 dcm-2 pluto[11830]: | 02 03 00 09 0a 00 00 00 0f 00 00 00 36 2e 00 00 Aug 19 15:43:11 dcm-2 pluto[11830]: | 02 00 01 00 00 00 10 03 00 01 00 00 00 00 00 00 Aug 19 15:43:12 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:43:12 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:12 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d8 00 00 00 00 00 00 00 00 Aug 19 15:43:12 dcm-2 pluto[11830]: | pfkey_get: SADB_ADD message 15 Aug 19 15:43:12 dcm-2 pluto[11830]: | grouping [EMAIL PROTECTED] and [EMAIL PROTECTED] Aug 19 15:43:12 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_X_GRPSA message 16 for group [EMAIL PROTECTED] Aug 19 15:43:12 dcm-2 pluto[11830]: | 02 0d 00 09 0d 00 00 00 10 00 00 00 36 2e 00 00 Aug 19 15:43:12 dcm-2 pluto[11830]: | 02 00 01 00 00 00 10 03 00 00 00 00 00 00 00 00 Aug 19 15:43:12 dcm-2 pluto[11830]: | 03 00 06 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:12 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 01 00 12 00 03 00 00 00 Aug 19 15:43:12 dcm-2 pluto[11830]: | 02 00 13 00 eb 08 f7 a8 00 00 00 00 00 00 00 00 Aug 19 15:43:12 dcm-2 pluto[11830]: | 03 00 14 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:12 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 Aug 19 15:43:12 dcm-2 pluto[11830]: | pfkey_get: SADB_X_GRPSA message 16 Aug 19 15:43:12 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_ADD message 17 for Add ESP SA [EMAIL PROTECTED] Aug 19 15:43:12 dcm-2 pluto[11830]: | 02 03 00 03 11 00 00 00 11 00 00 00 36 2e 00 00 Aug 19 15:43:12 dcm-2 pluto[11830]: | 02 00 01 00 72 9f b5 45 40 01 02 03 00 00 00 00 Aug 19 15:43:12 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:12 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:12 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:43:13 dcm-2 pluto[11830]: | 03 00 08 00 80 00 00 00 d3 48 dc c2 c2 44 2d de Aug 19 15:43:13 dcm-2 pluto[11830]: | 3c bd de 96 08 a1 0b 43 04 00 09 00 c0 00 00 00 Aug 19 15:43:13 dcm-2 pluto[11830]: | 51 3c 5b f1 b0 fc d1 eb 5e 96 ad bd f8 a3 c9 07 Aug 19 15:43:13 dcm-2 pluto[11830]: | f3 fa 68 de cc 6a 37 47 Aug 19 15:43:13 dcm-2 pluto[11830]: | pfkey_get: SADB_ADD message 17 Aug 19 15:43:13 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_ADD message 18 for Add IPIP SA [EMAIL PROTECTED] Aug 19 15:43:13 dcm-2 pluto[11830]: | 02 03 00 09 0a 00 00 00 12 00 00 00 36 2e 00 00 Aug 19 15:43:13 dcm-2 pluto[11830]: | 02 00 01 00 00 00 10 04 00 01 00 00 00 00 00 00 Aug 19 15:43:13 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:13 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:13 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:43:13 dcm-2 pluto[11830]: | pfkey_get: SADB_ADD message 18 Aug 19 15:43:13 dcm-2 pluto[11830]: | grouping [EMAIL PROTECTED] and [EMAIL PROTECTED] Aug 19 15:43:13 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_X_GRPSA message 19 for group [EMAIL PROTECTED] Aug 19 15:43:13 dcm-2 pluto[11830]: | 02 0d 00 09 0d 00 00 00 13 00 00 00 36 2e 00 00 Aug 19 15:43:13 dcm-2 pluto[11830]: | 02 00 01 00 00 00 10 04 00 00 00 00 00 00 00 00 Aug 19 15:43:13 dcm-2 pluto[11830]: | 03 00 06 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:43:13 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 01 00 12 00 03 00 00 00 Aug 19 15:43:13 dcm-2 pluto[11830]: | 02 00 13 00 72 9f b5 45 00 00 00 00 00 00 00 00 Aug 19 15:43:13 dcm-2 pluto[11830]: | 03 00 14 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:43:14 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 Aug 19 15:43:14 dcm-2 pluto[11830]: | pfkey_get: SADB_X_GRPSA message 19 Aug 19 15:43:14 dcm-2 pluto[11830]: | route owner of "sample" CK_PERMANENT unrouted: NULL; eroute owner: NULL Aug 19 15:43:14 dcm-2 pluto[11830]: | add eroute 172.18.195.216/32:5099 -> 172.18.195.217/32:5099 => [EMAIL PROTECTED]:17 Aug 19 15:43:14 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_X_ADDFLOW message 20 for flow [EMAIL PROTECTED] Aug 19 15:43:14 dcm-2 pluto[11830]: | 02 0e 00 09 17 00 00 00 14 00 00 00 36 2e 00 00 Aug 19 15:43:14 dcm-2 pluto[11830]: | 02 00 01 00 00 00 10 04 00 00 00 00 00 00 00 00 Aug 19 15:43:14 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:14 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:14 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:43:14 dcm-2 pluto[11830]: | 03 00 15 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:14 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 16 00 00 00 00 00 Aug 19 15:43:14 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:43:14 dcm-2 pluto[11830]: | 03 00 17 00 00 00 00 00 02 00 ff ff ff ff ff ff Aug 19 15:43:14 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 18 00 00 00 00 00 Aug 19 15:43:14 dcm-2 pluto[11830]: | 02 00 ff ff ff ff ff ff 32 31 37 00 00 00 00 00 Aug 19 15:43:14 dcm-2 pluto[11830]: | 01 00 1a 00 11 b7 00 00 Aug 19 15:43:14 dcm-2 pluto[11830]: ERROR: "sample" #2: pfkey write() of SADB_X_ADDFLOW message 20 for flow [EMAIL PROTECTED] failed. Errno 22: Invalid argument Aug 19 15:43:14 dcm-2 pluto[11830]: | delete [EMAIL PROTECTED] Aug 19 15:43:14 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_DELETE message 21 for Delete SA [EMAIL PROTECTED] Aug 19 15:43:14 dcm-2 pluto[11830]: | 02 04 00 03 0a 00 00 00 15 00 00 00 36 2e 00 00 Aug 19 15:43:15 dcm-2 pluto[11830]: | 02 00 01 00 72 9f b5 45 00 01 00 00 00 00 00 00 Aug 19 15:43:15 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:15 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:15 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:43:15 dcm-2 pluto[11830]: | pfkey_get: SADB_X_ADDFLOW message 20 Aug 19 15:43:15 dcm-2 pluto[11830]: | pfkey_get: SADB_DELETE message 21 Aug 19 15:43:15 dcm-2 pluto[11830]: | delete [EMAIL PROTECTED] Aug 19 15:43:15 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_DELETE message 22 for Delete SA [EMAIL PROTECTED] Aug 19 15:43:15 dcm-2 pluto[11830]: | 02 04 00 03 0a 00 00 00 16 00 00 00 36 2e 00 00 Aug 19 15:43:15 dcm-2 pluto[11830]: | 02 00 01 00 eb 08 f7 a8 00 01 00 00 00 00 00 00 Aug 19 15:43:15 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:43:15 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:15 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d8 00 00 00 00 00 00 00 00 Aug 19 15:43:15 dcm-2 pluto[11830]: | pfkey_get: SADB_DELETE message 22 Aug 19 15:43:15 dcm-2 pluto[11830]: | state transition function for STATE_QUICK_I1 had internal error Aug 19 15:43:15 dcm-2 pluto[11830]: | next event EVENT_RETRANSMIT in 14 seconds for #2 Aug 19 15:43:15 dcm-2 pluto[11830]: | pfkey_async: SADB_X_ADDFLOW len=2, errno=22, satype=9, seq=20, pid=11830 Aug 19 15:43:29 dcm-2 pluto[11830]: | Aug 19 15:43:29 dcm-2 pluto[11830]: | *time to handle event Aug 19 15:43:29 dcm-2 pluto[11830]: | event after this is EVENT_SHUNT_SCAN in 36 seconds Aug 19 15:43:29 dcm-2 pluto[11830]: | handling event EVENT_RETRANSMIT for 172.18.195.217 "sample" #2 Aug 19 15:43:29 dcm-2 pluto[11830]: | inserting event EVENT_RETRANSMIT, timeout in 40 seconds for #2 Aug 19 15:43:29 dcm-2 pluto[11830]: | next event EVENT_SHUNT_SCAN in 36 seconds Aug 19 15:43:30 dcm-2 pluto[11830]: | Aug 19 15:43:30 dcm-2 pluto[11830]: | *received 348 bytes from 172.18.195.217:500 on eth0 Aug 19 15:43:30 dcm-2 pluto[11830]: | ICOOKIE: b6 a4 3b 8d d4 43 82 7c Aug 19 15:43:30 dcm-2 pluto[11830]: | RCOOKIE: 01 e6 92 61 41 c2 76 b8 Aug 19 15:43:30 dcm-2 pluto[11830]: | peer: ac 12 c3 d9 Aug 19 15:43:30 dcm-2 pluto[11830]: | state hash entry 14 Aug 19 15:43:30 dcm-2 pluto[11830]: | state object #2 found, in STATE_QUICK_I1 Aug 19 15:43:30 dcm-2 pluto[11830]: | our client is subnet 172.18.195.216/32 Aug 19 15:43:30 dcm-2 pluto[11830]: | our client protocol/port is 17/5099 Aug 19 15:43:30 dcm-2 pluto[11830]: | peer client is subnet 172.18.195.217/32 Aug 19 15:43:30 dcm-2 pluto[11830]: | peer client protocol/port is 17/5099 Aug 19 15:43:30 dcm-2 pluto[11830]: | route owner of "sample" CK_PERMANENT unrouted: NULL; eroute owner: NULL Aug 19 15:43:30 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_ADD message 23 for Add ESP SA [EMAIL PROTECTED] Aug 19 15:43:30 dcm-2 pluto[11830]: | 02 03 00 03 11 00 00 00 17 00 00 00 36 2e 00 00 Aug 19 15:43:30 dcm-2 pluto[11830]: | 02 00 01 00 eb 08 f7 a8 40 01 02 03 00 00 00 00 Aug 19 15:43:30 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:43:30 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:30 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d8 00 00 00 00 00 00 00 00 Aug 19 15:43:30 dcm-2 pluto[11830]: | 03 00 08 00 80 00 00 00 fb 5e b5 62 16 4a f9 c0 Aug 19 15:43:30 dcm-2 pluto[11830]: | 70 97 df fc 07 05 09 6a 04 00 09 00 c0 00 00 00 Aug 19 15:43:31 dcm-2 pluto[11830]: | c8 3c 21 fc a8 7b 93 59 b0 bc 2c 3b 40 f4 ab 90 Aug 19 15:43:31 dcm-2 pluto[11830]: | 33 7d a9 99 fd 26 4c 7e Aug 19 15:43:31 dcm-2 pluto[11830]: | pfkey_get: SADB_ADD message 23 Aug 19 15:43:31 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_ADD message 24 for Add IPIP SA [EMAIL PROTECTED] Aug 19 15:43:31 dcm-2 pluto[11830]: | 02 03 00 09 0a 00 00 00 18 00 00 00 36 2e 00 00 Aug 19 15:43:31 dcm-2 pluto[11830]: | 02 00 01 00 00 00 10 05 00 01 00 00 00 00 00 00 Aug 19 15:43:31 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:43:31 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:31 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d8 00 00 00 00 00 00 00 00 Aug 19 15:43:31 dcm-2 pluto[11830]: | pfkey_get: SADB_ADD message 24 Aug 19 15:43:31 dcm-2 pluto[11830]: | grouping [EMAIL PROTECTED] and [EMAIL PROTECTED] Aug 19 15:43:31 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_X_GRPSA message 25 for group [EMAIL PROTECTED] Aug 19 15:43:31 dcm-2 pluto[11830]: | 02 0d 00 09 0d 00 00 00 19 00 00 00 36 2e 00 00 Aug 19 15:43:31 dcm-2 pluto[11830]: | 02 00 01 00 00 00 10 05 00 00 00 00 00 00 00 00 Aug 19 15:43:31 dcm-2 pluto[11830]: | 03 00 06 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:31 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 01 00 12 00 03 00 00 00 Aug 19 15:43:31 dcm-2 pluto[11830]: | 02 00 13 00 eb 08 f7 a8 00 00 00 00 00 00 00 00 Aug 19 15:43:31 dcm-2 pluto[11830]: | 03 00 14 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:31 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 Aug 19 15:43:31 dcm-2 pluto[11830]: | pfkey_get: SADB_X_GRPSA message 25 Aug 19 15:43:31 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_ADD message 26 for Add ESP SA [EMAIL PROTECTED] Aug 19 15:43:31 dcm-2 pluto[11830]: | 02 03 00 03 11 00 00 00 1a 00 00 00 36 2e 00 00 Aug 19 15:43:31 dcm-2 pluto[11830]: | 02 00 01 00 72 9f b5 45 40 01 02 03 00 00 00 00 Aug 19 15:43:32 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:32 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:32 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:43:32 dcm-2 pluto[11830]: | 03 00 08 00 80 00 00 00 d3 48 dc c2 c2 44 2d de Aug 19 15:43:32 dcm-2 pluto[11830]: | 3c bd de 96 08 a1 0b 43 04 00 09 00 c0 00 00 00 Aug 19 15:43:32 dcm-2 pluto[11830]: | 51 3c 5b f1 b0 fc d1 eb 5e 96 ad bd f8 a3 c9 07 Aug 19 15:43:32 dcm-2 pluto[11830]: | f3 fa 68 de cc 6a 37 47 Aug 19 15:43:32 dcm-2 pluto[11830]: | pfkey_get: SADB_ADD message 26 Aug 19 15:43:32 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_ADD message 27 for Add IPIP SA [EMAIL PROTECTED] Aug 19 15:43:32 dcm-2 pluto[11830]: | 02 03 00 09 0a 00 00 00 1b 00 00 00 36 2e 00 00 Aug 19 15:43:32 dcm-2 pluto[11830]: | 02 00 01 00 00 00 10 06 00 01 00 00 00 00 00 00 Aug 19 15:43:32 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:32 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:32 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:43:32 dcm-2 pluto[11830]: | pfkey_get: SADB_ADD message 27 Aug 19 15:43:32 dcm-2 pluto[11830]: | grouping [EMAIL PROTECTED] and [EMAIL PROTECTED] Aug 19 15:43:32 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_X_GRPSA message 28 for group [EMAIL PROTECTED] Aug 19 15:43:32 dcm-2 pluto[11830]: | 02 0d 00 09 0d 00 00 00 1c 00 00 00 36 2e 00 00 Aug 19 15:43:33 dcm-2 pluto[11830]: | 02 00 01 00 00 00 10 06 00 00 00 00 00 00 00 00 Aug 19 15:43:33 dcm-2 pluto[11830]: | 03 00 06 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:43:33 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 01 00 12 00 03 00 00 00 Aug 19 15:43:33 dcm-2 pluto[11830]: | 02 00 13 00 72 9f b5 45 00 00 00 00 00 00 00 00 Aug 19 15:43:33 dcm-2 pluto[11830]: | 03 00 14 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:43:33 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 Aug 19 15:43:33 dcm-2 pluto[11830]: | pfkey_get: SADB_X_GRPSA message 28 Aug 19 15:43:33 dcm-2 pluto[11830]: | route owner of "sample" CK_PERMANENT unrouted: NULL; eroute owner: NULL Aug 19 15:43:33 dcm-2 pluto[11830]: | add eroute 172.18.195.216/32:5099 -> 172.18.195.217/32:5099 => [EMAIL PROTECTED]:17 Aug 19 15:43:33 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_X_ADDFLOW message 29 for flow [EMAIL PROTECTED] Aug 19 15:43:33 dcm-2 pluto[11830]: | 02 0e 00 09 17 00 00 00 1d 00 00 00 36 2e 00 00 Aug 19 15:43:33 dcm-2 pluto[11830]: | 02 00 01 00 00 00 10 06 00 00 00 00 00 00 00 00 Aug 19 15:43:33 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:33 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:33 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:43:33 dcm-2 pluto[11830]: | 03 00 15 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:33 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 16 00 00 00 00 00 Aug 19 15:43:33 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:43:34 dcm-2 pluto[11830]: | 03 00 17 00 00 00 00 00 02 00 ff ff ff ff ff ff Aug 19 15:43:34 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 18 00 00 00 00 00 Aug 19 15:43:34 dcm-2 pluto[11830]: | 02 00 ff ff ff ff ff ff 32 31 37 00 00 00 00 00 Aug 19 15:43:34 dcm-2 pluto[11830]: | 01 00 1a 00 11 9c 00 00 Aug 19 15:43:34 dcm-2 pluto[11830]: ERROR: "sample" #2: pfkey write() of SADB_X_ADDFLOW message 29 for flow [EMAIL PROTECTED] failed. Errno 22: Invalid argument Aug 19 15:43:34 dcm-2 pluto[11830]: | delete [EMAIL PROTECTED] Aug 19 15:43:34 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_DELETE message 30 for Delete SA [EMAIL PROTECTED] Aug 19 15:43:34 dcm-2 pluto[11830]: | 02 04 00 03 0a 00 00 00 1e 00 00 00 36 2e 00 00 Aug 19 15:43:34 dcm-2 pluto[11830]: | 02 00 01 00 72 9f b5 45 00 01 00 00 00 00 00 00 Aug 19 15:43:34 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d8 Aug 19 15:43:34 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:34 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d9 00 00 00 00 00 00 00 00 Aug 19 15:43:34 dcm-2 pluto[11830]: | pfkey_get: SADB_X_ADDFLOW message 29 Aug 19 15:43:34 dcm-2 pluto[11830]: | pfkey_get: SADB_DELETE message 30 Aug 19 15:43:34 dcm-2 pluto[11830]: | delete [EMAIL PROTECTED] Aug 19 15:43:34 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_DELETE message 31 for Delete SA [EMAIL PROTECTED] Aug 19 15:43:34 dcm-2 pluto[11830]: | 02 04 00 03 0a 00 00 00 1f 00 00 00 36 2e 00 00 Aug 19 15:43:34 dcm-2 pluto[11830]: | 02 00 01 00 eb 08 f7 a8 00 01 00 00 00 00 00 00 Aug 19 15:43:35 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:43:35 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:43:35 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d8 00 00 00 00 00 00 00 00 Aug 19 15:43:35 dcm-2 pluto[11830]: | pfkey_get: SADB_DELETE message 31 Aug 19 15:43:35 dcm-2 pluto[11830]: | state transition function for STATE_QUICK_I1 had internal error Aug 19 15:43:35 dcm-2 pluto[11830]: | next event EVENT_SHUNT_SCAN in 30 seconds Aug 19 15:43:35 dcm-2 pluto[11830]: | pfkey_async: SADB_X_ADDFLOW len=2, errno=22, satype=9, seq=29, pid=11830 Aug 19 15:44:05 dcm-2 pluto[11830]: | Aug 19 15:44:05 dcm-2 pluto[11830]: | *time to handle event Aug 19 15:44:05 dcm-2 pluto[11830]: | event after this is EVENT_RETRANSMIT in 4 seconds Aug 19 15:44:05 dcm-2 pluto[11830]: | inserting event EVENT_SHUNT_SCAN, timeout in 120 seconds Aug 19 15:44:05 dcm-2 pluto[11830]: | scanning for shunt eroutes Aug 19 15:44:05 dcm-2 pluto[11830]: | next event EVENT_RETRANSMIT in 4 seconds for #2 Aug 19 15:44:09 dcm-2 pluto[11830]: | Aug 19 15:44:09 dcm-2 pluto[11830]: | *time to handle event Aug 19 15:44:09 dcm-2 pluto[11830]: | event after this is EVENT_SHUNT_SCAN in 116 seconds Aug 19 15:44:09 dcm-2 pluto[11830]: | handling event EVENT_RETRANSMIT for 172.18.195.217 "sample" #2 Aug 19 15:44:09 dcm-2 pluto[11830]: "sample" #2: max number of retransmissions (2) reached STATE_QUICK_I1. No acceptable response to our first Quick Mode message: perhaps peer likes no proposal Aug 19 15:44:09 dcm-2 pluto[11830]: "sample" #2: starting keying attempt 2 of an unlimited number, but releasing whack Aug 19 15:44:09 dcm-2 pluto[11830]: | duplicating state object #1 Aug 19 15:44:09 dcm-2 pluto[11830]: | creating state object #3 at 0x80abb68 Aug 19 15:44:09 dcm-2 pluto[11830]: | ICOOKIE: b6 a4 3b 8d d4 43 82 7c Aug 19 15:44:09 dcm-2 pluto[11830]: | RCOOKIE: 01 e6 92 61 41 c2 76 b8 Aug 19 15:44:09 dcm-2 pluto[11830]: | peer: ac 12 c3 d9 Aug 19 15:44:09 dcm-2 pluto[11830]: | state hash entry 14 Aug 19 15:44:09 dcm-2 pluto[11830]: | inserting event EVENT_SO_DISCARD, timeout in 0 seconds for #3 Aug 19 15:44:09 dcm-2 pluto[11830]: "sample" #3: initiating Quick Mode RSASIG+ENCRYPT+TUNNEL+PFS+DISABLEARRIVALCHECK to replace #2 Aug 19 15:44:09 dcm-2 pluto[11830]: | generate SPI: eb 08 f7 a9 Aug 19 15:44:09 dcm-2 pluto[11830]: | inserting event EVENT_RETRANSMIT, timeout in 10 seconds for #3 Aug 19 15:44:09 dcm-2 pluto[11830]: | next event EVENT_RETRANSMIT in 10 seconds for #3 Aug 19 15:44:10 dcm-2 pluto[11830]: | Aug 19 15:44:10 dcm-2 pluto[11830]: | *received 348 bytes from 172.18.195.217:500 on eth0 Aug 19 15:44:10 dcm-2 pluto[11830]: | ICOOKIE: b6 a4 3b 8d d4 43 82 7c Aug 19 15:44:10 dcm-2 pluto[11830]: | RCOOKIE: 01 e6 92 61 41 c2 76 b8 Aug 19 15:44:10 dcm-2 pluto[11830]: | peer: ac 12 c3 d9 Aug 19 15:44:10 dcm-2 pluto[11830]: | state hash entry 14 Aug 19 15:44:10 dcm-2 pluto[11830]: | state object #3 found, in STATE_QUICK_I1 Aug 19 15:44:10 dcm-2 pluto[11830]: | our client is subnet 172.18.195.216/32 Aug 19 15:44:10 dcm-2 pluto[11830]: | our client protocol/port is 17/5099 Aug 19 15:44:10 dcm-2 pluto[11830]: | peer client is subnet 172.18.195.217/32 Aug 19 15:44:10 dcm-2 pluto[11830]: | peer client protocol/port is 17/5099 Aug 19 15:44:10 dcm-2 pluto[11830]: | route owner of "sample" CK_PERMANENT unrouted: NULL; eroute owner: NULL Aug 19 15:44:10 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_ADD message 32 for Add ESP SA [EMAIL PROTECTED] Aug 19 15:44:10 dcm-2 pluto[11830]: | 02 03 00 03 11 00 00 00 20 00 00 00 36 2e 00 00 Aug 19 15:44:10 dcm-2 pluto[11830]: | 02 00 01 00 eb 08 f7 a9 40 01 02 03 00 00 00 00 Aug 19 15:44:10 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:44:10 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:44:10 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d8 00 00 00 00 00 00 00 00 Aug 19 15:44:10 dcm-2 pluto[11830]: | 03 00 08 00 80 00 00 00 52 27 b5 ff 97 8d f2 9b Aug 19 15:44:10 dcm-2 pluto[11830]: | 41 66 00 3d cb 1b 94 11 04 00 09 00 c0 00 00 00 Aug 19 15:44:10 dcm-2 pluto[11830]: | 03 66 76 f6 9f 8c ba f8 09 2c 90 4d 70 6e 84 6e Aug 19 15:44:10 dcm-2 pluto[11830]: | ef 20 63 00 a6 e9 5c 48 Aug 19 15:44:10 dcm-2 pluto[11830]: | pfkey_get: SADB_ADD message 32 Aug 19 15:44:10 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_ADD message 33 for Add IPIP SA [EMAIL PROTECTED] Aug 19 15:44:10 dcm-2 pluto[11830]: | 02 03 00 09 0a 00 00 00 21 00 00 00 36 2e 00 00 Aug 19 15:44:10 dcm-2 pluto[11830]: | 02 00 01 00 00 00 10 07 00 01 00 00 00 00 00 00 Aug 19 15:44:10 dcm-2 pluto[11830]: | 03 00 05 00 00 00 00 00 02 00 13 eb ac 12 c3 d9 Aug 19 15:44:11 dcm-2 pluto[11830]: | 00 00 00 00 00 00 00 00 03 00 06 00 00 00 00 00 Aug 19 15:44:11 dcm-2 pluto[11830]: | 02 00 13 eb ac 12 c3 d8 00 00 00 00 00 00 00 00 Aug 19 15:44:11 dcm-2 pluto[11830]: | pfkey_get: SADB_ADD message 33 Aug 19 15:44:11 dcm-2 pluto[11830]: | grouping [EMAIL PROTECTED] and [EMAIL PROTECTED] Aug 19 15:44:11 dcm-2 pluto[11830]: | finish_pfkey_msg: SADB_X_GRPSA message 34 for group [EMAIL PROTECTED]
_______________________________________________ FreeS/WAN Users mailing list [EMAIL PROTECTED] https://mj2.freeswan.org/cgi-bin/mj_wwwusr
