Device Info
BoardID: DSL-2640U
Software Version: RU_1.25
Bootloader (CFE) Version: 1.0.37-12.1
Release Date: Nov.29,2010
Wireless Driver Version: 4.174.64.19.cpe1.0sd
Вернёмся к проблеме... это — сразу после включения:
Код:
Jan 1 00:00:25 syslog emerg BCM96345 started: BusyBox v1.00 (2010.11.29-11:52+0000)
Jan 1 00:00:26 user notice kernel: klogd started: BusyBox v1.00 (2010.11.29-11:52+0000)
Jan 1 00:00:26 user warn kernel: Linux version 2.6.8.1 (zhouyu@localhost.localdomain) (gcc version 3.4.2) #1 Mon Nov 29 19:49:56 CST 2010
Jan 1 00:00:26 user warn kernel: Parallel flash device: name MX29LV320AB, id 0x22a8, size 4096KB
Jan 1 00:00:26 user warn kernel: 96338W2_E7T prom init
Jan 1 00:00:26 user warn kernel: CPU revision is: 00029010
Jan 1 00:00:26 user warn kernel: Determined physical RAM map:
Jan 1 00:00:26 user warn kernel: memory: 00fa0000 @ 00000000 (usable)
Jan 1 00:00:26 user debug kernel: On node 0 totalpages: 4000
Jan 1 00:00:26 user debug kernel: DMA zone: 4000 pages, LIFO batch:1
Jan 1 00:00:26 user debug kernel: Normal zone: 0 pages, LIFO batch:1
Jan 1 00:00:26 user debug kernel: HighMem zone: 0 pages, LIFO batch:1
Jan 1 00:00:26 user warn kernel: Built 1 zonelists
Jan 1 00:00:26 user warn kernel: Kernel command line: root=31:0 ro noinitrd console=ttyS0,115200
Jan 1 00:00:26 user debug syslog: sntp -s 0.ru.pool.ntp.org -s 2.ru.pool.ntp.org -t "Abu Dhabi, Muscat" &
Jan 1 00:00:26 user debug syslog: brctl enableportsnooping 1
Jan 1 00:00:26 user warn kernel: brcm mips: enabling icache and dcache...
Jan 1 00:00:26 user warn kernel: Primary instruction cache 16kB, physically tagged, 2-way, linesize 16 bytes.
Jan 1 00:00:26 user warn kernel: Primary data cache 8kB 2-way, linesize 16 bytes.
Jan 1 00:00:26 user warn kernel: PID hash table entries: 64 (order 6: 512 bytes)
Jan 1 00:00:26 user warn kernel: Using 120.000 MHz high precision timer.
Jan 1 00:00:26 user warn kernel: Dentry cache hash table entries: 4096 (order: 2, 16384 bytes)
Jan 1 00:00:26 user warn kernel: Inode-cache hash table entries: 2048 (order: 1, 8192 bytes)
Jan 1 00:00:26 user info kernel: Memory: 13864k/16000k available (1483k kernel code, 2116k reserved, 220k data, 68k init, 0k highmem)
Jan 1 00:00:26 user warn kernel: KLOB Pool 1 Initialized: 1048576 bytes <0x80e00000 ... 0x80f00000>
Jan 1 00:00:26 user warn kernel: Calibrating delay loop... 239.20 BogoMIPS
Jan 1 00:00:26 user warn kernel: Mount-cache hash table entries: 512 (order: 0, 4096 bytes)
Jan 1 00:00:26 user warn kernel: Checking for 'wait' instruction... unavailable.
Jan 1 00:00:26 user info kernel: NET: Registered protocol family 16
Jan 1 00:00:26 user warn kernel: Total Flash size: 4096K with 71 sectors
Jan 1 00:00:26 user warn kernel: File system address: 0xbfc10100
Jan 1 00:00:26 user warn kernel: Blk# BlkOff Blks MemLen Partition Name
Jan 1 00:00:26 user warn kernel: 0 1408 1 1024 NVRAM
Jan 1 00:00:26 user warn kernel: 69 40960 1 24576 Config 2
Jan 1 00:00:26 user warn kernel: 70 32768 1 8192 Scratch PAD
Jan 1 00:00:26 user warn kernel: 70 40960 1 24576 Config 1
Jan 1 00:00:26 user warn kernel: Can't analyze prologue code at 80181744
Jan 1 00:00:26 user info kernel: Initializing Cryptographic API
Jan 1 00:00:26 user info kernel: PPP generic driver version 2.4.2
Jan 1 00:00:26 user info kernel: PPP Deflate Compression module registered
Jan 1 00:00:26 user info kernel: PPP BSD Compression module registered
Jan 1 00:00:26 user info kernel: MPPE/MPPC encryption/compression module registered
Jan 1 00:00:26 user info kernel: NET: Registered protocol family 24
Jan 1 00:00:26 user warn kernel: Using noop io scheduler
Jan 1 00:00:26 user warn kernel: bcm963xx_mtd driver v1.0
Jan 1 00:00:26 user warn kernel: brcmboard: brcm_board_init entry
Jan 1 00:00:26 user warn kernel: SES: Button Interrupt 0x0 is enabled
Jan 1 00:00:26 user warn kernel: SES: LED GPIO 0x8005 is enabled
Jan 1 00:00:26 user info kernel: Serial: BCM63XX driver $Revision: 3.00 $
Jan 1 00:00:26 user warn kernel: ttyS0 at MMIO 0xfffe0300 (irq = 10) is a BCM63XX
Jan 1 00:00:26 user info kernel: NET: Registered protocol family 2
Jan 1 00:00:26 user info kernel: IP: routing cache hash table of 512 buckets, 4Kbytes
Jan 1 00:00:26 user info kernel: TCP: Hash tables configured (established 512 bind 1024)
Jan 1 00:00:26 user info kernel: Initializing IPsec netlink socket
Jan 1 00:00:26 user info kernel: NET: Registered protocol family 1
Jan 1 00:00:26 user info kernel: NET: Registered protocol family 17
Jan 1 00:00:26 user info kernel: NET: Registered protocol family 15
Jan 1 00:00:26 user notice kernel: Ebtables v2.0 registered
Jan 1 00:00:26 user info kernel: NET: Registered protocol family 8
Jan 1 00:00:26 user info kernel: NET: Registered protocol family 20
Jan 1 00:00:26 user info kernel: 802.1Q VLAN Support v1.8 Ben Greear
Jan 1 00:00:26 user info kernel: All bugs added by David S. Miller
Jan 1 00:00:26 user warn kernel: VFS: Mounted root (squashfs filesystem) readonly.
Jan 1 00:00:26 user info kernel: Freeing unused kernel memory: 68k freed
Jan 1 00:00:26 user warn kernel: Algorithmics/MIPS FPU Emulator v1.5
Jan 1 00:00:26 user warn kernel: atmapi: module license 'Proprietary' taints kernel.
Jan 1 00:00:26 user warn kernel: adsl: adsl_init entry
Jan 1 00:00:26 user warn kernel: blaadd: blaa_detect entry
Jan 1 00:00:26 user info kernel: Broadcom BCMPROCFS v1.0 initialized
Jan 1 00:00:26 user warn kernel: dgasp: kerSysRegisterDyingGaspHandler: wl0 registered
Jan 1 00:00:26 user warn kernel: Trying to free free IRQ25
Jan 1 00:00:26 user warn kernel: Broadcom BCM6338A2 Ethernet Network Device v0.3 Nov 29 2010 19:49:11
Jan 1 00:00:26 user warn kernel: Config Ethernet Switch Through MDIO Pseudo PHY Interface
Jan 1 00:00:26 user warn kernel: ====>PAGE_MANAGEMENT=02, REG_DEV_ID=30, val32=00000000
Jan 1 00:00:26 user warn kernel: ethsw: found bcm5325e!
Jan 1 00:00:26 user warn kernel: dgasp: kerSysRegisterDyingGaspHandler: eth0 registered
Jan 1 00:00:26 user warn kernel: eth0: MAC Address: 00:24:01:8A:B5:C2
Jan 1 00:00:26 user crit kernel: eth0 Link UP.
Jan 1 00:00:26 user warn kernel: BcmAdsl_Initialize=0xC00663E8, g_pFnNotifyCallback=0xC0080FE4
Jan 1 00:00:26 user warn kernel: AnnexCParam=0x7FFF7EB8 AnnexAParam=0x00000980 adsl2=0x00000000
Jan 1 00:00:26 user warn kernel: pSdramPHY=0xA0FFFFF8, 0x1B7A24 0xDEADBEEF
Jan 1 00:00:26 user warn kernel: AdslCoreHwReset: AdslOemDataAddr = 0xA0FEF96C
Jan 1 00:00:26 user warn kernel: AnnexCParam=0x7FFF7EB8 AnnexAParam=0x00000980 adsl2=0x00000000
Jan 1 00:00:26 user warn kernel: dgasp: kerSysRegisterDyingGaspHandler: dsl0 registered
Jan 1 00:00:26 user warn kernel: ATM proc init !!!
Jan 1 00:00:26 user warn kernel: ip_tables: (C) 2000-2002 Netfilter core team
Jan 1 00:00:26 user warn kernel: ip_conntrack version 2.1 (125 buckets, 0 max) - 384 bytes per conntrack
Jan 1 00:00:26 user warn kernel: ip_conntrack_pptp version 2.1 loaded
Jan 1 00:00:26 user warn kernel: ip_nat_pptp version 2.0 loaded
Jan 1 00:00:26 user crit kernel: ADSL G.994 training
Jan 1 00:00:26 user warn kernel: ip_ct_h323: init success
Jan 1 00:00:26 user warn kernel: ip_nat_h323: init success
Jan 1 00:00:26 user warn kernel: BRCM NAT Caching v1.0 Nov 20 2007 10:22:27
Jan 1 00:00:26 user warn kernel: c00a1088
Jan 1 00:00:26 user warn kernel: ip_conntrack_rtsp v0.01 loading
Jan 1 00:00:26 user warn kernel: ip_nat_rtsp v0.01 loading
Jan 1 00:00:26 user info kernel: device eth0 entered promiscuous mode
Jan 1 00:00:26 user info kernel: br0: port 1(eth0) entering learning state
Jan 1 00:00:26 user info kernel: br0: topology change detected, propagating
Jan 1 00:00:26 user info kernel: br0: port 1(eth0) entering forwarding state
Jan 1 00:00:26 user info kernel: device wl0 entered promiscuous mode
Jan 1 00:00:26 user info kernel: br0: port 2(wl0) entering learning state
Jan 1 00:00:26 user info kernel: br0: topology change detected, propagating
Jan 1 00:00:26 user info kernel: br0: port 2(wl0) entering forwarding state
Jan 1 00:00:26 user info kernel: device eth0 left promiscuous mode
Jan 1 00:00:26 user info kernel: br0: port 1(eth0) entering disabled state
Jan 1 00:00:26 user info kernel: device wl0 left promiscuous mode
Jan 1 00:00:26 user info kernel: br0: port 2(wl0) entering disabled state
Jan 1 00:00:26 user info kernel: device eth0 entered promiscuous mode
Jan 1 00:00:26 user info kernel: br0: port 1(eth0) entering learning state
Jan 1 00:00:26 user info kernel: br0: topology change detected, propagating
Jan 1 00:00:26 user info kernel: br0: port 1(eth0) entering forwarding state
Jan 1 00:00:26 user info kernel: device wl0 entered promiscuous mode
Jan 1 00:00:26 user info kernel: br0: port 2(wl0) entering learning state
Jan 1 00:00:26 user info kernel: br0: topology change detected, propagating
Jan 1 00:00:26 user info kernel: br0: port 2(wl0) entering forwarding state
Jan 1 00:00:27 user debug syslog: brctl addbr pppbr
Jan 1 00:00:27 user debug syslog: pppd keepalive 0 0 -c 0.1.50.1 -r stream -i nas_0_1_50 -u "ppp*****@mtu" -p ********** -f 0 -d -m 00308817a499/2c86 &
Jan 1 00:00:27 user crit kernel: ADSL G.992 started
Jan 1 00:00:28 daemon notice pppd[396]: pppd 2.4.1 started by admin, uid 0
Jan 1 00:00:30 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 up, interleaved, us=768, ds=6144
Jan 1 00:00:35 user warn kernel: ATM Soft SAR: ATM link connected.
Jan 1 00:00:36 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:37 daemon notice pppd[396]: PPP: Start to connect ...
Jan 1 00:00:37 daemon debug pppd[396]: Sent packet: Ether addr: 00:30:88:17:a4:99 (PPPOE Discovery) PPPoE hdr: ver=0x1 type=0x1 code=0xa7 sid=0x2c86 length=0x000c (PADT) PPPoE tag: type=0103 length=0004 (Host Uniq) data (bin): 10 04 71 5
Jan 1 00:00:37 daemon info pppd[396]: Sending PADI
Jan 1 00:00:37 daemon debug pppd[396]: Sent packet: Ether addr: ff:ff:ff:ff:ff:ff (PPPOE Discovery) PPPoE hdr: ver=0x1 type=0x1 code=0x09 sid=0x0000 length=0x0012 (PADI) PPPoE tag: type=0103 length=0004 (Host Uniq) data (bin): 10 04 71 5
Jan 1 00:00:37 daemon info pppd[396]: HOST_UNIQ successful match
Jan 1 00:00:37 daemon crit pppd[396]: PPP server detected.
Jan 1 00:00:37 daemon debug pppd[396]: Sent packet: Ether addr: 00:30:88:17:a4:99 (PPPOE Discovery) PPPoE hdr: ver=0x1 type=0x1 code=0x19 sid=0x0000 length=0x0012 (PADR) PPPoE tag: type=0101 length=0006 (Service name) data (UTF-8): stream
Jan 1 00:00:38 daemon info pppd[396]: HOST_UNIQ successful match
Jan 1 00:00:38 daemon info pppd[396]: Got connection: 195e
Jan 1 00:00:38 daemon crit pppd[396]: PPP session established.
Jan 1 00:00:38 daemon debug pppd[396]: using channel 1
Jan 1 00:00:38 daemon info pppd[396]: Using interface ppp0_1_50_1
Jan 1 00:00:38 daemon notice pppd[396]: Connect: ppp_0_1_50_1 <--> nas_0_1_50
Jan 1 00:00:38 daemon warn pppd[396]: Couldn't increase MTU to 1500.
Jan 1 00:00:38 daemon err pppd[396]: Couldn't increase MRU to 1500
Jan 1 00:00:38 daemon debug pppd[396]: sent [LCP ConfReq id=0x1 ]
Jan 1 00:00:38 daemon debug pppd[396]: rcvd [LCP ConfReq id=0x7 ]
Jan 1 00:00:38 daemon debug pppd[396]: sent [LCP ConfAck id=0x7 ]
Jan 1 00:00:38 daemon debug pppd[396]: rcvd [LCP ConfAck id=0x1 ]
Jan 1 00:00:38 daemon crit pppd[396]: PPP LCP UP.
Jan 1 00:00:38 daemon debug pppd[396]: rcvd [CHAP Challenge id=0x1 <*****>, name = "*****"]
Jan 1 00:00:38 daemon debug pppd[396]: sent [CHAP Response id=0x1 <*****>, name = "ppp*****@mtu"]
Jan 1 00:00:38 daemon debug pppd[396]: rcvd [CHAP Success id=0x1 "CHAP authentication success, unit 22166"]
Jan 1 00:00:38 daemon info pppd[396]: Remote message: CHAP authentication success, unit 22166
Jan 1 00:00:38 daemon debug pppd[396]: sent [IPCP ConfReq id=0x1 ]
Jan 1 00:00:38 daemon debug pppd[396]: rcvd [IPCP ConfReq id=0x13 ]
Jan 1 00:00:38 daemon debug pppd[396]: sent [IPCP ConfAck id=0x13 ]
Jan 1 00:00:38 daemon debug pppd[396]: rcvd [IPCP ConfNak id=0x1 ]
Jan 1 00:00:38 daemon debug pppd[396]: sent [IPCP ConfReq id=0x2 ]
Jan 1 00:00:38 daemon debug pppd[396]: rcvd [IPCP ConfAck id=0x2 ]
Jan 1 00:00:38 daemon notice pppd[396]: local IP address 91.78.202.6
Jan 1 00:00:38 daemon notice pppd[396]: remote IP address 91.78.192.1
Jan 1 00:00:38 daemon notice pppd[396]: primary DNS address 195.34.32.116
Jan 1 00:00:38 daemon notice pppd[396]: secondary DNS address 212.188.4.10
Jan 1 00:00:39 daemon crit pppd[396]: Received valid IP address from server. Connection UP.
Jan 1 00:00:39 daemon debug pppd[396]: Script /etc/ppp/ip-up started (pid 678)
Jan 1 00:00:39 daemon debug pppd[396]: Script /etc/ppp/ip-up finished (pid 678), status = 0x0
Jan 1 00:00:39 user debug syslog: route add default gw 91.78.192.1 2>/dev/null
Jan 1 00:00:39 user debug syslog: iptables -A FORWARD -o ppp_0_1_50_1 -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu
Jan 1 00:00:39 user debug syslog: iptables -A FORWARD -i ppp_0_1_50_1 -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu
Jan 1 00:00:40 user debug syslog: echo > /proc/net/ip_conntrack
Jan 1 00:00:40 user debug syslog: echo "1000" > /proc/sys/net/ipv4/ip_conntrack_max
Jan 1 00:00:40 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:40 user debug syslog: iptables -t nat -D POSTROUTING -o ppp_0_1_50_1 -s 192.168.1.0/255.255.255.0 -j MASQUERADE 2>/dev/null
Jan 1 00:00:40 user debug syslog: iptables -t nat -A POSTROUTING -o ppp_0_1_50_1 -s 192.168.1.0/255.255.255.0 -j MASQUERADE
Jan 1 00:00:40 user debug syslog: iptables -t nat -D PREROUTING -i br0 -d 192.168.1.1 -p udp --dport 53 -j DNAT --to 195.34.32.116 2>/dev/null
Jan 1 00:00:40 user debug syslog: iptables -t nat -A PREROUTING -i br0 -d 192.168.1.1 -p udp --dport 53 -j DNAT --to 195.34.32.116
Jan 1 00:00:40 user debug syslog: /bin/dnsprobe &
Jan 1 00:00:40 user notice dnsprobe[745]: dnsprobe started!
Jan 1 00:00:41 user debug syslog: iptables -I INPUT 1 -i ppp_0_1_50_1 -p udp --dport 5060 -j ACCEPT 2>/dev/null
Jan 1 00:00:41 user debug syslog: iptables -I INPUT 1 -i ppp_0_1_50_1 -p udp --dport 7070:7079 -j ACCEPT 2>/dev/null
Jan 1 00:00:41 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:41 user debug syslog: siproxd --config /var/siproxd/siproxd.conf &
Mar 12 20:35:51 user notice syslog: siproxd.c:192 INFO:siproxd-0.5.10-2844 i686-redhat-linux-gnu starting up
Mar 12 20:35:51 user notice syslog: siproxd.c:218 INFO:daemonized, pid=799
Mar 12 20:35:51 user notice syslog: sock.c:65 INFO:bound to port 5060
Mar 12 20:35:51 user notice syslog: register.c:65 WARNING:registration file not found, starting with empty table
Mar 12 20:35:51 user notice syslog: siproxd.c:265 INFO:siproxd-0.5.10-2844 i686-redhat-linux-gnu started
Mar 12 20:35:51 user debug syslog: setkey -f /var/setkey.conf
Mar 12 20:35:53 user debug syslog: ifconfig ppp_0_1_50_1 mtu 1492
Дальше, обрываем линию и ждём переподключения (провайдер делает это раз в сутки):
Код:
Mar 12 20:43:36 user crit kernel: ADSL link down
Mar 12 20:43:39 daemon notice pppd[396]: Terminating connection due to link down.
Mar 12 20:43:39 daemon crit pppd[396]: Clear IP addresses. Connection DOWN.
Mar 12 20:43:39 daemon crit pppd[396]: Clear IP addresses. PPP connection DOWN.
Mar 12 20:43:39 daemon warn pppd[396]: Couldn't increase MTU to 1500.
Mar 12 20:43:39 daemon err pppd[396]: Couldn't increase MRU to 1500
Mar 12 20:43:39 daemon debug pppd[396]: sent [LCP TermReq id=0x2 "Link down"]
Mar 12 20:43:39 user crit kernel: ADSL G.994 training
Mar 12 20:43:42 daemon debug pppd[396]: sent [LCP TermReq id=0x3 "Link down"]
Mar 12 20:43:45 daemon notice pppd[396]: Connection terminated.
Mar 12 20:43:45 daemon info pppd[396]: Connect time 6415003.1 minutes.
Mar 12 20:43:45 daemon info pppd[396]: Sent 121387 bytes, received 914010 bytes.
Mar 12 20:43:45 daemon warn pppd[396]: Doing disconnect
Mar 12 20:43:45 daemon debug pppd[396]: Sent packet: Ether addr: 00:30:88:17:a4:99 (PPPOE Discovery) PPPoE hdr: ver=0x1 type=0x1 code=0xa7 sid=0x195e length=0x000c (PADT) PPPoE tag: type=0103 length=0004 (Host Uniq) data (bin): 10 04 71 5
Mar 12 20:43:57 user crit kernel: ADSL G.992 channel analysis
Mar 12 20:44:01 user crit kernel: ADSL G.992 message exchange
Mar 12 20:44:02 user crit kernel: ADSL link up, interleaved, us=768, ds=6144
Mar 12 20:44:04 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
Mar 12 20:44:05 daemon notice pppd[396]: PPP: Start to connect ...
Mar 12 20:44:05 daemon debug pppd[396]: Sent packet: Ether addr: 00:30:88:17:a4:99 (PPPOE Discovery) PPPoE hdr: ver=0x1 type=0x1 code=0xa7 sid=0x195e length=0x000c (PADT) PPPoE tag: type=0103 length=0004 (Host Uniq) data (bin): 10 04 71 5
Mar 12 20:44:05 daemon info pppd[396]: Sending PADI
Mar 12 20:44:05 daemon debug pppd[396]: Sent packet: Ether addr: ff:ff:ff:ff:ff:ff (PPPOE Discovery) PPPoE hdr: ver=0x1 type=0x1 code=0x09 sid=0x0000 length=0x0012 (PADI) PPPoE tag: type=0103 length=0004 (Host Uniq) data (bin): 10 04 71 5
Mar 12 20:44:05 daemon info pppd[396]: HOST_UNIQ mismatch: 31393935 %i
Mar 12 20:44:05 daemon err pppd[396]: Failed to negotiate PPPoE connection: 25 Inappropriate ioctl for device
Mar 12 20:44:05 daemon info pppd[396]: Exit.
Приплыли. И, как следствие:
Код:
Mar 12 20:45:52 user warn dnsprobe[745]: dns query failed
Mar 12 20:45:54 user warn dnsprobe[745]: dns query failed
Mar 12 20:45:56 user warn dnsprobe[745]: dns query failed
Mar 12 20:45:56 user notice dnsprobe[745]: Primary DNS server Is Down... Switching To Secondary DNS server
Куда копать?