Jump to content
Local
Alexey Osipov

purestg2

Recommended Posts

Новая опция в плагине для pppd - latedisconnect. Управляет тем, когда плагин pppd пошлёт запрос старгейзеру на отключение юзверя. Если опция НЕ задана, то запрос на отключение будет послан ДО запуска скриптов auth-down и ip-down; иначе - после их выполнения.

 

Поторопился с этой опцией. Тесты показали, что оба этих скрипта запускаются асинхронно по отношению к pppd, то есть нельзя гарантировать, что они будут закончены или начаты к моменту отключения пользователя в старгейзере. Поэтому эту опцию выкинул.

 

Вместо неё появилась другая опция - predownscript <путь>. Собственно, выполняет указанный аргументом скрипт синхронно непосредственно ДО отключения пользователя в старгейзере.

Share this post


Link to post
Share on other sites

интересует такой вопрос, в какой момент отрабатывания ip-up происходит авторизация биллингом и запуск OnConnect?

 

а то столкнулся с проблемой, в скриптах OnConnect и OnDisconnect отрабатываются шейперы по интерфейсам, а они у меня жёстко vpn_$LOGIN через ip-up, и шейперы срабатывают не всегда, делаю офф/он юзеру шейпы включаются

Share this post


Link to post
Share on other sites

интересует такой вопрос, в какой момент отрабатывания ip-up происходит авторизация биллингом и запуск OnConnect?

Приказ старгейзеру авторизовать юзверя уходит ДО запуска ip-up и даже ДО запуска auth-up (если он используется). Запрос уходит синхронно, то есть плагин ждёт его завершения. НО. Скрипт OnConnect в старгейзере вызывается асинхроно к авторизации пользователя. То есть, между моментом авторизации пользователя в старгейзере и его подключением (вызовом скрипта OnConnect) проходит некоторое время. Исходя из сказанного, имеющимися средствами нельзя гарантировать определенную очередность выполнения ip-up и OnConnect.

 

Так значит... в pppd есть ещё штатный скрипт ip-pre-up, он выполняется синхронно, но уже после auth-up и соответственно после авторизации в старгейзере. Есть вариант перевесить отправку запроса авторизации старгейзеру на момент сразу после ip-pre-up, но перед ip-up. Тогда в ip-pre-up можно будет помещать команды, которые должны быть выполнены гарантированно до авторизации в старгейзере.

 

Нюанс: в момент вызова ip-pre-up ppp-интерфейс уже существует, но находится в состоянии "down" и ещё не имеет IP адреса. Это как-то может помешать? Если да, то я могу сделать в своем плагине ещё одну опцию наподобие predownscript - preupscript, который будет вызываться до авторизации в старгейзере и до запуска ip-up, но уже после того, как интерфейс окончательно поднят и настроен.

Share this post


Link to post
Share on other sites

следал задержку OnConnect банальным /bin/sleep 1 в начале скрипта, всё встало на свои места :P

 

Alexey Osipov, жду не дождусь 2.1 :P

Share this post


Link to post
Share on other sites

Alexey Osipov, жду не дождусь 2.1 :)

 

А мы не будем ждать madf и rc3. :) Выпустил purestg2 2.1. Качать на гуглекоде: http://code.google.com/p/purestg2/

 

Список новшеств можно прочитать в файле NEWS.

По-русски это будет:

* Пользователь в старгейзере теперь подключается после полной конфигурации IP-протокола для ppp-интерфейса, но до вызова скрипта ip-up. Раньше подключение в старгейзере выполнялось раньше - ещё до конфигурации IP-протокола и даже до вызова скрипта auth-up.

* Новые опции pppd-плагина: 'predownscript', 'preupscript'. Описание в README или выше в этой ветке форума.

* Новые опции Stargazer-плагина: 'ipparamsave', 'ipparamauth', 'allowemptyipparam', 'kickprevious', 'pppunitsave'. Описание в README или выше в этой ветке форума.

* Более аккуратная проверка наличия необходимых заголовочных файлов на этапке конфигурации.

* "Красивое" завершение работы pppd в случае завершения работы старгейзера. Красивое - это значит, что перед выходом pppd пошлёт клиенту пакет с сообщением о том, что соединение сейчас будет закрыто.

Ну или всё то, что я уже описывал в этой теме.

Share this post


Link to post
Share on other sites

Alexey Osipov, просто огромное спасибо за ваш труд :) качаю

Share this post


Link to post
Share on other sites

checking for users.h... no
configure: error: headers not found or not useable

хотя собираю ./configure --with-stg-headers=/include и там он есть :)

Share this post


Link to post
Share on other sites

checking for users.h... no
configure: error: headers not found or not useable

хотя собираю ./configure --with-stg-headers=/include и там он есть :)

 

simba@dahari:/data/src/stg/pure/purestg2$ ls ./stginc
actions.h	  base_store.h	     ia_packets.h   raw_ip_packet.h    stg_error.h     user_conf.h
actions.inl.h	  blowfish.h	     ibpp.h	    resetable.h        stg_int.h       user.h
admin_conf.h	  common.h	     lp2_blocks.h   rs_packets.h       stg_locker.h    user_ips.h
admin_conf.inc.h  common_settings.h  mempool.h	    script_executer.h  stg_logger.h    user_property.h
admin.h		  conffiles.h	     mimetype.h     servconf.h	       stg_message.h   users.h
admins.h	  corp_conf.h	     netunit.h	    service_conf.h     stg_timer.h     user_stat.h
ag_md5.h	  debug.h	     noncopyable.h  settings.h	       tariff_conf.h   user_traff.h
base_auth.h	  dotconfpp.h	     notifer.h	    stdstring.h        tariff.h        utime.h
base_db.h	  eventloop.h	     os_int.h	    stg_common.h       tariffs.h       version.h
base_plugin.h	  hostallow.h	     pinger.h	    stg_comp_stat.h    test.h	       vpn_stg_packets.h
base_settings.h   ia_auth_c.h	     rad_packets.h  stg_const.h        traffcounter.h

 

./configure --with-stg-headers=`pwd`/stginc

проходит на ура.

 

Рекомендуется указывать полный путь к папке с заголовками.

 

И... покажи побольше лога конфигурации. :)

Share this post


Link to post
Share on other sites

собрал, поставил, посмотрим как покажет себя в бою :)

 

оффтоп: сегодня поднял таки accel-pptpd вместно стандартного pptpd, ресурсов потребляет в разы меньше, purestg2 так же отлично работает с ним :)

Edited by yKpon

Share this post


Link to post
Share on other sites

....Получился этакий себе мини-RADIUS. :)

....

Предложения и пожелания как всегда принимаются.

 

Простите, а зачем изобретать "очередной велосипед"?

Не проще допилить уже существующий модуль к стандартному радиусу?

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

Share this post


Link to post
Share on other sites

....Получился этакий себе мини-RADIUS. :)

....

Предложения и пожелания как всегда принимаются.

 

Простите, а зачем изобретать "очередной велосипед"?

Не проще допилить уже существующий модуль к стандартному радиусу?

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

