LinuxForum.Fans
Тема

Падение ppp

Автор: u__________12 9 сообщений 3018 просмотров
#283202

Такая ситуация. Есть роутер, на нём инет с помощью ptp. Там же QUAGA + ospf.

Происходит обрыв связи, (со стороны прова или нет, тоже не ясно, но вроде от провайдера), но восстанавливается всё только после физической перезагрузки сервера.

text
Oct 31 05:43:44 TGGW ospfd[7301]: Packet[DD]: Neighbor 192.168.6.0: Initial DBD from Slave, ignoring.
Oct 31 05:43:44 TGGW ospfd[7301]: Packet[DD]: Neighbor 192.168.6.0 Negotiation done (Master).
Oct 31 05:43:44 TGGW ospfd[7301]: nsm_change_state(192.168.6.0, Exchange -> Full): scheduling new router-LSA origination
Oct 31 06:02:04 TGGW ospfd[7301]: nsm_change_state(192.168.6.0, Full -> Deleted): scheduling new router-LSA origination

как бы всё ок..

text
Oct 31 06:02:04 TGGW ospfd[7301]: Received signal 11 at 1351648924 (si_addr 0xb76fcb10, PC 0xb769e608); aborting...

обрыв детектед

text
Oct 31 06:02:04 TGGW ospfd[7301]: Backtrace for 9 stack frames:
Oct 31 06:02:04 TGGW ospfd[7301]: /usr/lib/libzebra.so.0(zlog_backtrace_sigsafe+0x39) [0xb7658896]
Oct 31 06:02:04 TGGW ospfd[7301]: /usr/lib/libzebra.so.0(zlog_signal+0x250) [0xb7658e26]
Oct 31 06:02:04 TGGW ospfd[7301]: /usr/lib/libzebra.so.0(+0x2e0c6) [0xb76610c6]
Oct 31 06:02:04 TGGW ospfd[7301]: [0xb770440c]
Oct 31 06:02:04 TGGW ospfd[7301]: /usr/lib/libospf.so.0(ospf_nsm_event+0xa4) [0xb769e608]
Oct 31 06:02:04 TGGW ospfd[7301]: /usr/lib/libzebra.so.0(thread_call+0x64) [0xb764e196]
Oct 31 06:02:04 TGGW ospfd[7301]: /usr/lib/quagga/ospfd(main+0x3e1) [0xb7724731]
Oct 31 06:02:04 TGGW ospfd[7301]: /lib/i386-linux-gnu/i686/cmov/libc.so.6(__libc_start_main+0xe6) [0xb74abe46]
Oct 31 06:02:04 TGGW ospfd[7301]: /usr/lib/quagga/ospfd(+0x178d) [0xb772478d]
Oct 31 06:02:04 TGGW zebra[7297]: client 12 disconnected. 24 ospf routes removed from the rib
Oct 31 06:02:04 TGGW watchquagga[7306]: ospfd state -> down : read returned EOF
Oct 31 06:02:45 TGGW asterisk[7447]: NOTICE[7473]: chan_iax2.c:11982 in __iax2_poke_noanswer: Peer 'office' is now UNREACHABLE! Time: 19

И под занавес астериск ругается на недоступность пира (что логично )


Теперь попытка всё это поднять автоматом

text
Oct 31 11:40:46 TGGW pppd[3055]: LCP terminated by peer
Oct 31 11:40:46 TGGW pppd[3055]: Connect time 1440.0 minutes.
Oct 31 11:40:46 TGGW pppd[3055]: Sent 146195787 bytes, received 211046173 bytes.
Oct 31 11:40:49 TGGW pppd[3055]: Connection terminated.
Oct 31 11:40:49 TGGW pppd[3055]: Modem hangup
Oct 31 11:41:09 TGGW pppd[3055]: PPP session is 27683
Oct 31 11:41:09 TGGW pppd[3055]: Connected to 00:27:0d:ad:08:00 via interface eth2
Oct 31 11:41:09 TGGW pppd[3055]: Using interface ppp0
Oct 31 11:41:09 TGGW pppd[3055]: Connect: ppp0 <--> eth2
Oct 31 11:41:09 TGGW pppd[3055]: CHAP authentication succeeded
Oct 31 11:41:09 TGGW pppd[3055]: CHAP authentication succeeded
Oct 31 11:41:09 TGGW pppd[3055]: peer from calling number 00:27:0D:AD:08:00 authorized
Oct 31 11:41:09 TGGW pppd[3055]: local  IP address 212.12.x.x
Oct 31 11:41:09 TGGW pppd[3055]: remote IP address 212.12.y.y

а вот смысл сей записи так и не пойму.. ( я не о переводе, а именно о смысле)

