Являюсь пользователем оборудования D-Link уже несколько лет и всем доволен.
Появилась проблема при использовании ADSL модема D-Link 2540U с провайдером ЮТК (г.Краснодар), они меня отправляют в поддержку Д-Линк, хотя я уверен, что проблема у них, и модем не виноват.

По поиску не смог найти обсуждение такой ошибки.

Посмотрите пожалуйста на описанную ситуацию и подскажите

====

С 1го декабря начались проблемы, каждый день при включении модема интернет сразу не работает (линк поднимается без проблем, параметры линии в норме).

Ошибка в консоли модема D-Link "PPP down"

Сотрудники на голосовой поддержке ничем помочь не могут и говорят, что это проблема в модеме. Хорошо, я взял еще один модем для проверки.

Лог сегодняшних мытарств:
- включаю основной модем (D-Link 2540U, H/W ревизия A1, прошивка стоит 2100, в факах именно она рекомендуется для провайдера ЮТК), соединения нет

Вот логи модема (отрезана часть спереди):

Jan 1 00:00:57 daemon crit pppd[287]: PPP server detected.
Jan 1 00:00:59 user crit kernel: eth0 Link DOWN.
Jan 1 00:00:59 user info kernel: br0: port 1(eth0) entering disabled state
Jan 1 00:01:00 daemon notice pppd[287]: PPP: Start to connect ...
Jan 1 00:01:00 daemon crit pppd[287]: PPP server detected.
Jan 1 00:01:03 user crit kernel: eth0 Link UP.
Jan 1 00:01:03 user info kernel: br0: port 1(eth0) entering learning state
Jan 1 00:01:03 user info kernel: br0: topology change detected, propagating
Jan 1 00:01:03 user info kernel: br0: port 1(eth0) entering forwarding state
Jan 1 00:01:03 daemon notice pppd[287]: PPP: Start to connect ...
Jan 1 00:01:04 daemon crit pppd[287]: PPP server detected.
Jan 1 00:01:07 daemon notice pppd[287]: PPP: Start to connect ...
Jan 1 00:01:07 daemon crit pppd[287]: PPP server detected.
Jan 1 00:01:10 daemon notice pppd[287]: PPP: Start to connect ...
Jan 1 00:01:10 daemon crit pppd[287]: PPP session established.
Jan 1 00:01:22 daemon notice pppd[287]: PPP: Start to connect ...
Jan 1 00:01:22 daemon crit pppd[287]: PPP server detected.
Jan 1 00:01:25 daemon notice pppd[287]: PPP: Start to connect ...
Jan 1 00:01:25 daemon crit pppd[287]: PPP server detected.
Jan 1 00:01:28 daemon notice pppd[287]: PPP: Start to connect ...
Jan 1 00:01:28 daemon crit pppd[287]: PPP server detected.
Jan 1 00:01:31 daemon notice pppd[287]: PPP: Start to connect ...
Jan 1 00:01:31 daemon crit pppd[287]: PPP session established.
Jan 1 00:01:32 user crit kernel: eth0 Link DOWN.
Jan 1 00:01:33 user info kernel: br0: port 1(eth0) entering disabled state
Jan 1 00:01:35 user crit kernel: eth0 Link UP.
Jan 1 00:01:35 user info kernel: br0: port 1(eth0) entering learning state
Jan 1 00:01:35 user info kernel: br0: topology change detected, propagating
Jan 1 00:01:35 user info kernel: br0: port 1(eth0) entering forwarding state
Jan 1 00:01:43 daemon notice pppd[287]: PPP: Start to connect ...
Jan 1 00:01:43 daemon crit pppd[287]: PPP server detected.
Jan 1 00:01:46 daemon notice pppd[287]: PPP: Start to connect ...
Jan 1 00:01:46 daemon crit pppd[287]: PPP server detected.
Jan 1 00:01:49 daemon notice pppd[287]: PPP: Start to connect ...
Jan 1 00:01:49 daemon crit pppd[287]: PPP server detected.
Jan 1 00:01:52 daemon notice pppd[287]: PPP: Start to connect ...
Jan 1 00:01:52 daemon crit pppd[287]: PPP server detected.
Jan 1 00:01:55 daemon notice pppd[287]: PPP: Start to connect ...
Jan 1 00:01:55 daemon crit pppd[287]: PPP session established.
Jan 1 00:02:07 daemon notice pppd[287]: PPP: Start to connect ...
Jan 1 00:02:08 daemon crit pppd[287]: PPP server detected.
далее последние строчки идут по кругу

выключил-включил модем, ничего, в логах опять по кругу
Jan 1 00:01:53 daemon crit pppd[281]: PPP server detected.
Jan 1 00:01:56 daemon notice pppd[281]: PPP: Start to connect ...
Jan 1 00:01:56 daemon crit pppd[281]: PPP server detected.

