purestg2

[Alexey Osipov 38]

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

 

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

 

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

[yKpon 8]

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

 

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

[Alexey Osipov 38]

интересует такой вопрос, в какой момент отрабатывания 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, но уже после того, как интерфейс окончательно поднят и настроен.

[yKpon 8]

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

 

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

[Alexey Osipov 38]

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 пошлёт клиенту пакет с сообщением о том, что соединение сейчас будет закрыто.

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

[yKpon 8]

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

[yKpon 8]

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

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

[Alexey Osipov 38]

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

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

 

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

 

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

[yKpon 8]

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

 

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

Відредаговано 2011-03-08 07:12:55 yKpon

[keshaLG 5]

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

....

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

 

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

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

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

[madf 279]

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

....

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

 

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

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

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

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

[yKpon 8]

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

[Alexey Osipov 38]

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

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

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

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

[yKpon 8]

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

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

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

[Alexey Osipov 38]

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.

[yKpon 8]

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

 

наступил новый месяц, у абонента тариф 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 висит онлайн до сих пор

[Alexey Osipov 38]

наступил новый месяц, у абонента тариф 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 долго не приходило никаких весточек.

[yKpon 8]

Крайне любопытно. Теоретически мог повлиять 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 это логин из биллинга

[Alexey Osipov 38]

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 это логин из биллинга

Не должно.

[yKpon 8]

до 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

[madf 279]

...

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

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

 

...

Подключен.

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

[Alexey Osipov 38]

Вообще интересные вещи происходят у товарища 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. Обе опции без аргументов.

[yKpon 8]

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

[yKpon 8]

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

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

[Alexey Osipov 38]

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. Обе опции без аргументов.

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

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