text
Oct 31 11:41:09 TGGW zebra[7297]: warning: PtP interface ppp0 with addr 212.12.x.x/32 needs a peer address

ну и далее

text
Oct 31 11:41:09 TGGW racoon: INFO: 212.12.x.x[500] used for NAT-T
Oct 31 11:41:09 TGGW racoon: INFO: 212.12.x.x[500] used as isakmp port (fd=38)
Oct 31 11:41:09 TGGW racoon: INFO: 212.12.x.x[4500] used for NAT-T
Oct 31 11:41:09 TGGW racoon: INFO: 212.12.x.x[4500] used as isakmp port (fd=39)

Теперь делаем pon prvider

text
Oct 31 13:14:29 TGGW pppd[3055]: Terminating on signal 15
Oct 31 13:14:29 TGGW pppd[3055]: Connect time 93.4 minutes.
Oct 31 13:14:29 TGGW pppd[3055]: Sent 0 bytes, received 100587 bytes.
Oct 31 13:14:29 TGGW pppd[3055]: Connection terminated.
Oct 31 13:14:29 TGGW pppd[3055]: Exit.
Oct 31 13:15:01 TGGW /USR/SBIN/CRON[29972]: (root) CMD (   cat /root/iptables|iptables-restore)
Oct 31 13:15:01 TGGW pppd[29975]: Plugin rp-pppoe.so loaded.
Oct 31 13:15:01 TGGW pppd[29976]: pppd 2.4.5 started by root, uid 0
Oct 31 13:15:01 TGGW pppd[29976]: PPP session is 17564
Oct 31 13:15:01 TGGW pppd[29976]: Connected to 00:27:0d:ad:08:00 via interface eth2
Oct 31 13:15:01 TGGW pppd[29976]: Using interface ppp0
Oct 31 13:15:01 TGGW pppd[29976]: Connect: ppp0 <--> eth2
Oct 31 13:15:01 TGGW pppd[29976]: CHAP authentication succeeded
Oct 31 13:15:01 TGGW pppd[29976]: CHAP authentication succeeded
Oct 31 13:15:01 TGGW pppd[29976]: peer from calling number 00:27:0D:AD:08:00 authorized
Oct 31 13:15:01 TGGW pppd[29976]: local  IP address 212.12.x.x
Oct 31 13:15:01 TGGW pppd[29976]: remote IP address 212.12.y.y
Oct 31 13:15:01 TGGW zebra[7297]: warning: PtP interface ppp0 with addr 212.12.x.x/32 needs a peer address
Oct 31 13:15:01 TGGW racoon: INFO: 212.12.x.x[500] used for NAT-T
Oct 31 13:15:01 TGGW racoon: INFO: 212.12.x.x[500] used as isakmp port (fd=38)
Oct 31 13:15:01 TGGW racoon: INFO: 212.12.x.x[4500] used for NAT-T
Oct 31 13:15:01 TGGW racoon: INFO: 212.12.x.x[4500] used as isakmp port (fd=39)

и ничего...


и спасает только

text
Oct 31 13:16:25 TGGW shutdown[30015]: shutting down for system reboot

/etc/ppp/peers# cat dsl-provider

text
# Minimalistic default options file for DSL/PPPoE connections

#noipdefault nodefaultroute #replacedefaultroute hide-password #lcp-echo-interval 30 #lcp-echo-failure 4 noauth persist #mtu 1450 #persist maxfail 0 holdoff 20 plugin rp-pppoe.so eth2 user "xxxxxxxx"

Если ещё какие логи/конфиги нужны, выложу.

#283218
"Сергей В." написал:
#lcp-echo-interval 30
#lcp-echo-failure 4

может все-таки включить?
у меня

text
    lcp-echo-interval 15
    lcp-echo-failure 3

man pppd

#283232

Да, об этом думал. Но..
1. Так работало (вроде-бы :) )
2. Теперь делаем pon prvider