Связка stg-pptpd позволяет намного больше чем связка stg-freeradius-pptpd. Двухсторонний обмен, все-таки.

Share this post


Link to post
Share on other sites

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

Share this post


Link to post
Share on other sites

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

Проверил. После

kill <pid процесса pppd>

Online нормально сбрасывается. Или под "убитая вручную клиентская pppd сессия" имелось ввиду что-то другое?

Share this post


Link to post
Share on other sites

kill <pid процесса pppd>

Online нормально сбрасывается. Или под "убитая вручную клиентская pppd сессия" имелось ввиду что-то другое?

да, именно так, а сколько таймаут?

Share this post


Link to post
Share on other sites

kill <pid процесса pppd>

Online нормально сбрасывается. Или под "убитая вручную клиентская pppd сессия" имелось ввиду что-то другое?

да, именно так, а сколько таймаут?

Нисколько. Сразу сбрасывается. В логах это выглядит примерно так:

Mar 29 20:28:22 tera pppd[21906]: Terminating on signal 15

Mar 29 20:28:22 tera kernel: [4485141.945601] ipt_NETFLOW: flushing

Mar 29 20:28:23 tera pppd[21906]: purestg2: User test disconnected.

Mar 29 20:28:23 tera pppd[21906]: purestg2: Disconnected from stargazer.

Mar 29 20:28:23 tera pppd[21906]: Connect time 234.0 minutes.

Mar 29 20:28:23 tera pppd[21906]: Sent 11082885 bytes, received 2589511 bytes.

Mar 29 20:28:23 tera pppd[21906]: Connection terminated.

Mar 29 20:28:23 tera pppd[21906]: Exit.

Share this post


Link to post
Share on other sites

да действительно, всё отрабатввается корректно, но!

 

наступил новый месяц, у абонента тариф vip без абонплаты и без ограничений, сессия онлайн висит с 0 часов до сих пор, абонент не может авторизоваться, ошибка 691, привожу куски лога старгейзера связанные с работой purestg2

2011-04-01 00:03:28 -- purestg2: User qwest (socket=15) is disconnected.
2011-04-01 00:03:28 -- Broken pipe!
2011-04-01 00:03:28 -- purestg2: ERROR: Can't send reply: Broken pipe
2011-04-01 00:03:28 -- purestg2: ERROR: can't handle client connection for socket 15
2011-04-01 00:03:28 -- purestg2: Accepted new client connection (socket=19)
2011-04-01 00:03:28 -- purestg2: Accepted new client connection (socket=15)
2011-04-01 00:03:28 -- Admin 'sgconf', 127.0.0.1: User 'lerm2': 'DisabledDetailStat' parameter changed from '1' to '1'.
2011-04-01 00:03:28 -- Admin '@stargazer', 0.0.0.0: User 'sauron': 'freeMb' parameter changed from '0.000000' to '0.000000'. Prepaid traffic
2011-04-01 00:03:28 -- Admin '@stargazer', 0.0.0.0: User 'sanek': 'freeMb' parameter changed from '0.000000' to '0.000000'. Prepaid traffic
2011-04-01 00:03:28 -- purestg2: User master (socket=14) is disconnected.
2011-04-01 00:03:28 -- purestg2: User wws (socket=16) is disconnected.
2011-04-01 00:03:28 -- purestg2: BUG: Can't del hupped connection!
2011-04-01 00:03:29 -- Admin 'sgconf', 127.0.0.1: User 'shmel': 'DisabledDetailStat' parameter changed from '1' to '1'.
2011-04-01 00:03:29 -- purestg2: User orlova_ya (socket=15) is already connected.

2011-04-01 00:03:33 -- purestg2: Accepted new client connection (socket=14)

2011-04-01 00:03:36 -- purestg2: User artmaster (socket=14) is connected.
2011-04-01 00:03:41 -- purestg2: Accepted new client connection (socket=15)
2011-04-01 00:03:42 -- purestg2: Accepted new client connection (socket=16)
2011-04-01 00:03:45 -- purestg2: User wws (socket=15) is connected.
2011-04-01 00:03:45 -- purestg2: User qwest (socket=16) is connected.
2011-04-01 00:03:55 -- purestg2: Accepted new client connection (socket=18)
2011-04-01 00:03:57 -- purestg2: User orlova_ya (socket=18) is already connected.
2011-04-01 00:04:05 -- purestg2: Accepted new client connection (socket=18)
2011-04-01 00:04:07 -- purestg2: User orlova_ya (socket=18) is already connected.

2011-04-01 14:00:13 -- purestg2: Accepted new client connection (socket=21)
2011-04-01 14:00:16 -- purestg2: User orlova_ya (socket=21) is already connected.
2011-04-01 14:01:24 -- purestg2: Accepted new client connection (socket=21)
2011-04-01 14:01:27 -- purestg2: User orlova_ya (socket=21) is already connected.
2011-04-01 14:02:40 -- purestg2: Accepted new client connection (socket=21)
2011-04-01 14:02:43 -- purestg2: User orlova_ya (socket=21) is already connected.
2011-04-01 14:03:44 -- purestg2: Accepted new client connection (socket=21)
2011-04-01 14:03:49 -- purestg2: User orlova_ya (socket=21) is already connected.
2011-04-01 14:04:49 -- purestg2: Accepted new client connection (socket=21)
2011-04-01 14:04:51 -- purestg2: User orlova_ya (socket=21) is already connected.
2011-04-01 14:05:51 -- purestg2: Accepted new client connection (socket=21)
2011-04-01 14:05:53 -- purestg2: User orlova_ya (socket=21) is already connected.

проблема у логина orlova_ya висит онлайн до сих пор

Share this post


Link to post
Share on other sites

наступил новый месяц, у абонента тариф vip без абонплаты и без ограничений, сессия онлайн висит с 0 часов до сих пор, абонент не может авторизоваться, ошибка 691, привожу куски лога старгейзера связанные с работой purestg2

2011-04-01 00:03:28 -- purestg2: User qwest (socket=15) is disconnected.
2011-04-01 00:03:28 -- Broken pipe!
2011-04-01 00:03:28 -- purestg2: ERROR: Can't send reply: Broken pipe
2011-04-01 00:03:28 -- purestg2: ERROR: can't handle client connection for socket 15
2011-04-01 00:03:28 -- purestg2: Accepted new client connection (socket=19)
2011-04-01 00:03:28 -- purestg2: Accepted new client connection (socket=15)
2011-04-01 00:03:28 -- Admin 'sgconf', 127.0.0.1: User 'lerm2': 'DisabledDetailStat' parameter changed from '1' to '1'.
2011-04-01 00:03:28 -- Admin '@stargazer', 0.0.0.0: User 'sauron': 'freeMb' parameter changed from '0.000000' to '0.000000'. Prepaid traffic
2011-04-01 00:03:28 -- Admin '@stargazer', 0.0.0.0: User 'sanek': 'freeMb' parameter changed from '0.000000' to '0.000000'. Prepaid traffic
2011-04-01 00:03:28 -- purestg2: User master (socket=14) is disconnected.
2011-04-01 00:03:28 -- purestg2: User wws (socket=16) is disconnected.
2011-04-01 00:03:28 -- purestg2: BUG: Can't del hupped connection!
2011-04-01 00:03:29 -- Admin 'sgconf', 127.0.0.1: User 'shmel': 'DisabledDetailStat' parameter changed from '1' to '1'.
2011-04-01 00:03:29 -- purestg2: User orlova_ya (socket=15) is already connected.

