[h1vs2]

Добрый день!

 

Собрался таки попробовать завести влан на юзера с куинку.

Не подскажете почему не собирается?

 

 

 

root@brs-acl:~/accel-ppp.git/build# cmake -DRADIUS=TRUE -DNETSNMP=TRUE -DBUILD_IPOE_DRIVER=TRUE -DLUA=TRUE  -DLOG_PGSQL=FALSE -DBUILD_DRIVER=FALSE -DCMAKE_BUILD_TYPE=Debug ../accel-pppd/
-- The C compiler identification is GNU 4.7.2
-- The CXX compiler identification is GNU 4.7.2
-- Check for working C compiler: /usr/bin/gcc
-- Check for working C compiler: /usr/bin/gcc -- works
-- Detecting C compiler ABI info
-- Detecting C compiler ABI info - done
-- Check for working CXX compiler: /usr/bin/c++
-- Check for working CXX compiler: /usr/bin/c++ -- works
-- Detecting CXX compiler ABI info
-- Detecting CXX compiler ABI info - done
CMake Warning (dev) at radius/CMakeLists.txt:17 (ADD_DEFINITIONS):
 Policy CMP0005 is not set: Preprocessor definition values are now escaped
 automatically.  Run "cmake --help-policy CMP0005" for policy details.  Use
 the cmake_policy command to set the policy and suppress this warning.
This warning is for project developers.  Use -Wno-dev to suppress it.

-- Looking for timerfd_create
-- Looking for timerfd_create - found
CMake Warning (dev) at triton/CMakeLists.txt:33 (ADD_DEFINITIONS):
 Policy CMP0005 is not set: Preprocessor definition values are now escaped
 automatically.  Run "cmake --help-policy CMP0005" for policy details.  Use
 the cmake_policy command to set the policy and suppress this warning.
This warning is for project developers.  Use -Wno-dev to suppress it.

CMake Warning (dev) at ctrl/l2tp/CMakeLists.txt:3 (ADD_DEFINITIONS):
 Policy CMP0005 is not set: Preprocessor definition values are now escaped
 automatically.  Run "cmake --help-policy CMP0005" for policy details.  Use
 the cmake_policy command to set the policy and suppress this warning.
This warning is for project developers.  Use -Wno-dev to suppress it.

-- Found Lua51: /usr/lib/x86_64-linux-gnu/liblua5.1.so;/usr/lib/x86_64-linux-gnu/libm.so (found version "5.1.5")
-- Looking for linux/netfilter/ipset/ip_set.h
-- Looking for linux/netfilter/ipset/ip_set.h - found
CMake Warning (dev) in CMakeLists.txt:
 No cmake_minimum_required command is present.  A line of code such as

   cmake_minimum_required(VERSION 2.8)

 should be added at the top of the file.  The version specified may be lower
 if you wish to support older CMake versions for this project.  For more
 information run "cmake --help-policy CMP0000".
This warning is for project developers.  Use -Wno-dev to suppress it.

-- Configuring done
CMake Warning (dev) at CMakeLists.txt:52 (ADD_EXECUTABLE):
 Policy CMP0003 should be set before this line.  Add code such as

   if(COMMAND cmake_policy)
     cmake_policy(SET CMP0003 NEW)
   endif(COMMAND cmake_policy)

 as early as possible but after the most recent call to
 cmake_minimum_required or cmake_policy(VERSION).  This warning appears
 because target "accel-pppd" links to some libraries for which the linker
 must search:

   rt, pthread, pcre, dl

 and other libraries with known full path:

   /root/accel-ppp.git/build/triton/libtriton.so

 CMake is adding directories in the second list to the linker search path in
 case they are needed to find libraries from the first list (for backwards
 compatibility with CMake 2.4).  Set policy CMP0003 to OLD or NEW to enable
 or disable this behavior explicitly.  Run "cmake --help-policy CMP0003" for
 more information.
This warning is for project developers.  Use -Wno-dev to suppress it.

-- Generating done
CMake Warning:
 Manually-specified variables were not used by the project:

   BUILD_DRIVER
   BUILD_IPOE_DRIVER


-- Build files have been written to: /root/accel-ppp.git/build
root@brs-acl:~/accel-ppp.git/build# make
Scanning dependencies of target triton
[  1%] Building C object triton/CMakeFiles/triton.dir/md.o
[  2%] Building C object triton/CMakeFiles/triton.dir/timer.o
[  3%] Building C object triton/CMakeFiles/triton.dir/triton.o
[  4%] Building C object triton/CMakeFiles/triton.dir/conf_file.o
[  5%] Building C object triton/CMakeFiles/triton.dir/loader.o
/root/accel-ppp.git/accel-pppd/triton/loader.c: In function ‘load_modules’:
/root/accel-ppp.git/accel-pppd/triton/loader.c:27:15: error: expected expression before ‘/’ token
make[2]: *** [triton/CMakeFiles/triton.dir/loader.o] Error 1
make[1]: *** [triton/CMakeFiles/triton.dir/all] Error 2
make: *** [all] Error 2
root@brs-acl:~/accel-ppp.git/build#

 

 

root@brs-acl:~/accel-ppp.git/build# uname -a
Linux brs-acl 3.2.0-4-amd64 #1 SMP Debian 3.2.57-3+deb7u1 x86_64 GNU/Linux

 

Собираю из мастер-бранча гита. Нужно мне ipoe, я так понимаю оно только в 1.8.

 

 

Спасибо!

[xeb]

принципиально из исходников собирать ?

есть готовые дебы для дебиан7

[h1vs2]

принципиально из исходников собирать ?

есть готовые дебы для дебиан7

 

Дико извиняюсь. Уже все нормально собралось, видать чего-то лишнего поставил.

 

Подскажите, в случае qinq обязательно svlan создавать руками создавать, и на них вешать monitor, или он может все делать ?

[kayot]

h1vs2

Обязательно руками создавать, иначе слишком уж заумная схема получится.

[fet4]

fet4 (15 мая 2014 - 08:34) писал:

Я так понял в разделе log указать level=5 ? И показать log-file=/var/log/accel-ppp/accel-ppp.log ?

 

Да.

 

log level=5. В логах есть спровоцированный обрыв и потом следом восстановление под логином maxi. Может что-нибудь будет понятно.

