1. This site uses cookies. By continuing to use this site, you are agreeing to our use of cookies. Learn More.

Tomato 1.27 drops PPoE connection and wont re-establish it

Discussion in 'Tomato Firmware' started by lanmtl, Jan 17, 2010.

  1. lanmtl

    lanmtl Addicted to LI Member

    Hi all,
    More weird issues from the router that makes wifi faster than ethernet (see my last post)...

    Now it randomly drops the PPoE connection and won't re-establish it. Reboot, cold restart, shamanism, whatever nothing works.
    I don't do P2P, the most connections the router has to handle is 100, 150 tops.
    Another interesting thing is that when the router restarts, the CPU charge is around 0.20/0.00/0.00

    I turned on PPoE logs and here are the last 100 lines from the log:

    Code:
    Dec 31 19:00:05 ? user.info kernel: PPP generic driver version 2.4.2
    Dec 31 19:00:05 ? user.debug kernel: Physically mapped flash: Found an alias at 0x400000 for the chip at 0x0
    Dec 31 19:00:05 ? user.debug kernel: Physically mapped flash: Found an alias at 0x800000 for the chip at 0x0
    Dec 31 19:00:05 ? user.debug kernel: Physically mapped flash: Found an alias at 0xc00000 for the chip at 0x0
    Dec 31 19:00:05 ? user.debug kernel: Physically mapped flash: Found an alias at 0x1000000 for the chip at 0x0
    Dec 31 19:00:05 ? user.debug kernel: Physically mapped flash: Found an alias at 0x1400000 for the chip at 0x0
    Dec 31 19:00:05 ? user.debug kernel: Physically mapped flash: Found an alias at 0x1800000 for the chip at 0x0
    Dec 31 19:00:05 ? user.debug kernel: Physically mapped flash: Found an alias at 0x1c00000 for the chip at 0x0
    Dec 31 19:00:05 ? user.debug kernel: number of CFI chips: 1
    Dec 31 19:00:05 ? user.debug kernel: 0: offset=0x0,size=0x2000,blocks=8
    Dec 31 19:00:05 ? user.debug kernel: 1: offset=0x10000,size=0x10000,blocks=63
    Dec 31 19:00:05 ? user.notice kernel: Flash device: 0x400000 at 0x1c000000
    Dec 31 19:00:05 ? user.notice kernel: Creating 5 MTD partitions on "Physically mapped flash":
    Dec 31 19:00:05 ? user.notice kernel: 0x00000000-0x00040000 : "pmon"
    Dec 31 19:00:05 ? user.notice kernel: 0x00040000-0x003f0000 : "linux"
    Dec 31 19:00:05 ? user.notice kernel: 0x000e2a14-0x002f0000 : "rootfs"
    Dec 31 19:00:05 ? user.notice kernel: 0x002f0000-0x003f0000 : "jffs2"
    Dec 31 19:00:05 ? user.notice kernel: 0x003f0000-0x00400000 : "nvram"
    Dec 31 19:00:05 ? user.err kernel: sflash: found no supported devices
    Dec 31 19:00:05 ? user.info kernel: NET4: Linux TCP/IP 1.0 for NET4.0
    Dec 31 19:00:05 ? user.info kernel: IP Protocols: ICMP, UDP, TCP, IGMP
    Dec 31 19:00:05 ? user.info kernel: IP: routing cache hash table of 512 buckets, 4Kbytes
    Dec 31 19:00:05 ? user.info kernel: TCP: Hash tables configured (established 1024 bind 2048)
    Dec 31 19:00:05 ? user.info kernel: Linux IP multicast router 0.06 plus PIM-SM
    Dec 31 19:00:05 ? user.warn kernel: ip_conntrack version 2.1 (8092 buckets, 4096 max) - 368 bytes per conntrack
    Dec 31 19:00:05 ? user.warn kernel: ip_tables: (C) 2000-2002 Netfilter core team
    Dec 31 19:00:05 ? user.info kernel: NET4: Unix domain sockets 1.0/SMP for Linux NET4.0.
    Dec 31 19:00:05 ? user.info kernel: NET4: Ethernet Bridge 008 for NET4.0
    Dec 31 19:00:05 ? user.alert kernel: 802.1Q VLAN Support v1.7 Ben Greear <greearb@candelatech.com>
    Dec 31 19:00:05 ? user.alert kernel: All bugs added by David S. Miller <davem@redhat.com>
    Dec 31 19:00:05 ? user.warn kernel: VFS: Mounted root (squashfs filesystem) readonly.
    Dec 31 19:00:05 ? user.info kernel: Mounted devfs on /dev
    Dec 31 19:00:05 ? user.info kernel: Freeing unused kernel memory: 64k freed
    Dec 31 19:00:05 ? user.warn kernel: Algorithmics/MIPS FPU Emulator v1.5
    Dec 31 19:00:05 ? user.warn kernel: ip_conntrack_pptp version 1.9 loaded
    Dec 31 19:00:05 ? user.warn kernel: ip_nat_pptp version 1.5 loaded
    Dec 31 19:00:05 ? user.warn kernel: ip_conntrack_rtsp v0.01 loading
    Dec 31 19:00:05 ? user.warn kernel: ip_nat_rtsp v0.01 loading
    Dec 31 19:00:05 ? user.warn kernel: eth0: Broadcom BCM47xx 10/100 Mbps Ethernet Controller 3.90.38.0
    Dec 31 19:00:05 ? user.warn kernel: eth1: Broadcom BCM4320 802.11 Wireless Controller 3.90.38.0
    Dec 31 19:00:05 ? user.warn kernel: tomato_ct.c [Nov 29 2009 06:54:59]
    Dec 31 19:00:05 ? user.info kernel: vlan0: dev_set_promiscuity(master, 1)
    Dec 31 19:00:05 ? user.info kernel: device eth0 entered promiscuous mode
    Dec 31 19:00:05 ? user.info kernel: device vlan0 entered promiscuous mode
    Dec 31 19:00:05 ? user.info kernel: device eth1 entered promiscuous mode
    Dec 31 19:00:05 ? user.info kernel: br0: port 2(eth1) entering learning state
    Dec 31 19:00:05 ? user.info kernel: br0: port 1(vlan0) entering learning state
    Dec 31 19:00:05 ? user.info kernel: br0: port 2(eth1) entering forwarding state
    Dec 31 19:00:05 ? user.info kernel: br0: topology change detected, propagating
    Dec 31 19:00:05 ? user.info kernel: br0: port 1(vlan0) entering forwarding state
    Dec 31 19:00:05 ? user.info kernel: br0: topology change detected, propagating
    Dec 31 19:00:05 ? user.warn kernel: vlan1: Setting MAC address to  00 0f 66 XX XX XX.
    Dec 31 19:00:07 ? daemon.info dnsmasq[83]: started, version 2.51 cachesize 150
    Dec 31 19:00:07 ? daemon.info dnsmasq[83]: compile time options: no-IPv6 GNU-getopt no-RTC no-DBus no-I18N DHCP no-scripts no-TFTP
    Dec 31 19:00:07 ? daemon.info dnsmasq-dhcp[83]: DHCP, IP range 192.168.1.100 -- 192.168.1.149, lease time 1d
    Dec 31 19:00:07 ? daemon.warn dnsmasq[83]: failed to access /etc/resolv.dnsmasq: No such file or directory
    Dec 31 19:00:07 ? daemon.info dnsmasq[83]: read /etc/hosts - 0 addresses
    Dec 31 19:00:07 ? daemon.info dnsmasq[83]: read /etc/hosts.dnsmasq - 6 addresses
    Dec 31 19:00:07 ? user.info init[1]: Tomato 1.27.1798
    Dec 31 19:00:07 ? cron.err crond[87]: crond (busybox 1.14.4) started, log level 9
    Dec 31 19:00:07 ? user.info init[1]: Linksys WRT54G/GS/GL
    Dec 31 19:00:08 ? daemon.info dnsmasq-dhcp[83]: DHCPREQUEST(br0) 192.168.1.132 00:01:6c:70:e3:e2 
    Dec 31 19:00:08 ? daemon.info dnsmasq-dhcp[83]: DHCPACK(br0) 192.168.1.132 00:01:6c:70:e3:e2 mathieu-pc
    Dec 31 19:00:15 ? daemon.info pppoe[67]: Resending...
    Dec 31 19:00:16 ? daemon.info dnsmasq-dhcp[83]: DHCPREQUEST(br0) 192.168.1.101 00:26:bb:05:43:89 
    Dec 31 19:00:16 ? daemon.info dnsmasq-dhcp[83]: DHCPACK(br0) 192.168.1.101 00:26:bb:05:43:89 pierre-laptop
    Dec 31 19:00:25 ? daemon.info pppoe[67]: Resending...
    Dec 31 19:00:30 ? daemon.info pppoe[67]: Resending...
    Dec 31 19:00:38 ? daemon.info pppoe[67]: Resending...
    Dec 31 19:00:44 ? daemon.info pppoe[67]: Sending PADI.
    Dec 31 19:00:45 ? user.info redial[62]: WAN down. Reconnecting...
    Dec 31 19:00:45 ? daemon.info dnsmasq[83]: exiting on receipt of SIGTERM
    Dec 31 19:00:45 ? daemon.info dnsmasq[144]: started, version 2.51 cachesize 150
    Dec 31 19:00:45 ? daemon.info dnsmasq[144]: compile time options: no-IPv6 GNU-getopt no-RTC no-DBus no-I18N DHCP no-scripts no-TFTP
    Dec 31 19:00:45 ? daemon.info dnsmasq-dhcp[144]: DHCP, IP range 192.168.1.100 -- 192.168.1.149, lease time 1d
    Dec 31 19:00:45 ? daemon.warn dnsmasq[144]: no servers found in /etc/resolv.dnsmasq, will retry
    Dec 31 19:00:45 ? daemon.info dnsmasq[144]: read /etc/hosts - 0 addresses
    Dec 31 19:00:45 ? daemon.info dnsmasq[144]: read /etc/hosts.dnsmasq - 6 addresses
    Dec 31 19:00:52 ? daemon.info pppoe[147]: Starting
    Dec 31 19:00:52 ? daemon.info pppoe[147]: Sending PADI.
    Dec 31 19:00:52 ? user.info redial[146]: Started. Time: 30
    Dec 31 19:01:02 ? daemon.info pppoe[147]: Resending...
    Dec 31 19:01:12 ? daemon.info pppoe[147]: Resending...
    Dec 31 19:01:17 ? daemon.info pppoe[147]: Resending...
    Dec 31 19:01:25 ? daemon.info pppoe[147]: Resending...
    Dec 31 19:01:32 ? user.info redial[146]: WAN down. Reconnecting...
    Dec 31 19:01:33 ? daemon.info dnsmasq[144]: exiting on receipt of SIGTERM
    Dec 31 19:01:33 ? daemon.info dnsmasq[181]: started, version 2.51 cachesize 150
    Dec 31 19:01:33 ? daemon.info dnsmasq[181]: compile time options: no-IPv6 GNU-getopt no-RTC no-DBus no-I18N DHCP no-scripts no-TFTP
    Dec 31 19:01:33 ? daemon.info dnsmasq-dhcp[181]: DHCP, IP range 192.168.1.100 -- 192.168.1.149, lease time 1d
    Dec 31 19:01:33 ? daemon.warn dnsmasq[181]: no servers found in /etc/resolv.dnsmasq, will retry
    Dec 31 19:01:33 ? daemon.info dnsmasq[181]: read /etc/hosts - 0 addresses
    Dec 31 19:01:33 ? daemon.info dnsmasq[181]: read /etc/hosts.dnsmasq - 6 addresses
    Dec 31 19:01:40 ? daemon.info pppoe[200]: Starting
    Dec 31 19:01:40 ? daemon.info pppoe[200]: Sending PADI.
    Dec 31 19:01:40 ? user.info redial[199]: Started. Time: 30
    Dec 31 19:01:50 ? daemon.info pppoe[200]: Resending...
    Dec 31 19:01:50 ? daemon.info pppoe[200]: Received PADO.
    Dec 31 19:01:50 ? daemon.info pppoe[200]: Sending PADR.
    Dec 31 19:02:00 ? daemon.info pppoe[200]: Resending...
    
     
  2. lanmtl

    lanmtl Addicted to LI Member

    and after a while it does re-establish the connection
    Code:
    Dec 31 19:02:20 ? user.info redial[199]: WAN down. Reconnecting...
    Dec 31 19:02:20 ? daemon.info dnsmasq[181]: exiting on receipt of SIGTERM
    Dec 31 19:02:20 ? daemon.info dnsmasq[233]: started, version 2.51 cachesize 150
    Dec 31 19:02:20 ? daemon.info dnsmasq[233]: compile time options: no-IPv6 GNU-getopt no-RTC no-DBus no-I18N DHCP no-scripts no-TFTP
    Dec 31 19:02:20 ? daemon.info dnsmasq-dhcp[233]: DHCP, IP range 192.168.1.100 -- 192.168.1.149, lease time 1d
    Dec 31 19:02:20 ? daemon.warn dnsmasq[233]: no servers found in /etc/resolv.dnsmasq, will retry
    Dec 31 19:02:20 ? daemon.info dnsmasq[233]: read /etc/hosts - 0 addresses
    Dec 31 19:02:20 ? daemon.info dnsmasq[233]: read /etc/hosts.dnsmasq - 6 addresses
    Dec 31 19:02:27 ? daemon.info pppoe[240]: Starting
    Dec 31 19:02:27 ? daemon.info pppoe[240]: Sending PADI.
    Dec 31 19:02:27 ? user.info redial[239]: Started. Time: 30
    Dec 31 19:02:28 ? daemon.info pppoe[240]: Received PADO.
    Dec 31 19:02:28 ? daemon.info pppoe[240]: Sending PADR.
    Dec 31 19:02:28 ? daemon.info pppoe[240]: Received PADS. SID: 0x721B
    Dec 31 19:02:28 ? daemon.info pppoe[240]: Sending LCP Configuration-Request
    Dec 31 19:02:30 ? daemon.info pppoe[240]: Received LCP Configuration-Request
    Dec 31 19:02:30 ? daemon.info pppoe[240]: Sending LCP Configuration-Ack
    Dec 31 19:02:30 ? daemon.info pppoe[240]: Received LCP Configuration-Ack
    Dec 31 19:02:30 ? daemon.info pppoe[240]: Sending PAP Authenticate-Request.
    Dec 31 19:02:31 ? daemon.info pppoe[240]: PAP authentication succeeded.
    Dec 31 19:02:31 ? daemon.info pppoe[240]: Sending IPCP Configuration-Request
    Dec 31 19:02:31 ? daemon.info pppoe[240]: Received IPCP Configuration-Request
    Dec 31 19:02:31 ? daemon.info pppoe[240]: Sending IPCP Configuration-Ack
    Dec 31 19:02:31 ? daemon.info pppoe[240]: Received IPCP Configuration-Nak
    Dec 31 19:02:31 ? daemon.info pppoe[240]: Sending IPCP Configuration-Request
    Dec 31 19:02:31 ? daemon.info pppoe[240]: Received IPCP Configuration-Ack
    Dec 31 19:02:31 ? daemon.notice pppoe[240]: Connected.
     

Share This Page