Крайне любопытно. Теоретически мог повлиять Broken pipe, но я пока не понимаю как.

 

А pppd для orlova_ya соответственно уже не существует?

А запись о дисконнекте orlova_ya была до этого?

А можно ещё посмотреть на логи pppd в районе этого самого Broken Pipe (2011-04-01 00:03:28)?

 

Сбросить онлайн можно через конфигуратор - поставить пользователю флаг disabled и затем убрать.

 

Ну и я наверное сделаю ещё дополнительно отключение о таймауту, если от pppd долго не приходило никаких весточек.

Share this post


Link to post
Share on other sites

Крайне любопытно. Теоретически мог повлиять Broken pipe, но я пока не понимаю как.

 

А pppd для orlova_ya соответственно уже не существует?

А запись о дисконнекте orlova_ya была до этого?

А можно ещё посмотреть на логи pppd в районе этого самого Broken Pipe (2011-04-01 00:03:28)?

 

Сбросить онлайн можно через конфигуратор - поставить пользователю флаг disabled и затем убрать.

 

Ну и я наверное сделаю ещё дополнительно отключение о таймауту, если от pppd долго не приходило никаких весточек.

временно выдал другой логин для авторизации

 

нет дисконнекта не было

 

по логам как будто все сесси были оборваны при начислении у биллинга

 