root ~ # tail -n 1000 /var/log/accel-ppp/accel-ppp.log
[2014-05-19 23:18:18]: debug: ppp18: recv [LCP EchoRep id=20 <magic 6fb3e6c7>]
[2014-05-19 23:18:18]: debug: ppp1: send [LCP EchoReq id=20 <magic 327b23c6>]
[2014-05-19 23:18:18]: debug: ppp1: recv [LCP EchoReq id=1f <magic 7463fe14>]
[2014-05-19 23:18:18]: debug: ppp1: send [LCP EchoRep id=1f <magic 327b23c6>]
[2014-05-19 23:18:18]: debug: ppp1: recv [LCP EchoRep id=20 <magic 14fe6374>]
[2014-05-19 23:18:19]: debug: ppp20: send [LCP EchoReq id=20 <magic 0216231b>]
[2014-05-19 23:18:19]: debug: ppp20: recv [LCP EchoRep id=20 <magic aead4f27>]
[2014-05-19 23:18:19]: debug: ppp21: send [LCP EchoReq id=20 <magic 1f16e9e8>]
[2014-05-19 23:18:19]: debug: ppp21: recv [LCP EchoRep id=20 <magic 17ee604d>]
[2014-05-19 23:18:20]: debug: ppp5: send [LCP EchoReq id=21 <magic 19495cff>]
[2014-05-19 23:18:20]: debug: ppp5: recv [LCP EchoRep id=21 <magic 3b0ef428>]
[2014-05-19 23:18:20]: debug: ppp22: send [LCP EchoReq id=20 <magic 1190cde7>]
[2014-05-19 23:18:20]: debug: ppp22: recv [LCP EchoRep id=20 <magic c18dd627>]
[2014-05-19 23:18:20]: debug: ppp8: send [LCP EchoReq id=21 <magic 238e1f29>]
[2014-05-19 23:18:20]: debug: ppp8: recv [LCP EchoRep id=21 <magic 4a59bd5d>]
[2014-05-19 23:18:20]: debug: ppp23: send [LCP EchoReq id=20 <magic 66ef438d>]
[2014-05-19 23:18:20]: debug: ppp23: recv [LCP EchoRep id=20 <magic 6c0a6359>]
[2014-05-19 23:18:20]: debug: ppp24: send [LCP EchoReq id=20 <magic 140e0f76>]
[2014-05-19 23:18:20]: debug: ppp24: recv [LCP EchoRep id=20 <magic cab0c92d>]
[2014-05-19 23:18:20]: debug: ppp25: send [LCP EchoReq id=20 <magic 3352255a>]
[2014-05-19 23:18:20]: debug: ppp25: recv [LCP EchoRep id=20 <magic 5164687f>]
[2014-05-19 23:18:20]: debug: ppp26: send [LCP EchoReq id=20 <magic 109cf92e>]
[2014-05-19 23:18:20]: debug: ppp26: recv [LCP EchoRep id=20 <magic 4808c10b>]
[2014-05-19 23:18:20]: debug: ppp62: recv [LCP EchoReq id=d <magic 321d51ba>]
[2014-05-19 23:18:20]: debug: ppp62: send [LCP EchoRep id=d <magic 2a487cb0>]
[2014-05-19 23:18:21]: debug: ppp30: send [LCP EchoReq id=20 <magic 41a7c4c9>]
[2014-05-19 23:18:21]: debug: ppp30: recv [LCP EchoRep id=20 <magic 2aecdd1f>]
[2014-05-19 23:18:21]: debug: ppp31: send [LCP EchoReq id=20 <magic 6b68079a>]
[2014-05-19 23:18:21]: debug: ppp31: recv [LCP EchoRep id=20 <magic fa8e9c3e>]
[2014-05-19 23:18:21]: debug: ppp32: send [LCP EchoReq id=20 <magic 4e6afb66>]
[2014-05-19 23:18:21]: debug: ppp32: recv [LCP EchoRep id=20 <magic 891329f5>]
[2014-05-19 23:18:21]: debug: ppp33: send [LCP EchoReq id=20 <magic 25e45d32>]
[2014-05-19 23:18:21]: debug: ppp33: recv [LCP EchoRep id=20 <magic ebbb0ed5>]
[2014-05-19 23:18:21]: debug: ppp37: send [LCP EchoReq id=20 <magic 7c83e458>]
[2014-05-19 23:18:21]: debug: ppp37: recv [LCP EchoRep id=20 <magic 08d9163b>]
[2014-05-19 23:18:21]: debug: ppp40: send [LCP EchoReq id=20 <magic 62bbd95a>]
[2014-05-19 23:18:21]: debug: ppp39: send [LCP EchoReq id=20 <magic 436c6125>]
[2014-05-19 23:18:21]: debug: ppp39: recv [LCP EchoRep id=20 <magic 362521d6>]
[2014-05-19 23:18:21]: debug: ppp40: recv [LCP EchoRep id=20 <magic b2999f4b>]
[2014-05-19 23:18:21]: debug: ppp42: send [LCP EchoReq id=20 <magic 333ab105>]
[2014-05-19 23:18:21]: debug: ppp42: recv [LCP EchoRep id=20 <magic 35f2d0ec>]
[2014-05-19 23:18:21]: debug: ppp36: send [LCP EchoReq id=20 <magic 3f2dba31>]
[2014-05-19 23:18:21]: debug: ppp36: recv [LCP EchoRep id=20 <magic 8d3f6c4a>]
[2014-05-19 23:18:21]: debug: ppp43: send [LCP EchoReq id=20 <magic 721da317>]
[2014-05-19 23:18:21]: debug: ppp43: recv [LCP EchoRep id=20 <magic 244cb284>]
[2014-05-19 23:18:21]: debug: ppp44: send [LCP EchoReq id=20 <magic 2443a858>]
[2014-05-19 23:18:21]: debug: ppp44: recv [LCP EchoRep id=20 <magic c930ab61>]
[2014-05-19 23:18:21]: debug: ppp48: send [LCP EchoReq id=20 <magic 08edbdab>]
[2014-05-19 23:18:21]: debug: ppp48: recv [LCP EchoRep id=20 <magic ad3c1841>]
[2014-05-19 23:18:21]: debug: ppp6: send [LCP EchoReq id=20 <magic 2ae8944a>]
[2014-05-19 23:18:21]: debug: ppp6: recv [LCP EchoRep id=20 <magic d412041d>]
[2014-05-19 23:18:21]: debug: ppp65: send [LCP EchoReq id=11 <magic 2cd89a32>]
[2014-05-19 23:18:21]: debug: ppp65: recv [LCP EchoRep id=11 <magic 71663348>]
[2014-05-19 23:18:21]: debug: ppp14: send [LCP EchoReq id=21 <magic 79e2a9e3>]
[2014-05-19 23:18:21]: debug: ppp14: recv [LCP EchoRep id=21 <magic 3f29223a>]
[2014-05-19 23:18:21]: debug: ppp17: send [LCP EchoReq id=20 <magic 5bd062c2>]
[2014-05-19 23:18:21]: debug: ppp17: recv [LCP EchoRep id=20 <magic 1566694d>]
[2014-05-19 23:18:21]: debug: ppp49: send [LCP EchoReq id=20 <magic 79838cb2>]
[2014-05-19 23:18:21]: debug: ppp49: recv [LCP EchoRep id=20 <magic f127af0f>]
[2014-05-19 23:18:22]: debug: ppp67: send [LCP EchoReq id=1e <magic 3804823e>]
[2014-05-19 23:18:22]: debug: ppp67: recv [LCP EchoRep id=1e <magic fd6b362b>]
[2014-05-19 23:18:22]: debug: ppp63: send [LCP EchoReq id=17 <magic 3855585c>]
[2014-05-19 23:18:22]: debug: ppp63: recv [LCP EchoRep id=17 <magic e453f07f>]
[2014-05-19 23:18:22]: debug: ppp51: send [LCP EchoReq id=20 <magic 0b03e0c6>]
[2014-05-19 23:18:22]: debug: ppp51: recv [LCP EchoRep id=20 <magic 21c44c87>]
[2014-05-19 23:18:22]: debug: ppp9: recv [LCP EchoReq id=68 <magic 6229d6c4>]
[2014-05-19 23:18:22]: debug: ppp9: send [LCP EchoRep id=68 <magic 46e87ccd>]
[2014-05-19 23:18:23]: debug: ppp68: send [LCP EchoReq id=1c <magic 5c482a97>]
[2014-05-19 23:18:23]: debug: ppp68: recv [LCP EchoRep id=1c <magic 38728940>]
[2014-05-19 23:18:24]: debug: ppp34: send [LCP EchoReq id=20 <magic 519b500d>]
[2014-05-19 23:18:24]: debug: ppp34: recv [LCP EchoRep id=20 <magic 0524ea01>]
[2014-05-19 23:18:24]: debug: ppp38: send [LCP EchoReq id=20 <magic 257130a3>]
[2014-05-19 23:18:24]: debug: ppp38: recv [LCP EchoRep id=20 <magic 9cf6e0dc>]
[2014-05-19 23:18:24]: debug: ppp41: send [LCP EchoReq id=20 <magic 628c895d>]
[2014-05-19 23:18:24]: debug: ppp41: recv [LCP EchoRep id=20 <magic 9f464225>]
[2014-05-19 23:18:24]: debug: ppp45: send [LCP EchoReq id=20 <magic 2d1d5ae9>]
[2014-05-19 23:18:24]: debug: ppp46: send [LCP EchoReq id=20 <magic 6763845e>]
[2014-05-19 23:18:24]: debug: ppp46: recv [LCP EchoRep id=20 <magic cd97b70b>]
[2014-05-19 23:18:24]: debug: ppp45: recv [LCP EchoRep id=20 <magic f501fbe9>]
[2014-05-19 23:18:24]: debug: ppp47: send [LCP EchoReq id=20 <magic 75a2a8d4>]
[2014-05-19 23:18:24]: debug: ppp47: recv [LCP EchoRep id=20 <magic 40d054b0>]
[2014-05-19 23:18:24]: debug: ppp63: recv [LCP EchoReq id=b <magic 7ff053e4>]
[2014-05-19 23:18:24]: debug: ppp63: send [LCP EchoRep id=b <magic 3855585c>]
[2014-05-19 23:18:24]: debug: ppp16: send [LCP EchoReq id=22 <magic 515f007c>]
[2014-05-19 23:18:26]: debug: ppp53: send [LCP EchoReq id=1f <magic 737b8ddc>]
[2014-05-19 23:18:26]: debug: ppp53: recv [LCP EchoRep id=1f <magic 21881300>]
[2014-05-19 23:18:26]: debug: ppp3: send [LCP EchoReq id=e <magic 57e4ccaf>]
[2014-05-19 23:18:26]: debug: ppp3: recv [LCP EchoRep id=e <magic 56dbfed6>]
[2014-05-19 23:18:26]: debug: ppp60: send [LCP EchoReq id=1f <magic 6ceaf087>]
[2014-05-19 23:18:26]: debug: ppp60: recv [LCP EchoRep id=1f <magic 59878000>]
[2014-05-19 23:18:26]: debug: ppp61: send [LCP EchoReq id=1f <magic 22221a70>]
[2014-05-19 23:18:26]: debug: ppp61: recv [LCP EchoRep id=1f <magic 97d55800>]
[2014-05-19 23:18:26]: debug: ppp2: send [LCP EchoReq id=a <magic 4b588f54>]
[2014-05-19 23:18:26]: debug: ppp2: recv [LCP EchoRep id=a <magic 570e1976>]
[2014-05-19 23:18:27]:  info: ppp65: recv [LCP TermReq id=9]
[2014-05-19 23:18:27]:  info: ppp65: send [LCP TermAck id=9]
[2014-05-19 23:18:27]: debug: ppp65: terminate
[2014-05-19 23:18:27]:  info: ppp65: send [RADIUS(1) Accounting-Request id=2 <User-Name "maxi"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 127.0.0.1> <NAS-Port 65> <NAS-Port-Type Virtual> <Service-Type Framed-User> <Framed-Protocol PPP> <Calling-Station-Id "44:87:fc:52:4f:3b"> <Called-Station-Id "64:66:b3:03:b0:3e"> <Acct-Status-Type Stop> <Acct-Authentic RADIUS> <Acct-Session-Id "5c7bde34627a8f1f"> <Acct-Session-Time 309> <Acct-Input-Octets 1526537> <Acct-Output-Octets 56339488> <Acct-Input-Packets 25048> <Acct-Output-Packets 41630> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> <Framed-IP-Address 10.192.86.50> <Acct-Terminate-Cause User-Request>]
[2014-05-19 23:18:27]:  info: ppp65: recv [RADIUS(1) Accounting-Response id=2]
[2014-05-19 23:18:27]: debug: ppp65: lcp_layer_finish
[2014-05-19 23:18:27]: debug: ppp65: auth_layer_finish
[2014-05-19 23:18:27]: debug: ppp65: auth_layer_finished
[2014-05-19 23:18:27]: debug: ppp65: ccp_layer_finish
[2014-05-19 23:18:27]: debug: ppp65: ccp_layer_finished
[2014-05-19 23:18:27]: debug: ppp65: ipcp_layer_finish
[2014-05-19 23:18:27]: debug: ppp65: ipcp_layer_finished
[2014-05-19 23:18:27]: debug: ppp65: ipv6cp_layer_finish
[2014-05-19 23:18:27]: debug: ppp65: ipv6cp_layer_finished
[2014-05-19 23:18:27]:  info: recv [PPPoE PADT 44:87:fc:52:4f:3b => 64:66:b3:03:b0:3e sid=0036]
[2014-05-19 23:18:27]:  info: ppp65: pppd_compat: ip-down started (pid 16795)
[2014-05-19 23:18:27]:  info: ppp65: pppd_compat: ip-down finished (0)
[2014-05-19 23:18:27]: debug: ppp65: lcp_layer_free
[2014-05-19 23:18:27]: debug: ppp65: auth_layer_free
[2014-05-19 23:18:27]: debug: ppp65: ccp_layer_free
[2014-05-19 23:18:27]: debug: ppp65: ipcp_layer_free
[2014-05-19 23:18:27]: debug: ppp65: ipv6cp_layer_free
[2014-05-19 23:18:27]: debug: ppp65: ppp destablished
[2014-05-19 23:18:27]: debug: ppp65: pppoe: ppp finished
[2014-05-19 23:18:27]:  info: send [PPPoE PADT 64:66:b3:03:b0:3e => 44:87:fc:52:4f:3b sid=0036 <AC-Name accel-ppp> <Service-Name > <Host-Uniq 38000000000000006f000000>]
[2014-05-19 23:18:27]:  info: ppp65: disconnected
[2014-05-19 23:18:28]: debug: ppp54: send [LCP EchoReq id=20 <magic 71f32454>]
[2014-05-19 23:18:28]: debug: ppp54: recv [LCP EchoRep id=20 <magic 75f519ed>]
[2014-05-19 23:18:28]: debug: ppp0: recv [LCP EchoReq id=15 <magic 3884287b>]
[2014-05-19 23:18:28]: debug: ppp0: send [LCP EchoRep id=15 <magic 6b8b4567>]
[2014-05-19 23:18:28]: debug: ppp9: recv [LCP EchoReq id=69 <magic 6229d6c4>]
[2014-05-19 23:18:28]: debug: ppp9: send [LCP EchoRep id=69 <magic 46e87ccd>]
[2014-05-19 23:18:29]: debug: ppp4: send [LCP EchoReq id=c <magic 7a6d8d3c>]
[2014-05-19 23:18:29]: debug: ppp4: recv [LCP EchoRep id=c <magic b02bcf83>]
[2014-05-19 23:18:30]: debug: ppp55: send [LCP EchoReq id=21 <magic 3a95f874>]
[2014-05-19 23:18:30]: debug: ppp55: recv [LCP EchoRep id=21 <magic 8a37c07f>]
[2014-05-19 23:18:30]: debug: ppp57: send [LCP EchoReq id=20 <magic 08138641>]
[2014-05-19 23:18:30]: debug: ppp57: recv [LCP EchoRep id=20 <magic 43f34f3e>]
[2014-05-19 23:18:30]: debug: ppp16: send [LCP EchoReq id=23 <magic 515f007c>]
[2014-05-19 23:18:30]: debug: ppp62: send [LCP EchoReq id=15 <magic 2a487cb0>]
[2014-05-19 23:18:30]: debug: ppp56: send [LCP EchoReq id=1f <magic 419ac241>]
[2014-05-19 23:18:30]: debug: ppp56: recv [LCP EchoRep id=1f <magic ca2eea1f>]
[2014-05-19 23:18:30]: debug: ppp62: recv [LCP EchoRep id=15 <magic ba511d32>]
[2014-05-19 23:18:31]: debug: ppp64: send [LCP EchoReq id=1f <magic 5577f8e1>]
[2014-05-19 23:18:31]: debug: ppp64: recv [LCP EchoRep id=1f <magic 59207556>]
[2014-05-19 23:18:31]: debug: ppp6: recv [LCP EchoReq id=15 <magic 1d0412d4>]
[2014-05-19 23:18:31]: debug: ppp6: send [LCP EchoRep id=15 <magic 2ae8944a>]
[2014-05-19 23:18:32]:  info: recv [PPPoE PADI 44:87:fc:52:4f:3b => ff:ff:ff:ff:ff:ff sid=0000 <Service-Name > <Host-Uniq 390000000000000070000000>]
[2014-05-19 23:18:32]:  info: send [PPPoE PADO 64:66:b3:03:b0:3e => 44:87:fc:52:4f:3b sid=0000 <AC-Name accel-ppp> <Service-Name > <AC-Cookie f84fbaacf72464c5e58a55abcd8b532d80bc317eb3a4a047> <Host-Uniq 390000000000000070000000>]
[2014-05-19 23:18:32]:  info: recv [PPPoE PADR 44:87:fc:52:4f:3b => 64:66:b3:03:b0:3e sid=0000 <Service-Name > <Host-Uniq 390000000000000071000000> <AC-Cookie f84fbaacf72464c5e58a55abcd8b532d80bc317eb3a4a047>]
[2014-05-19 23:18:32]:  info: send [PPPoE PADS 64:66:b3:03:b0:3e => 44:87:fc:52:4f:3b sid=0038 <AC-Name accel-ppp> <Service-Name > <Host-Uniq 390000000000000071000000>]
[2014-05-19 23:18:32]:  info: ppp11: connect: ppp11 <--> pppoe(44:87:fc:52:4f:3b)
[2014-05-19 23:18:32]: debug: ppp11: lcp_layer_init
[2014-05-19 23:18:32]: debug: ppp11: auth_layer_init
[2014-05-19 23:18:32]: debug: ppp11: ccp_layer_init
[2014-05-19 23:18:32]: debug: ppp11: ipcp_layer_init
[2014-05-19 23:18:32]: debug: ppp11: ipv6cp_layer_init
[2014-05-19 23:18:32]: debug: ppp11: ppp established
[2014-05-19 23:18:32]: debug: ppp11: lcp_layer_start
[2014-05-19 23:18:32]:  info: ppp11: send [LCP ConfReq id=1 <auth MSCHAP-v2> <mru 1492> <magic 542289ec>]
[2014-05-19 23:18:32]:  info: ppp11: recv [LCP ConfReq id=0 <mru 1480> <magic 680d78cd> <pcomp> <accomp> < d 3 6 >]
[2014-05-19 23:18:32]:  info: ppp11: send [LCP ConfRej id=0  <pcomp> <accomp> < d 3 6 >]
[2014-05-19 23:18:32]:  info: ppp11: recv [LCP ConfAck id=1 <auth MSCHAP-v2> <mru 1492> <magic 542289ec>]
[2014-05-19 23:18:32]:  info: ppp11: recv [LCP ConfReq id=1 <mru 1480> <magic 680d78cd>]
[2014-05-19 23:18:32]:  info: ppp11: send [LCP ConfAck id=1 ]
[2014-05-19 23:18:32]: debug: ppp11: lcp_layer_started
[2014-05-19 23:18:32]: debug: ppp11: auth_layer_start
[2014-05-19 23:18:32]:  info: ppp11: send [MSCHAP-v2 Challenge id=1 <3a1fbfafdcb13976cd8c3f79d29db8>]
[2014-05-19 23:18:32]:  info: ppp11: recv [LCP Ident id=2 <MSRASV5.20>]
[2014-05-19 23:18:32]:  info: ppp11: recv [LCP Ident id=3 <MSRAS-0-MAX>]
[2014-05-19 23:18:32]:  info: ppp11: recv [LCP Ident id=4 <ӷTOe3H▒▒▒և▒l>]
[2014-05-19 23:18:32]:  info: ppp11: recv [MSCHAP-v2 Response id=1 <b669ff58c697993f056e6a69788e365>, <575eefcd42cc95f3cf5e4c8492c4bddca812e7a2db52fb>, F=0, name="maxi"]
[2014-05-19 23:18:32]:  info: ppp11: send [RADIUS(1) Access-Request id=1 <User-Name "maxi"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 127.0.0.1> <NAS-Port 11> <NAS-Port-Type Virtual> <Service-Type Framed-User> <Framed-Protocol PPP> <Calling-Station-Id "44:87:fc:52:4f:3b"> <Called-Station-Id "64:66:b3:03:b0:3e"><Microsoft MS-CHAP-Challenge ><Microsoft MS-CHAP2-Response >]
[2014-05-19 23:18:32]: debug: ppp58: send [LCP EchoReq id=21 <magic 1e7ff521>]
[2014-05-19 23:18:32]: debug: ppp58: recv [LCP EchoRep id=21 <magic d975ac5b>]
[2014-05-19 23:18:32]:  info: ppp11: recv [RADIUS(1) Access-Accept id=1 <Acct-Interim-Interval 300> <Session-Timeout 1039288> <Framed-IP-Address 10.192.252.232> <Framed-IP-Netmask 255.255.255.255><Microsoft MS-CHAP2-Success ><Microsoft MS-MPPE-Recv-Key ><Microsoft MS-MPPE-Send-Key ><Microsoft MS-MPPE-Encryption-Policy 1><Microsoft MS-MPPE-Encryption-Type 6>]
[2014-05-19 23:18:32]:  info: ppp11: send [MSCHAP-v2 Success id=1 "S=C52E589AE62760AB7365D13DFAD20A0193F10C0C M=Authentication succeeded"]
[2014-05-19 23:18:32]: debug: ppp11: auth_layer_started
[2014-05-19 23:18:32]: debug: ppp11: ccp_layer_start
[2014-05-19 23:18:32]: debug: ppp11: ipcp_layer_start
[2014-05-19 23:18:32]:  info: ppp11: send [iPCP ConfReq id=1 <addr 172.31.1.254>]
[2014-05-19 23:18:32]: debug: ppp11: ipv6cp_layer_start
[2014-05-19 23:18:32]:  info: ppp11: maxi: authentication succeeded
[2014-05-19 23:18:32]:  warn: ppp11: IPV6CP: discarding packet
[2014-05-19 23:18:32]:  info: ppp11: send [LCP ProtoRej id=3 <8057>]
[2014-05-19 23:18:32]:  info: ppp11: recv [CCP ConfReq id=6 <mppe -H -M -S -L -D -C>]
[2014-05-19 23:18:32]:  info: ppp11: send [CCP ConfReq id=1 <mppe -H -M -S -L -D -C>]
[2014-05-19 23:18:32]:  info: ppp11: send [CCP ConfAck id=6]
[2014-05-19 23:18:32]:  info: ppp11: recv [iPCP ConfReq id=7 <addr 0.0.0.0> <dns1 0.0.0.0> <wins1 0.0.0.0> <dns2 0.0.0.0> <wins2 0.0.0.0>]
[2014-05-19 23:18:32]:  info: ppp11: send [iPCP ConfNak id=7 <addr 10.192.252.232> <dns1 172.30.0.1> <dns2 172.30.1.1>]
[2014-05-19 23:18:32]:  info: ppp11: recv [iPCP ConfAck id=1 <addr 172.31.1.254>]
[2014-05-19 23:18:32]:  info: ppp11: recv [CCP ConfAck id=1 <mppe -H -M -S -L -D -C>]
[2014-05-19 23:18:32]: debug: ppp11: ccp_layer_started
[2014-05-19 23:18:32]:  info: ppp11: recv [iPCP ConfReq id=8 <addr 10.192.252.232> <dns1 172.30.0.1> <wins1 0.0.0.0> <dns2 172.30.1.1> <wins2 0.0.0.0>]
[2014-05-19 23:18:32]:  info: ppp11: send [iPCP ConfAck id=8]
[2014-05-19 23:18:32]: debug: ppp11: ipcp_layer_started
[2014-05-19 23:18:32]:  info: ppp11: send [RADIUS(1) Accounting-Request id=1 <User-Name "maxi"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 127.0.0.1> <NAS-Port 11> <NAS-Port-Type Virtual> <Service-Type Framed-User> <Framed-Protocol PPP> <Calling-Station-Id "44:87:fc:52:4f:3b"> <Called-Station-Id "64:66:b3:03:b0:3e"> <Acct-Status-Type Start> <Acct-Authentic RADIUS> <Acct-Session-Id "5c7bde34627a8f23"> <Acct-Session-Time 0> <Acct-Input-Octets 0> <Acct-Output-Octets 0> <Acct-Input-Packets 0> <Acct-Output-Packets 0> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> <Framed-IP-Address 10.192.252.232>]
[2014-05-19 23:18:32]:  info: ppp11: recv [RADIUS(1) Accounting-Response id=1]
[2014-05-19 23:18:32]: debug: ppp11: pppoe: ppp started
[2014-05-19 23:18:32]:  info: ppp11: pppd_compat: ip-up started (pid 16815)
[2014-05-19 23:18:32]:  info: ppp11: pppd_compat: ip-up finished (0)
[2014-05-19 23:18:34]: debug: ppp9: recv [LCP EchoReq id=6a <magic 6229d6c4>]
[2014-05-19 23:18:34]: debug: ppp9: send [LCP EchoRep id=6a <magic 46e87ccd>]
[2014-05-19 23:18:35]: debug: ppp16: send [LCP EchoReq id=24 <magic 515f007c>]
[2014-05-19 23:18:37]: debug: ppp59: send [LCP EchoReq id=20 <magic 7c3dbd3d>]
[2014-05-19 23:18:37]: debug: ppp59: recv [LCP EchoRep id=20 <magic 0b22f899>]
[2014-05-19 23:18:37]: debug: ppp7: send [LCP EchoReq id=22 <magic 625558ec>]
[2014-05-19 23:18:37]: debug: ppp7: recv [LCP EchoRep id=22 <magic ec75247e>]
[2014-05-19 23:18:38]: debug: ppp9: send [LCP EchoReq id=21 <magic 46e87ccd>]
[2014-05-19 23:18:38]: debug: ppp9: recv [LCP EchoRep id=21 <magic c4d62962>]
[2014-05-19 23:18:38]: debug: ppp54: recv [LCP EchoReq id=15 <magic ed19f575>]
[2014-05-19 23:18:38]: debug: ppp54: send [LCP EchoRep id=15 <magic 71f32454>]
[2014-05-19 23:18:38]: debug: ppp0: send [LCP EchoReq id=21 <magic 6b8b4567>]
[2014-05-19 23:18:38]: debug: ppp0: recv [LCP EchoRep id=21 <magic 7b288438>]
[2014-05-19 23:18:38]: debug: ppp10: send [LCP EchoReq id=21 <magic 3d1b58ba>]
[2014-05-19 23:18:38]: debug: ppp10: recv [LCP EchoRep id=21 <magic 00855cb3>]
[2014-05-19 23:18:38]: debug: ppp12: send [LCP EchoReq id=21 <magic 2eb141f2>]
[2014-05-19 23:18:38]: debug: ppp12: recv [LCP EchoRep id=21 <magic fa80c1e7>]
[2014-05-19 23:18:38]: debug: ppp13: send [LCP EchoReq id=21 <magic 41b71efb>]
[2014-05-19 23:18:38]: debug: ppp13: recv [LCP EchoRep id=21 <magic 84f8a33c>]
[2014-05-19 23:18:38]: debug: ppp15: send [LCP EchoReq id=21 <magic 7545e146>]
[2014-05-19 23:18:38]: debug: ppp15: recv [LCP EchoRep id=21 <magic 636f2f6b>]
[2014-05-19 23:18:38]: debug: ppp18: send [LCP EchoReq id=21 <magic 12200854>]
[2014-05-19 23:18:38]: debug: ppp18: recv [LCP EchoRep id=21 <magic 6fb3e6c7>]
[2014-05-19 23:18:38]: debug: ppp1: send [LCP EchoReq id=21 <magic 327b23c6>]
[2014-05-19 23:18:38]: debug: ppp1: recv [LCP EchoReq id=20 <magic 7463fe14>]
[2014-05-19 23:18:38]: debug: ppp1: send [LCP EchoRep id=20 <magic 327b23c6>]
[2014-05-19 23:18:38]: debug: ppp1: recv [LCP EchoRep id=21 <magic 14fe6374>]
[2014-05-19 23:18:39]: debug: ppp20: send [LCP EchoReq id=21 <magic 0216231b>]
[2014-05-19 23:18:39]: debug: ppp20: recv [LCP EchoRep id=21 <magic aead4f27>]
[2014-05-19 23:18:39]: debug: ppp21: send [LCP EchoReq id=21 <magic 1f16e9e8>]
[2014-05-19 23:18:39]: debug: ppp21: recv [LCP EchoRep id=21 <magic 17ee604d>]
[2014-05-19 23:18:39]: debug: ppp16: send [LCP EchoReq id=25 <magic 515f007c>]
[2014-05-19 23:18:40]: debug: ppp5: send [LCP EchoReq id=22 <magic 19495cff>]
[2014-05-19 23:18:40]: debug: ppp5: recv [LCP EchoRep id=22 <magic 3b0ef428>]
[2014-05-19 23:18:40]: debug: ppp22: send [LCP EchoReq id=21 <magic 1190cde7>]
[2014-05-19 23:18:40]: debug: ppp22: recv [LCP EchoRep id=21 <magic c18dd627>]
[2014-05-19 23:18:40]: debug: ppp9: recv [LCP EchoReq id=6b <magic 6229d6c4>]
[2014-05-19 23:18:40]: debug: ppp9: send [LCP EchoRep id=6b <magic 46e87ccd>]
[2014-05-19 23:18:40]: debug: ppp8: send [LCP EchoReq id=22 <magic 238e1f29>]
[2014-05-19 23:18:40]: debug: ppp8: recv [LCP EchoRep id=22 <magic 4a59bd5d>]
[2014-05-19 23:18:40]: debug: ppp23: send [LCP EchoReq id=21 <magic 66ef438d>]
[2014-05-19 23:18:40]: debug: ppp23: recv [LCP EchoRep id=21 <magic 6c0a6359>]
[2014-05-19 23:18:40]: debug: ppp24: send [LCP EchoReq id=21 <magic 140e0f76>]
[2014-05-19 23:18:40]: debug: ppp24: recv [LCP EchoRep id=21 <magic cab0c92d>]
[2014-05-19 23:18:40]: debug: ppp25: send [LCP EchoReq id=21 <magic 3352255a>]
[2014-05-19 23:18:40]: debug: ppp25: recv [LCP EchoRep id=21 <magic 5164687f>]
[2014-05-19 23:18:40]: debug: ppp26: send [LCP EchoReq id=21 <magic 109cf92e>]
[2014-05-19 23:18:40]: debug: ppp26: recv [LCP EchoRep id=21 <magic 4808c10b>]
[2014-05-19 23:18:41]: debug: ppp30: send [LCP EchoReq id=21 <magic 41a7c4c9>]
[2014-05-19 23:18:41]: debug: ppp30: recv [LCP EchoRep id=21 <magic 2aecdd1f>]
[2014-05-19 23:18:41]: debug: ppp31: send [LCP EchoReq id=21 <magic 6b68079a>]
[2014-05-19 23:18:41]: debug: ppp31: recv [LCP EchoRep id=21 <magic fa8e9c3e>]
[2014-05-19 23:18:41]: debug: ppp32: send [LCP EchoReq id=21 <magic 4e6afb66>]
[2014-05-19 23:18:41]: debug: ppp32: recv [LCP EchoRep id=21 <magic 891329f5>]
[2014-05-19 23:18:41]: debug: ppp33: send [LCP EchoReq id=21 <magic 25e45d32>]
[2014-05-19 23:18:41]: debug: ppp33: recv [LCP EchoRep id=21 <magic ebbb0ed5>]
[2014-05-19 23:18:41]: debug: ppp37: send [LCP EchoReq id=21 <magic 7c83e458>]
[2014-05-19 23:18:41]: debug: ppp37: recv [LCP EchoRep id=21 <magic 08d9163b>]
[2014-05-19 23:18:41]: debug: ppp39: send [LCP EchoReq id=21 <magic 436c6125>]
[2014-05-19 23:18:41]: debug: ppp40: send [LCP EchoReq id=21 <magic 62bbd95a>]
[2014-05-19 23:18:41]: debug: ppp40: recv [LCP EchoRep id=21 <magic b2999f4b>]
[2014-05-19 23:18:41]: debug: ppp39: recv [LCP EchoRep id=21 <magic 362521d6>]
[2014-05-19 23:18:41]: debug: ppp42: send [LCP EchoReq id=21 <magic 333ab105>]
[2014-05-19 23:18:41]: debug: ppp42: recv [LCP EchoRep id=21 <magic 35f2d0ec>]
[2014-05-19 23:18:41]: debug: ppp36: send [LCP EchoReq id=21 <magic 3f2dba31>]
[2014-05-19 23:18:41]: debug: ppp36: recv [LCP EchoRep id=21 <magic 8d3f6c4a>]
[2014-05-19 23:18:41]: debug: ppp43: send [LCP EchoReq id=21 <magic 721da317>]
[2014-05-19 23:18:41]: debug: ppp43: recv [LCP EchoRep id=21 <magic 244cb284>]
[2014-05-19 23:18:41]: debug: ppp44: send [LCP EchoReq id=21 <magic 2443a858>]
[2014-05-19 23:18:41]: debug: ppp44: recv [LCP EchoRep id=21 <magic c930ab61>]
[2014-05-19 23:18:41]: debug: ppp48: send [LCP EchoReq id=21 <magic 08edbdab>]
[2014-05-19 23:18:41]: debug: ppp48: recv [LCP EchoRep id=21 <magic ad3c1841>]
[2014-05-19 23:18:41]: debug: ppp6: send [LCP EchoReq id=21 <magic 2ae8944a>]
[2014-05-19 23:18:41]: debug: ppp6: recv [LCP EchoRep id=21 <magic d412041d>]
[2014-05-19 23:18:41]: debug: ppp14: send [LCP EchoReq id=22 <magic 79e2a9e3>]
[2014-05-19 23:18:41]: debug: ppp14: recv [LCP EchoRep id=22 <magic 3f29223a>]
[2014-05-19 23:18:41]: debug: ppp17: send [LCP EchoReq id=21 <magic 5bd062c2>]
[2014-05-19 23:18:41]: debug: ppp17: recv [LCP EchoRep id=21 <magic 1566694d>]
[2014-05-19 23:18:41]: debug: ppp49: send [LCP EchoReq id=21 <magic 79838cb2>]
[2014-05-19 23:18:41]: debug: ppp49: recv [LCP EchoRep id=21 <magic f127af0f>]
[2014-05-19 23:18:42]: debug: ppp67: send [LCP EchoReq id=1f <magic 3804823e>]
[2014-05-19 23:18:42]: debug: ppp67: recv [LCP EchoRep id=1f <magic fd6b362b>]
[2014-05-19 23:18:42]: debug: ppp63: send [LCP EchoReq id=18 <magic 3855585c>]
[2014-05-19 23:18:42]: debug: ppp63: recv [LCP EchoRep id=18 <magic e453f07f>]
[2014-05-19 23:18:42]: debug: ppp51: send [LCP EchoReq id=21 <magic 0b03e0c6>]
[2014-05-19 23:18:42]: debug: ppp51: recv [LCP EchoRep id=21 <magic 21c44c87>]
[2014-05-19 23:18:42]: debug: ppp16: send [LCP EchoReq id=26 <magic 515f007c>]
[2014-05-19 23:18:43]: debug: ppp68: send [LCP EchoReq id=1d <magic 5c482a97>]
[2014-05-19 23:18:43]: debug: ppp68: recv [LCP EchoRep id=1d <magic 38728940>]
[2014-05-19 23:18:44]: debug: ppp34: send [LCP EchoReq id=21 <magic 519b500d>]
[2014-05-19 23:18:44]: debug: ppp34: recv [LCP EchoRep id=21 <magic 0524ea01>]
[2014-05-19 23:18:44]: debug: ppp38: send [LCP EchoReq id=21 <magic 257130a3>]
[2014-05-19 23:18:44]: debug: ppp38: recv [LCP EchoRep id=21 <magic 9cf6e0dc>]
[2014-05-19 23:18:44]: debug: ppp41: send [LCP EchoReq id=21 <magic 628c895d>]
[2014-05-19 23:18:44]: debug: ppp41: recv [LCP EchoRep id=21 <magic 9f464225>]
[2014-05-19 23:18:44]: debug: ppp45: send [LCP EchoReq id=21 <magic 2d1d5ae9>]
[2014-05-19 23:18:44]: debug: ppp46: send [LCP EchoReq id=21 <magic 6763845e>]
[2014-05-19 23:18:44]: debug: ppp46: recv [LCP EchoRep id=21 <magic cd97b70b>]
[2014-05-19 23:18:44]: debug: ppp45: recv [LCP EchoRep id=21 <magic f501fbe9>]
[2014-05-19 23:18:44]: debug: ppp47: send [LCP EchoReq id=21 <magic 75a2a8d4>]
[2014-05-19 23:18:44]: debug: ppp47: recv [LCP EchoRep id=21 <magic 40d054b0>]
[2014-05-19 23:18:44]: debug: ppp16: send [LCP EchoReq id=27 <magic 515f007c>]
[2014-05-19 23:18:46]: debug: ppp53: send [LCP EchoReq id=20 <magic 737b8ddc>]
[2014-05-19 23:18:46]: debug: ppp53: recv [LCP EchoRep id=20 <magic 21881300>]
[2014-05-19 23:18:46]: debug: ppp3: send [LCP EchoReq id=f <magic 57e4ccaf>]
[2014-05-19 23:18:46]: debug: ppp3: recv [LCP EchoRep id=f <magic 56dbfed6>]
[2014-05-19 23:18:46]: debug: ppp60: send [LCP EchoReq id=20 <magic 6ceaf087>]
[2014-05-19 23:18:46]: debug: ppp60: recv [LCP EchoRep id=20 <magic 59878000>]
[2014-05-19 23:18:46]: debug: ppp9: recv [LCP EchoReq id=6c <magic 6229d6c4>]
[2014-05-19 23:18:46]: debug: ppp9: send [LCP EchoRep id=6c <magic 46e87ccd>]
[2014-05-19 23:18:46]: debug: ppp61: send [LCP EchoReq id=20 <magic 22221a70>]
[2014-05-19 23:18:46]: debug: ppp61: recv [LCP EchoRep id=20 <magic 97d55800>]
[2014-05-19 23:18:46]: debug: ppp16: send [LCP EchoReq id=28 <magic 515f007c>]
[2014-05-19 23:18:46]: debug: ppp2: send [LCP EchoReq id=b <magic 4b588f54>]
[2014-05-19 23:18:46]: debug: ppp2: recv [LCP EchoRep id=b <magic 570e1976>]
[2014-05-19 23:18:47]: debug: ppp59: recv [LCP EchoReq id=15 <magic 99f8220b>]
[2014-05-19 23:18:47]: debug: ppp59: send [LCP EchoRep id=15 <magic 7c3dbd3d>]
[2014-05-19 23:18:47]:  info: recv [PPPoE PADI 44:87:fc:52:4f:3b => ff:ff:ff:ff:ff:ff sid=0000 <Service-Name > <Host-Uniq 3a0000000000000072000000>]
[2014-05-19 23:18:47]:  info: send [PPPoE PADO 64:66:b3:03:b0:3e => 44:87:fc:52:4f:3b sid=0000 <AC-Name accel-ppp> <Service-Name > <AC-Cookie 202b7449fe5a59be4656fd5b8a4a2d3a0d9f80dbcd638e5e> <Host-Uniq 3a0000000000000072000000>]
[2014-05-19 23:18:47]:  info: recv [PPPoE PADR 44:87:fc:52:4f:3b => 64:66:b3:03:b0:3e sid=0000 <Service-Name > <Host-Uniq 3a0000000000000073000000> <AC-Cookie 202b7449fe5a59be4656fd5b8a4a2d3a0d9f80dbcd638e5e>]
[2014-05-19 23:18:47]:  info: send [PPPoE PADS 64:66:b3:03:b0:3e => 44:87:fc:52:4f:3b sid=0039 <AC-Name accel-ppp> <Service-Name > <Host-Uniq 3a0000000000000073000000>]
[2014-05-19 23:18:47]:  info: ppp19: connect: ppp19 <--> pppoe(44:87:fc:52:4f:3b)
[2014-05-19 23:18:47]: debug: ppp19: lcp_layer_init
[2014-05-19 23:18:47]: debug: ppp19: auth_layer_init
[2014-05-19 23:18:47]: debug: ppp19: ccp_layer_init
[2014-05-19 23:18:47]: debug: ppp19: ipcp_layer_init
[2014-05-19 23:18:47]: debug: ppp19: ipv6cp_layer_init
[2014-05-19 23:18:47]: debug: ppp19: ppp established
[2014-05-19 23:18:47]: debug: ppp19: lcp_layer_start
[2014-05-19 23:18:47]:  info: ppp19: send [LCP ConfReq id=1 <auth MSCHAP-v2> <mru 1492> <magic 6de91b18>]
[2014-05-19 23:18:47]:  info: ppp19: recv [LCP ConfReq id=0 <mru 1480> <magic 2c4112e4> <pcomp> <accomp> < d 3 6 >]
[2014-05-19 23:18:47]:  info: ppp19: send [LCP ConfRej id=0  <pcomp> <accomp> < d 3 6 >]
[2014-05-19 23:18:47]:  info: ppp19: recv [LCP ConfReq id=1 <mru 1480> <magic 2c4112e4>]
[2014-05-19 23:18:47]:  info: ppp19: send [LCP ConfAck id=1 ]
[2014-05-19 23:18:48]: debug: ppp54: send [LCP EchoReq id=21 <magic 71f32454>]
[2014-05-19 23:18:48]: debug: ppp54: recv [LCP EchoRep id=21 <magic 75f519ed>]
[2014-05-19 23:18:48]: debug: ppp16: send [LCP EchoReq id=29 <magic 515f007c>]
[2014-05-19 23:18:49]: debug: ppp4: send [LCP EchoReq id=d <magic 7a6d8d3c>]
[2014-05-19 23:18:49]: debug: ppp4: recv [LCP EchoRep id=d <magic b02bcf83>]
[2014-05-19 23:18:50]: debug: ppp55: send [LCP EchoReq id=22 <magic 3a95f874>]
[2014-05-19 23:18:50]: debug: ppp55: recv [LCP EchoRep id=22 <magic 8a37c07f>]
[2014-05-19 23:18:50]: debug: ppp57: send [LCP EchoReq id=21 <magic 08138641>]
[2014-05-19 23:18:50]: debug: ppp57: recv [LCP EchoRep id=21 <magic 43f34f3e>]
[2014-05-19 23:18:50]: debug: ppp19: fsm timeout
[2014-05-19 23:18:50]:  info: ppp19: send [LCP ConfReq id=1 <auth MSCHAP-v2> <mru 1492> <magic 6de91b18>]
[2014-05-19 23:18:50]:  info: ppp19: recv [LCP ConfAck id=1 <auth MSCHAP-v2> <mru 1492> <magic 6de91b18>]
[2014-05-19 23:18:50]: debug: ppp19: lcp_layer_started
[2014-05-19 23:18:50]: debug: ppp19: auth_layer_start
[2014-05-19 23:18:50]:  info: ppp19: send [MSCHAP-v2 Challenge id=1 <8399758099c84b91ed9257ef7eeaf4be>]
[2014-05-19 23:18:50]:  info: ppp19: recv [LCP Ident id=2 <MSRASV5.20>]
[2014-05-19 23:18:50]:  info: ppp19: recv [LCP Ident id=3 <MSRAS-0-MAX>]
[2014-05-19 23:18:50]:  info: ppp19: recv [LCP Ident id=4 <▒Ax▒/▒▒@▒UZ▒▒fuW>]
[2014-05-19 23:18:50]:  info: ppp19: recv [MSCHAP-v2 Response id=1 <8964e23d32596771cc946860cc647e>, <8461c18cc21688cf669288bed1933edb6bb3c97e39df6>, F=0, name="maxi"]
[2014-05-19 23:18:50]:  info: ppp19: send [RADIUS(1) Access-Request id=1 <User-Name "maxi"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 127.0.0.1> <NAS-Port 19> <NAS-Port-Type Virtual> <Service-Type Framed-User> <Framed-Protocol PPP> <Calling-Station-Id "44:87:fc:52:4f:3b"> <Called-Station-Id "64:66:b3:03:b0:3e"><Microsoft MS-CHAP-Challenge ><Microsoft MS-CHAP2-Response >]
[2014-05-19 23:18:50]:  info: ppp19: recv [RADIUS(1) Access-Accept id=1 <Acct-Interim-Interval 300> <Session-Timeout 1039270> <Framed-IP-Address 10.192.252.232> <Framed-IP-Netmask 255.255.255.255><Microsoft MS-CHAP2-Success ><Microsoft MS-MPPE-Recv-Key ><Microsoft MS-MPPE-Send-Key ><Microsoft MS-MPPE-Encryption-Policy 1><Microsoft MS-MPPE-Encryption-Type 6>]
[2014-05-19 23:18:50]:  info: ppp19: send [MSCHAP-v2 Success id=1 "S=E7D625D80F8EC94C77CBD6F4489D8FC92B0AACB9 M=Authentication succeeded"]
[2014-05-19 23:18:50]: debug: ppp19: auth_layer_started
[2014-05-19 23:18:50]: debug: ppp19: ccp_layer_start
[2014-05-19 23:18:50]: debug: ppp19: ipcp_layer_start
[2014-05-19 23:18:50]:  info: ppp19: send [iPCP ConfReq id=1 <addr 172.31.1.254>]
[2014-05-19 23:18:50]: debug: ppp19: ipv6cp_layer_start
[2014-05-19 23:18:50]:  info: ppp19: maxi: authentication succeeded
[2014-05-19 23:18:50]:  warn: ppp19: IPV6CP: discarding packet
[2014-05-19 23:18:50]:  info: ppp19: send [LCP ProtoRej id=3 <8057>]
[2014-05-19 23:18:50]:  info: ppp19: recv [CCP ConfReq id=6 <mppe -H -M -S -L -D -C>]
[2014-05-19 23:18:50]:  info: ppp19: send [CCP ConfReq id=1 <mppe -H -M -S -L -D -C>]
[2014-05-19 23:18:50]:  info: ppp19: send [CCP ConfAck id=6]
[2014-05-19 23:18:50]:  info: ppp19: recv [iPCP ConfReq id=7 <addr 0.0.0.0> <dns1 0.0.0.0> <wins1 0.0.0.0> <dns2 0.0.0.0> <wins2 0.0.0.0>]
[2014-05-19 23:18:50]:  info: ppp19: send [iPCP ConfNak id=7 <addr 10.192.252.232> <dns1 172.30.0.1> <dns2 172.30.1.1>]
[2014-05-19 23:18:50]:  info: ppp19: recv [iPCP ConfAck id=1 <addr 172.31.1.254>]
[2014-05-19 23:18:50]:  info: ppp19: recv [CCP ConfAck id=1 <mppe -H -M -S -L -D -C>]
[2014-05-19 23:18:50]: debug: ppp19: ccp_layer_started
[2014-05-19 23:18:50]:  info: ppp19: recv [iPCP ConfReq id=8 <addr 10.192.252.232> <dns1 172.30.0.1> <wins1 0.0.0.0> <dns2 172.30.1.1> <wins2 0.0.0.0>]
[2014-05-19 23:18:50]:  info: ppp19: send [iPCP ConfAck id=8]
[2014-05-19 23:18:50]: debug: ppp19: ipcp_layer_started
[2014-05-19 23:18:50]:  info: ppp19: send [RADIUS(1) Accounting-Request id=1 <User-Name "maxi"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 127.0.0.1> <NAS-Port 19> <NAS-Port-Type Virtual> <Service-Type Framed-User> <Framed-Protocol PPP> <Calling-Station-Id "44:87:fc:52:4f:3b"> <Called-Station-Id "64:66:b3:03:b0:3e"> <Acct-Status-Type Start> <Acct-Authentic RADIUS> <Acct-Session-Id "5c7bde34627a8f24"> <Acct-Session-Time 0> <Acct-Input-Octets 0> <Acct-Output-Octets 0> <Acct-Input-Packets 0> <Acct-Output-Packets 0> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> <Framed-IP-Address 10.192.252.232>]
[2014-05-19 23:18:50]:  info: ppp19: recv [RADIUS(1) Accounting-Response id=1]
[2014-05-19 23:18:50]: debug: ppp19: pppoe: ppp started
[2014-05-19 23:18:50]:  info: ppp19: pppd_compat: ip-up started (pid 16839)
[2014-05-19 23:18:50]:  info: ppp19: pppd_compat: ip-up finished (0)
[2014-05-19 23:18:50]: debug: ppp16: send [LCP EchoReq id=2a <magic 515f007c>]
[2014-05-19 23:18:50]: debug: ppp62: send [LCP EchoReq id=16 <magic 2a487cb0>]
[2014-05-19 23:18:50]: debug: ppp56: send [LCP EchoReq id=20 <magic 419ac241>]
[2014-05-19 23:18:50]: debug: ppp56: recv [LCP EchoRep id=20 <magic ca2eea1f>]
[2014-05-19 23:18:50]: debug: ppp62: recv [LCP EchoRep id=16 <magic ba511d32>]
[2014-05-19 23:18:50]: debug: ppp62: recv [LCP EchoReq id=e <magic 321d51ba>]
[2014-05-19 23:18:50]: debug: ppp62: send [LCP EchoRep id=e <magic 2a487cb0>]
[2014-05-19 23:18:51]: debug: ppp64: send [LCP EchoReq id=20 <magic 5577f8e1>]
[2014-05-19 23:18:51]: debug: ppp64: recv [LCP EchoRep id=20 <magic 59207556>]
[2014-05-19 23:18:52]: debug: ppp11: send [LCP EchoReq id=3 <magic 542289ec>]
[2014-05-19 23:18:52]: debug: ppp58: send [LCP EchoReq id=22 <magic 1e7ff521>]
[2014-05-19 23:18:52]: debug: ppp58: recv [LCP EchoRep id=22 <magic d975ac5b>]
[2014-05-19 23:18:52]: debug: ppp9: recv [LCP EchoReq id=6d <magic 6229d6c4>]
[2014-05-19 23:18:52]: debug: ppp9: send [LCP EchoRep id=6d <magic 46e87ccd>]
[2014-05-19 23:18:52]: debug: ppp16: send [LCP EchoReq id=2b <magic 515f007c>]
[2014-05-19 23:18:54]: debug: ppp16: send [LCP EchoReq id=2c <magic 515f007c>]
[2014-05-19 23:18:56]: debug: ppp16: send [LCP EchoReq id=2d <magic 515f007c>]
[2014-05-19 23:18:57]: debug: ppp59: send [LCP EchoReq id=21 <magic 7c3dbd3d>]
[2014-05-19 23:18:57]: debug: ppp59: recv [LCP EchoRep id=21 <magic 0b22f899>]
[2014-05-19 23:18:57]: debug: ppp7: send [LCP EchoReq id=23 <magic 625558ec>]
[2014-05-19 23:18:57]: debug: ppp7: recv [LCP EchoRep id=23 <magic ec75247e>]
[2014-05-19 23:18:58]: debug: ppp9: send [LCP EchoReq id=22 <magic 46e87ccd>]
[2014-05-19 23:18:58]: debug: ppp9: recv [LCP EchoRep id=22 <magic c4d62962>]
[2014-05-19 23:18:58]: debug: ppp0: send [LCP EchoReq id=22 <magic 6b8b4567>]
[2014-05-19 23:18:58]: debug: ppp0: recv [LCP EchoRep id=22 <magic 7b288438>]
[2014-05-19 23:18:58]: debug: ppp0: recv [LCP EchoReq id=16 <magic 3884287b>]
[2014-05-19 23:18:58]: debug: ppp0: send [LCP EchoRep id=16 <magic 6b8b4567>]
[2014-05-19 23:18:58]: debug: ppp9: recv [LCP EchoReq id=6e <magic 6229d6c4>]
[2014-05-19 23:18:58]: debug: ppp9: send [LCP EchoRep id=6e <magic 46e87ccd>]
[2014-05-19 23:18:58]: debug: ppp10: send [LCP EchoReq id=22 <magic 3d1b58ba>]
[2014-05-19 23:18:58]: debug: ppp10: recv [LCP EchoRep id=22 <magic 00855cb3>]
[2014-05-19 23:18:58]: debug: ppp12: send [LCP EchoReq id=22 <magic 2eb141f2>]
[2014-05-19 23:18:58]: debug: ppp12: recv [LCP EchoRep id=22 <magic fa80c1e7>]
[2014-05-19 23:18:58]: debug: ppp13: send [LCP EchoReq id=22 <magic 41b71efb>]
[2014-05-19 23:18:58]: debug: ppp13: recv [LCP EchoRep id=22 <magic 84f8a33c>]
[2014-05-19 23:18:58]: debug: ppp15: send [LCP EchoReq id=22 <magic 7545e146>]
[2014-05-19 23:18:58]: debug: ppp15: recv [LCP EchoRep id=22 <magic 636f2f6b>]
[2014-05-19 23:18:58]: debug: ppp16: send [LCP EchoReq id=2e <magic 515f007c>]
[2014-05-19 23:18:58]: debug: ppp18: send [LCP EchoReq id=22 <magic 12200854>]
[2014-05-19 23:18:58]: debug: ppp18: recv [LCP EchoRep id=22 <magic 6fb3e6c7>]
[2014-05-19 23:18:58]: debug: ppp1: send [LCP EchoReq id=22 <magic 327b23c6>]
[2014-05-19 23:18:58]: debug: ppp1: recv [LCP EchoReq id=21 <magic 7463fe14>]
[2014-05-19 23:18:58]: debug: ppp1: send [LCP EchoRep id=21 <magic 327b23c6>]
[2014-05-19 23:18:58]: debug: ppp1: recv [LCP EchoRep id=22 <magic 14fe6374>]
[2014-05-19 23:18:59]: debug: ppp20: send [LCP EchoReq id=22 <magic 0216231b>]
[2014-05-19 23:18:59]: debug: ppp20: recv [LCP EchoRep id=22 <magic aead4f27>]
[2014-05-19 23:18:59]: debug: ppp21: send [LCP EchoReq id=22 <magic 1f16e9e8>]
[2014-05-19 23:18:59]: debug: ppp21: recv [LCP EchoRep id=22 <magic 17ee604d>]
[2014-05-19 23:19:00]: debug: ppp5: send [LCP EchoReq id=23 <magic 19495cff>]
[2014-05-19 23:19:00]: debug: ppp5: recv [LCP EchoRep id=23 <magic 3b0ef428>]
[2014-05-19 23:19:00]: debug: ppp22: send [LCP EchoReq id=22 <magic 1190cde7>]
[2014-05-19 23:19:00]: debug: ppp22: recv [LCP EchoRep id=22 <magic c18dd627>]
[2014-05-19 23:19:00]: debug: ppp8: send [LCP EchoReq id=23 <magic 238e1f29>]
[2014-05-19 23:19:00]: debug: ppp8: recv [LCP EchoRep id=23 <magic 4a59bd5d>]
[2014-05-19 23:19:00]: debug: ppp23: send [LCP EchoReq id=22 <magic 66ef438d>]
[2014-05-19 23:19:00]: debug: ppp23: recv [LCP EchoRep id=22 <magic 6c0a6359>]
[2014-05-19 23:19:00]: debug: ppp24: send [LCP EchoReq id=22 <magic 140e0f76>]
[2014-05-19 23:19:00]: debug: ppp24: recv [LCP EchoRep id=22 <magic cab0c92d>]
[2014-05-19 23:19:00]: debug: ppp25: send [LCP EchoReq id=22 <magic 3352255a>]
[2014-05-19 23:19:00]: debug: ppp25: recv [LCP EchoRep id=22 <magic 5164687f>]
[2014-05-19 23:19:00]: debug: ppp26: send [LCP EchoReq id=22 <magic 109cf92e>]
[2014-05-19 23:19:00]: debug: ppp26: recv [LCP EchoRep id=22 <magic 4808c10b>]
[2014-05-19 23:19:00]: debug: ppp16: send [LCP EchoReq id=2f <magic 515f007c>]
[2014-05-19 23:19:01]: debug: ppp30: send [LCP EchoReq id=22 <magic 41a7c4c9>]
[2014-05-19 23:19:01]: debug: ppp30: recv [LCP EchoRep id=22 <magic 2aecdd1f>]
[2014-05-19 23:19:01]: debug: ppp31: send [LCP EchoReq id=22 <magic 6b68079a>]
[2014-05-19 23:19:01]: debug: ppp31: recv [LCP EchoRep id=22 <magic fa8e9c3e>]
[2014-05-19 23:19:01]: debug: ppp32: send [LCP EchoReq id=22 <magic 4e6afb66>]
[2014-05-19 23:19:01]: debug: ppp32: recv [LCP EchoRep id=22 <magic 891329f5>]
[2014-05-19 23:19:01]: debug: ppp33: send [LCP EchoReq id=22 <magic 25e45d32>]
[2014-05-19 23:19:01]: debug: ppp33: recv [LCP EchoRep id=22 <magic ebbb0ed5>]
[2014-05-19 23:19:01]: debug: ppp37: send [LCP EchoReq id=22 <magic 7c83e458>]
[2014-05-19 23:19:01]: debug: ppp37: recv [LCP EchoRep id=22 <magic 08d9163b>]
[2014-05-19 23:19:01]: debug: ppp39: send [LCP EchoReq id=22 <magic 436c6125>]
[2014-05-19 23:19:01]: debug: ppp40: send [LCP EchoReq id=22 <magic 62bbd95a>]
[2014-05-19 23:19:01]: debug: ppp40: recv [LCP EchoRep id=22 <magic b2999f4b>]
[2014-05-19 23:19:01]: debug: ppp39: recv [LCP EchoRep id=22 <magic 362521d6>]
[2014-05-19 23:19:01]: debug: ppp42: send [LCP EchoReq id=22 <magic 333ab105>]
[2014-05-19 23:19:01]: debug: ppp42: recv [LCP EchoRep id=22 <magic 35f2d0ec>]
[2014-05-19 23:19:01]: debug: ppp36: send [LCP EchoReq id=22 <magic 3f2dba31>]
[2014-05-19 23:19:01]: debug: ppp36: recv [LCP EchoRep id=22 <magic 8d3f6c4a>]
[2014-05-19 23:19:01]: debug: ppp43: send [LCP EchoReq id=22 <magic 721da317>]
[2014-05-19 23:19:01]: debug: ppp43: recv [LCP EchoRep id=22 <magic 244cb284>]
[2014-05-19 23:19:01]: debug: ppp44: send [LCP EchoReq id=22 <magic 2443a858>]
[2014-05-19 23:19:01]: debug: ppp44: recv [LCP EchoRep id=22 <magic c930ab61>]
[2014-05-19 23:19:01]: debug: ppp48: send [LCP EchoReq id=22 <magic 08edbdab>]
[2014-05-19 23:19:01]: debug: ppp48: recv [LCP EchoRep id=22 <magic ad3c1841>]
[2014-05-19 23:19:01]: debug: ppp6: send [LCP EchoReq id=22 <magic 2ae8944a>]
[2014-05-19 23:19:01]: debug: ppp6: recv [LCP EchoRep id=22 <magic d412041d>]
[2014-05-19 23:19:01]: debug: ppp14: send [LCP EchoReq id=23 <magic 79e2a9e3>]
[2014-05-19 23:19:01]: debug: ppp14: recv [LCP EchoRep id=23 <magic 3f29223a>]
[2014-05-19 23:19:01]: debug: ppp17: send [LCP EchoReq id=22 <magic 5bd062c2>]
[2014-05-19 23:19:01]: debug: ppp17: recv [LCP EchoRep id=22 <magic 1566694d>]
[2014-05-19 23:19:01]: debug: ppp49: send [LCP EchoReq id=22 <magic 79838cb2>]
[2014-05-19 23:19:01]: debug: ppp49: recv [LCP EchoRep id=22 <magic f127af0f>]
[2014-05-19 23:19:01]: debug: ppp6: recv [LCP EchoReq id=16 <magic 1d0412d4>]
[2014-05-19 23:19:01]: debug: ppp6: send [LCP EchoRep id=16 <magic 2ae8944a>]
[2014-05-19 23:19:02]: debug: ppp67: send [LCP EchoReq id=20 <magic 3804823e>]
[2014-05-19 23:19:02]: debug: ppp67: recv [LCP EchoRep id=20 <magic fd6b362b>]
[2014-05-19 23:19:02]: debug: ppp63: send [LCP EchoReq id=19 <magic 3855585c>]
[2014-05-19 23:19:02]: debug: ppp63: recv [LCP EchoRep id=19 <magic e453f07f>]
[2014-05-19 23:19:02]: debug: ppp51: send [LCP EchoReq id=22 <magic 0b03e0c6>]
[2014-05-19 23:19:02]: debug: ppp51: recv [LCP EchoRep id=22 <magic 21c44c87>]
[2014-05-19 23:19:02]: debug: ppp16: send [LCP EchoReq id=30 <magic 515f007c>]
[2014-05-19 23:19:03]: debug: ppp68: send [LCP EchoReq id=1e <magic 5c482a97>]
[2014-05-19 23:19:03]: debug: ppp68: recv [LCP EchoRep id=1e <magic 38728940>]
[2014-05-19 23:19:04]: debug: ppp34: send [LCP EchoReq id=22 <magic 519b500d>]
[2014-05-19 23:19:04]: debug: ppp34: recv [LCP EchoRep id=22 <magic 0524ea01>]
[2014-05-19 23:19:04]: debug: ppp38: send [LCP EchoReq id=22 <magic 257130a3>]
[2014-05-19 23:19:04]: debug: ppp38: recv [LCP EchoRep id=22 <magic 9cf6e0dc>]
[2014-05-19 23:19:04]: debug: ppp41: send [LCP EchoReq id=22 <magic 628c895d>]
[2014-05-19 23:19:04]: debug: ppp41: recv [LCP EchoRep id=22 <magic 9f464225>]
[2014-05-19 23:19:04]: debug: ppp45: send [LCP EchoReq id=22 <magic 2d1d5ae9>]
[2014-05-19 23:19:04]: debug: ppp45: recv [LCP EchoRep id=22 <magic f501fbe9>]
[2014-05-19 23:19:04]: debug: ppp46: send [LCP EchoReq id=22 <magic 6763845e>]
[2014-05-19 23:19:04]: debug: ppp46: recv [LCP EchoRep id=22 <magic cd97b70b>]
[2014-05-19 23:19:04]: debug: ppp47: send [LCP EchoReq id=22 <magic 75a2a8d4>]
[2014-05-19 23:19:04]: debug: ppp47: recv [LCP EchoRep id=22 <magic 40d054b0>]
[2014-05-19 23:19:04]: debug: ppp9: recv [LCP EchoReq id=6f <magic 6229d6c4>]
[2014-05-19 23:19:04]: debug: ppp9: send [LCP EchoRep id=6f <magic 46e87ccd>]
[2014-05-19 23:19:04]: debug: ppp16: send [LCP EchoReq id=31 <magic 515f007c>]
[2014-05-19 23:19:06]: debug: ppp53: send [LCP EchoReq id=21 <magic 737b8ddc>]
[2014-05-19 23:19:06]: debug: ppp53: recv [LCP EchoRep id=21 <magic 21881300>]
[2014-05-19 23:19:06]: debug: ppp3: send [LCP EchoReq id=10 <magic 57e4ccaf>]
[2014-05-19 23:19:06]: debug: ppp3: recv [LCP EchoRep id=10 <magic 56dbfed6>]
[2014-05-19 23:19:06]: debug: ppp60: send [LCP EchoReq id=21 <magic 6ceaf087>]
[2014-05-19 23:19:06]: debug: ppp60: recv [LCP EchoRep id=21 <magic 59878000>]
[2014-05-19 23:19:06]: debug: ppp61: send [LCP EchoReq id=21 <magic 22221a70>]
[2014-05-19 23:19:06]: debug: ppp61: recv [LCP EchoRep id=21 <magic 97d55800>]
[2014-05-19 23:19:06]: debug: ppp16: send [LCP EchoReq id=32 <magic 515f007c>]
[2014-05-19 23:19:06]: debug: ppp2: send [LCP EchoReq id=c <magic 4b588f54>]
[2014-05-19 23:19:06]: debug: ppp2: recv [LCP EchoRep id=c <magic 570e1976>]
[2014-05-19 23:19:07]:  info: ppp19: recv [LCP TermReq id=9]
[2014-05-19 23:19:07]:  info: ppp19: send [LCP TermAck id=9]
[2014-05-19 23:19:07]: debug: ppp19: terminate
[2014-05-19 23:19:07]:  info: ppp19: send [RADIUS(1) Accounting-Request id=1 <User-Name "maxi"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 127.0.0.1> <NAS-Port 19> <NAS-Port-Type Virtual> <Service-Type Framed-User> <Framed-Protocol PPP> <Calling-Station-Id "44:87:fc:52:4f:3b"> <Called-Station-Id "64:66:b3:03:b0:3e"> <Acct-Status-Type Stop> <Acct-Authentic RADIUS> <Acct-Session-Id "5c7bde34627a8f24"> <Acct-Session-Time 20> <Acct-Input-Octets 23519> <Acct-Output-Octets 86> <Acct-Input-Packets 260> <Acct-Output-Packets 5> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> <Framed-IP-Address 10.192.252.232> <Acct-Terminate-Cause User-Request>]
[2014-05-19 23:19:07]:  info: ppp19: recv [RADIUS(1) Accounting-Response id=1]
[2014-05-19 23:19:07]: debug: ppp19: lcp_layer_finish
[2014-05-19 23:19:07]: debug: ppp19: auth_layer_finish
[2014-05-19 23:19:07]: debug: ppp19: auth_layer_finished
[2014-05-19 23:19:07]: debug: ppp19: ccp_layer_finish
[2014-05-19 23:19:07]: debug: ppp19: ccp_layer_finished
[2014-05-19 23:19:07]: debug: ppp19: ipcp_layer_finish
[2014-05-19 23:19:07]: debug: ppp19: ipcp_layer_finished
[2014-05-19 23:19:07]: debug: ppp19: ipv6cp_layer_finish
[2014-05-19 23:19:07]: debug: ppp19: ipv6cp_layer_finished
[2014-05-19 23:19:07]:  info: recv [PPPoE PADT 44:87:fc:52:4f:3b => 64:66:b3:03:b0:3e sid=0039]
[2014-05-19 23:19:07]:  info: ppp19: pppd_compat: ip-down started (pid 16861)
[2014-05-19 23:19:07]:  info: ppp19: pppd_compat: ip-down finished (0)
[2014-05-19 23:19:07]: debug: ppp19: lcp_layer_free
[2014-05-19 23:19:07]: debug: ppp19: auth_layer_free
[2014-05-19 23:19:07]: debug: ppp19: ccp_layer_free
[2014-05-19 23:19:07]: debug: ppp19: ipcp_layer_free
[2014-05-19 23:19:07]: debug: ppp19: ipv6cp_layer_free
[2014-05-19 23:19:07]: debug: ppp19: ppp destablished
[2014-05-19 23:19:07]: debug: ppp19: pppoe: ppp finished
[2014-05-19 23:19:07]:  info: send [PPPoE PADT 64:66:b3:03:b0:3e => 44:87:fc:52:4f:3b sid=0039 <AC-Name accel-ppp> <Service-Name > <Host-Uniq 3a0000000000000073000000>]
[2014-05-19 23:19:07]:  info: ppp19: disconnected
[2014-05-19 23:19:08]: debug: ppp54: send [LCP EchoReq id=22 <magic 71f32454>]
[2014-05-19 23:19:08]: debug: ppp54: recv [LCP EchoRep id=22 <magic 75f519ed>]
[2014-05-19 23:19:08]: debug: ppp54: recv [LCP EchoReq id=16 <magic ed19f575>]
[2014-05-19 23:19:08]: debug: ppp54: send [LCP EchoRep id=16 <magic 71f32454>]
[2014-05-19 23:19:08]: debug: ppp16: send [LCP EchoReq id=33 <magic 515f007c>]
[2014-05-19 23:19:09]: debug: ppp4: send [LCP EchoReq id=e <magic 7a6d8d3c>]
[2014-05-19 23:19:09]: debug: ppp4: recv [LCP EchoRep id=e <magic b02bcf83>]
[2014-05-19 23:19:09]:  info: recv [PPPoE PADI 44:87:fc:52:4f:3b => ff:ff:ff:ff:ff:ff sid=0000 <Service-Name > <Host-Uniq 3b0000000000000074000000>]
[2014-05-19 23:19:09]:  info: send [PPPoE PADO 64:66:b3:03:b0:3e => 44:87:fc:52:4f:3b sid=0000 <AC-Name accel-ppp> <Service-Name > <AC-Cookie 2bbc1676f2eaba7e81fa5924183b2f53c1ba4c5722631d45> <Host-Uniq 3b0000000000000074000000>]
[2014-05-19 23:19:09]:  info: recv [PPPoE PADR 44:87:fc:52:4f:3b => 64:66:b3:03:b0:3e sid=0000 <Service-Name > <Host-Uniq 3b0000000000000075000000> <AC-Cookie 2bbc1676f2eaba7e81fa5924183b2f53c1ba4c5722631d45>]
[2014-05-19 23:19:09]:  info: send [PPPoE PADS 64:66:b3:03:b0:3e => 44:87:fc:52:4f:3b sid=003a <AC-Name accel-ppp> <Service-Name > <Host-Uniq 3b0000000000000075000000>]
[2014-05-19 23:19:09]:  info: ppp19: connect: ppp19 <--> pppoe(44:87:fc:52:4f:3b)
[2014-05-19 23:19:09]: debug: ppp19: lcp_layer_init
[2014-05-19 23:19:09]: debug: ppp19: auth_layer_init
[2014-05-19 23:19:09]: debug: ppp19: ccp_layer_init
[2014-05-19 23:19:09]: debug: ppp19: ipcp_layer_init
[2014-05-19 23:19:09]: debug: ppp19: ipv6cp_layer_init
[2014-05-19 23:19:09]: debug: ppp19: ppp established
[2014-05-19 23:19:09]: debug: ppp19: lcp_layer_start
[2014-05-19 23:19:09]:  info: ppp19: send [LCP ConfReq id=1 <auth MSCHAP-v2> <mru 1492> <magic 38437fdb>]
[2014-05-19 23:19:09]:  info: ppp19: recv [LCP ConfReq id=0 <mru 1480> <magic 407654ef> <pcomp> <accomp> < d 3 6 >]
[2014-05-19 23:19:09]:  info: ppp19: send [LCP ConfRej id=0  <pcomp> <accomp> < d 3 6 >]
[2014-05-19 23:19:09]:  info: ppp19: recv [LCP ConfAck id=1 <auth MSCHAP-v2> <mru 1492> <magic 38437fdb>]
[2014-05-19 23:19:09]:  info: ppp19: recv [LCP ConfReq id=1 <mru 1480> <magic 407654ef>]
[2014-05-19 23:19:09]:  info: ppp19: send [LCP ConfAck id=1 ]
[2014-05-19 23:19:09]: debug: ppp19: lcp_layer_started
[2014-05-19 23:19:09]: debug: ppp19: auth_layer_start
[2014-05-19 23:19:09]:  info: ppp19: send [MSCHAP-v2 Challenge id=1 <8343358386bcebdb61e883858ee92e3>]
[2014-05-19 23:19:09]:  info: ppp19: recv [LCP Ident id=2 <MSRASV5.20>]
[2014-05-19 23:19:09]:  info: ppp19: recv [LCP Ident id=3 <MSRAS-0-MAX>]
[2014-05-19 23:19:09]:  info: ppp19: recv [LCP Ident id=4 <
?▒Q▒J▒{8@▒/c>]
[2014-05-19 23:19:09]:  info: ppp19: recv [MSCHAP-v2 Response id=1 <aea43759cdfc33e9c49bf1f60e3f95b>, <2695a041d57268f6c8db99656db301acb6cb0cc2352b0>, F=0, name="maxi"]
[2014-05-19 23:19:09]:  info: ppp19: send [RADIUS(1) Access-Request id=1 <User-Name "maxi"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 127.0.0.1> <NAS-Port 19> <NAS-Port-Type Virtual> <Service-Type Framed-User> <Framed-Protocol PPP> <Calling-Station-Id "44:87:fc:52:4f:3b"> <Called-Station-Id "64:66:b3:03:b0:3e"><Microsoft MS-CHAP-Challenge ><Microsoft MS-CHAP2-Response >]
[2014-05-19 23:19:09]:  info: ppp19: recv [RADIUS(1) Access-Accept id=1 <Acct-Interim-Interval 300> <Session-Timeout 1039251> <Framed-IP-Address 10.192.6.65> <Framed-IP-Netmask 255.255.255.255><Microsoft MS-CHAP2-Success ><Microsoft MS-MPPE-Recv-Key ><Microsoft MS-MPPE-Send-Key ><Microsoft MS-MPPE-Encryption-Policy 1><Microsoft MS-MPPE-Encryption-Type 6>]
[2014-05-19 23:19:09]:  info: ppp19: send [MSCHAP-v2 Success id=1 "S=DE91E532A2F8EDF88F40FF2C51101258351B6D3F M=Authentication succeeded"]
[2014-05-19 23:19:09]: debug: ppp19: auth_layer_started
[2014-05-19 23:19:09]: debug: ppp19: ccp_layer_start
[2014-05-19 23:19:09]: debug: ppp19: ipcp_layer_start
[2014-05-19 23:19:09]:  info: ppp19: send [iPCP ConfReq id=1 <addr 172.31.1.254>]
[2014-05-19 23:19:09]: debug: ppp19: ipv6cp_layer_start
[2014-05-19 23:19:09]:  info: ppp19: maxi: authentication succeeded
[2014-05-19 23:19:09]:  warn: ppp19: IPV6CP: discarding packet
[2014-05-19 23:19:09]:  info: ppp19: send [LCP ProtoRej id=3 <8057>]
[2014-05-19 23:19:09]:  info: ppp19: recv [CCP ConfReq id=6 <mppe -H -M -S -L -D -C>]
[2014-05-19 23:19:09]:  info: ppp19: send [CCP ConfReq id=1 <mppe -H -M -S -L -D -C>]
[2014-05-19 23:19:09]:  info: ppp19: send [CCP ConfAck id=6]
[2014-05-19 23:19:09]:  info: ppp19: recv [iPCP ConfReq id=7 <addr 0.0.0.0> <dns1 0.0.0.0> <wins1 0.0.0.0> <dns2 0.0.0.0> <wins2 0.0.0.0>]
[2014-05-19 23:19:09]:  info: ppp19: send [iPCP ConfNak id=7 <addr 10.192.6.65> <dns1 172.30.0.1> <dns2 172.30.1.1>]
[2014-05-19 23:19:09]:  info: ppp19: recv [iPCP ConfAck id=1 <addr 172.31.1.254>]
[2014-05-19 23:19:09]:  info: ppp19: recv [CCP ConfAck id=1 <mppe -H -M -S -L -D -C>]
[2014-05-19 23:19:09]: debug: ppp19: ccp_layer_started
[2014-05-19 23:19:09]:  info: ppp19: recv [iPCP ConfReq id=8 <addr 10.192.6.65> <dns1 172.30.0.1> <wins1 0.0.0.0> <dns2 172.30.1.1> <wins2 0.0.0.0>]
[2014-05-19 23:19:09]:  info: ppp19: send [iPCP ConfAck id=8]
[2014-05-19 23:19:09]: debug: ppp19: ipcp_layer_started
[2014-05-19 23:19:09]:  info: ppp19: send [RADIUS(1) Accounting-Request id=1 <User-Name "maxi"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 127.0.0.1> <NAS-Port 19> <NAS-Port-Type Virtual> <Service-Type Framed-User> <Framed-Protocol PPP> <Calling-Station-Id "44:87:fc:52:4f:3b"> <Called-Station-Id "64:66:b3:03:b0:3e"> <Acct-Status-Type Start> <Acct-Authentic RADIUS> <Acct-Session-Id "5c7bde34627a8f25"> <Acct-Session-Time 0> <Acct-Input-Octets 0> <Acct-Output-Octets 0> <Acct-Input-Packets 0> <Acct-Output-Packets 0> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> <Framed-IP-Address 10.192.6.65>]
[2014-05-19 23:19:09]:  info: ppp19: recv [RADIUS(1) Accounting-Response id=1]
[2014-05-19 23:19:09]: debug: ppp19: pppoe: ppp started
[2014-05-19 23:19:09]:  info: ppp19: pppd_compat: ip-up started (pid 16882)
[2014-05-19 23:19:09]:  info: ppp19: pppd_compat: ip-up finished (0)
[2014-05-19 23:19:10]: debug: ppp55: send [LCP EchoReq id=23 <magic 3a95f874>]
[2014-05-19 23:19:10]: debug: ppp55: recv [LCP EchoRep id=23 <magic 8a37c07f>]
[2014-05-19 23:19:10]: debug: ppp57: send [LCP EchoReq id=22 <magic 08138641>]
[2014-05-19 23:19:10]: debug: ppp57: recv [LCP EchoRep id=22 <magic 43f34f3e>]
[2014-05-19 23:19:10]: debug: ppp9: recv [LCP EchoReq id=70 <magic 6229d6c4>]
[2014-05-19 23:19:10]: debug: ppp9: send [LCP EchoRep id=70 <magic 46e87ccd>]
[2014-05-19 23:19:10]: debug: ppp62: send [LCP EchoReq id=17 <magic 2a487cb0>]
[2014-05-19 23:19:10]: debug: ppp62: recv [LCP EchoRep id=17 <magic ba511d32>]
[2014-05-19 23:19:10]: debug: ppp56: send [LCP EchoReq id=21 <magic 419ac241>]
[2014-05-19 23:19:10]: debug: ppp56: recv [LCP EchoRep id=21 <magic ca2eea1f>]
[2014-05-19 23:19:10]: debug: ppp16: send [LCP EchoReq id=34 <magic 515f007c>]
[2014-05-19 23:19:11]: debug: ppp64: send [LCP EchoReq id=21 <magic 5577f8e1>]
[2014-05-19 23:19:11]: debug: ppp64: recv [LCP EchoRep id=21 <magic 59207556>]
[2014-05-19 23:19:12]: debug: ppp11: send [LCP EchoReq id=4 <magic 542289ec>]
[2014-05-19 23:19:12]: debug: ppp58: send [LCP EchoReq id=23 <magic 1e7ff521>]
[2014-05-19 23:19:12]: debug: ppp58: recv [LCP EchoRep id=23 <magic d975ac5b>]
[2014-05-19 23:19:12]: debug: ppp16: send [LCP EchoReq id=35 <magic 515f007c>]
[2014-05-19 23:19:14]: debug: ppp16: send [LCP EchoReq id=36 <magic 515f007c>]
[2014-05-19 23:19:16]: debug: ppp9: recv [LCP EchoReq id=71 <magic 6229d6c4>]
[2014-05-19 23:19:16]: debug: ppp9: send [LCP EchoRep id=71 <magic 46e87ccd>]
[2014-05-19 23:19:16]: debug: ppp16: send [LCP EchoReq id=37 <magic 515f007c>]
[2014-05-19 23:19:17]: debug: ppp59: send [LCP EchoReq id=22 <magic 7c3dbd3d>]
[2014-05-19 23:19:17]: debug: ppp59: recv [LCP EchoRep id=22 <magic 0b22f899>]
[2014-05-19 23:19:17]: debug: ppp59: recv [LCP EchoReq id=16 <magic 99f8220b>]
[2014-05-19 23:19:17]: debug: ppp59: send [LCP EchoRep id=16 <magic 7c3dbd3d>]
[2014-05-19 23:19:17]: debug: ppp7: send [LCP EchoReq id=24 <magic 625558ec>]
[2014-05-19 23:19:17]: debug: ppp7: recv [LCP EchoRep id=24 <magic ec75247e>]
[2014-05-19 23:19:18]: debug: ppp9: send [LCP EchoReq id=23 <magic 46e87ccd>]
[2014-05-19 23:19:18]: debug: ppp9: recv [LCP EchoRep id=23 <magic c4d62962>]
[2014-05-19 23:19:18]: debug: ppp0: send [LCP EchoReq id=23 <magic 6b8b4567>]
[2014-05-19 23:19:18]: debug: ppp0: recv [LCP EchoRep id=23 <magic 7b288438>]
[2014-05-19 23:19:18]: debug: ppp10: send [LCP EchoReq id=23 <magic 3d1b58ba>]
[2014-05-19 23:19:18]: debug: ppp10: recv [LCP EchoRep id=23 <magic 00855cb3>]
[2014-05-19 23:19:18]: debug: ppp12: send [LCP EchoReq id=23 <magic 2eb141f2>]
[2014-05-19 23:19:18]: debug: ppp12: recv [LCP EchoRep id=23 <magic fa80c1e7>]
[2014-05-19 23:19:18]: debug: ppp13: send [LCP EchoReq id=23 <magic 41b71efb>]
[2014-05-19 23:19:18]: debug: ppp13: recv [LCP EchoRep id=23 <magic 84f8a33c>]
[2014-05-19 23:19:18]: debug: ppp15: send [LCP EchoReq id=23 <magic 7545e146>]
[2014-05-19 23:19:18]: debug: ppp15: recv [LCP EchoRep id=23 <magic 636f2f6b>]
[2014-05-19 23:19:18]: debug: ppp16: send [LCP EchoReq id=38 <magic 515f007c>]
[2014-05-19 23:19:18]: debug: ppp18: send [LCP EchoReq id=23 <magic 12200854>]
[2014-05-19 23:19:18]: debug: ppp18: recv [LCP EchoRep id=23 <magic 6fb3e6c7>]
[2014-05-19 23:19:18]: debug: ppp1: send [LCP EchoReq id=23 <magic 327b23c6>]
[2014-05-19 23:19:18]: debug: ppp1: recv [LCP EchoReq id=22 <magic 7463fe14>]
[2014-05-19 23:19:18]: debug: ppp1: send [LCP EchoRep id=22 <magic 327b23c6>]
[2014-05-19 23:19:18]: debug: ppp1: recv [LCP EchoRep id=23 <magic 14fe6374>]
[2014-05-19 23:19:19]: debug: ppp20: send [LCP EchoReq id=23 <magic 0216231b>]
[2014-05-19 23:19:19]: debug: ppp20: recv [LCP EchoRep id=23 <magic aead4f27>]
[2014-05-19 23:19:19]: debug: ppp21: send [LCP EchoReq id=23 <magic 1f16e9e8>]
[2014-05-19 23:19:19]: debug: ppp21: recv [LCP EchoRep id=23 <magic 17ee604d>]
[2014-05-19 23:19:20]: debug: ppp5: send [LCP EchoReq id=24 <magic 19495cff>]
[2014-05-19 23:19:20]: debug: ppp5: recv [LCP EchoRep id=24 <magic 3b0ef428>]
[2014-05-19 23:19:20]: debug: ppp22: send [LCP EchoReq id=23 <magic 1190cde7>]
[2014-05-19 23:19:20]: debug: ppp22: recv [LCP EchoRep id=23 <magic c18dd627>]
[2014-05-19 23:19:20]: debug: ppp8: send [LCP EchoReq id=24 <magic 238e1f29>]
[2014-05-19 23:19:20]: debug: ppp8: recv [LCP EchoRep id=24 <magic 4a59bd5d>]
[2014-05-19 23:19:20]: debug: ppp23: send [LCP EchoReq id=23 <magic 66ef438d>]
[2014-05-19 23:19:20]: debug: ppp23: recv [LCP EchoRep id=23 <magic 6c0a6359>]
[2014-05-19 23:19:20]: debug: ppp24: send [LCP EchoReq id=23 <magic 140e0f76>]
[2014-05-19 23:19:20]: debug: ppp24: recv [LCP EchoRep id=23 <magic cab0c92d>]
[2014-05-19 23:19:20]: debug: ppp25: send [LCP EchoReq id=23 <magic 3352255a>]
[2014-05-19 23:19:20]: debug: ppp25: recv [LCP EchoRep id=23 <magic 5164687f>]
[2014-05-19 23:19:20]: debug: ppp26: send [LCP EchoReq id=23 <magic 109cf92e>]
[2014-05-19 23:19:20]: debug: ppp26: recv [LCP EchoRep id=23 <magic 4808c10b>]
[2014-05-19 23:19:20]:  warn: ppp16: lcp: no echo reply
[2014-05-19 23:19:20]: debug: ppp16: terminate
[2014-05-19 23:19:20]:  info: ppp16: send [RADIUS(1) Accounting-Request id=3 <User-Name "per_ivansusan20"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 127.0.0.1> <NAS-Port 16> <NAS-Port-Type Virtual> <Service-Type Framed-User> <Framed-Protocol PPP> <Calling-Station-Id "dc:9f:db:90:5e:55"> <Called-Station-Id "64:66:b3:03:b0:3e"> <Acct-Status-Type Stop> <Acct-Authentic RADIUS> <Acct-Session-Id "5c7bde34627a8ed7"> <Acct-Session-Time 682> <Acct-Input-Octets 1282> <Acct-Output-Octets 9724> <Acct-Input-Packets 22> <Acct-Output-Packets 22> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> <Framed-IP-Address 10.192.168.27> <Acct-Terminate-Cause Lost-Carrier>]
[2014-05-19 23:19:20]:  info: ppp16: recv [RADIUS(1) Accounting-Response id=3]
[2014-05-19 23:19:20]:  info: ppp16: pppd_compat: ip-down started (pid 16907)
[2014-05-19 23:19:20]: debug: ppp62: recv [LCP EchoReq id=f <magic 321d51ba>]
[2014-05-19 23:19:20]: debug: ppp62: send [LCP EchoRep id=f <magic 2a487cb0>]
[2014-05-19 23:19:20]:  info: ppp16: pppd_compat: ip-down finished (0)
[2014-05-19 23:19:20]: debug: ppp16: lcp_layer_free
[2014-05-19 23:19:20]: debug: ppp16: auth_layer_free
[2014-05-19 23:19:20]: debug: ppp16: ccp_layer_free
[2014-05-19 23:19:20]: debug: ppp16: ipcp_layer_free
[2014-05-19 23:19:20]: debug: ppp16: ipv6cp_layer_free
[2014-05-19 23:19:20]: debug: ppp16: ppp destablished
[2014-05-19 23:19:20]: debug: ppp16: pppoe: ppp finished
[2014-05-19 23:19:20]:  info: send [PPPoE PADT 64:66:b3:03:b0:3e => dc:9f:db:90:5e:55 sid=0007 <AC-Name accel-ppp> <Service-Name > <Host-Uniq 00000dfb>]
[2014-05-19 23:19:20]:  info: ppp16: disconnected
[2014-05-19 23:19:21]: debug: ppp30: send [LCP EchoReq id=23 <magic 41a7c4c9>]
[2014-05-19 23:19:21]: debug: ppp30: recv [LCP EchoRep id=23 <magic 2aecdd1f>]
[2014-05-19 23:19:21]: debug: ppp31: send [LCP EchoReq id=23 <magic 6b68079a>]
[2014-05-19 23:19:21]: debug: ppp31: recv [LCP EchoRep id=23 <magic fa8e9c3e>]
[2014-05-19 23:19:21]: debug: ppp32: send [LCP EchoReq id=23 <magic 4e6afb66>]
[2014-05-19 23:19:21]: debug: ppp32: recv [LCP EchoRep id=23 <magic 891329f5>]
[2014-05-19 23:19:21]: debug: ppp33: send [LCP EchoReq id=23 <magic 25e45d32>]
[2014-05-19 23:19:21]: debug: ppp33: recv [LCP EchoRep id=23 <magic ebbb0ed5>]
[2014-05-19 23:19:21]: debug: ppp37: send [LCP EchoReq id=23 <magic 7c83e458>]
[2014-05-19 23:19:21]: debug: ppp37: recv [LCP EchoRep id=23 <magic 08d9163b>]
[2014-05-19 23:19:21]: debug: ppp39: send [LCP EchoReq id=23 <magic 436c6125>]
[2014-05-19 23:19:21]: debug: ppp40: send [LCP EchoReq id=23 <magic 62bbd95a>]
[2014-05-19 23:19:21]: debug: ppp40: recv [LCP EchoRep id=23 <magic b2999f4b>]
[2014-05-19 23:19:21]: debug: ppp39: recv [LCP EchoRep id=23 <magic 362521d6>]
[2014-05-19 23:19:21]: debug: ppp42: send [LCP EchoReq id=23 <magic 333ab105>]
[2014-05-19 23:19:21]: debug: ppp42: recv [LCP EchoRep id=23 <magic 35f2d0ec>]
[2014-05-19 23:19:21]: debug: ppp36: send [LCP EchoReq id=23 <magic 3f2dba31>]
[2014-05-19 23:19:21]: debug: ppp36: recv [LCP EchoRep id=23 <magic 8d3f6c4a>]
[2014-05-19 23:19:21]: debug: ppp43: send [LCP EchoReq id=23 <magic 721da317>]
[2014-05-19 23:19:21]: debug: ppp43: recv [LCP EchoRep id=23 <magic 244cb284>]
[2014-05-19 23:19:21]: debug: ppp44: send [LCP EchoReq id=23 <magic 2443a858>]
[2014-05-19 23:19:21]: debug: ppp44: recv [LCP EchoRep id=23 <magic c930ab61>]
[2014-05-19 23:19:21]: debug: ppp48: send [LCP EchoReq id=23 <magic 08edbdab>]
[2014-05-19 23:19:21]: debug: ppp48: recv [LCP EchoRep id=23 <magic ad3c1841>]
[2014-05-19 23:19:21]: debug: ppp6: send [LCP EchoReq id=23 <magic 2ae8944a>]
[2014-05-19 23:19:21]: debug: ppp6: recv [LCP EchoRep id=23 <magic d412041d>]
[2014-05-19 23:19:21]: debug: ppp14: send [LCP EchoReq id=24 <magic 79e2a9e3>]
[2014-05-19 23:19:21]: debug: ppp14: recv [LCP EchoRep id=24 <magic 3f29223a>]
[2014-05-19 23:19:21]: debug: ppp17: send [LCP EchoReq id=23 <magic 5bd062c2>]
[2014-05-19 23:19:21]: debug: ppp17: recv [LCP EchoRep id=23 <magic 1566694d>]
[2014-05-19 23:19:21]: debug: ppp49: send [LCP EchoReq id=23 <magic 79838cb2>]
[2014-05-19 23:19:21]: debug: ppp49: recv [LCP EchoRep id=23 <magic f127af0f>]
[2014-05-19 23:19:22]: debug: ppp67: send [LCP EchoReq id=21 <magic 3804823e>]
[2014-05-19 23:19:22]: debug: ppp67: recv [LCP EchoRep id=21 <magic fd6b362b>]
[2014-05-19 23:19:22]: debug: ppp63: send [LCP EchoReq id=1a <magic 3855585c>]
[2014-05-19 23:19:22]: debug: ppp63: recv [LCP EchoRep id=1a <magic e453f07f>]
[2014-05-19 23:19:22]: debug: ppp51: send [LCP EchoReq id=23 <magic 0b03e0c6>]
[2014-05-19 23:19:22]: debug: ppp51: recv [LCP EchoRep id=23 <magic 21c44c87>]
[2014-05-19 23:19:22]: debug: ppp9: recv [LCP EchoReq id=72 <magic 6229d6c4>]
[2014-05-19 23:19:22]: debug: ppp9: send [LCP EchoRep id=72 <magic 46e87ccd>]
[2014-05-19 23:19:23]: debug: ppp68: send [LCP EchoReq id=1f <magic 5c482a97>]
[2014-05-19 23:19:23]: debug: ppp68: recv [LCP EchoRep id=1f <magic 38728940>]
[2014-05-19 23:19:24]: debug: ppp34: send [LCP EchoReq id=23 <magic 519b500d>]
[2014-05-19 23:19:24]: debug: ppp34: recv [LCP EchoRep id=23 <magic 0524ea01>]
[2014-05-19 23:19:24]: debug: ppp38: send [LCP EchoReq id=23 <magic 257130a3>]
[2014-05-19 23:19:24]: debug: ppp38: recv [LCP EchoRep id=23 <magic 9cf6e0dc>]
[2014-05-19 23:19:24]: debug: ppp41: send [LCP EchoReq id=23 <magic 628c895d>]
[2014-05-19 23:19:24]: debug: ppp41: recv [LCP EchoRep id=23 <magic 9f464225>]
[2014-05-19 23:19:24]: debug: ppp45: send [LCP EchoReq id=23 <magic 2d1d5ae9>]
[2014-05-19 23:19:24]: debug: ppp46: send [LCP EchoReq id=23 <magic 6763845e>]
[2014-05-19 23:19:24]: debug: ppp45: recv [LCP EchoRep id=23 <magic f501fbe9>]
[2014-05-19 23:19:24]: debug: ppp46: recv [LCP EchoRep id=23 <magic cd97b70b>]
[2014-05-19 23:19:24]: debug: ppp47: send [LCP EchoReq id=23 <magic 75a2a8d4>]
[2014-05-19 23:19:24]: debug: ppp47: recv [LCP EchoRep id=23 <magic 40d054b0>]
[2014-05-19 23:19:25]: debug: ppp63: recv [LCP EchoReq id=c <magic 7ff053e4>]
[2014-05-19 23:19:25]: debug: ppp63: send [LCP EchoRep id=c <magic 3855585c>]
[2014-05-19 23:19:26]: debug: ppp53: send [LCP EchoReq id=22 <magic 737b8ddc>]
[2014-05-19 23:19:26]: debug: ppp53: recv [LCP EchoRep id=22 <magic 21881300>]
[2014-05-19 23:19:26]: debug: ppp3: send [LCP EchoReq id=11 <magic 57e4ccaf>]
[2014-05-19 23:19:26]: debug: ppp3: recv [LCP EchoRep id=11 <magic 56dbfed6>]
[2014-05-19 23:19:26]: debug: ppp60: send [LCP EchoReq id=22 <magic 6ceaf087>]
[2014-05-19 23:19:26]: debug: ppp60: recv [LCP EchoRep id=22 <magic 59878000>]
[2014-05-19 23:19:26]: debug: ppp61: send [LCP EchoReq id=22 <magic 22221a70>]
[2014-05-19 23:19:26]: debug: ppp61: recv [LCP EchoRep id=22 <magic 97d55800>]
[2014-05-19 23:19:26]: debug: ppp2: send [LCP EchoReq id=d <magic 4b588f54>]
[2014-05-19 23:19:26]: debug: ppp2: recv [LCP EchoRep id=d <magic 570e1976>]
[2014-05-19 23:19:28]: debug: ppp54: send [LCP EchoReq id=23 <magic 71f32454>]
[2014-05-19 23:19:28]: debug: ppp54: recv [LCP EchoRep id=23 <magic 75f519ed>]
[2014-05-19 23:19:28]: debug: ppp0: recv [LCP EchoReq id=17 <magic 3884287b>]
[2014-05-19 23:19:28]: debug: ppp0: send [LCP EchoRep id=17 <magic 6b8b4567>]
[2014-05-19 23:19:28]: debug: ppp9: recv [LCP EchoReq id=73 <magic 6229d6c4>]
[2014-05-19 23:19:28]: debug: ppp9: send [LCP EchoRep id=73 <magic 46e87ccd>]
[2014-05-19 23:19:29]: debug: ppp4: send [LCP EchoReq id=f <magic 7a6d8d3c>]
[2014-05-19 23:19:29]: debug: ppp4: recv [LCP EchoRep id=f <magic b02bcf83>]
[2014-05-19 23:19:29]: debug: ppp19: send [LCP EchoReq id=3 <magic 38437fdb>]
[2014-05-19 23:19:29]: debug: ppp19: recv [LCP EchoRep id=3 <magic ef547640>]
[2014-05-19 23:19:30]: debug: ppp55: send [LCP EchoReq id=24 <magic 3a95f874>]
[2014-05-19 23:19:30]: debug: ppp55: recv [LCP EchoRep id=24 <magic 8a37c07f>]
[2014-05-19 23:19:30]: debug: ppp57: send [LCP EchoReq id=23 <magic 08138641>]
[2014-05-19 23:19:30]: debug: ppp57: recv [LCP EchoRep id=23 <magic 43f34f3e>]
[2014-05-19 23:19:30]: debug: ppp62: send [LCP EchoReq id=18 <magic 2a487cb0>]
[2014-05-19 23:19:30]: debug: ppp56: send [LCP EchoReq id=22 <magic 419ac241>]
[2014-05-19 23:19:30]: debug: ppp56: recv [LCP EchoRep id=22 <magic ca2eea1f>]
[2014-05-19 23:19:30]: debug: ppp62: recv [LCP EchoRep id=18 <magic ba511d32>]
[2014-05-19 23:19:31]: debug: ppp64: send [LCP EchoReq id=22 <magic 5577f8e1>]
[2014-05-19 23:19:31]: debug: ppp64: recv [LCP EchoRep id=22 <magic 59207556>]
[2014-05-19 23:19:31]: debug: ppp6: recv [LCP EchoReq id=17 <magic 1d0412d4>]
[2014-05-19 23:19:31]: debug: ppp6: send [LCP EchoRep id=17 <magic 2ae8944a>]
[2014-05-19 23:19:32]: debug: ppp11: send [LCP EchoReq id=5 <magic 542289ec>]
[2014-05-19 23:19:32]: debug: ppp58: send [LCP EchoReq id=24 <magic 1e7ff521>]
[2014-05-19 23:19:32]: debug: ppp58: recv [LCP EchoRep id=24 <magic d975ac5b>]
[2014-05-19 23:19:34]: debug: ppp9: recv [LCP EchoReq id=74 <magic 6229d6c4>]
[2014-05-19 23:19:34]: debug: ppp9: send [LCP EchoRep id=74 <magic 46e87ccd>]
[2014-05-19 23:19:37]: debug: ppp59: send [LCP EchoReq id=23 <magic 7c3dbd3d>]
[2014-05-19 23:19:37]: debug: ppp59: recv [LCP EchoRep id=23 <magic 0b22f899>]
[2014-05-19 23:19:37]: debug: ppp7: send [LCP EchoReq id=25 <magic 625558ec>]
[2014-05-19 23:19:37]: debug: ppp7: recv [LCP EchoRep id=25 <magic ec75247e>]
[2014-05-19 23:19:38]: debug: ppp9: send [LCP EchoReq id=24 <magic 46e87ccd>]
[2014-05-19 23:19:38]: debug: ppp9: recv [LCP EchoRep id=24 <magic c4d62962>]
[2014-05-19 23:19:38]: debug: ppp54: recv [LCP EchoReq id=17 <magic ed19f575>]
[2014-05-19 23:19:38]: debug: ppp54: send [LCP EchoRep id=17 <magic 71f32454>]
[2014-05-19 23:19:38]: debug: ppp0: send [LCP EchoReq id=24 <magic 6b8b4567>]
[2014-05-19 23:19:38]: debug: ppp0: recv [LCP EchoRep id=24 <magic 7b288438>]
[2014-05-19 23:19:38]: debug: ppp10: send [LCP EchoReq id=24 <magic 3d1b58ba>]
[2014-05-19 23:19:38]: debug: ppp10: recv [LCP EchoRep id=24 <magic 00855cb3>]
[2014-05-19 23:19:38]: debug: ppp12: send [LCP EchoReq id=24 <magic 2eb141f2>]
[2014-05-19 23:19:38]: debug: ppp12: recv [LCP EchoRep id=24 <magic fa80c1e7>]
[2014-05-19 23:19:38]: debug: ppp13: send [LCP EchoReq id=24 <magic 41b71efb>]
[2014-05-19 23:19:38]: debug: ppp13: recv [LCP EchoRep id=24 <magic 84f8a33c>]
[2014-05-19 23:19:38]: debug: ppp15: send [LCP EchoReq id=24 <magic 7545e146>]
[2014-05-19 23:19:38]: debug: ppp15: recv [LCP EchoRep id=24 <magic 636f2f6b>]
[2014-05-19 23:19:38]: debug: ppp18: send [LCP EchoReq id=24 <magic 12200854>]
[2014-05-19 23:19:38]: debug: ppp18: recv [LCP EchoRep id=24 <magic 6fb3e6c7>]
[2014-05-19 23:19:38]: debug: ppp1: send [LCP EchoReq id=24 <magic 327b23c6>]
[2014-05-19 23:19:38]: debug: ppp1: recv [LCP EchoReq id=23 <magic 7463fe14>]
[2014-05-19 23:19:38]: debug: ppp1: send [LCP EchoRep id=23 <magic 327b23c6>]
[2014-05-19 23:19:38]: debug: ppp1: recv [LCP EchoRep id=24 <magic 14fe6374>]
[2014-05-19 23:19:39]: debug: ppp20: send [LCP EchoReq id=24 <magic 0216231b>]
[2014-05-19 23:19:39]: debug: ppp20: recv [LCP EchoRep id=24 <magic aead4f27>]
[2014-05-19 23:19:39]: debug: ppp21: send [LCP EchoReq id=24 <magic 1f16e9e8>]
[2014-05-19 23:19:39]: debug: ppp21: recv [LCP EchoRep id=24 <magic 17ee604d>]
[2014-05-19 23:19:40]: debug: ppp5: send [LCP EchoReq id=25 <magic 19495cff>]
[2014-05-19 23:19:40]: debug: ppp5: recv [LCP EchoRep id=25 <magic 3b0ef428>]
[2014-05-19 23:19:40]: debug: ppp22: send [LCP EchoReq id=24 <magic 1190cde7>]
[2014-05-19 23:19:40]: debug: ppp22: recv [LCP EchoRep id=24 <magic c18dd627>]
[2014-05-19 23:19:40]: debug: ppp8: send [LCP EchoReq id=25 <magic 238e1f29>]
[2014-05-19 23:19:40]: debug: ppp8: recv [LCP EchoRep id=25 <magic 4a59bd5d>]
[2014-05-19 23:19:40]: debug: ppp23: send [LCP EchoReq id=24 <magic 66ef438d>]
[2014-05-19 23:19:40]: debug: ppp23: recv [LCP EchoRep id=24 <magic 6c0a6359>]
[2014-05-19 23:19:40]: debug: ppp9: recv [LCP EchoReq id=75 <magic 6229d6c4>]
[2014-05-19 23:19:40]: debug: ppp9: send [LCP EchoRep id=75 <magic 46e87ccd>]
[2014-05-19 23:19:40]: debug: ppp24: send [LCP EchoReq id=24 <magic 140e0f76>]
[2014-05-19 23:19:40]: debug: ppp24: recv [LCP EchoRep id=24 <magic cab0c92d>]
[2014-05-19 23:19:40]: debug: ppp25: send [LCP EchoReq id=24 <magic 3352255a>]
[2014-05-19 23:19:40]: debug: ppp25: recv [LCP EchoRep id=24 <magic 5164687f>]
[2014-05-19 23:19:40]: debug: ppp26: send [LCP EchoReq id=24 <magic 109cf92e>]
[2014-05-19 23:19:40]: debug: ppp26: recv [LCP EchoRep id=24 <magic 4808c10b>]
[2014-05-19 23:19:41]: debug: ppp30: send [LCP EchoReq id=24 <magic 41a7c4c9>]
[2014-05-19 23:19:41]: debug: ppp30: recv [LCP EchoRep id=24 <magic 2aecdd1f>]
[2014-05-19 23:19:41]: debug: ppp31: send [LCP EchoReq id=24 <magic 6b68079a>]
[2014-05-19 23:19:41]: debug: ppp31: recv [LCP EchoRep id=24 <magic fa8e9c3e>]
[2014-05-19 23:19:41]: debug: ppp32: send [LCP EchoReq id=24 <magic 4e6afb66>]
[2014-05-19 23:19:41]: debug: ppp32: recv [LCP EchoRep id=24 <magic 891329f5>]
[2014-05-19 23:19:41]: debug: ppp33: send [LCP EchoReq id=24 <magic 25e45d32>]
[2014-05-19 23:19:41]: debug: ppp33: recv [LCP EchoRep id=24 <magic ebbb0ed5>]
[2014-05-19 23:19:41]: debug: ppp37: send [LCP EchoReq id=24 <magic 7c83e458>]
[2014-05-19 23:19:41]: debug: ppp37: recv [LCP EchoRep id=24 <magic 08d9163b>]
[2014-05-19 23:19:41]: debug: ppp39: send [LCP EchoReq id=24 <magic 436c6125>]
[2014-05-19 23:19:41]: debug: ppp40: send [LCP EchoReq id=24 <magic 62bbd95a>]
[2014-05-19 23:19:41]: debug: ppp40: recv [LCP EchoRep id=24 <magic b2999f4b>]
[2014-05-19 23:19:41]: debug: ppp39: recv [LCP EchoRep id=24 <magic 362521d6>]
[2014-05-19 23:19:41]: debug: ppp42: send [LCP EchoReq id=24 <magic 333ab105>]
[2014-05-19 23:19:41]: debug: ppp42: recv [LCP EchoRep id=24 <magic 35f2d0ec>]
[2014-05-19 23:19:41]: debug: ppp36: send [LCP EchoReq id=24 <magic 3f2dba31>]
[2014-05-19 23:19:41]: debug: ppp36: recv [LCP EchoRep id=24 <magic 8d3f6c4a>]
[2014-05-19 23:19:41]: debug: ppp43: send [LCP EchoReq id=24 <magic 721da317>]
[2014-05-19 23:19:41]: debug: ppp43: recv [LCP EchoRep id=24 <magic 244cb284>]
[2014-05-19 23:19:41]: debug: ppp44: send [LCP EchoReq id=24 <magic 2443a858>]
[2014-05-19 23:19:41]: debug: ppp44: recv [LCP EchoRep id=24 <magic c930ab61>]
[2014-05-19 23:19:41]: debug: ppp48: send [LCP EchoReq id=24 <magic 08edbdab>]
[2014-05-19 23:19:41]: debug: ppp48: recv [LCP EchoRep id=24 <magic ad3c1841>]
[2014-05-19 23:19:41]: debug: ppp6: send [LCP EchoReq id=24 <magic 2ae8944a>]
[2014-05-19 23:19:41]: debug: ppp6: recv [LCP EchoRep id=24 <magic d412041d>]
[2014-05-19 23:19:41]: debug: ppp14: send [LCP EchoReq id=25 <magic 79e2a9e3>]
[2014-05-19 23:19:41]: debug: ppp14: recv [LCP EchoRep id=25 <magic 3f29223a>]
[2014-05-19 23:19:41]: debug: ppp17: send [LCP EchoReq id=24 <magic 5bd062c2>]
[2014-05-19 23:19:41]: debug: ppp17: recv [LCP EchoRep id=24 <magic 1566694d>]
[2014-05-19 23:19:41]: debug: ppp49: send [LCP EchoReq id=24 <magic 79838cb2>]
[2014-05-19 23:19:41]: debug: ppp49: recv [LCP EchoRep id=24 <magic f127af0f>]
[2014-05-19 23:19:42]: debug: ppp67: send [LCP EchoReq id=22 <magic 3804823e>]
[2014-05-19 23:19:42]: debug: ppp67: recv [LCP EchoRep id=22 <magic fd6b362b>]
[2014-05-19 23:19:42]: debug: ppp63: send [LCP EchoReq id=1b <magic 3855585c>]
[2014-05-19 23:19:42]: debug: ppp63: recv [LCP EchoRep id=1b <magic e453f07f>]
[2014-05-19 23:19:42]: debug: ppp51: send [LCP EchoReq id=24 <magic 0b03e0c6>]
[2014-05-19 23:19:42]: debug: ppp51: recv [LCP EchoRep id=24 <magic 21c44c87>]
[2014-05-19 23:19:43]: debug: ppp68: send [LCP EchoReq id=20 <magic 5c482a97>]
[2014-05-19 23:19:43]: debug: ppp68: recv [LCP EchoRep id=20 <magic 38728940>]
[2014-05-19 23:19:44]: debug: ppp34: send [LCP EchoReq id=24 <magic 519b500d>]
[2014-05-19 23:19:44]: debug: ppp34: recv [LCP EchoRep id=24 <magic 0524ea01>]
[2014-05-19 23:19:44]: debug: ppp38: send [LCP EchoReq id=24 <magic 257130a3>]
[2014-05-19 23:19:44]: debug: ppp38: recv [LCP EchoRep id=24 <magic 9cf6e0dc>]
[2014-05-19 23:19:44]: debug: ppp41: send [LCP EchoReq id=24 <magic 628c895d>]
[2014-05-19 23:19:44]: debug: ppp41: recv [LCP EchoRep id=24 <magic 9f464225>]
[2014-05-19 23:19:44]: debug: ppp45: send [LCP EchoReq id=24 <magic 2d1d5ae9>]
[2014-05-19 23:19:44]: debug: ppp46: send [LCP EchoReq id=24 <magic 6763845e>]
[2014-05-19 23:19:44]: debug: ppp46: recv [LCP EchoRep id=24 <magic cd97b70b>]
[2014-05-19 23:19:44]: debug: ppp45: recv [LCP EchoRep id=24 <magic f501fbe9>]
[2014-05-19 23:19:44]: debug: ppp47: send [LCP EchoReq id=24 <magic 75a2a8d4>]
[2014-05-19 23:19:44]: debug: ppp47: recv [LCP EchoRep id=24 <magic 40d054b0>]
[2014-05-19 23:19:46]: debug: ppp53: send [LCP EchoReq id=23 <magic 737b8ddc>]
[2014-05-19 23:19:46]: debug: ppp53: recv [LCP EchoRep id=23 <magic 21881300>]
[2014-05-19 23:19:46]: debug: ppp3: send [LCP EchoReq id=12 <magic 57e4ccaf>]
[2014-05-19 23:19:46]: debug: ppp3: recv [LCP EchoRep id=12 <magic 56dbfed6>]
[2014-05-19 23:19:46]: debug: ppp60: send [LCP EchoReq id=23 <magic 6ceaf087>]
[2014-05-19 23:19:46]: debug: ppp60: recv [LCP EchoRep id=23 <magic 59878000>]
[2014-05-19 23:19:46]: debug: ppp9: recv [LCP EchoReq id=76 <magic 6229d6c4>]
[2014-05-19 23:19:46]: debug: ppp9: send [LCP EchoRep id=76 <magic 46e87ccd>]
[2014-05-19 23:19:46]: debug: ppp61: send [LCP EchoReq id=23 <magic 22221a70>]
[2014-05-19 23:19:46]: debug: ppp61: recv [LCP EchoRep id=23 <magic 97d55800>]
[2014-05-19 23:19:46]: debug: ppp2: send [LCP EchoReq id=e <magic 4b588f54>]
[2014-05-19 23:19:46]: debug: ppp2: recv [LCP EchoRep id=e <magic 570e1976>]
[2014-05-19 23:19:47]: debug: ppp59: recv [LCP EchoReq id=17 <magic 99f8220b>]
[2014-05-19 23:19:47]: debug: ppp59: send [LCP EchoRep id=17 <magic 7c3dbd3d>]
[2014-05-19 23:19:48]: debug: ppp11: send [LCP EchoReq id=6 <magic 542289ec>]
[2014-05-19 23:19:48]: debug: ppp54: send [LCP EchoReq id=24 <magic 71f32454>]
[2014-05-19 23:19:48]: debug: ppp54: recv [LCP EchoRep id=24 <magic 75f519ed>]
[2014-05-19 23:19:49]: debug: ppp4: send [LCP EchoReq id=10 <magic 7a6d8d3c>]
[2014-05-19 23:19:49]: debug: ppp4: recv [LCP EchoRep id=10 <magic b02bcf83>]
[2014-05-19 23:19:49]: debug: ppp19: send [LCP EchoReq id=4 <magic 38437fdb>]
[2014-05-19 23:19:49]: debug: ppp19: recv [LCP EchoRep id=4 <magic ef547640>]
[2014-05-19 23:19:50]: debug: ppp55: send [LCP EchoReq id=25 <magic 3a95f874>]
[2014-05-19 23:19:50]: debug: ppp55: recv [LCP EchoRep id=25 <magic 8a37c07f>]
[2014-05-19 23:19:50]: debug: ppp57: send [LCP EchoReq id=24 <magic 08138641>]
[2014-05-19 23:19:50]: debug: ppp57: recv [LCP EchoRep id=24 <magic 43f34f3e>]
[2014-05-19 23:19:50]: debug: ppp62: send [LCP EchoReq id=19 <magic 2a487cb0>]
[2014-05-19 23:19:50]: debug: ppp62: recv [LCP EchoRep id=19 <magic ba511d32>]
[2014-05-19 23:19:50]: debug: ppp56: send [LCP EchoReq id=23 <magic 419ac241>]
[2014-05-19 23:19:50]: debug: ppp56: recv [LCP EchoRep id=23 <magic ca2eea1f>]
[2014-05-19 23:19:50]: debug: ppp62: recv [LCP EchoReq id=10 <magic 321d51ba>]
[2014-05-19 23:19:50]: debug: ppp62: send [LCP EchoRep id=10 <magic 2a487cb0>]
[2014-05-19 23:19:51]: debug: ppp64: send [LCP EchoReq id=23 <magic 5577f8e1>]
[2014-05-19 23:19:51]: debug: ppp64: recv [LCP EchoRep id=23 <magic 59207556>]
[2014-05-19 23:19:52]: debug: ppp58: send [LCP EchoReq id=25 <magic 1e7ff521>]
[2014-05-19 23:19:52]: debug: ppp58: recv [LCP EchoRep id=25 <magic d975ac5b>]
[2014-05-19 23:19:52]: debug: ppp9: recv [LCP EchoReq id=77 <magic 6229d6c4>]
[2014-05-19 23:19:52]: debug: ppp9: send [LCP EchoRep id=77 <magic 46e87ccd>]
[2014-05-19 23:19:57]: debug: ppp59: send [LCP EchoReq id=24 <magic 7c3dbd3d>]
[2014-05-19 23:19:57]: debug: ppp59: recv [LCP EchoRep id=24 <magic 0b22f899>]
[2014-05-19 23:19:57]: debug: ppp7: send [LCP EchoReq id=26 <magic 625558ec>]
[2014-05-19 23:19:57]: debug: ppp7: recv [LCP EchoRep id=26 <magic ec75247e>]
[2014-05-19 23:19:58]: debug: ppp9: send [LCP EchoReq id=25 <magic 46e87ccd>]
[2014-05-19 23:19:58]: debug: ppp9: recv [LCP EchoRep id=25 <magic c4d62962>]
[2014-05-19 23:19:58]: debug: ppp0: send [LCP EchoReq id=25 <magic 6b8b4567>]
[2014-05-19 23:19:58]: debug: ppp0: recv [LCP EchoRep id=25 <magic 7b288438>]
[2014-05-19 23:19:58]: debug: ppp0: recv [LCP EchoReq id=18 <magic 3884287b>]
[2014-05-19 23:19:58]: debug: ppp0: send [LCP EchoRep id=18 <magic 6b8b4567>]
[2014-05-19 23:19:58]: debug: ppp9: recv [LCP EchoReq id=78 <magic 6229d6c4>]
[2014-05-19 23:19:58]: debug: ppp9: send [LCP EchoRep id=78 <magic 46e87ccd>]
[2014-05-19 23:19:58]: debug: ppp10: send [LCP EchoReq id=25 <magic 3d1b58ba>]
[2014-05-19 23:19:58]: debug: ppp10: recv [LCP EchoRep id=25 <magic 00855cb3>]
[2014-05-19 23:19:58]: debug: ppp12: send [LCP EchoReq id=25 <magic 2eb141f2>]
[2014-05-19 23:19:58]: debug: ppp13: send [LCP EchoReq id=25 <magic 41b71efb>]
[2014-05-19 23:19:58]: debug: ppp13: recv [LCP EchoRep id=25 <magic 84f8a33c>]
[2014-05-19 23:19:58]: debug: ppp12: recv [LCP EchoRep id=25 <magic fa80c1e7>]
[2014-05-19 23:19:58]: debug: ppp15: send [LCP EchoReq id=25 <magic 7545e146>]
[2014-05-19 23:19:58]: debug: ppp15: recv [LCP EchoRep id=25 <magic 636f2f6b>]
[2014-05-19 23:19:58]: debug: ppp18: send [LCP EchoReq id=25 <magic 12200854>]
[2014-05-19 23:19:58]: debug: ppp18: recv [LCP EchoRep id=25 <magic 6fb3e6c7>]
[2014-05-19 23:19:58]: debug: ppp1: send [LCP EchoReq id=25 <magic 327b23c6>]
[2014-05-19 23:19:58]: debug: ppp1: recv [LCP EchoReq id=24 <magic 7463fe14>]
[2014-05-19 23:19:58]: debug: ppp1: send [LCP EchoRep id=24 <magic 327b23c6>]
[2014-05-19 23:19:58]: debug: ppp1: recv [LCP EchoRep id=25 <magic 14fe6374>]
[2014-05-19 23:19:59]: debug: ppp20: send [LCP EchoReq id=25 <magic 0216231b>]
[2014-05-19 23:19:59]: debug: ppp20: recv [LCP EchoRep id=25 <magic aead4f27>]
[2014-05-19 23:19:59]: debug: ppp21: send [LCP EchoReq id=25 <magic 1f16e9e8>]
[2014-05-19 23:19:59]: debug: ppp21: recv [LCP EchoRep id=25 <magic 17ee604d>]
[2014-05-19 23:20:00]: debug: ppp5: send [LCP EchoReq id=26 <magic 19495cff>]
[2014-05-19 23:20:00]: debug: ppp5: recv [LCP EchoRep id=26 <magic 3b0ef428>]
[2014-05-19 23:20:00]: debug: ppp11: send [LCP EchoReq id=7 <magic 542289ec>]
[2014-05-19 23:20:00]: debug: ppp22: send [LCP EchoReq id=25 <magic 1190cde7>]
[2014-05-19 23:20:00]: debug: ppp22: recv [LCP EchoRep id=25 <magic c18dd627>]
[2014-05-19 23:20:00]: debug: ppp8: send [LCP EchoReq id=26 <magic 238e1f29>]
[2014-05-19 23:20:00]: debug: ppp8: recv [LCP EchoRep id=26 <magic 4a59bd5d>]
[2014-05-19 23:20:00]: debug: ppp23: send [LCP EchoReq id=25 <magic 66ef438d>]
[2014-05-19 23:20:00]: debug: ppp23: recv [LCP EchoRep id=25 <magic 6c0a6359>]
[2014-05-19 23:20:00]: debug: ppp24: send [LCP EchoReq id=25 <magic 140e0f76>]
[2014-05-19 23:20:00]: debug: ppp24: recv [LCP EchoRep id=25 <magic cab0c92d>]
[2014-05-19 23:20:00]: debug: ppp25: send [LCP EchoReq id=25 <magic 3352255a>]
[2014-05-19 23:20:00]: debug: ppp25: recv [LCP EchoRep id=25 <magic 5164687f>]
[2014-05-19 23:20:00]: debug: ppp26: send [LCP EchoReq id=25 <magic 109cf92e>]
[2014-05-19 23:20:00]: debug: ppp26: recv [LCP EchoRep id=25 <magic 4808c10b>]
[2014-05-19 23:20:01]: debug: ppp30: send [LCP EchoReq id=25 <magic 41a7c4c9>]
[2014-05-19 23:20:01]: debug: ppp30: recv [LCP EchoRep id=25 <magic 2aecdd1f>]
[2014-05-19 23:20:01]: debug: ppp31: send [LCP EchoReq id=25 <magic 6b68079a>]
[2014-05-19 23:20:01]: debug: ppp31: recv [LCP EchoRep id=25 <magic fa8e9c3e>]
[2014-05-19 23:20:01]: debug: ppp32: send [LCP EchoReq id=25 <magic 4e6afb66>]
[2014-05-19 23:20:01]: debug: ppp32: recv [LCP EchoRep id=25 <magic 891329f5>]
[2014-05-19 23:20:01]: debug: ppp33: send [LCP EchoReq id=25 <magic 25e45d32>]
[2014-05-19 23:20:01]: debug: ppp33: recv [LCP EchoRep id=25 <magic ebbb0ed5>]
[2014-05-19 23:20:01]: debug: ppp37: send [LCP EchoReq id=25 <magic 7c83e458>]
[2014-05-19 23:20:01]: debug: ppp37: recv [LCP EchoRep id=25 <magic 08d9163b>]
[2014-05-19 23:20:01]: debug: ppp40: send [LCP EchoReq id=25 <magic 62bbd95a>]
[2014-05-19 23:20:01]: debug: ppp39: send [LCP EchoReq id=25 <magic 436c6125>]
[2014-05-19 23:20:01]: debug: ppp39: recv [LCP EchoRep id=25 <magic 362521d6>]
[2014-05-19 23:20:01]: debug: ppp40: recv [LCP EchoRep id=25 <magic b2999f4b>]
[2014-05-19 23:20:01]: debug: ppp42: send [LCP EchoReq id=25 <magic 333ab105>]
[2014-05-19 23:20:01]: debug: ppp42: recv [LCP EchoRep id=25 <magic 35f2d0ec>]
[2014-05-19 23:20:01]: debug: ppp36: send [LCP EchoReq id=25 <magic 3f2dba31>]
[2014-05-19 23:20:01]: debug: ppp36: recv [LCP EchoRep id=25 <magic 8d3f6c4a>]
[2014-05-19 23:20:01]: debug: ppp43: send [LCP EchoReq id=25 <magic 721da317>]
[2014-05-19 23:20:01]: debug: ppp43: recv [LCP EchoRep id=25 <magic 244cb284>]
[2014-05-19 23:20:01]: debug: ppp44: send [LCP EchoReq id=25 <magic 2443a858>]
[2014-05-19 23:20:01]: debug: ppp44: recv [LCP EchoRep id=25 <magic c930ab61>]
[2014-05-19 23:20:01]: debug: ppp48: send [LCP EchoReq id=25 <magic 08edbdab>]
[2014-05-19 23:20:01]: debug: ppp48: recv [LCP EchoRep id=25 <magic ad3c1841>]
[2014-05-19 23:20:01]: debug: ppp6: send [LCP EchoReq id=25 <magic 2ae8944a>]
[2014-05-19 23:20:01]: debug: ppp6: recv [LCP EchoRep id=25 <magic d412041d>]
[2014-05-19 23:20:01]: debug: ppp14: send [LCP EchoReq id=26 <magic 79e2a9e3>]
[2014-05-19 23:20:01]: debug: ppp14: recv [LCP EchoRep id=26 <magic 3f29223a>]
[2014-05-19 23:20:01]: debug: ppp17: send [LCP EchoReq id=25 <magic 5bd062c2>]
[2014-05-19 23:20:01]: debug: ppp17: recv [LCP EchoRep id=25 <magic 1566694d>]
[2014-05-19 23:20:01]: debug: ppp49: send [LCP EchoReq id=25 <magic 79838cb2>]
[2014-05-19 23:20:01]: debug: ppp49: recv [LCP EchoRep id=25 <magic f127af0f>]
[2014-05-19 23:20:01]: debug: ppp6: recv [LCP EchoReq id=18 <magic 1d0412d4>]
[2014-05-19 23:20:01]: debug: ppp6: send [LCP EchoRep id=18 <magic 2ae8944a>]
[2014-05-19 23:20:02]: debug: ppp67: send [LCP EchoReq id=23 <magic 3804823e>]
[2014-05-19 23:20:02]: debug: ppp67: recv [LCP EchoRep id=23 <magic fd6b362b>]
[2014-05-19 23:20:02]: debug: ppp63: send [LCP EchoReq id=1c <magic 3855585c>]
[2014-05-19 23:20:02]: debug: ppp63: recv [LCP EchoRep id=1c <magic e453f07f>]
[2014-05-19 23:20:02]: debug: ppp51: send [LCP EchoReq id=25 <magic 0b03e0c6>]
[2014-05-19 23:20:02]: debug: ppp51: recv [LCP EchoRep id=25 <magic 21c44c87>]
[2014-05-19 23:20:03]: debug: ppp68: send [LCP EchoReq id=21 <magic 5c482a97>]
[2014-05-19 23:20:03]: debug: ppp68: recv [LCP EchoRep id=21 <magic 38728940>]
[2014-05-19 23:20:04]: debug: ppp34: send [LCP EchoReq id=25 <magic 519b500d>]
[2014-05-19 23:20:04]: debug: ppp34: recv [LCP EchoRep id=25 <magic 0524ea01>]
[2014-05-19 23:20:04]: debug: ppp38: send [LCP EchoReq id=25 <magic 257130a3>]
[2014-05-19 23:20:04]: debug: ppp38: recv [LCP EchoRep id=25 <magic 9cf6e0dc>]
[2014-05-19 23:20:04]: debug: ppp41: send [LCP EchoReq id=25 <magic 628c895d>]
[2014-05-19 23:20:04]: debug: ppp41: recv [LCP EchoRep id=25 <magic 9f464225>]
[2014-05-19 23:20:04]: debug: ppp45: send [LCP EchoReq id=25 <magic 2d1d5ae9>]
[2014-05-19 23:20:04]: debug: ppp46: send [LCP EchoReq id=25 <magic 6763845e>]
[2014-05-19 23:20:04]: debug: ppp46: recv [LCP EchoRep id=25 <magic cd97b70b>]
[2014-05-19 23:20:04]: debug: ppp45: recv [LCP EchoRep id=25 <magic f501fbe9>]
[2014-05-19 23:20:04]: debug: ppp47: send [LCP EchoReq id=25 <magic 75a2a8d4>]
[2014-05-19 23:20:04]: debug: ppp47: recv [LCP EchoRep id=25 <magic 40d054b0>]
[2014-05-19 23:20:04]: debug: ppp9: recv [LCP EchoReq id=79 <magic 6229d6c4>]
[2014-05-19 23:20:04]: debug: ppp9: send [LCP EchoRep id=79 <magic 46e87ccd>]

 

А зачем вы используете устаревшие записи в настройке радиус.

auth-server=127.0.0.1:1812,8888888888888888

acct-server=127.0.0.1:1813,8888888888888888

 

Убрал ничего не поменялось.

[xeb]

честно говоря, я не понимаю смысл приведённого лога, здесь всё отрабатывает как надо, сам завершил сессиию, сам подключился

я так понимаю проблема существует при выдёргивании кабеля, вот эти логи и надо смотреть

и желательно грепом вычитить только интересующие две сессии: та которая обрывается выдёргиванием кабеля и вновь подключенная

[fet4]

я так понимаю проблема существует при выдёргивании кабеля, вот эти логи и надо смотреть

Как их глянуть?

 

В приведенном выше логе был выдернуть кабель и произведено подключение.

 

Еще раз с выборкой. Подключился, выдернул кабель, повторный звонок снова подключился (не работает), отключился корректно, снова подключился (работает).

 

[2014-05-20 16:35:10]: debug: ppp64: fsm timeout
[2014-05-20 16:35:10]:  info: ppp64: send [LCP ConfReq id=1 <auth MSCHAP-v2> <mru 1492> <magic 0cc1016f>]
[2014-05-20 16:35:10]:  info: ppp64: recv [LCP ConfAck id=1 <auth MSCHAP-v2> <mru 1492> <magic 0cc1016f>]
[2014-05-20 16:35:10]: debug: ppp64: lcp_layer_started
[2014-05-20 16:35:10]: debug: ppp64: auth_layer_start
[2014-05-20 16:35:10]:  info: ppp64: send [MSCHAP-v2 Challenge id=1 <2ca1244ecf9b4333ff7c75cb78edae7f>]
[2014-05-20 16:35:10]:  info: ppp64: recv [LCP Ident id=2 <MSRASV5.20>]
[2014-05-20 16:35:10]:  info: ppp64: recv [LCP Ident id=3 <MSRAS-0-MAX>]
[2014-05-20 16:35:10]:  info: ppp64: recv [LCP Ident id=4 <▒▒C▒uy▒B▒׳▒▒]▒1>]
[2014-05-20 16:35:10]:  info: ppp64: recv [MSCHAP-v2 Response id=1 <471287f7a177063c3f7eb9d8fb198a>, <35545733f23f69f8d6172e9dc7e7f9ff4cbdf8be2033aaf0>, F=0, name="maxi"]
[2014-05-20 16:35:10]:  info: ppp64: send [RADIUS(1) Access-Request id=1 <User-Name "maxi"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 127.0.0.1> <NAS-Port 64> <NAS-Port-Type Virtual> <Service-Type Framed-User> <Framed-Protocol PPP> <Calling-Station-Id "44:87:fc:52:4f:3b"> <Called-Station-Id "64:66:b3:03:b0:3e"><Microsoft MS-CHAP-Challenge ><Microsoft MS-CHAP2-Response >]
[2014-05-20 16:35:10]:  info: ppp64: recv [RADIUS(1) Access-Accept id=1 <Acct-Interim-Interval 300> <Session-Timeout 977090> <Framed-IP-Address 10.192.76.215> <Framed-IP-Netmask 255.255.255.255><Microsoft MS-CHAP2-Success ><Microsoft MS-MPPE-Recv-Key ><Microsoft MS-MPPE-Send-Key ><Microsoft MS-MPPE-Encryption-Policy 1><Microsoft MS-MPPE-Encryption-Type 6>]
[2014-05-20 16:35:10]:  info: ppp64: send [MSCHAP-v2 Success id=1 "S=67619DFE9A52C26725A04F7F3000A105BB901849 M=Authentication succeeded"]
[2014-05-20 16:35:10]: debug: ppp64: auth_layer_started
[2014-05-20 16:35:10]: debug: ppp64: ccp_layer_start
[2014-05-20 16:35:10]: debug: ppp64: ipcp_layer_start
[2014-05-20 16:35:10]:  info: ppp64: send [iPCP ConfReq id=1 <addr 172.31.1.254>]
[2014-05-20 16:35:10]: debug: ppp64: ipv6cp_layer_start
[2014-05-20 16:35:10]:  info: ppp64: maxi: authentication succeeded
[2014-05-20 16:35:10]:  warn: ppp64: IPV6CP: discarding packet
[2014-05-20 16:35:10]:  info: ppp64: send [LCP ProtoRej id=3 <8057>]
[2014-05-20 16:35:10]:  info: ppp64: recv [CCP ConfReq id=6 <mppe -H -M -S -L -D -C>]
[2014-05-20 16:35:10]:  info: ppp64: send [CCP ConfReq id=1 <mppe -H -M -S -L -D -C>]
[2014-05-20 16:35:10]:  info: ppp64: send [CCP ConfAck id=6]
[2014-05-20 16:35:10]:  info: ppp64: recv [iPCP ConfReq id=7 <addr 0.0.0.0> <dns1 0.0.0.0> <wins1 0.0.0.0> <dns2 0.0.0.0> <wins2 0.0.0.0>]
[2014-05-20 16:35:10]:  info: ppp64: send [iPCP ConfNak id=7 <addr 10.192.76.215> <dns1 172.30.0.1> <dns2 172.30.1.1>]
[2014-05-20 16:35:10]:  info: ppp64: recv [iPCP ConfAck id=1 <addr 172.31.1.254>]
[2014-05-20 16:35:10]:  info: ppp64: recv [CCP ConfAck id=1 <mppe -H -M -S -L -D -C>]
[2014-05-20 16:35:10]: debug: ppp64: ccp_layer_started
[2014-05-20 16:35:10]:  info: ppp64: recv [iPCP ConfReq id=8 <addr 10.192.76.215> <dns1 172.30.0.1> <wins1 0.0.0.0> <dns2 172.30.1.1> <wins2 0.0.0.0>]
[2014-05-20 16:35:10]:  info: ppp64: send [iPCP ConfAck id=8]
[2014-05-20 16:35:10]: debug: ppp64: ipcp_layer_started
[2014-05-20 16:35:10]:  info: ppp64: send [RADIUS(1) Accounting-Request id=1 <User-Name "maxi"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 127.0.0.1> <NAS-Port 64> <NAS-Port-Type Virtual> <Service-Type Framed-User> <Framed-Protocol PPP> <Calling-Station-Id "44:87:fc:52:4f:3b"> <Called-Station-Id "64:66:b3:03:b0:3e"> <Acct-Status-Type Start> <Acct-Authentic RADIUS> <Acct-Session-Id "5c7bde34627a8f80"> <Acct-Session-Time 0> <Acct-Input-Octets 0> <Acct-Output-Octets 0> <Acct-Input-Packets 0> <Acct-Output-Packets 0> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> <Framed-IP-Address 10.192.76.215>]
[2014-05-20 16:35:10]:  info: ppp64: recv [RADIUS(1) Accounting-Response id=1]
[2014-05-20 16:35:10]: debug: ppp64: pppoe: ppp started
[2014-05-20 16:35:10]:  info: ppp64: pppd_compat: ip-up started (pid 3463)
[2014-05-20 16:35:10]:  info: ppp64: pppd_compat: ip-up finished (0)

