faq обучение настройка
Текущее время: Чт июл 17, 2025 23:06

Часовой пояс: UTC + 3 часа




Начать новую тему Ответить на тему  [ Сообщений: 3 ] 
Автор Сообщение
СообщениеДобавлено: Пт май 28, 2010 22:42 
Не в сети

Зарегистрирован: Вт авг 11, 2009 01:32
Сообщений: 10
Недавно появилась странная проблема — при рассинхронизации и последующей повторной синхронизации модем не поднимает PPP-сессию. Вот кусок лога:

Код:
Jan 1 10:31:15   user   crit   kernel: ADSL link down
Jan 1 10:31:15   daemon crit   pppd[242]: Clear IP addresses. Connection DOWN.
Jan 1 10:31:15   daemon crit   pppd[242]: Clear IP addresses. PPP connection DOWN.
Jan 1 10:31:16   user   crit   kernel: ADSL G.994 training
Jan 1 10:31:31   user   crit   kernel: ADSL G.992 started
Jan 1 10:31:35   user   crit   kernel: ADSL G.992 channel analysis
Jan 1 10:31:41   user   crit   kernel: ADSL G.992 message exchange
Jan 1 10:31:42   user   crit   kernel: ADSL link down
Jan 1 10:31:43   user   crit   kernel: ADSL G.994 training
Jan 1 10:31:53   user   warn   dnsprobe[542]: dns query failed
Jan 1 10:31:55   user   warn   dnsprobe[542]: dns query failed
Jan 1 10:31:57   user   warn   dnsprobe[542]: dns query failed
Jan 1 10:31:57   user   notice dnsprobe[542]: Primary DNS server Is Down... Switching To Secondary DNS server
Jan 1 10:31:58   user   crit   kernel: ADSL G.992 started
Jan 1 10:32:02   user   crit   kernel: ADSL G.992 channel analysis
Jan 1 10:32:08   user   crit   kernel: ADSL G.992 message exchange
Jan 1 10:32:09   user   crit   kernel: ADSL link down
Jan 1 10:32:09   user   crit   kernel: ADSL link up, interleaved, us=2410, ds=22850
Jan 1 10:32:12   daemon notice pppd[242]: PPP: Start to connect ...
Jan 1 10:32:13   daemon info   pppd[242]: Exit.


А вот как выглядит нормальный процесс корректного поднятия сессии после пересинхронизации:

Код:
Jan 1 00:00:24   user   crit   kernel: ADSL G.992 started
Jan 1 00:00:28   user   crit   kernel: ADSL G.992 channel analysis
Jan 1 00:00:34   user   crit   kernel: ADSL G.992 message exchange
Jan 1 00:00:35   user   crit   kernel: ADSL link down
Jan 1 00:00:35   user   crit   kernel: ADSL link up, interleaved, us=2394, ds=23015
Jan 1 00:00:35   user   warn   kernel: ATM Soft SAR: ATM link connected.
Jan 1 00:00:38   daemon notice pppd[242]: PPP: Start to connect ...
Jan 1 00:00:39   daemon crit   pppd[242]: PPP server detected.
Jan 1 00:00:39   daemon crit   pppd[242]: PPP session established.
Jan 1 00:00:39   daemon crit   pppd[242]: PPP LCP UP.
Jan 1 00:00:39   daemon crit   pppd[242]: Received valid IP address from server. Connection UP.


Экспериментировал с различными значениями KeepAlive (0, 1, 5) — не помогает.

D-Link 2500U/BRU/C, S/W ver.: 1.24 RU


Вернуться наверх
 Профиль  
 
СообщениеДобавлено: Чт июн 03, 2010 15:23 
Не в сети
Сотрудник D-LINK
Сотрудник D-LINK

Зарегистрирован: Пн июл 10, 2006 13:40
Сообщений: 5821
Откуда: D-Link, Moscow
Auren писал(а):
Недавно появилась странная проблема — при рассинхронизации и последующей повторной синхронизации модем не поднимает PPP-сессию. Вот кусок лога:

Код:
Jan 1 10:31:15   user   crit   kernel: ADSL link down
Jan 1 10:31:15   daemon crit   pppd[242]: Clear IP addresses. Connection DOWN.
Jan 1 10:31:15   daemon crit   pppd[242]: Clear IP addresses. PPP connection DOWN.
Jan 1 10:31:16   user   crit   kernel: ADSL G.994 training
Jan 1 10:31:31   user   crit   kernel: ADSL G.992 started
Jan 1 10:31:35   user   crit   kernel: ADSL G.992 channel analysis
Jan 1 10:31:41   user   crit   kernel: ADSL G.992 message exchange
Jan 1 10:31:42   user   crit   kernel: ADSL link down
Jan 1 10:31:43   user   crit   kernel: ADSL G.994 training
Jan 1 10:31:53   user   warn   dnsprobe[542]: dns query failed
Jan 1 10:31:55   user   warn   dnsprobe[542]: dns query failed
Jan 1 10:31:57   user   warn   dnsprobe[542]: dns query failed
Jan 1 10:31:57   user   notice dnsprobe[542]: Primary DNS server Is Down... Switching To Secondary DNS server
Jan 1 10:31:58   user   crit   kernel: ADSL G.992 started
Jan 1 10:32:02   user   crit   kernel: ADSL G.992 channel analysis
Jan 1 10:32:08   user   crit   kernel: ADSL G.992 message exchange
Jan 1 10:32:09   user   crit   kernel: ADSL link down
Jan 1 10:32:09   user   crit   kernel: ADSL link up, interleaved, us=2410, ds=22850
Jan 1 10:32:12   daemon notice pppd[242]: PPP: Start to connect ...
Jan 1 10:32:13   daemon info   pppd[242]: Exit.


А вот как выглядит нормальный процесс корректного поднятия сессии после пересинхронизации:

Код:
Jan 1 00:00:24   user   crit   kernel: ADSL G.992 started
Jan 1 00:00:28   user   crit   kernel: ADSL G.992 channel analysis
Jan 1 00:00:34   user   crit   kernel: ADSL G.992 message exchange
Jan 1 00:00:35   user   crit   kernel: ADSL link down
Jan 1 00:00:35   user   crit   kernel: ADSL link up, interleaved, us=2394, ds=23015
Jan 1 00:00:35   user   warn   kernel: ATM Soft SAR: ATM link connected.
Jan 1 00:00:38   daemon notice pppd[242]: PPP: Start to connect ...
Jan 1 00:00:39   daemon crit   pppd[242]: PPP server detected.
Jan 1 00:00:39   daemon crit   pppd[242]: PPP session established.
Jan 1 00:00:39   daemon crit   pppd[242]: PPP LCP UP.
Jan 1 00:00:39   daemon crit   pppd[242]: Received valid IP address from server. Connection UP.


Экспериментировал с различными значениями KeepAlive (0, 1, 5) — не помогает.

D-Link 2500U/BRU/C, S/W ver.: 1.24 RU