Apr  1 00:03:05 skyprox pptp[9075]: Connection terminated.
Apr  1 00:03:05 skyprox pptp[9075]: Modem hangup
Apr  1 00:03:05 skyprox pptp[9075]: Exit.
Apr  1 00:03:05 skyprox pptpd[9074]: CTRL: Client 172.19.20.4 control connection finished
Apr  1 00:03:08 skyprox pptpd[24128]: CTRL: Client 172.19.20.4 control connection started
Apr  1 00:03:08 skyprox pptpd[24128]: CTRL: Starting call (launching pppd, opening GRE)
Apr  1 00:03:08 skyprox pppd[24129]: Plugin purestg2.so loaded.
Apr  1 00:03:08 skyprox pppd[24129]: Stargazer (purestg2 2.1) auth plugin initialized.
Apr  1 00:03:08 skyprox pptp[24129]: Plugin pptp.so loaded.
Apr  1 00:03:08 skyprox pptp[24129]: PPTP plugin version 0.8.5 compiled for pppd-2.4.4, linux-2.6.26
Apr  1 00:03:08 skyprox pptp[24129]: purestg2: Pap check is allowed.
Apr  1 00:03:08 skyprox pptp[24129]: pppd 2.4.4 started by root, uid 0
Apr  1 00:03:08 skyprox pptp[24129]: purestg2: Connected to stargazer via /var/run/purestg2.sock.
Apr  1 00:03:22 skyprox pptp[30763]: purestg2: No ping from stargazer, exiting.
Apr  1 00:03:22 skyprox pptp[30763]: Terminating on signal 15
Apr  1 00:03:24 skyprox pptp[21687]: purestg2: No ping from stargazer, exiting.
Apr  1 00:03:24 skyprox pptp[21687]: Terminating on signal 15
Apr  1 00:03:29 skyprox pptp[21687]: purestg2: Can't disconnect user wws
Apr  1 00:03:29 skyprox pptp[30763]: purestg2: Can't disconnect user master
Apr  1 00:03:29 skyprox pptp[21687]: Couldn't get PPP statistics: No such device
Apr  1 00:03:29 skyprox pptp[30763]: Couldn't get PPP statistics: No such device
Apr  1 00:03:29 skyprox pptp[30763]: Couldn't get PPP statistics: No such device
Apr  1 00:03:29 skyprox pptp[21687]: Couldn't get PPP statistics: No such device
Apr  1 00:03:29 skyprox pptp[30763]: ioctl (SIOCGIFFLAGS): No such device (line 2334)
Apr  1 00:03:29 skyprox pptp[24129]: purestg2: ifunit set to 6.
Apr  1 00:03:29 skyprox pptp[24129]: Using interface ppp6
Apr  1 00:03:29 skyprox pptp[24129]: Connect: ppp6 <--> pptp (172.19.20.4)
Apr  1 00:03:29 skyprox pptp[24129]: purestg2: Chap check is allowed.
Apr  1 00:03:29 skyprox pptp[24129]: purestg2: Pap check is allowed.
Apr  1 00:03:29 skyprox pptp[24129]: purestg2: Chap check is allowed.
Apr  1 00:03:29 skyprox pptpd[24128]: CTRL: Ignored a SET LINK INFO packet with real ACCMs!
Apr  1 00:03:29 skyprox pptp[21687]: ioctl (SIOCGIFFLAGS): No such device (line 2334)
Apr  1 00:03:29 skyprox pptp[24129]: purestg2: CHAP started.
Apr  1 00:03:29 skyprox pptp[24129]: purestg2: Can't get passwd for user orlova_ya.
Apr  1 00:03:29 skyprox pptp[24129]: Peer orlova_ya failed CHAP authentication
Apr  1 00:03:29 skyprox pptp[21687]: ioctl(SIOCSIFADDR): No such device (line 2492)
Apr  1 00:03:29 skyprox pptpd[24128]: CTRL: Reaping child PPP[24129]
Apr  1 00:03:29 skyprox pptpd[24128]: CTRL: Client pppd TERM sending
Apr  1 00:03:29 skyprox pptpd[24128]: CTRL: Client pppd finish wait
Apr  1 00:03:29 skyprox pptp[24129]: Terminating on signal 15
Apr  1 00:03:29 skyprox pptp[24129]: Connection terminated.
Apr  1 00:03:29 skyprox pptpd[21686]: CTRL: Reaping child PPP[21687]
Apr  1 00:03:29 skyprox pptpd[21686]: CTRL: Client pppd TERM sending
Apr  1 00:03:29 skyprox pptpd[21686]: CTRL: Client pppd finish wait
Apr  1 00:03:29 skyprox pptp[21687]: Terminating on signal 15
Apr  1 00:03:29 skyprox pptp[30763]: ioctl(SIOCSIFADDR): No such device (line 2492)
Apr  1 00:03:29 skyprox pptp[30763]: Connection terminated.
Apr  1 00:03:29 skyprox pptp[24129]: Exit.
Apr  1 00:03:29 skyprox pptpd[24128]: CTRL: Client 172.19.20.4 control connection finished
Apr  1 00:03:29 skyprox pptp[30763]: Exit.
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Reaping child PPP[30763]
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Client pppd TERM sending
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Client pppd finish wait
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Asked to free call when no call open, not handled well
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Could not free Call ID [call clear]!
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Got call clear request after call manually shutdown - buggy client
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Asked to free call when no call open, not handled well
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Could not free Call ID [call clear]!
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Got call clear request after call manually shutdown - buggy client
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Client 172.23.4.61 control connection finished
Apr  1 00:03:33 skyprox pptpd[26212]: CTRL: Client 172.23.4.61 control connection started
Apr  1 00:03:33 skyprox pptpd[26212]: CTRL: Starting call (launching pppd, opening GRE)
Apr  1 00:03:33 skyprox pppd[26216]: Plugin purestg2.so loaded.
Apr  1 00:03:33 skyprox pppd[26216]: Stargazer (purestg2 2.1) auth plugin initialized.
Apr  1 00:03:33 skyprox pptp[26216]: Plugin pptp.so loaded.
Apr  1 00:03:33 skyprox pptp[26216]: PPTP plugin version 0.8.5 compiled for pppd-2.4.4, linux-2.6.26
Apr  1 00:03:33 skyprox pptp[26216]: purestg2: Pap check is allowed.
Apr  1 00:03:33 skyprox pptp[26216]: pppd 2.4.4 started by root, uid 0
Apr  1 00:03:33 skyprox pptp[26216]: purestg2: Connected to stargazer via /var/run/purestg2.sock.
Apr  1 00:03:33 skyprox pptp[26216]: purestg2: ifunit set to 5.
Apr  1 00:03:33 skyprox pptp[26216]: Using interface ppp5
Apr  1 00:03:33 skyprox pptp[26216]: Connect: ppp5 <--> pptp (172.23.4.61)
Apr  1 00:03:33 skyprox pptp[26216]: purestg2: Chap check is allowed.
Apr  1 00:03:33 skyprox pptp[26216]: purestg2: Pap check is allowed.
Apr  1 00:03:33 skyprox pptp[26216]: purestg2: Chap check is allowed.
Apr  1 00:03:35 skyprox pptp[21687]: Connection terminated.
Apr  1 00:03:35 skyprox pptp[21687]: Modem hangup
Apr  1 00:03:35 skyprox pptp[21687]: Exit.
Apr  1 00:03:35 skyprox pptpd[21686]: CTRL: Client 172.19.26.55 control connection finished
Apr  1 00:03:36 skyprox pptp[26216]: purestg2: CHAP started.
Apr  1 00:03:36 skyprox pptp[26216]: purestg2: Got passwd for user artmaster.
Apr  1 00:03:36 skyprox pptp[26216]: purestg2: IP choose started.
Apr  1 00:03:36 skyprox pptp[26216]: purestg2: Allowed address.
Apr  1 00:03:36 skyprox pptp[26216]: purestg2: Good address.
Apr  1 00:03:36 skyprox pptp[26216]: Cannot determine ethernet address for proxy ARP
Apr  1 00:03:36 skyprox pptp[26216]: local  IP address 10.0.0.101
Apr  1 00:03:36 skyprox pptp[26216]: remote IP address 192.168.4.5
Apr  1 00:03:36 skyprox pptp[26216]: purestg2: User artmaster connected.
Apr  1 00:03:42 skyprox pptpd[26918]: CTRL: Client 172.19.26.55 control connection started
Apr  1 00:03:42 skyprox pptpd[26918]: CTRL: Starting call (launching pppd, opening GRE)
Apr  1 00:03:42 skyprox pppd[26919]: Plugin purestg2.so loaded.
Apr  1 00:03:42 skyprox pppd[26919]: Stargazer (purestg2 2.1) auth plugin initialized.
Apr  1 00:03:42 skyprox pptp[26919]: Plugin pptp.so loaded.
Apr  1 00:03:42 skyprox pptp[26919]: PPTP plugin version 0.8.5 compiled for pppd-2.4.4, linux-2.6.26
Apr  1 00:03:42 skyprox pptp[26919]: purestg2: Pap check is allowed.
Apr  1 00:03:42 skyprox pptp[26919]: pppd 2.4.4 started by root, uid 0
Apr  1 00:03:42 skyprox pptp[26919]: purestg2: Connected to stargazer via /var/run/purestg2.sock.
Apr  1 00:03:42 skyprox pptp[26919]: purestg2: ifunit set to 6.
Apr  1 00:03:42 skyprox pptp[26919]: Using interface ppp6
Apr  1 00:03:42 skyprox pptp[26919]: Connect: ppp6 <--> pptp (172.19.26.55)
Apr  1 00:03:42 skyprox pptp[26919]: purestg2: Chap check is allowed.
Apr  1 00:03:42 skyprox pptp[26919]: purestg2: Pap check is allowed.
Apr  1 00:03:42 skyprox pptp[26919]: purestg2: Chap check is allowed.
Apr  1 00:03:43 skyprox pptpd[26926]: CTRL: Client 172.28.31.18 control connection started
Apr  1 00:03:43 skyprox pptpd[26926]: CTRL: Starting call (launching pppd, opening GRE)
Apr  1 00:03:43 skyprox pppd[26927]: Plugin purestg2.so loaded.
Apr  1 00:03:43 skyprox pppd[26927]: Stargazer (purestg2 2.1) auth plugin initialized.
Apr  1 00:03:43 skyprox pptp[26927]: Plugin pptp.so loaded.
Apr  1 00:03:43 skyprox pptp[26927]: PPTP plugin version 0.8.5 compiled for pppd-2.4.4, linux-2.6.26
Apr  1 00:03:43 skyprox pptp[26927]: purestg2: Pap check is allowed.
Apr  1 00:03:43 skyprox pptp[26927]: pppd 2.4.4 started by root, uid 0
Apr  1 00:03:43 skyprox pptp[26927]: purestg2: Connected to stargazer via /var/run/purestg2.sock.
Apr  1 00:03:43 skyprox pptp[26927]: purestg2: ifunit set to 7.
Apr  1 00:03:43 skyprox pptp[26927]: Using interface ppp7
Apr  1 00:03:43 skyprox pptp[26927]: Connect: ppp7 <--> pptp (172.28.31.18)
Apr  1 00:03:43 skyprox pptp[26927]: purestg2: Chap check is allowed.
Apr  1 00:03:43 skyprox pptp[26927]: purestg2: Pap check is allowed.
Apr  1 00:03:43 skyprox pptp[26927]: purestg2: Chap check is allowed.
Apr  1 00:03:45 skyprox pptp[26919]: purestg2: CHAP started.
Apr  1 00:03:45 skyprox pptp[26919]: purestg2: Got passwd for user wws.
Apr  1 00:03:45 skyprox pptp[26919]: purestg2: IP choose started.
Apr  1 00:03:45 skyprox pptp[26919]: purestg2: Allowed address.
Apr  1 00:03:45 skyprox pptp[26919]: purestg2: Good address.
Apr  1 00:03:45 skyprox pptp[26919]: Cannot determine ethernet address for proxy ARP
Apr  1 00:03:45 skyprox pptp[26919]: local  IP address 10.0.0.101
Apr  1 00:03:45 skyprox pptp[26919]: remote IP address 192.168.4.6
Apr  1 00:03:45 skyprox pptp[26919]: purestg2: User wws connected.
Apr  1 00:03:46 skyprox pptpd[26926]: CTRL: Ignored a SET LINK INFO packet with real ACCMs!
Apr  1 00:03:46 skyprox pptp[26927]: purestg2: CHAP started.
Apr  1 00:03:46 skyprox pptp[26927]: purestg2: Got passwd for user qwest.
Apr  1 00:03:46 skyprox pptp[26927]: purestg2: IP choose started.
Apr  1 00:03:46 skyprox pptp[26927]: purestg2: Allowed address.
Apr  1 00:03:46 skyprox pptp[26927]: purestg2: Good address.
Apr  1 00:03:46 skyprox pptp[26927]: Cannot determine ethernet address for proxy ARP
Apr  1 00:03:46 skyprox pptp[26927]: local  IP address 10.0.0.101
Apr  1 00:03:46 skyprox pptp[26927]: remote IP address 192.168.3.5
Apr  1 00:03:46 skyprox pptp[26927]: purestg2: User qwest connected.
Apr  1 00:03:55 skyprox pptpd[27283]: CTRL: Client 172.19.20.4 control connection started
Apr  1 00:03:55 skyprox pptpd[27283]: CTRL: Starting call (launching pppd, opening GRE)
Apr  1 00:03:55 skyprox pppd[27284]: Plugin purestg2.so loaded.
Apr  1 00:03:55 skyprox pppd[27284]: Stargazer (purestg2 2.1) auth plugin initialized.
Apr  1 00:03:55 skyprox pptp[27284]: Plugin pptp.so loaded.
Apr  1 00:03:55 skyprox pptp[27284]: PPTP plugin version 0.8.5 compiled for pppd-2.4.4, linux-2.6.26
Apr  1 00:03:55 skyprox pptp[27284]: purestg2: Pap check is allowed.
Apr  1 00:03:55 skyprox pptp[27284]: pppd 2.4.4 started by root, uid 0
Apr  1 00:03:55 skyprox pptp[27284]: purestg2: Connected to stargazer via /var/run/purestg2.sock.
Apr  1 00:03:55 skyprox pptp[27284]: purestg2: ifunit set to 9.
Apr  1 00:03:56 skyprox pptp[27284]: Using interface ppp9
Apr  1 00:03:56 skyprox pptp[27284]: Connect: ppp9 <--> pptp (172.19.20.4)
Apr  1 00:03:56 skyprox pptp[27284]: purestg2: Chap check is allowed.
Apr  1 00:03:56 skyprox pptp[27284]: purestg2: Pap check is allowed.
Apr  1 00:03:56 skyprox pptp[27284]: purestg2: Chap check is allowed.
Apr  1 00:03:58 skyprox pptpd[27283]: CTRL: Ignored a SET LINK INFO packet with real ACCMs!
Apr  1 00:03:58 skyprox pptp[27284]: purestg2: CHAP started.
Apr  1 00:03:58 skyprox pptp[27284]: purestg2: Can't get passwd for user orlova_ya.
Apr  1 00:03:58 skyprox pptp[27284]: Peer orlova_ya failed CHAP authentication
Apr  1 00:03:58 skyprox pptp[27284]: Connection terminated.
Apr  1 00:03:58 skyprox pptpd[27283]: CTRL: Reaping child PPP[27284]
Apr  1 00:03:58 skyprox pptpd[27283]: CTRL: Client pppd TERM sending
Apr  1 00:03:58 skyprox pptpd[27283]: CTRL: Client pppd finish wait
Apr  1 00:03:58 skyprox pptp[27284]: Exit.
Apr  1 00:03:58 skyprox pptpd[27283]: CTRL: Client 172.19.20.4 control connection finished

 