[2014-05-20 16:35:22]:  info: recv [PPPoE PADI 44:87:fc:52:4f:3b => ff:ff:ff:ff:ff:ff sid=0000 <Service-Name > <Host-Uniq 050000000000000009000000>]
[2014-05-20 16:35:22]:  info: send [PPPoE PADO 64:66:b3:03:b0:3e => 44:87:fc:52:4f:3b sid=0000 <AC-Name accel-ppp> <Service-Name > <AC-Cookie fa4d9edb8a519ca5d351a41afe6f828c874adaa1564d1ba5> <Host-Uniq 050000000000000009000000>]
[2014-05-20 16:35:22]:  info: recv [PPPoE PADR 44:87:fc:52:4f:3b => 64:66:b3:03:b0:3e sid=0000 <Service-Name > <Host-Uniq 05000000000000000a000000> <AC-Cookie fa4d9edb8a519ca5d351a41afe6f828c874adaa1564d1ba5>]
[2014-05-20 16:35:22]:  info: send [PPPoE PADS 64:66:b3:03:b0:3e => 44:87:fc:52:4f:3b sid=0061 <AC-Name accel-ppp> <Service-Name > <Host-Uniq 05000000000000000a000000>]
[2014-05-20 16:35:22]:  info: ppp65: connect: ppp65 <--> pppoe(44:87:fc:52:4f:3b)
[2014-05-20 16:35:22]: debug: ppp65: lcp_layer_init
[2014-05-20 16:35:22]: debug: ppp65: auth_layer_init
[2014-05-20 16:35:22]: debug: ppp65: ccp_layer_init
[2014-05-20 16:35:22]: debug: ppp65: ipcp_layer_init
[2014-05-20 16:35:22]: debug: ppp65: ipv6cp_layer_init
[2014-05-20 16:35:22]: debug: ppp65: ppp established
[2014-05-20 16:35:22]: debug: ppp65: lcp_layer_start
[2014-05-20 16:35:22]:  info: ppp65: send [LCP ConfReq id=1 <auth MSCHAP-v2> <mru 1492> <magic 43f18422>]
[2014-05-20 16:35:22]:  info: ppp65: recv [LCP ConfReq id=0 <mru 1480> <magic 0ab50159> <pcomp> <accomp> < d 3 6 >]
[2014-05-20 16:35:22]:  info: ppp65: send [LCP ConfRej id=0  <pcomp> <accomp> < d 3 6 >]
[2014-05-20 16:35:22]:  info: ppp65: recv [LCP ConfReq id=1 <mru 1480> <magic 0ab50159>]
[2014-05-20 16:35:22]:  info: ppp65: send [LCP ConfAck id=1 ]