ОК, подключил другой модем (тоже Длинк 2540, но H/W ревизия C1)

вот логи с него (отрезана часть сначала):
1st day 00:03:19 user crit kernel: OAM loopback response not received on PORT/VPI/VCI 0/0/35.
1st day 00:03:19 user crit kernel: OAM loopback response not received on PORT/VPI/VCI 0/0/35.
1st day 00:03:21 daemon notice pppd[260]: PPP: Start to connect ...
1st day 00:03:25 daemon notice pppd[260]: PPP: Start to connect ...

< .. пропущены одинаковые попытки .. >

1st day 00:05:35 daemon crit pppd[260]: PPP server detected.
1st day 00:05:35 daemon crit pppd[260]: PPP session established.
1st day 00:05:47 daemon notice pppd[260]: PPP: Start to connect ...
1st day 00:05:50 daemon notice pppd[260]: PPP: Start to connect ...
1st day 00:05:51 daemon crit pppd[260]: PPP server detected.
1st day 00:05:54 daemon notice pppd[260]: PPP: Start to connect ...
1st day 00:05:55 daemon crit pppd[260]: PPP server detected.
1st day 00:05:58 daemon notice pppd[260]: PPP: Start to connect ...
1st day 00:05:59 daemon crit pppd[260]: PPP server detected.
1st day 00:05:59 daemon crit pppd[260]: PPP session established.
1st day 00:06:06 user debug syslog: ethctl vport query 2>/var/vcfgerr
1st day 00:06:06 user debug syslog: rm /var/vcfgerr
1st day 00:06:07 user crit kernel: OAM loopback response not received on PORT/VPI/VCI 0/0/35.
1st day 00:06:09 user crit kernel: OAM loopback response not received on PORT/VPI/VCI 0/0/35.
1st day 00:06:09 user debug syslog: ping -c 1 -q 192.168.1.1
1st day 00:06:11 daemon notice pppd[260]: PPP: Start to connect ...

и далее всё то же самое по кругу
1st day 00:09:29 daemon notice pppd[260]: PPP: Start to connect ...
1st day 00:09:30 daemon crit pppd[260]: PPP server detected.
1st day 00:09:33 daemon notice pppd[260]: PPP: Start to connect ...
1st day 00:09:34 daemon crit pppd[260]: PPP session established.
1st day 00:09:46 daemon notice pppd[260]: PPP: Start to connect ...

======
Позвонил в тех.поддержку

Параметры линии проверили, всё нормально. На соседних портах пользователи авторизуются. Вывод делает сотрудник - "Значит проблема в модеме, перенастройте в режим бриджа, обновите прошивку, обратитесь в ТП Длинк".
Разговор на этом закончился у нас.

Перенастраиваю в режим моста, пробую - ошибка 651.

Тут же выключаю новый модем и переподключаю старый, с первой попытки соединение , всё нормально. Вышел в интернет и пишу вам.

Прошу помочь.

По логам модем теряет соединение с PPPoE-сервером провайдера.
Попробуйте обновить прошивку модема до 3-06-04-3J00.

ftp://ftp.dlink.ru/pub/ADSL/DSL-2540U/F ... 043J00.zip

Приведите также вывод страницы Status > ADSL.

Основной модем у меня ревизии A1

Board ID: D-4P
Software Version: RU_DSL-2540U_3-06-04-2100.A2pB021c.d19b
Bootloader (CFE) Version: 1.0.37-6.5


Status -> ADSL выдает такое

Mode: ADSL2+
Line Coding: Trellis On
Status: No Defect
Link Power State: L0

Downstream Upstream
SNR Margin (dB): 30.2 15.7
Attenuation (dB): 11.0 8.2
Output Power (dBm): 11.4 0.0
Attainable Rate (Kbps): 23220 1288
Rate (Kbps): 8191 1019
MSGc (number of bytes in overhead channel message): 59 12
B (number of bytes in Mux Data Frame): 254 31
M (number of Mux Data Frames in FEC Data Frame): 1 1
T (Mux Data Frames over sync bytes): 1 4
R (number of check bytes in FEC Data Frame): 0 0
S (ratio of FEC over PMD Data Frame length): 0.9922 0.9961
L (number of bits in PMD Data Frame): 2056 257
D (interleaver depth): 1 1
Delay (msec): 0 0

Super Frames: 761166 761164
Super Frame Errors: 1 0
RS Words: 0 0
RS Correctable Errors: 0 0
RS Uncorrectable Errors: 0 N/A

HEC Errors: 1 0
OCD Errors: 0 0
LCD Errors: 0 0
Total Cells: 237110697 239154
Data Cells: 3898938 2727
Bit Errors: 0 0