Не удается воспроизвести проблему. После каждого разрыва ADSL-линии модем начинает новую PPP сессию.

    Jan 1 00:00:38 daemon notice pppd[244]: PPP: Start to connect ...
    Jan 1 00:00:40 daemon crit pppd[244]: PPP server detected.
    Jan 1 00:00:40 daemon crit pppd[244]: PPP session established.
    Jan 1 00:00:40 daemon crit pppd[244]: PPP LCP UP.
    Jan 1 00:00:41 daemon crit pppd[244]: Received valid IP address from server. Connection UP.
    Jan 1 00:00:41 user debug syslog: route add default gw 10.1.1.1 2>/dev/null
    Jan 1 00:00:41 user debug syslog: iptables -A FORWARD -o ppp_0_0_100_1 -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu
    Jan 1 00:00:41 user debug syslog: iptables -A FORWARD -i ppp_0_0_100_1 -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu
    Jan 1 00:00:42 user debug syslog: echo > /proc/net/ip_conntrack
    Jan 1 00:00:42 user debug syslog: echo "1000" > /proc/sys/net/ipv4/ip_conntrack_max
    Jan 1 00:00:42 user debug syslog: iptables -t nat -D PREROUTING -i br0 -d 192.168.1.1 -p udp --dport 53 -j DNAT --to 128.9.0.107 2>/dev/null
    Jan 1 00:00:42 user debug syslog: iptables -t nat -D POSTROUTING -o ppp_0_0_100_1 -s 192.168.1.0/255.255.255.0 -j MASQUERADE 2>/dev/null
    Jan 1 00:00:42 user debug syslog: iptables -t nat -A POSTROUTING -o ppp_0_0_100_1 -s 192.168.1.0/255.255.255.0 -j MASQUERADE
    Jan 1 00:00:42 user debug syslog: iptables -t nat -D PREROUTING -i br0 -d 192.168.1.1 -p udp --dport 53 -j DNAT --to 8.8.8.8 2>/dev/null
    Jan 1 00:00:42 user debug syslog: iptables -t nat -A PREROUTING -i br0 -d 192.168.1.1 -p udp --dport 53 -j DNAT --to 8.8.8.8
    Jan 1 00:00:42 user debug syslog: /bin/dnsprobe &
    Jan 1 00:00:42 user notice dnsprobe[542]: dnsprobe started!
    Jan 1 00:00:42 user warn dnsprobe[542]: No Secondary DNS server found
    Jan 1 00:00:43 user debug syslog: iptables -I INPUT 1 -j ACCEPT -i ppp_0_0_100_1 -p tcp --dport 30005
    Jan 1 00:00:43 user debug syslog: iptables -I FORWARD 1 -j ACCEPT -i ppp_0_0_100_1 -p tcp --dport 30005
    Jan 1 00:00:43 user debug syslog: iptables -I INPUT 1 -j ACCEPT -i ppp_0_0_100_1 -p udp -s 192.168.1.1/24 --dport 30006
    Jan 1 00:00:43 user debug syslog: iptables -I FORWARD 1 -j ACCEPT -i ppp_0_0_100_1 -p udp -s 192.168.1.1/24 --dport 30006
    Jan 1 00:00:44 user debug syslog: ifconfig ppp_0_0_100_1 mtu 1492
    Jan 1 00:00:47 daemon err user: tr69c: Unable to retrieve attributes in scratch PAD
    Jan 1 00:00:47 daemon err user: Stored Parameter Attribute data is corrupt or missing
    Jan 1 00:11:16 user crit kernel: ADSL link down
    Jan 1 00:11:18 user crit kernel: ADSL G.994 training
    Jan 1 00:11:20 daemon crit pppd[244]: Clear IP addresses. Connection DOWN.
    Jan 1 00:11:20 daemon crit pppd[244]: Clear IP addresses. PPP connection DOWN.
    Jan 1 00:11:32 user crit kernel: ADSL G.992 started
    Jan 1 00:11:37 user crit kernel: ADSL G.992 channel analysis
    Jan 1 00:11:43 user crit kernel: ADSL G.992 message exchange
    Jan 1 00:11:43 user crit kernel: ADSL link up, interleaved, us=1051, ds=23655
    Jan 1 00:11:44 user debug syslog: iptables -t nat -A PREROUTING -i br0 -d 192.168.1.1 -p udp --dport 53 -j DNAT --to 128.9.0.107
    Jan 1 00:11:45 daemon notice pppd[244]: PPP: Start to connect ...
    Jan 1 00:11:46 daemon crit pppd[244]: PPP server detected.
    Jan 1 00:11:46 daemon crit pppd[244]: PPP session established.
    Jan 1 00:11:47 daemon crit pppd[244]: PPP LCP UP.

    Jan 1 00:11:47 daemon crit pppd[244]: Received valid IP address from server. Connection UP.
    Jan 1 00:11:50 user debug syslog: route add default gw 10.1.1.1 2>/dev/null
    Jan 1 00:11:50 user debug syslog: iptables -A FORWARD -o ppp_0_0_100_1 -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu
    Jan 1 00:11:50 user debug syslog: iptables -A FORWARD -i ppp_0_0_100_1 -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu
    Jan 1 00:11:51 user debug syslog: echo > /proc/net/ip_conntrack
    Jan 1 00:11:51 user debug syslog: echo "1000" > /proc/sys/net/ipv4/ip_conntrack_max
    Jan 1 00:11:51 user debug syslog: iptables -t nat -D PREROUTING -i br0 -d 192.168.1.1 -p udp --dport 53 -j DNAT --to 128.9.0.107 2>/dev/null
    Jan 1 00:11:51 user debug syslog: iptables -t nat -D POSTROUTING -o ppp_0_0_100_1 -s 192.168.1.0/255.255.255.0 -j MASQUERADE 2>/dev/null
    Jan 1 00:11:51 user debug syslog: iptables -t nat -A POSTROUTING -o ppp_0_0_100_1 -s 192.168.1.0/255.255.255.0 -j MASQUERADE
    Jan 1 00:11:51 user debug syslog: iptables -t nat -D PREROUTING -i br0 -d 192.168.1.1 -p udp --dport 53 -j DNAT --to 8.8.8.8 2>/dev/null
    Jan 1 00:11:51 user debug syslog: iptables -t nat -A PREROUTING -i br0 -d 192.168.1.1 -p udp --dport 53 -j DNAT --to 8.8.8.8
    Jan 1 00:11:51 user debug syslog: /bin/dnsprobe &
    Jan 1 00:11:51 user notice dnsprobe[1287]: dnsprobe started!
    Jan 1 00:11:51 user warn dnsprobe[1287]: No Secondary DNS server found
    Jan 1 00:11:51 user debug syslog: iptables -I INPUT 1 -j ACCEPT -i ppp_0_0_100_1 -p tcp --dport 30005
    Jan 1 00:11:51 user debug syslog: iptables -I FORWARD 1 -j ACCEPT -i ppp_0_0_100_1 -p tcp --dport 30005
    Jan 1 00:11:52 user debug syslog: iptables -I INPUT 1 -j ACCEPT -i ppp_0_0_100_1 -p udp -s 192.168.1.1/24 --dport 30006
    Jan 1 00:11:52 user debug syslog: iptables -I FORWARD 1 -j ACCEPT -i ppp_0_0_100_1 -p udp -s 192.168.1.1/24 --dport 30006
    Jan 1 00:11:54 user debug syslog: ifconfig ppp_0_0_100_1 mtu 1492