Oct 31 13:14:29 TGGW pppd[3055]: Terminating on signal 15
Oct 31 13:14:29 TGGW pppd[3055]: Connect time 93.4 minutes.
Oct 31 13:14:29 TGGW pppd[3055]: Sent 0 bytes, received 100587 bytes.
Oct 31 13:14:29 TGGW pppd[3055]: Connection terminated.
Oct 31 13:14:29 TGGW pppd[3055]: Exit.
Oct 31 13:15:01 TGGW /USR/SBIN/CRON[29972]: (root) CMD ( cat /root/iptables|iptables-restore)
Oct 31 13:15:01 TGGW pppd[29975]: Plugin rp-pppoe.so loaded.
Oct 31 13:15:01 TGGW pppd[29976]: pppd 2.4.5 started by root, uid 0
Oct 31 13:15:01 TGGW pppd[29976]: PPP session is 17564
Oct 31 13:15:01 TGGW pppd[29976]: Connected to 00:27:0d:ad:08:00 via interface eth2
Oct 31 13:15:01 TGGW pppd[29976]: Using interface ppp0
Oct 31 13:15:01 TGGW pppd[29976]: Connect: ppp0 <--> eth2
Oct 31 13:15:01 TGGW pppd[29976]: CHAP authentication succeeded
Oct 31 13:15:01 TGGW pppd[29976]: CHAP authentication succeeded
Oct 31 13:15:01 TGGW pppd[29976]: peer from calling number 00:27:0D:AD:08:00 authorized
Oct 31 13:15:01 TGGW pppd[29976]: local IP address 212.12.x.x
Oct 31 13:15:01 TGGW pppd[29976]: remote IP address 212.12.y.y
Oct 31 13:15:01 TGGW zebra[7297]: warning: PtP interface ppp0 with addr 212.12.x.x/32 needs a peer address
Oct 31 13:15:01 TGGW racoon: INFO: 212.12.x.x[500] used for NAT-T
Oct 31 13:15:01 TGGW racoon: INFO: 212.12.x.x[500] used as isakmp port (fd=38)
Oct 31 13:15:01 TGGW racoon: INFO: 212.12.x.x[4500] used for NAT-T
Oct 31 13:15:01 TGGW racoon: INFO: 212.12.x.x[4500] used as isakmp port (fd=39)
и ничего...

ведь НЕ поднимается..


Как мне рассказал предыдущий админ, подниматься пытается когда идёт первый пакет

#283236
OpenNet написал:
Была такая шняга с PPPoE. Полечилось прибиванием peer-ip наса на lo0. Как с PPtP провернуть сейчас не скажу сходу, но посыл, надеюсь, пойдет впрок.

http://www.opennet.ru/openforum/vsluhforumID6/21866.html
Гуглопоиском на фразу PtP interface ppp0 needs a peer address

#283238

Конкретно вопрос в чём. Отчего может быть такая ошибка?

text
zebra[7297]: warning: PtP interface ppp0 with addr 212.12.x.x/32 needs a peer address
#283239
"zenon" написал:
Полечилось прибиванием peer-ip наса на lo0

Не понял

#283268

Пришёл на место сам, всё нормально работает.. буду разбираться дальше..

#283274

Итак. Провёл "следственный эксперимент". Устроил обрыв, потом устранил.. и! после посыла первого пакета, с небольшой задержкой работа сети возобновляется. Далее - сравниваю логи, и вот что вижу.

"Сергей В." написал:
Oct 31 06:02:04 TGGW ospfd[7301]: Backtrace for 9 stack frames:
Oct 31 06:02:04 TGGW ospfd[7301]: /usr/lib/libzebra.so.0(zlog_backtrace_sigsafe+0x39) [0xb7658896]
Oct 31 06:02:04 TGGW ospfd[7301]: /usr/lib/libzebra.so.0(zlog_signal+0x250) [0xb7658e26]
Oct 31 06:02:04 TGGW ospfd[7301]: /usr/lib/libzebra.so.0(+0x2e0c6) [0xb76610c6]
Oct 31 06:02:04 TGGW ospfd[7301]: [0xb770440c]
Oct 31 06:02:04 TGGW ospfd[7301]: /usr/lib/libospf.so.0(ospf_nsm_event+0xa4) [0xb769e608]
Oct 31 06:02:04 TGGW ospfd[7301]: /usr/lib/libzebra.so.0(thread_call+0x64) [0xb764e196]
Oct 31 06:02:04 TGGW ospfd[7301]: /usr/lib/quagga/ospfd(main+0x3e1) [0xb7724731]
Oct 31 06:02:04 TGGW ospfd[7301]: /lib/i386-linux-gnu/i686/cmov/libc.so.6(__libc_start_main+0xe6) [0xb74abe46]
Oct 31 06:02:04 TGGW ospfd[7301]: /usr/lib/quagga/ospfd(+0x178d) [0xb772478d]
Oct 31 06:02:04 TGGW zebra[7297]: client 12 disconnected. 24 ospf routes removed from the rib
Oct 31 06:02:04 TGGW watchquagga[7306]: ospfd state -> down : read returned EOF

Вот ЭТОГО в этот раз нет. Выходит что проблема именно в том, что упал демон ospfd. И именно поэтому подключение не восстанавливалось. вместо ребута сервера можно было ребутнут демон. Но почему он упал??

#284248

up

Куда хоть рыть, знает кто?

Войдите или зарегистрируйтесь, чтобы ответить.