Total ES: 1 0
Total SES: 0 0
Total UAS: 14 0


===
Проблем с линком нет, соединение держится стабильно.

Прошивку обновил до 3-06-04-3J00, после обновления прошивки сразу не заработало, выдавало PPP down и в логах те же самые попытки, как я выше показывал.

Потом выключил модем минут на 5, включил - сразу поднялось, ИП шлюза показывается корректно и ИП мне присваивается. НО! Появилась другая проблема с видимостью сети дальше модема, пакеты не шли просто дальше ИП модема. Перепробовал перезагружать модем много раз, так и не удалось добиться работающей конфигурации: или PPP down и не присваивается ИП, или всё присваивается, но не пингуется почему-то (руки вроде не кривые).

Вот кусок логов от попытки, когда мне был присвоен ИП и должен работать интернет, но ничего не пинговалось:

Date/Time Facility Severity Message
Jan 1 00:00:24 user warn kernel: ATM Soft SAR: ATM link connected.
Jan 1 00:00:25 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:00:26 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:00:26 daemon crit pppd[271]: PPP server detected.
Jan 1 00:00:26 daemon crit pppd[271]: PPP session established.
Jan 1 00:00:28 daemon crit pppd[271]: PPP LCP UP.
Jan 1 00:00:29 daemon crit pppd[271]: Received valid IP address from server. Connection UP.
Jan 1 00:00:29 user debug syslog: route add default gw 85.172.0.5 2>/dev/null
Jan 1 00:00:29 user debug syslog: iptables -A FORWARD -o ppp_0_35_1 -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu
Jan 1 00:00:29 user debug syslog: iptables -A FORWARD -i ppp_0_35_1 -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu
Jan 1 00:00:29 user debug syslog: echo > /proc/net/ip_conntrack
Jan 1 00:00:29 user debug syslog: echo "512" > /proc/sys/net/ipv4/ip_conntrack_max
Jan 1 00:00:30 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:30 user debug syslog: iptables -t nat -D POSTROUTING -o ppp_0_35_1 -s 192.168.1.0/255.255.255.0 -j MASQUERADE 2>/dev/null
Jan 1 00:00:30 user debug syslog: iptables -t nat -A POSTROUTING -o ppp_0_35_1 -s 192.168.1.0/255.255.255.0 -j MASQUERADE
Jan 1 00:00:30 user debug syslog: iptables -t nat -D PREROUTING -i br0 -d 192.168.1.1 -p udp --dport 53 -j DNAT --to 83.239.0.202 2>/dev/null
Jan 1 00:00:30 user debug syslog: iptables -t nat -A PREROUTING -i br0 -d 192.168.1.1 -p udp --dport 53 -j DNAT --to 83.239.0.202
Jan 1 00:00:30 user debug syslog: /bin/dnsprobe &
Jan 1 00:00:30 user debug syslog: upnp -L br0 -W ppp_0_35_1 -D
Jan 1 00:00:30 user notice dnsprobe[463]: dnsprobe started!
Jan 1 00:00:30 user debug syslog: iptables -t filter -I OUTPUT -o ppp_0_35_1 -d 239.255.255.250 -j DROP 2>/dev/null
Jan 1 00:00:32 user debug syslog: iptables -D INPUT -i ppp_0_35_1 -j DROP
Jan 1 00:00:32 user debug syslog: iptables -A INPUT -i ppp_0_35_1 -j DROP
Jan 1 00:00:32 user debug syslog: iptables -I INPUT 1 -i ppp_0_35_1 -p udp --dport 5060 -j ACCEPT 2>/dev/null
Jan 1 00:00:32 user debug syslog: iptables -I INPUT 1 -i ppp_0_35_1 -p udp --dport 7070:7079 -j ACCEPT 2>/dev/null
Jan 1 00:00:32 user debug syslog: iptables -t nat -A PREROUTING -i br0 -p udp --dport 5060 -j REDIRECT --to-ports 5060 2>/dev/null
Jan 1 00:00:33 user debug syslog: siproxd --config /var/siproxd/siproxd.conf &
Jan 1 00:00:35 user notice syslog: siproxd.c:192 INFO:siproxd-0.5.10-2882 i686-redhat-linux-gnu starting up
Jan 1 00:00:35 user notice syslog: siproxd.c:218 INFO:daemonized, pid=519
Jan 1 00:00:36 user notice syslog: sock.c:65 INFO:bound to port 5060
Jan 1 00:00:36 user notice syslog: register.c:65 WARNING:registration file not found, starting with empty table
Jan 1 00:00:36 user notice syslog: siproxd.c:265 INFO:siproxd-0.5.10-2882 i686-redhat-linux-gnu started
Jan 1 00:01:02 user warn dnsprobe[463]: dns query failed
Jan 1 00:01:04 user warn dnsprobe[463]: dns query failed
Jan 1 00:01:06 user warn dnsprobe[463]: dns query failed
Jan 1 00:01:06 user notice dnsprobe[463]: Primary DNS server Is Down... Switching To Secondary DNS server
Jan 1 00:01:39 user warn dnsprobe[463]: dns query failed
Jan 1 00:01:41 user warn dnsprobe[463]: dns query failed
Jan 1 00:01:43 user warn dnsprobe[463]: dns query failed
Jan 1 00:02:15 user warn dnsprobe[463]: dns query failed
Jan 1 00:02:17 user warn dnsprobe[463]: dns query failed
Jan 1 00:02:19 user warn dnsprobe[463]: dns query failed
Jan 1 00:02:51 user warn dnsprobe[463]: dns query failed
Jan 1 00:02:53 user warn dnsprobe[463]: dns query failed
Jan 1 00:02:55 user warn dnsprobe[463]: dns query failed
Jan 1 00:03:27 user warn dnsprobe[463]: dns query failed
Jan 1 00:03:29 user warn dnsprobe[463]: dns query failed
Jan 1 00:03:31 user warn dnsprobe[463]: dns query failed
Jan 1 00:03:59 daemon crit pppd[271]: Clear IP addresses. Connection DOWN.
Jan 1 00:03:59 daemon crit pppd[271]: Clear IP addresses. PPP connection DOWN.
Jan 1 00:04:03 user warn dnsprobe[463]: dns query failed
Jan 1 00:04:05 user warn dnsprobe[463]: dns query failed
Jan 1 00:04:07 user warn dnsprobe[463]: dns query failed
Jan 1 00:04:09 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:04:12 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:04:15 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:04:18 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:04:21 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:04:24 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:04:27 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:04:30 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:04:33 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:04:36 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:04:36 daemon crit pppd[271]: PPP server detected.
Jan 1 00:04:39 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:04:39 daemon crit pppd[271]: PPP server detected.
Jan 1 00:04:39 user warn dnsprobe[463]: dns query failed
Jan 1 00:04:41 user warn dnsprobe[463]: dns query failed
Jan 1 00:04:42 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:04:43 user warn dnsprobe[463]: dns query failed
Jan 1 00:04:45 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:04:47 user debug igmp[1256]: iptables -t filter -I FORWARD 1 -i br0 -d 224.0.0.22 -j DROP 2>/dev/null
Jan 1 00:04:47 user debug igmp[1258]: iptables -t filter -D FORWARD -i br0 -d 224.0.0.22 -j ACCEPT 2>/dev/null
Jan 1 00:04:48 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:04:48 daemon crit pppd[271]: PPP server detected.
Jan 1 00:04:51 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:04:54 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:04:57 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:05:00 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:05:01 daemon crit pppd[271]: PPP session established.
Jan 1 00:05:14 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:05:15 user warn dnsprobe[463]: dns query failed
Jan 1 00:05:18 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:05:18 user warn dnsprobe[463]: dns query failed
Jan 1 00:05:20 user warn dnsprobe[463]: dns query failed
Jan 1 00:05:21 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:05:24 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:05:27 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:05:30 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:05:33 daemon notice pppd[271]: PPP: Start to connect ...
Jan 1 00:05:33 daemon crit pppd[271]: PPP session established.