[2014-05-20 16:35:25]: debug: ppp65: fsm timeout
[2014-05-20 16:35:25]:  info: ppp65: send [LCP ConfReq id=1 <auth MSCHAP-v2> <mru 1492> <magic 43f18422>]
[2014-05-20 16:35:25]:  info: ppp65: recv [LCP ConfAck id=1 <auth MSCHAP-v2> <mru 1492> <magic 43f18422>]
[2014-05-20 16:35:25]: debug: ppp65: lcp_layer_started
[2014-05-20 16:35:25]: debug: ppp65: auth_layer_start
[2014-05-20 16:35:25]:  info: ppp65: send [MSCHAP-v2 Challenge id=1 <1ca7bda21b9b7d602afd90a301683d2>]
[2014-05-20 16:35:25]:  info: ppp65: recv [LCP Ident id=2 <MSRASV5.20>]
[2014-05-20 16:35:25]:  info: ppp65: recv [LCP Ident id=3 <MSRAS-0-MAX>]
[2014-05-20 16:35:25]:  info: ppp65: recv [LCP Ident id=4 <▒o▒δ▒D▒▒▒B▒2I▒>]
[2014-05-20 16:35:25]:  info: ppp65: recv [MSCHAP-v2 Response id=1 <36dfc782473d7cc5b5b8a907631d54>, <2c6a82c12c818ac28a2a2e74e0d5857c3eaf75b35df364c>, F=0, name="maxi"]
[2014-05-20 16:35:25]:  info: ppp65: send [RADIUS(1) Access-Request id=1 <User-Name "maxi"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 127.0.0.1> <NAS-Port 65> <NAS-Port-Type Virtual> <Service-Type Framed-User> <Framed-Protocol PPP> <Calling-Station-Id "44:87:fc:52:4f:3b"> <Called-Station-Id "64:66:b3:03:b0:3e"><Microsoft MS-CHAP-Challenge ><Microsoft MS-CHAP2-Response >]
[2014-05-20 16:35:25]:  info: ppp65: recv [RADIUS(1) Access-Accept id=1 <Acct-Interim-Interval 300> <Session-Timeout 977075> <Framed-IP-Address 10.192.76.215> <Framed-IP-Netmask 255.255.255.255><Microsoft MS-CHAP2-Success ><Microsoft MS-MPPE-Recv-Key ><Microsoft MS-MPPE-Send-Key ><Microsoft MS-MPPE-Encryption-Policy 1><Microsoft MS-MPPE-Encryption-Type 6>]
[2014-05-20 16:35:25]:  info: ppp65: send [MSCHAP-v2 Success id=1 "S=91C1307BF13B644BADF0C2D4D226D52C076515AB M=Authentication succeeded"]
[2014-05-20 16:35:25]: debug: ppp65: auth_layer_started
[2014-05-20 16:35:25]: debug: ppp65: ccp_layer_start
[2014-05-20 16:35:25]: debug: ppp65: ipcp_layer_start
[2014-05-20 16:35:25]:  info: ppp65: send [iPCP ConfReq id=1 <addr 172.31.1.254>]
[2014-05-20 16:35:25]: debug: ppp65: ipv6cp_layer_start
[2014-05-20 16:35:25]:  info: ppp65: maxi: authentication succeeded
[2014-05-20 16:35:25]:  warn: ppp65: IPV6CP: discarding packet
[2014-05-20 16:35:25]:  info: ppp65: send [LCP ProtoRej id=3 <8057>]
[2014-05-20 16:35:25]:  info: ppp65: recv [CCP ConfReq id=6 <mppe -H -M -S -L -D -C>]
[2014-05-20 16:35:25]:  info: ppp65: send [CCP ConfReq id=1 <mppe -H -M -S -L -D -C>]
[2014-05-20 16:35:25]:  info: ppp65: send [CCP ConfAck id=6]
[2014-05-20 16:35:25]:  info: ppp65: recv [iPCP ConfReq id=7 <addr 0.0.0.0> <dns1 0.0.0.0> <wins1 0.0.0.0> <dns2 0.0.0.0> <wins2 0.0.0.0>]
[2014-05-20 16:35:25]:  info: ppp65: send [iPCP ConfNak id=7 <addr 10.192.76.215> <dns1 172.30.0.1> <dns2 172.30.1.1>]
[2014-05-20 16:35:25]:  info: ppp65: recv [iPCP ConfAck id=1 <addr 172.31.1.254>]
[2014-05-20 16:35:25]:  info: ppp65: recv [CCP ConfAck id=1 <mppe -H -M -S -L -D -C>]
[2014-05-20 16:35:25]: debug: ppp65: ccp_layer_started
[2014-05-20 16:35:25]:  info: ppp65: recv [iPCP ConfReq id=8 <addr 10.192.76.215> <dns1 172.30.0.1> <wins1 0.0.0.0> <dns2 172.30.1.1> <wins2 0.0.0.0>]
[2014-05-20 16:35:25]:  info: ppp65: send [iPCP ConfAck id=8]
[2014-05-20 16:35:25]: debug: ppp65: ipcp_layer_started
[2014-05-20 16:35:25]:  info: ppp65: send [RADIUS(1) Accounting-Request id=1 <User-Name "maxi"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 127.0.0.1> <NAS-Port 65> <NAS-Port-Type Virtual> <Service-Type Framed-User> <Framed-Protocol PPP> <Calling-Station-Id "44:87:fc:52:4f:3b"> <Called-Station-Id "64:66:b3:03:b0:3e"> <Acct-Status-Type Start> <Acct-Authentic RADIUS> <Acct-Session-Id "5c7bde34627a8f81"> <Acct-Session-Time 0> <Acct-Input-Octets 0> <Acct-Output-Octets 0> <Acct-Input-Packets 0> <Acct-Output-Packets 0> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> <Framed-IP-Address 10.192.76.215>]
[2014-05-20 16:35:25]:  info: ppp65: recv [RADIUS(1) Accounting-Response id=1]
[2014-05-20 16:35:25]: debug: ppp65: pppoe: ppp started
[2014-05-20 16:35:25]:  info: ppp65: pppd_compat: ip-up started (pid 3486)
[2014-05-20 16:35:25]:  info: ppp65: pppd_compat: ip-up finished (0)

