pptp - забивание syslog (lost or reordered)

[mihmih]

Сервер, подключение к интернету через PPTP VPN
Все нормально подключается и работает, но /var/log/syslog забивается записями типа:

(Нажмите, чтобы показать/скрыть)

Apr 23 08:47:29 ubuntu pptp[19629]: anon log[decaps_gre:pptp_gre.c:414]: buffering packet 448417 (expecting 448416, lost or reordered)
Apr 23 08:47:29 ubuntu pptp[19629]: anon log[decaps_gre:pptp_gre.c:414]: buffering packet 448418 (expecting 448416, lost or reordered)
Apr 23 08:47:29 ubuntu pptp[19629]: anon log[decaps_gre:pptp_gre.c:414]: buffering packet 448419 (expecting 448416, lost or reordered)
Apr 23 08:47:33 ubuntu pptp[19629]: anon log[decaps_gre:pptp_gre.c:414]: buffering packet 448424 (expecting 448423, lost or reordered)
Apr 23 08:47:33 ubuntu pptp[19629]: anon log[decaps_gre:pptp_gre.c:414]: buffering packet 448425 (expecting 448423, lost or reordered)
Apr 23 08:47:33 ubuntu pptp[19629]: anon log[decaps_gre:pptp_gre.c:414]: buffering packet 448426 (expecting 448423, lost or reordered)
Apr 23 08:47:33 ubuntu pptp[19629]: anon log[decaps_gre:pptp_gre.c:414]: buffering packet 448427 (expecting 448423, lost or reordered)
Apr 23 08:47:33 ubuntu pptp[19629]: anon log[decaps_gre:pptp_gre.c:414]: buffering packet 448428 (expecting 448423, lost or reordered)
Apr 23 08:47:33 ubuntu pptp[19629]: anon log[decaps_gre:pptp_gre.c:414]: buffering packet 448429 (expecting 448423, lost or reordered)
Apr 23 08:47:34 ubuntu pptp[19629]: anon log[decaps_gre:pptp_gre.c:414]: buffering packet 448432 (expecting 448430, lost or reordered)
Apr 23 08:47:34 ubuntu pptp[19629]: anon log[decaps_gre:pptp_gre.c:414]: buffering packet 448433 (expecting 448430, lost or reordered)
Apr 23 08:47:34 ubuntu pptp[19629]: anon log[decaps_gre:pptp_gre.c:414]: buffering packet 448434 (expecting 448430, lost or reordered)
Apr 23 08:47:34 ubuntu pptp[19629]: anon log[decaps_gre:pptp_gre.c:414]: buffering packet 448435 (expecting 448430, lost or reordered)

несмотря на то что в файле /etc/ppp/peers/atk есть запись --loglevel 0

/etc/ppp/peers/atk

(Нажмите, чтобы показать/скрыть)

pty "pptp 62.192.255.235 --nolaunchpppd --loglevel 0"
user myusername
password "mypassword"
remotename 62.192.255.235
+chap
unit 0
lock
noauth
nobsdcomp
nodeflate
defaultroute
replacedefaultroute
persist
maxfail 0
lcp-echo-interval 30
lcp-echo-failure 4
ipparam vpn
mtu 1372
mss 1372