==========


Понес обе ревизии модемов на другую квартиру, где подключен интернет от того же провайдера. Там оба модема отказались работать (те же PPP down), хотя старенький Zyxel 645R работал нормально, сразу соединялся.

Тогда я скачал с сайта провайдера рекомендуемую прошивку для 2540 - 3B00 и прошил её. Модем заработал сразу же, пробовал выключать-включать несколько раз - проблем не было, всё работало.

Принес модем обратно домой, с первой попытки не заработало, выдавало ту же ошибку PPP Down, но с этой прошивкой уже модем не пытался циклично законнектиться, а просто висел и ждал (видимо особенность прошивки).

Тогда я выключил-включил его и заработало. Но боюсь, что если я снова его выключу на ночь, то утром будет та же история.

С прошивкой 3B00 реконнекты каждые 24 часа шли нормально несколько дней. После 3-4 суток при реконнекте сессии:



и дальше не пыталось соединяться, перезагрузил модем:



и дальше снова нет попыток соединения

следующие ребуты не помогают - выдает Authentication failed (это глюки провайдера). Подождал полчаса, пока сессия сбросилась и переконнектился нормально.

===
ВОПРОС К САППОРТУ:
Почему в обоих логах после строчки

не делаются новые попытки подключения до тех пор, пока не выключишь/включишь модем? Это глюк прошивки 3B00?
Насколько я помню, с прошивкой 3J00 была та же беда, когда я пробовал её прошивать.