[2014-05-20 16:35:55]:  info: ppp65: recv [LCP TermReq id=9]
[2014-05-20 16:35:55]:  info: ppp65: send [LCP TermAck id=9]
[2014-05-20 16:35:55]: debug: ppp65: terminate
[2014-05-20 16:35:55]:  info: ppp65: send [RADIUS(1) Accounting-Request id=1 <User-Name "maxi"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 127.0.0.1> <NAS-Port 65> <NAS-Port-Type Virtual> <Service-Type Framed-User> <Framed-Protocol PPP> <Calling-Station-Id "44:87:fc:52:4f:3b"> <Called-Station-Id "64:66:b3:03:b0:3e"> <Acct-Status-Type Stop> <Acct-Authentic RADIUS> <Acct-Session-Id "5c7bde34627a8f81"> <Acct-Session-Time 33> <Acct-Input-Octets 28418> <Acct-Output-Octets 86> <Acct-Input-Packets 475> <Acct-Output-Packets 5> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> <Framed-IP-Address 10.192.76.215> <Acct-Terminate-Cause User-Request>]
[2014-05-20 16:35:55]:  info: ppp65: recv [RADIUS(1) Accounting-Response id=1]
[2014-05-20 16:35:55]: debug: ppp65: lcp_layer_finish
[2014-05-20 16:35:55]: debug: ppp65: auth_layer_finish
[2014-05-20 16:35:55]: debug: ppp65: auth_layer_finished
[2014-05-20 16:35:55]: debug: ppp65: ccp_layer_finish
[2014-05-20 16:35:55]: debug: ppp65: ccp_layer_finished
[2014-05-20 16:35:55]: debug: ppp65: ipcp_layer_finish
[2014-05-20 16:35:55]: debug: ppp65: ipcp_layer_finished
[2014-05-20 16:35:55]: debug: ppp65: ipv6cp_layer_finish
[2014-05-20 16:35:55]: debug: ppp65: ipv6cp_layer_finished
[2014-05-20 16:35:56]:  info: recv [PPPoE PADT 44:87:fc:52:4f:3b => 64:66:b3:03:b0:3e sid=0061]
[2014-05-20 16:35:56]:  info: ppp65: pppd_compat: ip-down started (pid 3515)
[2014-05-20 16:35:56]:  info: ppp65: pppd_compat: ip-down finished (0)
[2014-05-20 16:35:56]: debug: ppp65: lcp_layer_free
[2014-05-20 16:35:56]: debug: ppp65: auth_layer_free
[2014-05-20 16:35:56]: debug: ppp65: ccp_layer_free
[2014-05-20 16:35:56]: debug: ppp65: ipcp_layer_free
[2014-05-20 16:35:56]: debug: ppp65: ipv6cp_layer_free
[2014-05-20 16:35:56]: debug: ppp65: ppp destablished
[2014-05-20 16:35:56]: debug: ppp65: pppoe: ppp finished
[2014-05-20 16:35:56]:  info: send [PPPoE PADT 64:66:b3:03:b0:3e => 44:87:fc:52:4f:3b sid=0061 <AC-Name accel-ppp> <Service-Name > <Host-Uniq 05000000000000000a000000>]
[2014-05-20 16:35:56]:  info: ppp65: disconnected