Прошивка RU_1.24 (Apr.19,2010).
Есть ли возможность получить логи с PPPoE-сервера?


Вернуться наверх
 Профиль  
 
 Заголовок сообщения:
СообщениеДобавлено: Пт июн 04, 2010 11:32 
Не в сети

Зарегистрирован: Вт авг 11, 2009 01:32
Сообщений: 10
Логи у провайдера я вряд ли смогу получить, но появилось еще одно подозрение - pppd не поднимает сессию, если шлюз, на который попадает модем после пересинхронизации, отличается от предыдущего. Постараюсь уточнить, действительно ли это так.

А в это время (два разных шлюза подряд "выпадают" очень редко) не могли бы вы у себя в лабораторных условиях проверить такой момент? Ибо в вашем случае gateway выдавался один и тот же. А надо бы, чтобы в вашем случае на этапе Jan 1 00:11:50 user debug syslog: route add default gw это был бы не 10.1.1.1, а, к примеру, 10.1.1.2.


Вернуться наверх
 Профиль  
 
Показать сообщения за:  Сортировать по:  
Начать новую тему Ответить на тему  [ Сообщений: 3 ] 

Часовой пояс: UTC + 3 часа


Кто сейчас на форуме

Сейчас этот форум просматривают: нет зарегистрированных пользователей и гости: 23


Вы не можете начинать темы
Вы не можете отвечать на сообщения
Вы не можете редактировать свои сообщения
Вы не можете удалять свои сообщения
Вы не можете добавлять вложения

Найти:
Перейти:  
Создано на основе phpBB® Forum Software © phpBB Group
Русская поддержка phpBB