сбросить не получается, делаю disable офф/он всё равно онлайн

 

случайно никак не связано с тем что имена интерфейсов принудительно переименовываются с рррХ на vpn_LOGIN

- где LOGIN это логин из биллинга

Share this post


Link to post
Share on other sites

Apr  1 00:03:05 skyprox pptp[9075]: Connection terminated.
Apr  1 00:03:05 skyprox pptp[9075]: Modem hangup
Apr  1 00:03:05 skyprox pptp[9075]: Exit.
Apr  1 00:03:05 skyprox pptpd[9074]: CTRL: Client 172.19.20.4 control connection finished

Покажи чуть выше, где pptp[9075] начал отключаться.

 

сбросить не получается, делаю disable офф/он всё равно онлайн

Странно. Конфигуратор должен показывать, что пользователь стал оффлайн, пока включен disabled. Независимо от того, авторизован он или нет. Надо кстати уточнить у madf: что означает Online в конфигураторе: что пользователь авторизован или что пользователь подключен?

 

А в логах старгейзера при этом что?

 

Хотя да, это не поможет. На данный момент плагин проверят состояние пользователя только по приходу события PING от pppd. А раз pppd у нас упал, то и проверки disable не будет.

 

случайно никак не связано с тем что имена интерфейсов принудительно переименовываются с рррХ на vpn_LOGIN

- где LOGIN это логин из биллинга

Не должно.

Share this post


Link to post
Share on other sites

до pptp[9075]