[2014-05-20 16:35:57]:  info: recv [PPPoE PADI 44:87:fc:52:4f:3b => ff:ff:ff:ff:ff:ff sid=0000 <Service-Name > <Host-Uniq 06000000000000000b000000>]
[2014-05-20 16:35:57]:  info: send [PPPoE PADO 64:66:b3:03:b0:3e => 44:87:fc:52:4f:3b sid=0000 <AC-Name accel-ppp> <Service-Name > <AC-Cookie 5c784ebdc4ab53aa51ba8151fa8e9c38d06d6b53a0690372> <Host-Uniq 06000000000000000b000000>]
[2014-05-20 16:35:57]:  info: recv [PPPoE PADR 44:87:fc:52:4f:3b => 64:66:b3:03:b0:3e sid=0000 <Service-Name > <Host-Uniq 06000000000000000c000000> <AC-Cookie 5c784ebdc4ab53aa51ba8151fa8e9c38d06d6b53a0690372>]
[2014-05-20 16:35:57]:  info: send [PPPoE PADS 64:66:b3:03:b0:3e => 44:87:fc:52:4f:3b sid=0062 <AC-Name accel-ppp> <Service-Name > <Host-Uniq 06000000000000000c000000>]
[2014-05-20 16:35:57]:  info: ppp65: connect: ppp65 <--> pppoe(44:87:fc:52:4f:3b)
[2014-05-20 16:35:57]: debug: ppp65: lcp_layer_init
[2014-05-20 16:35:57]: debug: ppp65: auth_layer_init
[2014-05-20 16:35:57]: debug: ppp65: ccp_layer_init
[2014-05-20 16:35:57]: debug: ppp65: ipcp_layer_init
[2014-05-20 16:35:57]: debug: ppp65: ipv6cp_layer_init
[2014-05-20 16:35:57]: debug: ppp65: ppp established
[2014-05-20 16:35:57]: debug: ppp65: lcp_layer_start
[2014-05-20 16:35:57]:  info: ppp65: send [LCP ConfReq id=1 <auth MSCHAP-v2> <mru 1492> <magic 60ef0119>]
[2014-05-20 16:35:57]:  info: ppp65: recv [LCP ConfReq id=0 <mru 1480> <magic 4c736c6d> <pcomp> <accomp> < d 3 6 >]
[2014-05-20 16:35:57]:  info: ppp65: send [LCP ConfRej id=0  <pcomp> <accomp> < d 3 6 >]
[2014-05-20 16:35:57]:  info: ppp65: recv [LCP ConfReq id=1 <mru 1480> <magic 4c736c6d>]
[2014-05-20 16:35:57]:  info: ppp65: send [LCP ConfAck id=1 ]