читал,что это возможно происходит, если mtu eth0 такой, же как и у ppp0 - но при подключении (несмотря на настройку в /etc/ppp/peers/atk и даже в /etc/network/interfaces:

(Нажмите, чтобы показать/скрыть)

auto ppp0
iface ppp0 inet ppp
provider atk
mtu size 1372
pre-up ip link set eth0 up

mtu все равно 1500 (!) - провайдер  принудительно его таким выставляет?

Вопрос:
1. Как все-таки сделать mtu ppp0 1372
2. Откуда идут потери пакетов (вина провайдера или mtu)?
3. Как принудительно отключить "засирание" syslog клиентом pptp?

[Sam Stone]

Имхо вина и прова и чего-то в убунте. У меня с такой проблемой ночью лог засирался гораздо меньше. Чтоб не гадило делается затычка в виде скрипта pptp вместо аналогичного бинарника, куда доп.опцией прописывается loglevel 0. Сам бинарник при этом переименовывается.
Обновления все стоят? у меня примерно с месяц-полтора как-то все само образумилось. Точнее сообщения о потере есть, но они не тоннами сыпятся.

Имхо костыль, но если не шибко горит, то можно этим воспользоваться.

UPD
примерно такая дрянь:

Код: [Выделить]

cd /usr/sbin && mv pptp pptp0 && echo -e \#\!\/bin\/bash\ > pptp && echo \/usr\/sbin\/pptp0\ \$*\ --loglevel\ 0 >> pptp && chmod +x pptp



[mihmih]

loglevel стоит 0 - не помогает
придется, получается, патчить исходники:http://www.opennet.ru/openforum/vsluhforumID1/78553.html
или настраивать rsyslog на /dev/null
но вот почему mtu-то не меняется?

[Mam(O)n]

pty "pptp 62.192.255.235 --nobuffer --nolaunchpppd --loglevel 0"

уже пробовал?

[mihmih]

попробовал:

pty "pptp 62.192.255.235 --nolaunchpppd --timeout 10 --nobuffer --loglevel 0"

-не помогает.
После поднятия ppp0 пробовал:
ifconfig ppp0 mtu 1300
и ifconfig txqueuelen 100
тоже не помогает
кстати, смущает, что у интерфейса ppp0 state UNKNOWN - это почему может быть так?

pptp version 1.7.2, последняя, судя по http://sourceforge.net/projects/pptpclient/files/
кто-нибудь патчил исходники? подскажите процесс?

[Mam(O)n]

Конфиг тот правишь? Каким образом туннель поднимаешь?

[mihmih]

Конфиг правлю, вот /etc/ppp/peers/atk

(Нажмите, чтобы показать/скрыть)

pty "pptp 62.192.255.235 --nolaunchpppd --timeout 10 --nobuffer --loglevel 0"
user mihmih
password "***"
remotename 72.172.255.235
+chap
timeout 60
unit 0
lock
noauth
nobsdcomp
nodeflate
nobuffer
defaultroute
replacedefaultroute
persist
maxfail 0
lcp-echo-interval 30
lcp-echo-failure 10
ipparam vpn
mtu 1372
mss 1372

пока решил проблему прописыванием в /etc/rsyslog.d/50-default.conf

Код: [Выделить]

:programname, isequal, "pptp"    ~
завтра заменю pptp скомпиленным из исходников.

[Mam(O)n]

Каким образом туннель поднимаешь?

[mihmih]

Туннель поднимаю в /etc/network/interfaces

(Нажмите, чтобы показать/скрыть)

auto ppp0
iface ppp0 inet ppp
provider atk
mtu size 1400
pre-up ip link set eth0 up

А проблема была в файле: /etc/ppp/peers/atk
помимо первой строки pty "pptp 62.192.255.235 --nolaunchpppd --timeout 10 --nobuffer --loglevel 0"
были строчки:

Код: [Выделить]

timeout 10
nobuffer
loglevel 0так вот при запуске pptp -debug он (pptp) ругался, а при запуске через /etc/init.d/network restart
НЕТ, и запускался pptp с параметрами по-умолчанию (без nobuffer и loglevel)
При этом в логах НИЧЕГО не говорилось... Бред какой-то...

[Protopopulus]

(Нажмите, чтобы показать/скрыть)

Код: [Выделить]

Jul 25 02:44:26 sofocl pptp[20106]: anon log[logecho:pptp_ctrl.c:677]: Echo Request received.
Jul 25 02:44:26 sofocl pptp[20106]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 6 'Echo-Reply'
Jul 25 02:44:29 sofocl pppd[19999]: Serial connection established.
Jul 25 02:44:29 sofocl pppd[19999]: Using interface ppp1
Jul 25 02:44:29 sofocl pppd[19999]: Connect: ppp1 <--> /dev/pts/3
Jul 25 02:44:29 sofocl pptp[21616]: anon log[main:pptp.c:314]: The synchronous pptp option is NOT activated
Jul 25 02:44:29 sofocl pptp[21625]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 1 'Start-Control-Connection-Request'
Jul 25 02:44:29 sofocl pptp[21625]: anon log[ctrlp_disp:pptp_ctrl.c:739]: Received Start Control Connection Reply
Jul 25 02:44:29 sofocl pptp[21625]: anon log[ctrlp_disp:pptp_ctrl.c:773]: Client connection established.
Jul 25 02:44:30 sofocl pptp[21625]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 7 'Outgoing-Call-Request'
Jul 25 02:44:30 sofocl pptp[21625]: anon log[ctrlp_disp:pptp_ctrl.c:858]: Received Outgoing Call Reply.
Jul 25 02:44:30 sofocl pptp[21625]: anon log[ctrlp_disp:pptp_ctrl.c:897]: Outgoing call established (call ID 0, peer's call ID 8062).
Jul 25 02:44:30 sofocl pppd[19999]: CHAP authentication failed: Authentication failed
Jul 25 02:44:30 sofocl pppd[19999]: CHAP authentication failed
Jul 25 02:44:30 sofocl pppd[19999]: Connection terminated.
Jul 25 02:44:30 sofocl pptp[21625]: anon log[ctrlp_disp:pptp_ctrl.c:929]: Call disconnect notification received (call id 8062)
Jul 25 02:44:30 sofocl pptp[21625]: anon log[ctrlp_disp:pptp_ctrl.c:788]: Received Stop Control Connection Request.
Jul 25 02:44:30 sofocl pptp[21625]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 4 'Stop-Control-Connection-Reply'
Jul 25 02:44:30 sofocl pptp[21625]: anon log[callmgr_main:pptp_callmgr.c:258]: Closing connection (shutdown)
Jul 25 02:44:30 sofocl pptp[21625]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 12 'Call-Clear-Request'
Jul 25 02:44:30 sofocl pptp[21625]: anon log[call_callback:pptp_callmgr.c:79]: Closing connection (call state)
Jul 25 02:45:00 sofocl pppd[19999]: Serial connection established.
Jul 25 02:45:00 sofocl pppd[19999]: Using interface ppp1
Jul 25 02:45:00 sofocl pppd[19999]: Connect: ppp1 <--> /dev/pts/3
Jul 25 02:45:00 sofocl pptp[21707]: anon log[main:pptp.c:314]: The synchronous pptp option is NOT activated
Jul 25 02:45:00 sofocl pptp[21716]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 1 'Start-Control-Connection-Request'
Jul 25 02:45:00 sofocl pptp[21716]: anon log[ctrlp_disp:pptp_ctrl.c:739]: Received Start Control Connection Reply
Jul 25 02:45:00 sofocl pptp[21716]: anon log[ctrlp_disp:pptp_ctrl.c:773]: Client connection established.
Jul 25 02:45:01 sofocl pptp[21716]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 7 'Outgoing-Call-Request'
Jul 25 02:45:01 sofocl pptp[21716]: anon log[ctrlp_disp:pptp_ctrl.c:858]: Received Outgoing Call Reply.
Jul 25 02:45:01 sofocl pptp[21716]: anon log[ctrlp_disp:pptp_ctrl.c:897]: Outgoing call established (call ID 0, peer's call ID 8144).
Jul 25 02:45:01 sofocl pppd[19999]: CHAP authentication failed: Authentication failed
Jul 25 02:45:01 sofocl pppd[19999]: CHAP authentication failed
Jul 25 02:45:01 sofocl pppd[19999]: Connection terminated.
Jul 25 02:45:01 sofocl pptp[21716]: anon log[ctrlp_disp:pptp_ctrl.c:929]: Call disconnect notification received (call id 8144)
Jul 25 02:45:01 sofocl pptp[21716]: anon log[ctrlp_disp:pptp_ctrl.c:788]: Received Stop Control Connection Request.
Jul 25 02:45:01 sofocl pptp[21716]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 4 'Stop-Control-Connection-Reply'
Jul 25 02:45:01 sofocl pptp[21716]: anon log[callmgr_main:pptp_callmgr.c:258]: Closing connection (shutdown)
Jul 25 02:45:01 sofocl pptp[21716]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 12 'Call-Clear-Request'
Jul 25 02:45:01 sofocl pptp[21716]: anon log[call_callback:pptp_callmgr.c:79]: Closing connection (call state)
Jul 25 02:45:23 sofocl pptp[20106]: anon log[logecho:pptp_ctrl.c:677]: Echo Request received.
Jul 25 02:45:23 sofocl pptp[20106]: anon log[logecho:pptp_ctrl.c:679]: no more Echo Reply/Request packets will be reported.
Jul 25 02:45:32 sofocl pppd[19999]: Serial connection established.
Jul 25 02:45:32 sofocl pppd[19999]: Using interface ppp1
Jul 25 02:45:32 sofocl pppd[19999]: Connect: ppp1 <--> /dev/pts/3
Jul 25 02:45:32 sofocl pptp[21795]: anon log[main:pptp.c:314]: The synchronous pptp option is NOT activated


Никакими способами не могу избавиться от засорения лога. Пробовал --loglevel 0 писать где только можно, хотя он и так указан. Прописывал в /etc/ppp/options.prov nolog или logfile /dev/null. Все равно, огромными пачками сыплет в лог этот вредный pptp напару с pppd. Что делать? Совсем сломал голову

[mihmih]

Так у Вас соединение-то устанавливается? Инет поднимается?
Если да, то настройте в /etc/rsyslog.d/50-default.conf

Код: [Выделить]

:programname, isequal, "pptp"    -/dev/null
&~

Да и черт с ним...

[Protopopulus]

ЧЯДНТ? Добавил строки, перезапустил rsyslog, но pptp вместе с pppd засыпают таки syslog теми же сообщениями.

[mihmih]

Стопроцентно - Вы неправильно вставили строки в конф. файл.
Эти строки должны идти перед остальными.
Киньте сюда под спойлер /etc/rsyslog.conf и /etc/rsyslog.d/*.conf

[Гарри Кашпировский]

Попробуйте уже наконец accel-pptp.

[Protopopulus]

Стопроцентно - Вы неправильно вставили строки в конф. файл.
Эти строки должны идти перед остальными.
Киньте сюда под спойлер /etc/rsyslog.conf и /etc/rsyslog.d/*.conf

Да, действительно, я добавлял в конец файла. Спасибо за подсказку. Теперь все нормуль, ура!