Apr  1 00:01:35 skyprox pptp[4732]: LCP terminated by peer (_M-tbM-\^@<M-Mt^@^@^@^@)
Apr  1 00:01:42 skyprox pptpd[4726]: CTRL: Reaping child PPP[4732]
Apr  1 00:01:42 skyprox pptpd[4726]: CTRL: Client pppd TERM sending
Apr  1 00:01:42 skyprox pptpd[4726]: CTRL: Client pppd finish wait
Apr  1 00:01:42 skyprox pptp[4732]: purestg2: Can't disconnect user qwest
Apr  1 00:01:42 skyprox pptp[4732]: Couldn't get PPP statistics: No such device
Apr  1 00:01:42 skyprox pptp[4732]: Couldn't get PPP statistics: No such device
Apr  1 00:01:42 skyprox pptp[4732]: ioctl (SIOCGIFFLAGS): No such device (line 2334)
Apr  1 00:01:42 skyprox pptp[4732]: ioctl(SIOCSIFADDR): No such device (line 2492)
Apr  1 00:01:42 skyprox pptp[4732]: Terminating on signal 15
Apr  1 00:02:00 skyprox pptpd[23473]: CTRL: Client 172.28.31.18 control connection started
Apr  1 00:02:00 skyprox pptpd[23473]: CTRL: Starting call (launching pppd, opening GRE)
Apr  1 00:02:00 skyprox pppd[23474]: Plugin purestg2.so loaded.
Apr  1 00:02:00 skyprox pppd[23474]: Stargazer (purestg2 2.1) auth plugin initialized.
Apr  1 00:02:00 skyprox pptp[23474]: Plugin pptp.so loaded.
Apr  1 00:02:00 skyprox pptp[23474]: PPTP plugin version 0.8.5 compiled for pppd-2.4.4, linux-2.6.26
Apr  1 00:02:00 skyprox pptp[23474]: purestg2: Pap check is allowed.
Apr  1 00:02:00 skyprox pptp[23474]: pppd 2.4.4 started by root, uid 0
Apr  1 00:02:00 skyprox pptp[23474]: purestg2: Connected to stargazer via /var/run/purestg2.sock.
Apr  1 00:02:36 skyprox pptpd[23473]: CTRL: Reaping child PPP[23474]
Apr  1 00:02:36 skyprox pptpd[23473]: CTRL: Client pppd TERM sending
Apr  1 00:02:36 skyprox pptp[23474]: purestg2: Can't get ifunit. Exiting.
Apr  1 00:02:36 skyprox pptp[23474]: Exit.
Apr  1 00:02:36 skyprox pptpd[23473]: CTRL: Client pppd finish wait
Apr  1 00:02:36 skyprox pptpd[23473]: CTRL: Client 172.28.31.18 control connection finished
Apr  1 00:02:42 skyprox pptp[9075]: purestg2: No ping from stargazer, exiting.
Apr  1 00:02:42 skyprox pptp[9075]: Terminating on signal 15
Apr  1 00:02:43 skyprox pptp[4732]: purestg2: No ping from stargazer, exiting.
Apr  1 00:02:43 skyprox pptp[4732]: Connection terminated.
Apr  1 00:02:43 skyprox pptp[4732]: Terminating on signal 15
Apr  1 00:02:43 skyprox pptp[4732]: Modem hangup
Apr  1 00:02:43 skyprox pptp[4732]: Exit.
Apr  1 00:02:43 skyprox pptpd[4726]: CTRL: Client 172.28.31.18 control connection finished
Apr  1 00:02:59 skyprox pptpd[9074]: CTRL: Reaping child PPP[9075]
Apr  1 00:02:59 skyprox pptpd[9074]: CTRL: Client pppd TERM sending
Apr  1 00:02:59 skyprox pptpd[9074]: CTRL: Client pppd finish wait
Apr  1 00:02:59 skyprox pptp[9075]: purestg2: Can't disconnect user orlova_ya
Apr  1 00:02:59 skyprox pptp[9075]: Couldn't get PPP statistics: No such device
Apr  1 00:02:59 skyprox pptp[9075]: Couldn't get PPP statistics: No such device
Apr  1 00:02:59 skyprox pptp[9075]: ioctl (SIOCGIFFLAGS): No such device (line 2334)
Apr  1 00:02:59 skyprox pptp[9075]: ioctl(SIOCSIFADDR): No such device (line 2492)
Apr  1 00:02:59 skyprox pptp[9075]: Terminating on signal 15

Share this post


Link to post
Share on other sites

...

сбросить не получается, делаю disable офф/он всё равно онлайн

Странно. Конфигуратор должен показывать, что пользователь стал оффлайн, пока включен disabled. Независимо от того, авторизован он или нет. Надо кстати уточнить у madf: что означает Online в конфигураторе: что пользователь авторизован или что пользователь подключен?

 

...

Подключен.

Странно, похоже на глюк с "залипанием" авторизации.

Share this post


Link to post
Share on other sites

Вообще интересные вещи происходят у товарища yKponа.

 

1. Почему вдруг все пользователи начали повально отключаться в начале нового месяца? Причем, как самостоятельно:

Apr  1 00:01:35 skyprox pptp[4732]: LCP terminated by peer (_M-tbM-\^@<M-Mt^@^@^@^@)

Так и из-за потери связи pppd со старгейзером:

Apr  1 00:02:42 skyprox pptp[9075]: purestg2: No ping from stargazer, exiting.
Apr  1 00:02:42 skyprox pptp[9075]: Terminating on signal 15

 

2. pppd после загрузки плагина pptp.so преображается в pptp:

Apr  1 00:02:00 skyprox pptpd[23473]: CTRL: Client 172.28.31.18 control connection started
Apr  1 00:02:00 skyprox pptpd[23473]: CTRL: Starting call (launching pppd, opening GRE)
Apr  1 00:02:00 skyprox pppd[23474]: Plugin purestg2.so loaded.
Apr  1 00:02:00 skyprox pppd[23474]: Stargazer (purestg2 2.1) auth plugin initialized.
Apr  1 00:02:00 skyprox pptp[23474]: Plugin pptp.so loaded.
Apr  1 00:02:00 skyprox pptp[23474]: PPTP plugin version 0.8.5 compiled for pppd-2.4.4, linux-2.6.26

Не думаю, что это сильно страшно, но всё-же стоит проверить совместимость pptp.so и purestg2.so. Может быть в этом как-раз и проблема.

 

3. pppd/pptp два раза получает SIGTERM:

Apr  1 00:01:35 skyprox pptp[4732]: LCP terminated by peer (_M-tbM-\^@<M-Mt^@^@^@^@)
Apr  1 00:01:42 skyprox pptpd[4726]: CTRL: Reaping child PPP[4732]
Apr  1 00:01:42 skyprox pptpd[4726]: CTRL: Client pppd TERM sending
Apr  1 00:01:42 skyprox pptpd[4726]: CTRL: Client pppd finish wait
Apr  1 00:01:42 skyprox pptp[4732]: purestg2: Can't disconnect user qwest
Apr  1 00:01:42 skyprox pptp[4732]: Couldn't get PPP statistics: No such device
Apr  1 00:01:42 skyprox pptp[4732]: Couldn't get PPP statistics: No such device
Apr  1 00:01:42 skyprox pptp[4732]: ioctl (SIOCGIFFLAGS): No such device (line 2334)
Apr  1 00:01:42 skyprox pptp[4732]: ioctl(SIOCSIFADDR): No such device (line 2492)
Apr  1 00:01:42 skyprox pptp[4732]: Terminating on signal 15                             <- РАЗ
Apr  1 00:02:43 skyprox pptp[4732]: purestg2: No ping from stargazer, exiting.
Apr  1 00:02:43 skyprox pptp[4732]: Connection terminated.
Apr  1 00:02:43 skyprox pptp[4732]: Terminating on signal 15                             <- ДВА
Apr  1 00:02:43 skyprox pptp[4732]: Modem hangup
Apr  1 00:02:43 skyprox pptp[4732]: Exit.

Первый раз его посылает pptpd, так как случился LCP terminated by peer. Второй раз его инициирует purestg2, так как не получил ответа от старгейзера.

Вопрос первый: почему pppd/pptp не завершается после первого SIGTERM?

Вопрос второй: почему purestg2 не может получить ответ от старегейзера?

 

4. purestg2 не может достучаться до старгейзера и в других случаях:

Apr  1 00:01:42 skyprox pptp[4732]: purestg2: Can't disconnect user qwest
...
Apr  1 00:02:00 skyprox pppd[23474]: Plugin purestg2.so loaded.
Apr  1 00:02:00 skyprox pppd[23474]: Stargazer (purestg2 2.1) auth plugin initialized.
Apr  1 00:02:00 skyprox pptp[23474]: Plugin pptp.so loaded.
Apr  1 00:02:00 skyprox pptp[23474]: PPTP plugin version 0.8.5 compiled for pppd-2.4.4, linux-2.6.26
Apr  1 00:02:00 skyprox pptp[23474]: purestg2: Pap check is allowed.
Apr  1 00:02:00 skyprox pptp[23474]: pppd 2.4.4 started by root, uid 0
Apr  1 00:02:00 skyprox pptp[23474]: purestg2: Connected to stargazer via /var/run/purestg2.sock.
Apr  1 00:02:36 skyprox pptpd[23473]: CTRL: Reaping child PPP[23474]
Apr  1 00:02:36 skyprox pptpd[23473]: CTRL: Client pppd TERM sending
Apr  1 00:02:36 skyprox pptp[23474]: purestg2: Can't get ifunit. Exiting.
...
Apr  1 00:02:42 skyprox pptp[9075]: purestg2: No ping from stargazer, exiting.
Apr  1 00:02:42 skyprox pptp[9075]: Terminating on signal 15
Apr  1 00:02:43 skyprox pptp[4732]: purestg2: No ping from stargazer, exiting.
...
Apr  1 00:02:59 skyprox pptp[9075]: purestg2: Can't disconnect user orlova_ya