[2014-05-20 16:36:00]: debug: ppp65: fsm timeout
[2014-05-20 16:36:00]:  info: ppp65: send [LCP ConfReq id=1 <auth MSCHAP-v2> <mru 1492> <magic 60ef0119>]
[2014-05-20 16:36:00]:  info: ppp65: recv [LCP ConfAck id=1 <auth MSCHAP-v2> <mru 1492> <magic 60ef0119>]
[2014-05-20 16:36:00]: debug: ppp65: lcp_layer_started
[2014-05-20 16:36:00]: debug: ppp65: auth_layer_start
[2014-05-20 16:36:00]:  info: ppp65: send [MSCHAP-v2 Challenge id=1 <ce70d5fda2a6da95917092d84138df9f>]
[2014-05-20 16:36:00]:  info: ppp65: recv [LCP Ident id=2 <MSRASV5.20>]
[2014-05-20 16:36:00]:  info: ppp65: recv [LCP Ident id=3 <MSRAS-0-MAX>]
[2014-05-20 16:36:00]:  info: ppp65: recv [LCP Ident id=4 <▒i▒;▒RO▒▒ɭ▒-▒O>]
[2014-05-20 16:36:00]:  info: ppp65: recv [MSCHAP-v2 Response id=1 <faa48a8951fc8f4b406ae21a41502b>, <430477ea789b861b6c3725122236610cfccd44a4c2de6ca>, F=0, name="maxi"]
[2014-05-20 16:36:00]:  info: ppp65: send [RADIUS(1) Access-Request id=1 <User-Name "maxi"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 127.0.0.1> <NAS-Port 65> <NAS-Port-Type Virtual> <Service-Type Framed-User> <Framed-Protocol PPP> <Calling-Station-Id "44:87:fc:52:4f:3b"> <Called-Station-Id "64:66:b3:03:b0:3e"><Microsoft MS-CHAP-Challenge ><Microsoft MS-CHAP2-Response >]
[2014-05-20 16:36:01]:  info: ppp65: recv [RADIUS(1) Access-Accept id=1 <Acct-Interim-Interval 300> <Session-Timeout 977040> <Framed-IP-Address 10.192.31.141> <Framed-IP-Netmask 255.255.255.255><Microsoft MS-CHAP2-Success ><Microsoft MS-MPPE-Recv-Key ><Microsoft MS-MPPE-Send-Key ><Microsoft MS-MPPE-Encryption-Policy 1><Microsoft MS-MPPE-Encryption-Type 6>]
[2014-05-20 16:36:01]:  info: ppp65: send [MSCHAP-v2 Success id=1 "S=43B655F64BFBF297F75F6655905835A1D926000A M=Authentication succeeded"]
[2014-05-20 16:36:01]: debug: ppp65: auth_layer_started
[2014-05-20 16:36:01]: debug: ppp65: ccp_layer_start
[2014-05-20 16:36:01]: debug: ppp65: ipcp_layer_start
[2014-05-20 16:36:01]:  info: ppp65: send [iPCP ConfReq id=1 <addr 172.31.1.254>]
[2014-05-20 16:36:01]: debug: ppp65: ipv6cp_layer_start
[2014-05-20 16:36:01]:  info: ppp65: maxi: authentication succeeded
[2014-05-20 16:36:01]:  warn: ppp65: IPV6CP: discarding packet
[2014-05-20 16:36:01]:  info: ppp65: send [LCP ProtoRej id=3 <8057>]
[2014-05-20 16:36:01]:  info: ppp65: recv [CCP ConfReq id=6 <mppe -H -M -S -L -D -C>]
[2014-05-20 16:36:01]:  info: ppp65: send [CCP ConfReq id=1 <mppe -H -M -S -L -D -C>]
[2014-05-20 16:36:01]:  info: ppp65: send [CCP ConfAck id=6]
[2014-05-20 16:36:01]:  info: ppp65: recv [iPCP ConfReq id=7 <addr 0.0.0.0> <dns1 0.0.0.0> <wins1 0.0.0.0> <dns2 0.0.0.0> <wins2 0.0.0.0>]
[2014-05-20 16:36:01]:  info: ppp65: send [iPCP ConfNak id=7 <addr 10.192.31.141> <dns1 172.30.0.1> <dns2 172.30.1.1>]
[2014-05-20 16:36:01]:  info: ppp65: recv [iPCP ConfAck id=1 <addr 172.31.1.254>]
[2014-05-20 16:36:01]:  info: ppp65: recv [CCP ConfAck id=1 <mppe -H -M -S -L -D -C>]
[2014-05-20 16:36:01]: debug: ppp65: ccp_layer_started
[2014-05-20 16:36:01]:  info: ppp65: recv [iPCP ConfReq id=8 <addr 10.192.31.141> <dns1 172.30.0.1> <wins1 0.0.0.0> <dns2 172.30.1.1> <wins2 0.0.0.0>]
[2014-05-20 16:36:01]:  info: ppp65: send [iPCP ConfAck id=8]
[2014-05-20 16:36:01]: debug: ppp65: ipcp_layer_started
[2014-05-20 16:36:01]:  info: ppp65: send [RADIUS(1) Accounting-Request id=1 <User-Name "maxi"> <NAS-Identifier "accel-ppp"> <NAS-IP-Address 127.0.0.1> <NAS-Port 65> <NAS-Port-Type Virtual> <Service-Type Framed-User> <Framed-Protocol PPP> <Calling-Station-Id "44:87:fc:52:4f:3b"> <Called-Station-Id "64:66:b3:03:b0:3e"> <Acct-Status-Type Start> <Acct-Authentic RADIUS> <Acct-Session-Id "5c7bde34627a8f82"> <Acct-Session-Time 0> <Acct-Input-Octets 0> <Acct-Output-Octets 0> <Acct-Input-Packets 0> <Acct-Output-Packets 0> <Acct-Input-Gigawords 0> <Acct-Output-Gigawords 0> <Framed-IP-Address 10.192.31.141>]
[2014-05-20 16:36:01]:  info: ppp65: recv [RADIUS(1) Accounting-Response id=1]
[2014-05-20 16:36:01]: debug: ppp65: pppoe: ppp started
[2014-05-20 16:36:01]:  info: ppp65: pppd_compat: ip-up started (pid 3537)
[2014-05-20 16:36:01]: debug: ppp30: send [LCP EchoReq id=49 <magic 41a7c4c9>]
[2014-05-20 16:36:01]: debug: ppp30: recv [LCP EchoRep id=49 <magic 2aecdd1f>]
[2014-05-20 16:36:01]:  info: ppp65: pppd_compat: ip-up finished (0)

