Alexey Osipov 38 Posted 2011-02-23 15:46:16 Author Share Posted 2011-02-23 15:46:16 Новая опция в плагине для pppd - latedisconnect. Управляет тем, когда плагин pppd пошлёт запрос старгейзеру на отключение юзверя. Если опция НЕ задана, то запрос на отключение будет послан ДО запуска скриптов auth-down и ip-down; иначе - после их выполнения. Поторопился с этой опцией. Тесты показали, что оба этих скрипта запускаются асинхронно по отношению к pppd, то есть нельзя гарантировать, что они будут закончены или начаты к моменту отключения пользователя в старгейзере. Поэтому эту опцию выкинул. Вместо неё появилась другая опция - predownscript <путь>. Собственно, выполняет указанный аргументом скрипт синхронно непосредственно ДО отключения пользователя в старгейзере. Link to post Share on other sites
yKpon 8 Posted 2011-02-24 11:36:11 Share Posted 2011-02-24 11:36:11 интересует такой вопрос, в какой момент отрабатывания ip-up происходит авторизация биллингом и запуск OnConnect? а то столкнулся с проблемой, в скриптах OnConnect и OnDisconnect отрабатываются шейперы по интерфейсам, а они у меня жёстко vpn_$LOGIN через ip-up, и шейперы срабатывают не всегда, делаю офф/он юзеру шейпы включаются Link to post Share on other sites
Alexey Osipov 38 Posted 2011-02-24 14:22:06 Author Share Posted 2011-02-24 14:22:06 интересует такой вопрос, в какой момент отрабатывания 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, но уже после того, как интерфейс окончательно поднят и настроен. Link to post Share on other sites
yKpon 8 Posted 2011-02-28 04:23:13 Share Posted 2011-02-28 04:23:13 следал задержку OnConnect банальным /bin/sleep 1 в начале скрипта, всё встало на свои места Alexey Osipov, жду не дождусь 2.1 Link to post Share on other sites
Alexey Osipov 38 Posted 2011-03-02 19:59:49 Author Share Posted 2011-03-02 19:59:49 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 пошлёт клиенту пакет с сообщением о том, что соединение сейчас будет закрыто. Ну или всё то, что я уже описывал в этой теме. Link to post Share on other sites
yKpon 8 Posted 2011-03-03 10:58:30 Share Posted 2011-03-03 10:58:30 Alexey Osipov, просто огромное спасибо за ваш труд качаю Link to post Share on other sites
yKpon 8 Posted 2011-03-03 11:51:54 Share Posted 2011-03-03 11:51:54 checking for users.h... no configure: error: headers not found or not useable хотя собираю ./configure --with-stg-headers=/include и там он есть Link to post Share on other sites
Alexey Osipov 38 Posted 2011-03-03 13:42:49 Author Share Posted 2011-03-03 13:42:49 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 проходит на ура. Рекомендуется указывать полный путь к папке с заголовками. И... покажи побольше лога конфигурации. Link to post Share on other sites
yKpon 8 Posted 2011-03-04 15:07:01 Share Posted 2011-03-04 15:07:01 (edited) собрал, поставил, посмотрим как покажет себя в бою оффтоп: сегодня поднял таки accel-pptpd вместно стандартного pptpd, ресурсов потребляет в разы меньше, purestg2 так же отлично работает с ним Edited 2011-03-08 07:12:55 by yKpon Link to post Share on other sites
keshaLG 5 Posted 2011-03-18 12:07:13 Share Posted 2011-03-18 12:07:13 ....Получился этакий себе мини-RADIUS. .... Предложения и пожелания как всегда принимаются. Простите, а зачем изобретать "очередной велосипед"? Не проще допилить уже существующий модуль к стандартному радиусу? А все частный задачи (типа с номером интрефейса) делать внешними скриптами при коннекте клиента. Link to post Share on other sites
madf 279 Posted 2011-03-18 13:36:32 Share Posted 2011-03-18 13:36:32 ....Получился этакий себе мини-RADIUS. .... Предложения и пожелания как всегда принимаются. Простите, а зачем изобретать "очередной велосипед"? Не проще допилить уже существующий модуль к стандартному радиусу? А все частный задачи (типа с номером интрефейса) делать внешними скриптами при коннекте клиента. Связка stg-pptpd позволяет намного больше чем связка stg-freeradius-pptpd. Двухсторонний обмен, все-таки. Link to post Share on other sites
yKpon 8 Posted 2011-03-22 15:21:21 Share Posted 2011-03-22 15:21:21 и так, убитая вручную клиентская pppd сессия оставляет за собой Online в биллинге и не даёт подключиться выдавая 691 ошибку Link to post Share on other sites
Alexey Osipov 38 Posted 2011-03-29 09:41:03 Author Share Posted 2011-03-29 09:41:03 и так, убитая вручную клиентская pppd сессия оставляет за собой Online в биллинге и не даёт подключиться выдавая 691 ошибку Проверил. После kill <pid процесса pppd> Online нормально сбрасывается. Или под "убитая вручную клиентская pppd сессия" имелось ввиду что-то другое? Link to post Share on other sites
yKpon 8 Posted 2011-03-29 10:17:48 Share Posted 2011-03-29 10:17:48 kill <pid процесса pppd> Online нормально сбрасывается. Или под "убитая вручную клиентская pppd сессия" имелось ввиду что-то другое? да, именно так, а сколько таймаут? Link to post Share on other sites
Alexey Osipov 38 Posted 2011-03-29 13:36:12 Author Share Posted 2011-03-29 13:36:12 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. Link to post Share on other sites
yKpon 8 Posted 2011-04-01 10:20:49 Share Posted 2011-04-01 10:20:49 да действительно, всё отрабатввается корректно, но! наступил новый месяц, у абонента тариф 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 висит онлайн до сих пор Link to post Share on other sites
Alexey Osipov 38 Posted 2011-04-01 11:13:22 Author Share Posted 2011-04-01 11:13:22 наступил новый месяц, у абонента тариф 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 долго не приходило никаких весточек. Link to post Share on other sites
yKpon 8 Posted 2011-04-01 11:33:55 Share Posted 2011-04-01 11:33:55 Крайне любопытно. Теоретически мог повлиять 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 это логин из биллинга Link to post Share on other sites
Alexey Osipov 38 Posted 2011-04-01 12:57:15 Author Share Posted 2011-04-01 12:57: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 Покажи чуть выше, где pptp[9075] начал отключаться. сбросить не получается, делаю disable офф/он всё равно онлайн Странно. Конфигуратор должен показывать, что пользователь стал оффлайн, пока включен disabled. Независимо от того, авторизован он или нет. Надо кстати уточнить у madf: что означает Online в конфигураторе: что пользователь авторизован или что пользователь подключен? А в логах старгейзера при этом что? Хотя да, это не поможет. На данный момент плагин проверят состояние пользователя только по приходу события PING от pppd. А раз pppd у нас упал, то и проверки disable не будет. случайно никак не связано с тем что имена интерфейсов принудительно переименовываются с рррХ на vpn_LOGIN - где LOGIN это логин из биллинга Не должно. Link to post Share on other sites
yKpon 8 Posted 2011-04-01 14:00:21 Share Posted 2011-04-01 14:00:21 до 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 Link to post Share on other sites
madf 279 Posted 2011-04-01 15:37:40 Share Posted 2011-04-01 15:37:40 ... сбросить не получается, делаю disable офф/он всё равно онлайн Странно. Конфигуратор должен показывать, что пользователь стал оффлайн, пока включен disabled. Независимо от того, авторизован он или нет. Надо кстати уточнить у madf: что означает Online в конфигураторе: что пользователь авторизован или что пользователь подключен? ... Подключен. Странно, похоже на глюк с "залипанием" авторизации. Link to post Share on other sites
Alexey Osipov 38 Posted 2011-04-01 17:52:41 Author Share Posted 2011-04-01 17:52:41 Вообще интересные вещи происходят у товарища 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. Обе опции без аргументов. Link to post Share on other sites
yKpon 8 Posted 2011-04-02 06:50:54 Share Posted 2011-04-02 06:50:54 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 Link to post Share on other sites
yKpon 8 Posted 2011-04-02 06:58:21 Share Posted 2011-04-02 06:58:21 yKpon, было бы интересно посмотреть на те же самые логи, но с включенными опциями debug в конфиге purestg2 старгейзера и в конфиге pppd. Обе опции без аргументов. дело за малым, изобрести машину времени Link to post Share on other sites
Alexey Osipov 38 Posted 2011-04-02 08:53:46 Author Share Posted 2011-04-02 08:53:46 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. Обе опции без аргументов. дело за малым, изобрести машину времени Ну, в этом месяце планируется плановая перезагрузка старгейзера? Есть смысл воткнуть дебаг в обоих местах, чтобы при переходе на следующий месяц был отладочный вывод, если ситуация повторится. Link to post Share on other sites
Recommended Posts
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 accountSign in
Already have an account? Sign in here.
Sign In Now