То есть вообще никак - ни при отключении пользователя, ни при подключении (Can't get ifunit). Но коннект на сокет старгейзера при этом проходит (Connected to stargazer via...).

yKpon, покажи, пожалуйста, ещё логи старгейзера в окрестностях 00:02:00 +- пару минут.

 

5. pppd/pptp, похоже, сам удивлён происходящим:

Apr  1 00:02:59 skyprox pptp[9075]: Couldn't get PPP statistics: No such device
Apr  1 00:02:59 skyprox pptp[9075]: Couldn't get PPP statistics: No such device
Apr  1 00:02:59 skyprox pptp[9075]: ioctl (SIOCGIFFLAGS): No such device (line 2334)
Apr  1 00:02:59 skyprox pptp[9075]: ioctl(SIOCSIFADDR): No such device (line 2492)

 

Теперь моя гипотеза:

Пока старгейзер занят обработкой события "новый месяц" (сброс трафика, снятие абонки и прочее), он держит какую-то блокировку (mutex), которая нужна purestg2 для ответа на запросы от pppd/pptp.

Авторизатор purestg2 крутится в отдельном потоке и использует для своей работы следующие функции:

USERS::FindByName()

USER::GetAuthorized()

USER::IsAuthorizedBy()

USER::Unauthorize()

USER::Authorize()

USER::IsInetable()

а также доступ к некоторым свойствам USER::property.

madf, что ты думаешь об этом?

 

yKpon, было бы интересно посмотреть на те же самые логи, но с включенными опциями debug в конфиге purestg2 старгейзера и в конфиге pppd. Обе опции без аргументов.

Share this post


Link to post
Share on other sites

Alexey Osipov, что было до 00:02:00 и после 00:00 я выше привёл в логах (просто постороннее убрал)

 

а ниже вот как то так

Apr  1 00:02:36 skyprox pptpd[23473]: CTRL: Reaping child PPP[23474]
Apr  1 00:02:36 skyprox pptpd[23473]: CTRL: Client pppd TERM sending
Apr  1 00:02:36 skyprox pptp[23474]: purestg2: Can't get ifunit. Exiting.
Apr  1 00:02:36 skyprox pptp[23474]: Exit.
Apr  1 00:02:36 skyprox pptpd[23473]: CTRL: Client pppd finish wait
Apr  1 00:02:36 skyprox pptpd[23473]: CTRL: Client 172.28.31.18 control connection finished
Apr  1 00:02:42 skyprox pptp[9075]: purestg2: No ping from stargazer, exiting.
Apr  1 00:02:42 skyprox pptp[9075]: Terminating on signal 15
Apr  1 00:02:43 skyprox pptp[4732]: purestg2: No ping from stargazer, exiting.
Apr  1 00:02:43 skyprox pptp[4732]: Connection terminated.
Apr  1 00:02:43 skyprox pptp[4732]: Terminating on signal 15
Apr  1 00:02:43 skyprox pptp[4732]: Modem hangup
Apr  1 00:02:43 skyprox pptp[4732]: Exit.
Apr  1 00:02:43 skyprox pptpd[4726]: CTRL: Client 172.28.31.18 control connection finished
Apr  1 00:02:59 skyprox pptpd[9074]: CTRL: Reaping child PPP[9075]
Apr  1 00:02:59 skyprox pptpd[9074]: CTRL: Client pppd TERM sending
Apr  1 00:02:59 skyprox pptpd[9074]: CTRL: Client pppd finish wait
Apr  1 00:02:59 skyprox pptp[9075]: purestg2: Can't disconnect user orlova_ya
Apr  1 00:02:59 skyprox pptp[9075]: Couldn't get PPP statistics: No such device
Apr  1 00:02:59 skyprox pptp[9075]: Couldn't get PPP statistics: No such device
Apr  1 00:02:59 skyprox pptp[9075]: ioctl (SIOCGIFFLAGS): No such device (line 2334)
Apr  1 00:02:59 skyprox pptp[9075]: ioctl(SIOCSIFADDR): No such device (line 2492)
Apr  1 00:02:59 skyprox pptp[9075]: Terminating on signal 15
Apr  1 00:03:05 skyprox pptp[9075]: Connection terminated.
Apr  1 00:03:05 skyprox pptp[9075]: Modem hangup
Apr  1 00:03:05 skyprox pptp[9075]: Exit.
Apr  1 00:03:05 skyprox pptpd[9074]: CTRL: Client 172.19.20.4 control connection finished
Apr  1 00:03:08 skyprox pptpd[24128]: CTRL: Client 172.19.20.4 control connection started
Apr  1 00:03:08 skyprox pptpd[24128]: CTRL: Starting call (launching pppd, opening GRE)
Apr  1 00:03:08 skyprox pppd[24129]: Plugin purestg2.so loaded.
Apr  1 00:03:08 skyprox pppd[24129]: Stargazer (purestg2 2.1) auth plugin initialized.
Apr  1 00:03:08 skyprox pptp[24129]: Plugin pptp.so loaded.
Apr  1 00:03:08 skyprox pptp[24129]: PPTP plugin version 0.8.5 compiled for pppd-2.4.4, linux-2.6.26
Apr  1 00:03:08 skyprox pptp[24129]: purestg2: Pap check is allowed.
Apr  1 00:03:08 skyprox pptp[24129]: pppd 2.4.4 started by root, uid 0
Apr  1 00:03:08 skyprox pptp[24129]: purestg2: Connected to stargazer via /var/run/purestg2.sock.
Apr  1 00:03:22 skyprox pptp[30763]: purestg2: No ping from stargazer, exiting.
Apr  1 00:03:22 skyprox pptp[30763]: Terminating on signal 15
Apr  1 00:03:24 skyprox pptp[21687]: purestg2: No ping from stargazer, exiting.
Apr  1 00:03:24 skyprox pptp[21687]: Terminating on signal 15
Apr  1 00:03:29 skyprox pptp[21687]: purestg2: Can't disconnect user wws
Apr  1 00:03:29 skyprox pptp[30763]: purestg2: Can't disconnect user artmaster
Apr  1 00:03:29 skyprox pptp[21687]: Couldn't get PPP statistics: No such device
Apr  1 00:03:29 skyprox pptp[30763]: Couldn't get PPP statistics: No such device
Apr  1 00:03:29 skyprox pptp[30763]: Couldn't get PPP statistics: No such device
Apr  1 00:03:29 skyprox pptp[21687]: Couldn't get PPP statistics: No such device
Apr  1 00:03:29 skyprox pptp[30763]: ioctl (SIOCGIFFLAGS): No such device (line 2334)
Apr  1 00:03:29 skyprox pptp[24129]: purestg2: ifunit set to 6.
Apr  1 00:03:29 skyprox pptp[24129]: Using interface ppp6
Apr  1 00:03:29 skyprox pptp[24129]: Connect: ppp6 <--> pptp (172.19.20.4)
Apr  1 00:03:29 skyprox pptp[24129]: purestg2: Chap check is allowed.
Apr  1 00:03:29 skyprox pptp[24129]: purestg2: Pap check is allowed.
Apr  1 00:03:29 skyprox pptp[24129]: purestg2: Chap check is allowed.
Apr  1 00:03:29 skyprox pptpd[24128]: CTRL: Ignored a SET LINK INFO packet with real ACCMs!
Apr  1 00:03:29 skyprox pptp[21687]: ioctl (SIOCGIFFLAGS): No such device (line 2334)
Apr  1 00:03:29 skyprox pptp[24129]: purestg2: CHAP started.
Apr  1 00:03:29 skyprox pptp[24129]: purestg2: Can't get passwd for user orlova_ya.
Apr  1 00:03:29 skyprox pptp[24129]: Peer orlova_ya failed CHAP authentication
Apr  1 00:03:29 skyprox pptp[21687]: ioctl(SIOCSIFADDR): No such device (line 2492)
Apr  1 00:03:29 skyprox pptpd[24128]: CTRL: Reaping child PPP[24129]
Apr  1 00:03:29 skyprox pptpd[24128]: CTRL: Client pppd TERM sending
Apr  1 00:03:29 skyprox pptpd[24128]: CTRL: Client pppd finish wait
Apr  1 00:03:29 skyprox pptp[24129]: Terminating on signal 15
Apr  1 00:03:29 skyprox pptp[24129]: Connection terminated.
Apr  1 00:03:29 skyprox pptpd[21686]: CTRL: Reaping child PPP[21687]
Apr  1 00:03:29 skyprox pptpd[21686]: CTRL: Client pppd TERM sending
Apr  1 00:03:29 skyprox pptpd[21686]: CTRL: Client pppd finish wait
Apr  1 00:03:29 skyprox pptp[21687]: Terminating on signal 15
Apr  1 00:03:29 skyprox pptp[30763]: ioctl(SIOCSIFADDR): No such device (line 2492)
Apr  1 00:03:29 skyprox pptp[30763]: Connection terminated.
Apr  1 00:03:29 skyprox pptp[24129]: Exit.
Apr  1 00:03:29 skyprox pptpd[24128]: CTRL: Client 172.19.20.4 control connection finished
Apr  1 00:03:29 skyprox pptp[30763]: Exit.
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Reaping child PPP[30763]
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Client pppd TERM sending
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Client pppd finish wait
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Asked to free call when no call open, not handled well
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Could not free Call ID [call clear]!
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Got call clear request after call manually shutdown - buggy client
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Asked to free call when no call open, not handled well
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Could not free Call ID [call clear]!
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Got call clear request after call manually shutdown - buggy client
Apr  1 00:03:29 skyprox pptpd[30762]: CTRL: Client 172.23.4.61 control connection finished
Apr  1 00:03:33 skyprox pptpd[26212]: CTRL: Client 172.23.4.61 control connection started
Apr  1 00:03:33 skyprox pptpd[26212]: CTRL: Starting call (launching pppd, opening GRE)
Apr  1 00:03:33 skyprox pppd[26216]: Plugin purestg2.so loaded.
Apr  1 00:03:33 skyprox pppd[26216]: Stargazer (purestg2 2.1) auth plugin initialized.
Apr  1 00:03:33 skyprox pptp[26216]: Plugin pptp.so loaded.
Apr  1 00:03:33 skyprox pptp[26216]: PPTP plugin version 0.8.5 compiled for pppd-2.4.4, linux-2.6.26
Apr  1 00:03:33 skyprox pptp[26216]: purestg2: Pap check is allowed.
Apr  1 00:03:33 skyprox pptp[26216]: pppd 2.4.4 started by root, uid 0
Apr  1 00:03:33 skyprox pptp[26216]: purestg2: Connected to stargazer via /var/run/purestg2.sock.
Apr  1 00:03:33 skyprox pptp[26216]: purestg2: ifunit set to 5.
Apr  1 00:03:33 skyprox pptp[26216]: Using interface ppp5
Apr  1 00:03:33 skyprox pptp[26216]: Connect: ppp5 <--> pptp (172.23.4.61)
Apr  1 00:03:33 skyprox pptp[26216]: purestg2: Chap check is allowed.
Apr  1 00:03:33 skyprox pptp[26216]: purestg2: Pap check is allowed.

кстати у меня ядерный поптоп accel-pptpd версия 0.8.5

Share this post


Link to post
Share on other sites
yKpon, было бы интересно посмотреть на те же самые логи, но с включенными опциями debug в конфиге purestg2 старгейзера и в конфиге pppd. Обе опции без аргументов.

дело за малым, изобрести машину времени :)

Share this post


Link to post
Share on other sites

Alexey Osipov, что было до 00:02:00 и после 00:00 я выше привёл в логах (просто постороннее убрал)

Я имел ввиду логи старгейзера, а не pppd/pptp.

Самые ранние логи старгейзера, которые я увидел, начинаются с:

2011-04-01 00:03:28 -- purestg2: User qwest (socket=15) is disconnected.

А хочется увидеть, что было в 00:02:00 и рядом. Или там вообще ничего не было связанного с purestg2?

 

yKpon, было бы интересно посмотреть на те же самые логи, но с включенными опциями debug в конфиге purestg2 старгейзера и в конфиге pppd. Обе опции без аргументов.

дело за малым, изобрести машину времени :)

Ну, в этом месяце планируется плановая перезагрузка старгейзера? Есть смысл воткнуть дебаг в обоих местах, чтобы при переходе на следующий месяц был отладочный вывод, если ситуация повторится.

Share this post


Link to post
Share on other sites

Create an account or sign in to comment

You need to be a member in order to leave a comment

Create an account

Sign up for a new account in our community. It's easy!

Register a new account

Sign in

Already have an account? Sign in here.

Sign In Now

  • Recently Browsing   0 members

    No registered users viewing this page.

×