Изменено 20 мая, 2014 пользователем fet4

[h1vs2]

Добрый день!

 

Тест проходит нормально - все работает, пилим с биллингом потихоньку. Подскажите, а какими атрибутами из радиуса передать primary и secondary dns?

 

И еще вопрос можно ли заставить слать сразу accouting update после accouting start?

Спасибо!

[xeb]

fet4, да, теперь всё видно, явно предыдущая сессия не завершилась, а биллинг даёт добро на новую, т.е. копать, как и предполагалось, надо биллинг

 

Подскажите, а какими атрибутами из радиуса передать primary и secondary dns?

VENDOR Microsoft 311

ATTRIBUTE MS-Primary-DNS-Server 28 ipaddr

ATTRIBUTE MS-Secondary-DNS-Server 29 ipaddr

 

И еще вопрос можно ли заставить слать сразу accouting update после accouting start?

нет, такое поведение не предусмотрено

[fet4]

да, теперь всё видно, явно предыдущая сессия не завершилась, а биллинг даёт добро на новую, т.е. копать, как и предполагалось, надо биллинг

 

Спасибо напишу разработчику.

[NiTr0]

Можно конечно сделать хак - поставить PADO timeout в секунду к примеру. Хотя если появится левый сервер в сегменте - все коннекты повалят на него...

[ALex_hha]

А сталкивался кто с подключением MacOS клиентов по l2tp? Что то у меня не работает. Пробовал так, отключаю ipsec на клиенте

 

# echo "plugin L2TP.ppp" >> /etc/ppp/options
# echo "l2tpnoipsec" >> /etc/ppp/options

 

Пробую подключиться

 

Wed May 21 12:16:08 2014 : L2TP connecting to server 'xxx.xxx.xxx.217' (xxx.xxx.xxx.217)...
Wed May 21 12:16:08 2014 : L2TP sent SCCRQ
Wed May 21 12:16:08 2014 : L2TP received SCCRP
Wed May 21 12:16:08 2014 : L2TP sent SCCCN
Wed May 21 12:16:08 2014 : L2TP sent ICRQ
Wed May 21 12:16:08 2014 : L2TP received ICRP
Wed May 21 12:16:08 2014 : L2TP sent ICCN
Wed May 21 12:16:08 2014 : L2TP connection established.
Wed May 21 12:16:08 2014 : L2TP set port-mapping for en1, interface: 6, protocol: 0, privatePort: 0
Wed May 21 12:16:08 2014 : using link 0
Wed May 21 12:16:08 2014 : Using interface ppp0
Wed May 21 12:16:08 2014 : Connect: ppp0 <--> socket[34:18]
Wed May 21 12:16:09 2014 : sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x5d82f42e> <pcomp> <accomp>]
Wed May 21 12:16:09 2014 : rcvd [LCP ConfReq id=0x1 <auth chap MS-v2> <magic 0x61574095> <mru 1396>]
Wed May 21 12:16:09 2014 : lcp_reqci: returning CONFACK.
Wed May 21 12:16:09 2014 : sent [LCP ConfAck id=0x1 <auth chap MS-v2> <magic 0x61574095> <mru 1396>]
Wed May 21 12:16:09 2014 : rcvd [LCP ConfRej id=0x1 <asyncmap 0x0> <pcomp> <accomp>]
Wed May 21 12:16:09 2014 : sent [LCP ConfReq id=0x2 <magic 0x5d82f42e>]
Wed May 21 12:16:09 2014 : rcvd [LCP ConfAck id=0x2 <magic 0x5d82f42e>]
Wed May 21 12:16:09 2014 : sent [LCP EchoReq id=0x0 magic=0x5d82f42e]
Wed May 21 12:16:09 2014 : rcvd [CHAP Challenge id=0x1 <e12f50e2d8352f4debcc101c03a87cda>, name = ""]
Wed May 21 12:16:09 2014 : sent [CHAP Response id=0x1 <5022e13c241754550632c2e092a18a61000000000000000068dc1d6860b6eeeabc38a7431ece2dc903909b03549fec0f00>, name = "test"]
Wed May 21 12:16:09 2014 : rcvd [LCP EchoRep id=0x0 magic=0x61574095]
Wed May 21 12:16:09 2014 : rcvd [CHAP Success id=0x1 "S=05B9D22B30BC166487FCB7FA2A75C3982353A423 M=Authentication succeeded"]
Wed May 21 12:16:09 2014 : sent [iPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
Wed May 21 12:16:09 2014 : sent [iPV6CP ConfReq id=0x1 <addr fe80::0216:cbff:feb0:5140>]
Wed May 21 12:16:09 2014 : sent [ACSCP ConfReq id=0x1 <route vers 16777216> <domain vers 16777216>]
Wed May 21 12:16:09 2014 : rcvd [CCP ConfReq id=0x1 <mppe +H -M +S -L -D -C>]
Wed May 21 12:16:09 2014 : Unsupported protocol 'Compression Control Protocol' (0x80fd) received
Wed May 21 12:16:09 2014 : sent [LCP ProtRej id=0x3 80 fd 01 01 00 0a 12 06 01 00 00 40]
Wed May 21 12:16:09 2014 : rcvd [iPCP ConfReq id=0x1 <addr 192.168.1.1>]
Wed May 21 12:16:09 2014 : ipcp: returning Configure-ACK
Wed May 21 12:16:09 2014 : sent [iPCP ConfAck id=0x1 <addr 192.168.1.1>]
Wed May 21 12:16:09 2014 : rcvd [LCP ProtRej id=0x3 80 57]
Wed May 21 12:16:09 2014 : rcvd [LCP ProtRej id=0x4 82 35]
Wed May 21 12:16:09 2014 : rcvd [iPCP ConfNak id=0x1 <addr 192.168.1.50> <ms-dns1 8.8.8.8> <ms-dns3 8.8.4.4>]
Wed May 21 12:16:09 2014 : sent [iPCP ConfReq id=0x2 <addr 192.168.1.50> <ms-dns1 8.8.8.8> <ms-dns3 8.8.4.4>]
Wed May 21 12:16:09 2014 : rcvd [LCP TermReq id=0x5]
Wed May 21 12:16:09 2014 : LCP terminated by peer
Wed May 21 12:16:09 2014 : sent [LCP TermAck id=0x5]
Wed May 21 12:16:09 2014 : L2TP received StopCCN
Wed May 21 12:16:09 2014 : Connection terminated.
Wed May 21 12:16:09 2014 : L2TP clearing port-mapping for en1
Wed May 21 12:16:09 2014 : L2TP disconnecting...
Wed May 21 12:16:09 2014 : L2TP sent CDN
Wed May 21 12:16:09 2014 : L2TP sent StopCCN
Wed May 21 12:16:09 2014 : L2TP disconnected

 

Т.е. аутентификация проходит, а потом соединение сразу рвется. accel-ppp-1.7.3

[h1vs2]

Ребята, а поделитесь пожалуйста как в линуксе сделать редирект на заглушку(айпитейблес не видел в глаза много лет, и был счастлив:)).

 

iptables -t nat -A PREROUTING -p tcp -m set --match-set guest-ipoe src -j DNAT --dport 80 --to-destination 172.16.5.1:1123

 

 

176.16.5.1 висит на eth1.10 :

eth1.10   Link encap:Ethernet  HWaddr 00:1b:21:39:be:64
         inet addr:172.16.5.1  Bcast:172.16.5.255  Mask:255.255.255.0
         UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
         RX packets:54240 errors:0 dropped:0 overruns:0 frame:0
         TX packets:54887 errors:0 dropped:0 overruns:0 carrier:0
         collisions:0 txqueuelen:0
         RX bytes:20250968 (19.3 MiB)  TX bytes:3738582 (3.5 MiB)

 

Абоненты vlan на юзера терминируются на eth2.

 

На лупбеках gw для абонентов:

 

lo:1      Link encap:Local Loopback
         inet addr:192.168.11.250  Mask:255.255.255.255
         UP LOOPBACK RUNNING  MTU:16436  Metric:1

lo:2      Link encap:Local Loopback
         inet addr:192.168.12.250  Mask:255.255.255.255
         UP LOOPBACK RUNNING  MTU:16436  Metric:1

 

 

root@brs-acl:~# accel-cmd show sessions
    ifname     |        username        |    calling-sid    |       ip       | rate-limit  | type | comp | state  |  uptime
----------------+------------------------+-------------------+----------------+-------------+------+------+--------+----------
eth2.1500.3001 | 00137777fb8f:1500-3001 | 00:13:77:77:fb:8f | 192.168.11.120 | 50000/50000 | ipoe |      | active | 00:17:45
root@brs-acl:~# ipset -L
Name: guest-ipoe
Type: hash:ip
Header: family inet hashsize 1024 maxelem 65536
Size in memory: 16536
References: 0
Members:
192.168.11.120

 

Правило каунтится :

 

root@brs-acl:~# iptables -nvxL -t nat
Chain PREROUTING (policy ACCEPT 21 packets, 1601 bytes)
   pkts      bytes target     prot opt in     out     source               destination
    105     5320 DNAT       tcp  --  *      *       0.0.0.0/0            0.0.0.0/0            match-set guest-ipoe src tcp dpt:80 to:172.16.5.1:1123

 

 

Но вот неткат на 172.16.5.1 по порту 1123 - молчит.

 

 

Подскажите чего вкрутить надо, пожалуйста :)

[Abram]

h1vs2,

Для DNAT-а трафик должен проходить через ваш тазик в две стороны, что не всегда возможно. Да и неудобно.

Правильно так:

iptables -t nat -A PREROUTING -p tcp -m set --match-set guest-ipoe src -j REDIRECT --to-ports 8001

Это правило будет перенаправлять проходящий HTTP трафик на локальный порт 8001.

На порт 8001 вешаем nginx:

worker_processes  1;

events {
   worker_connections  1024;
}

http {

   server {
       listen       8001;
       server_name  local_negative;

       access_log /var/log/nginx/redirect.access.log;

       location / {
           if ( $http_user_agent ~ ^uTorrent ) {
                           return 404;
           }
           if ( $http_user_agent ~ Windows-Update-Agent ) {
                           return 404;
           }
           if ( $http_user_agent ~ "Microsoft NCSI" ) {
                           return 404;
           }
           if ( $http_user_agent ~ "ESS Update" ) {
                           return 404;
           }
           if ( $http_user_agent ~ "Google Update" ) {
                           return 404;
           }
           if ( $http_user_agent ~ "Microsoft-CryptoAPI" ) {
                           return 404;
           }
           if ( $http_user_agent ~ "Microsoft BITS" ) {
                           return 404;
           }
           if ( $http_user_agent ~ MSDW ) {
                           return 404;
           }
           if ( $http_user_agent ~ ^SeaPort ) {
                           return 404;
           }
           if ( $http_user_agent ~ ^Windows-Media-Player ) {
                           return 404;
           }
           if ( $http_user_agent ~ "Google Update" ) {
                           return 404;
           }
           if ( $http_user_agent ~ ^GoogleEarth ) {
                           return 404;
           }
           if ( $http_user_agent ~ ^Skype ) {
                           return 404;
           }
           if ( $http_user_agent = "MailRuSputnik" ) {
                           return 404;
           }
           if ( $http_user_agent ~ ^Ya\.Online ) {
                           return 404;
           }
           if ( $http_user_agent ~ ^MRA ) {
                           return 404;
           }
           if ( $http_user_agent ~* ^MediaGet ) {
                           return 404;
           }
           if ( $http_user_agent ~ ^BTWebClient ) {
                           return 404;
           }
           # Avast updater
           if ( $http_user_agent ~ ^Syncer ) {
                           return 404;
           }
           # Trend Micro updater
           if ( $http_user_agent = "TMUFE" ) {
                           return 404;
           }
           if ( $http_user_agent ~ ^Akamai\ NetSession\ Interface ) {
                           return 404;
           }
           if ( $http_user_agent ~ ^VKSaver ) {
                           return 404;
           }
           if ( $http_user_agent ~ DrWebUpdate ) {
                           return 404;
           }
           if ( $http_user_agent = "" ) {
                           return 404;
           }
           if ( $host ~ geo\.kaspersky\.com ) {
                           return 404;
           }
           if ( $host ~ (su|download|webrep).*\.avast\.com ) {
                           return 404;
           }

           if ( $host = wi2geo.mobile.yandex.net ) {
                           return 404;
           }
           if ( $host ~ bar.*\.yandex\.ru ) {
                           return 404;
           }
           if ( $host ~ (ping|master)\d*\.(dyngate|teamviewer)\.com ) {
                           return 404;
           }
           if ( $host ~ sitecheck\d*\.opera\.com ) {
                           return 404;
           }
           if ( $host ~ (xml\.my|mailsputnik|maps)\.mail\.ru ) {
                           return 404;
           }
           if ( $host = onlineconfigservice.ubi.com ) {
                           return 404;
           }
           if ( $host = com-services.pandonetworks.com ) {
                           return 404;
           }
           if ( $host ~ ticno\.com ) {
                           return 404;
           }
           if ( $host ~ conduit ) {
                           return 404;
           }
           if ( $host ~ cbox\.ws ) {
                           return 404;
           }
           if ( $host ~ (pricelist|metrics)\.skype\.com ) {
                           return 404;
           }
           if ( $host = weather.service.msn.com ) {
                           return 404;
           }
           if ( $host = advstat.letitbit.net ) {
                           return 404;
           }
           if ( $host = skymonk.net ) {
                           return 404;
           }
           if ( $host ~ counter ) {
                           return 404;
           }
           if ( $host ~ pluraserver ) {
                           return 404;
           }
           if ( $host ~ apps.bittorrent.com ) {
                           return 404;
           }
           if ( $host ~ offers.bittorrent.com ) {
                           return 404;
           }
           if ( $host ~ tracker ) {
                           return 404;
           }
           if ( $request_uri ~* (update|feed|announce|rss|xml|json|oauth) ) {
                           return 404;
           }
           if ( $request_uri ~* (toolbar|suggest) ) {
                           return 404;
           }
           if ( $request_filename ~ \.(ico|gif|jpg|png) ) {
                           return 404;
           }
           if ( $request_filename ~ \.(css|xml|js|swf|flv) ) {
                           return 404;
           }
           if ( $request_filename ~ \.(crl|txt|cab|msi|jar) ) {
                           return 404;
           }
           if ( $host ~ norton ) {
                           return 404;
           }


    rewrite ^ http://my.redirect.page.com/?lolwut redirect;
       }

       error_page   500 502 503 504  /50x.html;
       location = /50x.html {
           root   html;
       }

   }

Из бонусов такого решения - отсутствие cache poisoning браузера, фильтрация мусора (эти правила - это ещё не всё, вы у себя ещё гадости наловите) правильный URL у пользователя и возможность собственно этот URL задать (а не просто IP).

[h1vs2]

2 Abram, спасибо. Через редирект пакеты добегают.

[h1vs2]

Проблема с CoA на L4-redirect - не получается его убрать

 

В радиусе тип string. Передавали и пустой атрибут и 0 и начинается с 0+текст - не убирается. При этом иногда(если много раз повторно слать всякое) убирается Filter-ID.

 

 

    ifname     |        username        |    calling-sid    |      ip       | rate-limit  | type | comp | state  |  uptime
----------------+------------------------+-------------------+---------------+-------------+------+------+--------+----------
eth2.1500.3001 | 00137777fb8f:1500-3001 | 00:13:77:77:fb:8f | 192.168.14.36 | 50000/50000 | ipoe |      | active | 00:00:39

Биллинг отправил КоА:

echo -e "Acct-Session-Id = d289fa5e7c5a3a76,L4-Redirect = guest-ipoe" | /usr/bin/radclient -t1r2 127.0.0.1 coa testing123

 

 

Заблочило:

root@brs-acl:/usr/local/nodeny50.33# ipset list guest-ipoe
Name: guest-ipoe
Type: hash:ip
Header: family inet hashsize 1024 maxelem 65536
Size in memory: 16584
References: 1
Members:
192.168.14.36

 

 

отправили КоА:

root@brs-acl:/usr/local/nodeny50.33# echo -e "Acct-Session-Id = d289fa5e7c5a3a76,L4-Redirect = 0guest-ipoe" | /usr/bin/radclient -xt1r2 127.0.0.1 coa testing123
Sending CoA-Request of id 191 to 127.0.0.1 port 3799
Acct-Session-Id = "d289fa5e7c5a3a76"
L4-Redirect = "0guest-ipoe"
rad_recv: CoA-ACK packet from host 127.0.0.1 port 3799, id=191, length=20

 

И ничего :

root@brs-acl:/usr/local/nodeny50.33# ipset list guest-ipoe
Name: guest-ipoe
Type: hash:ip
Header: family inet hashsize 1024 maxelem 65536
Size in memory: 16584
References: 1
Members:
192.168.14.36

 

Пробовали отправлять и просто 0 и пустую строку и что угодно.

 

 

P.S : если передавать l4-redirect сразу при старте сессии, тоже не работает :

 

Sending Access-Accept of id 1 to 127.0.0.1 port 45618
DHCP-Router-IP-Address = 192.168.11.250
Framed-IP-Address = 192.168.11.36
L4-Redirect := "guest-ipoe"
Filter-Id = "50000/50000"
Acct-Interim-Interval = 60

 

И ничего.

 

root@brs-acl:/usr/local/nodeny50.33# ipset list guest-ipoe
Name: guest-ipoe
Type: hash:ip
Header: family inet hashsize 1024 maxelem 65536
Size in memory: 16584
References: 1
Members:

 

А в логе accel-ppp он вообще не отображается - нормально ?

 

[2014-05-21 17:34:06]:  info: eth2.1500.3001: recv [RADIUS(1) Access-Accept id=1 <DHCP-Router-IP-Address 192.168.11.250> <Framed-IP-Address 192.168.11.36> <DHCP-Mask 1735746931> <Filter-Id "50000/50000"> <A     cct-Interim-Interval 60>]

[xeb]

а в логах что ?

[h1vs2]

а в логах что ?

 

Уже подредактил пост.

 

 

В логах про этот атрибут вообще ничего :

 

Sending Access-Accept of id 1 to 127.0.0.1 port 45618
DHCP-Router-IP-Address = 192.168.11.250
Framed-IP-Address = 192.168.11.36
L4-Redirect := "guest-ipoe"
Filter-Id = "50000/50000"
Acct-Interim-Interval = 60

 

 

 

2014-05-21 17:34:06]:  info: eth2.1500.3001: recv [RADIUS(1) Access-Accept id=1 <DHCP-Router-IP-Address 192.168.11.250> <Framed-IP-Address 192.168.11.36> <DHCP-Mask 1735746931> <Filter-Id "50000/50000"> <A     cct-Interim-Interval 60>]

[xeb]

ну а где L4-Redirect в логах ?

[xeb]

А в логе accel-ppp он вообще не отображается - нормально ?

не нормально

что в конфиге и что в словарях радиуса accel-ppp ?

[fet4]

fet4, да, теперь всё видно, явно предыдущая сессия не завершилась, а биллинг даёт добро на новую, т.е. копать, как и предполагалось, надо биллинг

 

На форуме разработчика меня уверяют в обратном. Если не сложно гляньте пожалуйста http://abills.net.ua/forum/viewtopic.php?f=2&t=7213 . Хотелось бы разобраться с проблемой, а то уже достала.

 

Вот мой конфиг

[modules]
log_file
pppoe
auth_mschap_v2
radius
sigchld
pppd_compat
shaper

[core]
log-error=/var/log/accel-ppp/core.log
thread-count=4

[ppp]
verbose=1
min-mtu=1400
mtu=1492
mru=1492
ipv4=require
ipv6=deny
lcp-echo-interval=20
lcp-echo-timeout=120

[pppoe]
verbose=1

interface=eth0
interface=vlan100
interface=vlan101
interface=vlan102
interface=vlan103
interface=vlan104
interface=vlan105
interface=vlan106
interface=vlan107
interface=vlan108
interface=vlan109
interface=vlan110
interface=vlan111
interface=vlan112
interface=vlan113
interface=vlan114
interface=vlan115
interface=vlan116
interface=vlan117
interface=vlan118
interface=vlan119
interface=vlan120

[dns]
dns1=172.30.0.1
dns2=172.30.1.1

[radius]
dictionary=/usr/local/share/accel-ppp/radius/dictionary
nas-identifier=accel-ppp
nas-ip-address=127.0.0.1
gw-ip-address=172.31.1.254
#auth-server=127.0.0.1:1812,888888888888888
#acct-server=127.0.0.1:1813,888888888888888
server=127.0.0.1,888888888888888,auth-port=1812,acct-port=1813,req-limit=0,fail-time=0
dae-server=127.0.0.1:3799,888888888888888
verbose=1

[log]
log-file=/var/log/accel-ppp/accel-ppp.log
log-emerg=/var/log/accel-ppp/emerg.log
log-fail-file=/var/log/accel-ppp/auth-fail.log
copy=1
level=5

[pppd-compat]
#ip-pre-up=/etc/ppp/ip-pre-up
ip-up=/etc/ppp/ip-up
ip-down=/etc/ppp/ip-down
ip-change=/etc/ppp/ip-change
radattr-prefix=/var/run/radattr
verbose=1

[shaper]
up-limiter=police
down-limiter=tbf
attr-down=PPPD-Downstream-Speed-Limit
attr-up=PPPD-Upstream-Speed-Limit
verbose=1

[cli]
telnet=127.0.0.1:2000
tcp=127.0.0.1:2001
#password=123

Изменено 22 мая, 2014 пользователем fet4

[h1vs2]

А в логе accel-ppp он вообще не отображается - нормально ?

не нормально

что в конфиге и что в словарях радиуса accel-ppp ?

Разобрались в чем была проблема. http://sourceforge.net/apps/trac/accel-ppp/wiki/IPoE_ru в мануале написано :

 

ATTRIBUTE L4-Redirect 242 integer

 

А такой уже у :

ATTRIBUTE DHCP-Mask 242 integer

 

Не доглядели. Поменяли на 243.

 

 

Извините, спасибо!

[Abram]

fet4,

Вы Андрея побольше слушайте, он Вам много чего расскажет.

Мы Abills давно уже сами под себя пилим - это проще, чем слушать какие-то бредни.

[xeb]

fet4, у вас все логины по одной сессии или есть варианты ?

если все, то лучше контроль сессии передать accel

1.7:

[ppp]

single-session=replace

 

1.8

[common]

single-session=replace

 

в этом случае не придётся ждать пока завершится первая по таймауту, даже если биллинг всё правильно отрабатывает

[fet4]

Клиентам по одной сессии, некоторым админам больше одной.

 

А что она будет делать? Заменять предыдущую?