Jan 1 00:00:04 syslogd started: BusyBox v1.19.2
Jan 1 00:00:04 resident: init lan
Jan 1 00:00:04 resident: start ipoe
Jan 1 00:00:04 start_ip: begin (br0 -> br0)
Jan 1 00:00:04 resident: start ipoe (v717912176) on br0
Jan 1 00:00:04 kernel: klogd started: BusyBox v1.19.2 (2012-10-19 11:36:26 MSK)
Jan 1 00:00:04 kernel: [17179569.184000] Linux version 2.6.21 (builder@rd) (gcc version 4.1.2) #1 Fri Oct 19 11:35:19 MSK 2012
Jan 1 00:00:04 kernel: [17179569.184000]
Jan 1 00:00:04 kernel: [17179569.184000] The CPU feqenuce set to 360 MHz
Jan 1 00:00:04 kernel: [17179569.184000] CPU revision is: 0001964c
Jan 1 00:00:04 kernel: [17179569.184000] Determined physical RAM map:
Jan 1 00:00:04 kernel: [17179569.184000] memory: 02000000 @ 00000000 (usable)
Jan 1 00:00:04 kernel: [17179575.432000] RT305x_ESW: Link Status Changed
Jan 1 00:00:05 kernel: [17179575.492000] RT305x_ESW: Link Status Changed
Jan 1 00:00:05 resident: init wan
Jan 1 00:00:05 kernel: [17179575.984000] eth2.5: Setting MAC address to bc ae c5 60 6b e1.
Jan 1 00:00:05 resident: start ipoe
Jan 1 00:00:05 start_ip: begin (eth2.5 -> eth2.5_5)
Jan 1 00:00:05 resident: start ipoe (v717603564) on eth2.5
Jan 1 00:00:05 resident: start pppd on eth2.5_4
Jan 1 00:00:05 resident: stop pppd on eth2.5_4
Jan 1 00:00:05 resident: set lock on eth2.5_4, with "/var/lock/eth2.5_4.lock"
Jan 1 00:00:05 resident: stopped link(contype:ppp, iface:eth2.5_4)
Jan 1 00:00:05 pppd[743]: Plugin /usr/lib/pppd/rp-pppoe.so loaded.
Jan 1 00:00:05 pppd[744]: pppd 2.4.4 started by admin, uid 0
Jan 1 00:00:05 kernel: [17179576.276000] RT305x_ESW: Link Status Changed
Jan 1 00:00:07 kernel: [17179578.012000] RT305x_ESW: Link Status Changed
Jan 1 00:00:08 kernel: [17179578.780000] RT305x_ESW: Link Status Changed
Jan 1 00:00:08 update_ddns: start
Jan 1 00:00:08 telnet-start: begin...
Jan 1 00:00:08 telnet-start: running on 23 port
Jan 1 00:00:08 telnet-start: succes!
Jan 1 00:00:08 resident: Out init_device
Jan 1 00:00:08 resident: record = action:up;iface:br0;contag:0;ipaddr:192.168.0.1;netmask:255.255.255.0;gateway:;dns1:;dns2:;
Jan 1 00:00:08 resident: phys_iface br0
Jan 1 00:00:08 test: ip_type = (null)
Jan 1 00:00:08 resident: resident_ipoe_handler - Set default type: ipv4
Jan 1 00:00:08 resident: phys_iface
Jan 1 00:00:08 resident: resident_ipoe_handler - 1
Jan 1 00:00:08 resident: resident_ipoe_handler - 3, name: br0
Jan 1 00:00:08 resident: resident_ipoe_handler - l2:br0, l3:br0, tun:, ip_type:0
Jan 1 00:00:08 resident: resident_ipoe_handler - ipv4
Jan 1 00:00:08 resident: start ip_is_up on iface br0
Jan 1 00:00:08 resident: ip_is_up for link br0
Jan 1 00:00:09 resident_phy_link_handler: phy - 3, status - 1
Jan 1 00:00:09 resident_phy_link_handler: phy - 4, status - 1
Jan 1 00:00:09 resident_phy_link_handler: phy - 4, status - 0
Jan 1 00:00:09 resident_phy_link_handler: phy - 4, status - 1
Jan 1 00:00:09 resident_phy_link_handler: phy - 4, status - 0
Jan 1 00:00:09 resident: record = action:up;iface:eth2.5;contag:5;ipaddr:10.1.125.79;netmask:255.255.255.0;gateway:10.1.125.1;dns1:10.3.3.1;dns2:10.3.3.2;
Jan 1 00:00:09 resident: phys_iface eth2.5
Jan 1 00:00:09 test: ip_type = (null)
Jan 1 00:00:09 resident: resident_ipoe_handler - Set default type: ipv4
Jan 1 00:00:09 resident: phys_iface
Jan 1 00:00:09 resident: resident_ipoe_handler - 2
Jan 1 00:00:09 resident: resident_ipoe_handler - 3, name: eth2.5_5
Jan 1 00:00:09 resident: start dhcp+dns on br0
Jan 1 00:00:09 upnp: start - begin
Jan 1 00:00:09 upnp: upnp disable - exit
Jan 1 00:00:09 dnsmasq[776]: started, version cachesize 512
Jan 1 00:00:09 dnsmasq[776]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack
Jan 1 00:00:09 dnsmasq-dhcp[776]: DHCP, IP range 192.168.0.2 -- 192.168.0.100, lease time 1d
Jan 1 00:00:09 dnsmasq[776]: read /tmp/hosts - 2 addresses
Jan 1 00:00:09 resident: resident_ipoe_handler - l2:eth2.5, l3:eth2.5_5, tun:, ip_type:0
Jan 1 00:00:09 resident: resident_ipoe_handler - ipv4
Jan 1 00:00:09 resident: start ip_is_up on iface eth2.5_5
Jan 1 00:00:09 resident: ip_is_up for link eth2.5_5
Jan 1 00:00:09 set_dhcp_routes: set routes for iface eth2.5
Jan 1 00:00:09 resident: update dns
Jan 1 00:00:09 resident: update rip
Jan 1 00:00:09 dnsmasq[776]: read /tmp/hosts - 2 addresses
Jan 1 00:00:09 resident: update igmpx
Jan 1 00:00:09 resident: stop igmpx
Jan 1 00:00:09 iface: this is eth2.5
Jan 1 00:00:09 iface: this is ppp0
Jan 1 00:00:09 resident: igmpx ppp0
Jan 1 00:00:09 syslog: Loading liblogger v2.0.2
Jan 1 00:00:09 syslog: upstream ifaces: ppp0
Jan 1 00:00:09 syslog: downstream ifaces: br0
Jan 1 00:00:09 syslog: hews interval = 120.0 sec
Jan 1 00:00:09 syslog: doublet join enabled
Jan 1 00:00:09 syslog: Using old argv style
Jan 1 00:00:09 syslog: daemonizing ...
Jan 1 00:00:09 syslog: kernel multicast version is 305 (v3.05 ?)
Jan 1 00:00:09 syslog: kernel mroute inited
Jan 1 00:00:09 syslog: proxy prepared
Jan 1 00:00:09 syslog: ioctl: get iface index: ppp0: 19(No such device)
Jan 1 00:00:09 syslog: vifi #0 added for br0
Jan 1 00:00:09 syslog: valid VIF's configured: upstreams=0, downstreams=1. Bad.
Jan 1 00:00:09 resident: update igmpx compiled
Jan 1 00:00:09 kernel: [17179580.404000] RT305x_ESW: Link Status Changed
Jan 1 00:00:09 eth_status_IrqHandler: Change switch status
Jan 1 00:00:09 resident_phy_link_handler: phy - 4, status - 1
Jan 1 00:00:10 kernel: [17179581.196000] RT305x_ESW: Link Status Changed
Jan 1 00:00:10 eth_status_IrqHandler: Change switch status
Jan 1 00:00:10 resident_phy_link_handler: phy - 4, status - 0
Jan 1 00:00:14 dnsmasq[776]: exiting on receipt of SIGTERM
Jan 1 00:00:14 dnsmasq[810]: started, version cachesize 512
Jan 1 00:00:14 dnsmasq[810]: compile time options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack
Jan 1 00:00:14 dnsmasq-dhcp[810]: DHCP, IP range 192.168.0.2 -- 192.168.0.100, lease time 1d
Jan 1 00:00:14 dnsmasq[810]: read /tmp/hosts - 2 addresses
Jan 1 00:00:14 kernel: [17179585.352000] RT305x_ESW: Link Status Changed
Jan 1 00:00:14 resident_phy_link_handler: phy - 4, status - 1
Jan 1 00:00:14 eth_status_IrqHandler: Change switch status
Jan 1 00:00:15 kernel: [17179586.132000] RT305x_ESW: Link Status Changed
Jan 1 00:00:15 eth_status_IrqHandler: Change switch status
Jan 1 00:00:15 resident_phy_link_handler: phy - 4, status - 0
Jan 1 00:00:19 kernel: [17179590.172000] br0: topology change detected, propagating
Jan 1 00:00:19 kernel: [17179590.176000] br0: port 4(eth2.1) entering forwarding state
Jan 1 00:00:19 kernel: [17179590.180000] br0: topology change detected, propagating
Jan 1 00:00:19 kernel: [17179590.184000] br0: port 3(eth2.2) entering forwarding state
Jan 1 00:00:19 kernel: [17179590.188000] br0: topology change detected, propagating
Jan 1 00:00:19 kernel: [17179590.192000] br0: port 2(eth2.3) entering forwarding state
Jan 1 00:00:19 kernel: [17179590.196000] br0: topology change detected, propagating
Jan 1 00:00:19 kernel: [17179590.200000] br0: port 1(eth2.4) entering forwarding state
Jan 1 00:00:20 eth_status_IrqHandler: Change switch status
Jan 1 00:00:20 resident_phy_link_handler: phy - 4, status - 1
Jan 1 00:00:20 kernel: [17179591.084000] RT305x_ESW: Link Status Changed
Jan 1 00:00:21 pppd[744]: Timeout waiting for PADO packets
Jan 1 00:00:21 pppd[744]: Unable to complete PPPoE Discovery
Jan 1 00:00:21 pppd[744]: lcp_close: unit:0, reason: "" (status: 0, state: 1, was_ip_down: 0)
Jan 1 00:00:21 resident_ppp_handler: record = action:down;iface:ppp0;status:0;link:eth2.5_4;
Jan 1 00:00:21 resident: resident_ipoe_handler - Set default type: ipv4
Jan 1 00:00:21 resident: resident_ppp_handler - l2:eth2.5, l3:eth2.5_4, tun:
Jan 1 00:00:21 ppp_handler: ppp state 0
Jan 1 00:00:21 ppp_handler: Restart pppd
Jan 1 00:00:21 ppp_handler: sleep to 10 sec
Jan 1 00:00:21 resident: stop pppd on eth2.5_4
Jan 1 00:00:21 stop_pppd: Error type
Jan 1 00:00:21 kernel: [17179591.852000] RT305x_ESW: Link Status Changed
Jan 1 00:00:21 eth_status_IrqHandler: Change switch status
Jan 1 00:00:21 resident_phy_link_handler: phy - 4, status - 0
Jan 1 00:00:24 kernel: [17179595.232000] RT305x_ESW: Link Status Changed
Jan 1 00:00:24 resident_phy_link_handler: phy - 4, status - 1
Jan 1 00:00:24 eth_status_IrqHandler: Change switch status
Jan 1 00:00:25 resident_phy_link_handler: phy - 4, status - 0
Jan 1 00:00:25 kernel: [17179596.036000] RT305x_ESW: Link Status Changed
Jan 1 00:00:25 eth_status_IrqHandler: Change switch status
Jan 1 00:00:26 pppd[744]: Couldn't get PPP statistics: No such device
Jan 1 00:00:27 kernel: [17179597.760000] RT305x_ESW: Link Status Changed
Jan 1 00:00:27 resident_phy_link_handler: phy - 4, status - 1
Jan 1 00:00:27 eth_status_IrqHandler: Change switch status
Jan 1 00:00:28 kernel: [17179598.540000] RT305x_ESW: Link Status Changed
Jan 1 00:00:28 eth_status_IrqHandler: Change switch status
Jan 1 00:00:28 resident_phy_link_handler: phy - 4, status - 0
Jan 1 00:00:29 kernel: [17179600.220000] RT305x_ESW: Link Status Changed
Jan 1 00:00:29 eth_status_IrqHandler: Change switch status
Jan 1 00:00:29 resident_phy_link_handler: phy - 4, status - 1
Jan 1 00:00:30 kernel: [17179600.584000] RT305x_ESW: Link Status Changed
Jan 1 00:00:30 eth_status_IrqHandler: Change switch status
Jan 1 00:00:30 resident_phy_link_handler: phy - 4, status - 0
Jan 1 00:00:31 DEBUG!: I AM AWAKEN!!!
Jan 1 00:00:35 dnsmasq-dhcp[810]: DHCPDISCOVER(br0) bc:ae:c5:60:6b:e1
Jan 1 00:00:35 dnsmasq-dhcp[810]: DHCPOFFER(br0) 192.168.0.88 bc:ae:c5:60:6b:e1
Jan 1 00:00:35 dnsmasq-dhcp[810]: DHCPREQUEST(br0) 192.168.0.88 bc:ae:c5:60:6b:e1
Jan 1 00:00:35 dnsmasq-dhcp[810]: DHCPACK(br0) 192.168.0.88 bc:ae:c5:60:6b:e1 Maximus-PC
Jan 1 00:00:38 dnsmasq-dhcp[810]: DHCPINFORM(br0) 192.168.0.88 bc:ae:c5:60:6b:e1
Jan 1 00:00:38 dnsmasq-dhcp[810]: DHCPACK(br0) 192.168.0.88 bc:ae:c5:60:6b:e1 Maximus-PC
Jan 1 00:00:39 kernel: [17179610.220000] RT305x_ESW: Link Status Changed
Jan 1 00:00:39 eth_status_IrqHandler: Change switch status
Jan 1 00:00:39 resident_phy_link_handler: phy - 4, status - 1
Jan 1 00:00:40 kernel: [17179610.872000] RT305x_ESW: Link Status Changed
Jan 1 00:00:40 eth_status_IrqHandler: Change switch status
Jan 1 00:00:40 resident_phy_link_handler: phy - 4, status - 0
Jan 1 00:00:41 pppd[744]: Timeout waiting for PADO packets
Jan 1 00:00:41 pppd[744]: Unable to complete PPPoE Discovery
Jan 1 00:00:41 pppd[744]: lcp_close: unit:0, reason: "" (status: 0, state: 1, was_ip_down: 0)
Jan 1 00:00:41 resident_ppp_handler: record = action:down;iface:ppp0;status:0;link:eth2.5_4;
Jan 1 00:00:41 resident: resident_ipoe_handler - Set default type: ipv4
Jan 1 00:00:41 resident: resident_ppp_handler - l2:eth2.5, l3:eth2.5_4, tun:
Jan 1 00:00:41 ppp_handler: ppp state 0
Jan 1 00:00:41 ppp_handler: Restart pppd
Jan 1 00:00:41 ppp_handler: sleep to 10 sec
Jan 1 00:00:41 resident: stop pppd on eth2.5_4
Jan 1 00:00:41 stop_pppd: Error type
Jan 1 00:00:43 kernel: [17179614.236000] RT305x_ESW: Link Status Changed
Jan 1 00:00:43 resident_phy_link_handler: phy - 4, status - 1
Jan 1 00:00:43 eth_status_IrqHandler: Change switch status
Jan 1 00:00:44 kernel: [17179615.032000] RT305x_ESW: Link Status Changed
Jan 1 00:00:44 resident_phy_link_handler: phy - 4, status - 0
Jan 1 00:00:44 eth_status_IrqHandler: Change switch status
Jan 1 00:00:46 pppd[744]: Couldn't get PPP statistics: No such device
Jan 1 00:00:48 eth_status_IrqHandler: Change switch status
Jan 1 00:00:48 kernel: [17179618.580000] RT305x_ESW: Link Status Changed
Jan 1 00:00:48 resident_phy_link_handler: phy - 4, status - 1
Jan 1 00:00:48 eth_status_IrqHandler: Change switch status
Jan 1 00:00:48 kernel: [17179619.056000] RT305x_ESW: Link Status Changed
Jan 1 00:00:48 resident_phy_link_handler: phy - 4, status - 0
Jan 1 00:00:50 conf_wan[851]: begin wan_conf
Jan 1 00:00:50 conf_wan[851]: CONFIG_ACTION_READ - start
Jan 1 00:00:51 DEBUG!: I AM AWAKEN!!!
Jan 1 00:00:51 conf_wan[872]: begin wan_conf
Jan 1 00:00:51 conf_wan[872]: CONFIG_ACTION_READ - start
Jan 1 00:00:51 conf_wan[883]: begin wan_conf
Jan 1 00:00:51 conf_wan[883]: CONFIG_ACTION_READ - start
Jan 1 00:00:51 conf_wan[889]: begin wan_conf
Jan 1 00:00:51 conf_wan[889]: CONFIG_ACTION_READ - start
Jan 1 00:00:51 eth_status_IrqHandler: Change switch status
Jan 1 00:00:51 kernel: [17179622.172000] RT305x_ESW: Link Status Changed
Jan 1 00:00:51 resident_phy_link_handler: phy - 4, status - 1
Jan 1 00:00:52 kernel: [17179622.452000] RT305x_ESW: Link Status Changed
Jan 1 00:00:52 eth_status_IrqHandler: Change switch status
Jan 1 00:00:52 resident_phy_link_handler: phy - 4, status - 0
Jan 1 00:00:53 conf_wan[896]: begin wan_conf
Jan 1 00:00:53 conf_wan[896]: CONFIG_ACTION_READ - start
Jan 1 00:00:53 conf_wan[905]: begin wan_conf
Jan 1 00:00:53 conf_wan[905]: CONFIG_ACTION_READ - start
Jan 1 00:00:53 eth_status_IrqHandler: Change switch status
Jan 1 00:00:53 kernel: [17179624.140000] RT305x_ESW: Link Status Changed
Jan 1 00:00:53 resident_phy_link_handler: phy - 4, status - 1
Jan 1 00:00:54 conf_wan[939]: begin wan_conf
Jan 1 00:00:54 conf_wan[939]: CONFIG_ACTION_READ - start
Jan 1 00:00:54 kernel: [17179624.976000] RT305x_ESW: Link Status Changed
Jan 1 00:00:54 eth_status_IrqHandler: Change switch status
Jan 1 00:00:54 resident_phy_link_handler: phy - 4, status - 0
Jan 1 00:00:55 conf_wan[950]: begin wan_conf
Jan 1 00:00:55 conf_wan[950]: CONFIG_ACTION_READ - start
Jan 1 00:00:55 conf_wan[958]: begin wan_conf
Jan 1 00:00:55 conf_wan[958]: CONFIG_ACTION_READ - start
Jan 1 00:00:58 conf_wan[979]: begin wan_conf
Jan 1 00:00:58 conf_wan[979]: CONFIG_ACTION_READ - start
Jan 1 00:01:01 pppd[744]: Timeout waiting for PADO packets
Jan 1 00:01:01 pppd[744]: Unable to complete PPPoE Discovery
Jan 1 00:01:01 pppd[744]: lcp_close: unit:0, reason: "" (status: 0, state: 1, was_ip_down: 0)
Jan 1 00:01:01 resident_ppp_handler: record = action:down;iface:ppp0;status:0;link:eth2.5_4;
Jan 1 00:01:01 resident: resident_ipoe_handler - Set default type: ipv4
Jan 1 00:01:01 resident: resident_ppp_handler - l2:eth2.5, l3:eth2.5_4, tun:
Jan 1 00:01:01 ppp_handler: ppp state 0
Jan 1 00:01:01 ppp_handler: Restart pppd
Jan 1 00:01:01 ppp_handler: sleep to 10 sec
Jan 1 00:01:02 kernel: [17179632.648000] RT305x_ESW: Link Status Changed
Jan 1 00:01:02 resident_phy_link_handler: phy - 4, status - 1
Jan 1 00:01:02 eth_status_IrqHandler: Change switch status
Jan 1 00:01:03 kernel: [17179633.520000] RT305x_ESW: Link Status Changed
Jan 1 00:01:03 resident_phy_link_handler: phy - 4, status - 0
Jan 1 00:01:03 eth_status_IrqHandler: Change switch status
Jan 1 00:01:04 conf_wan[997]: begin wan_conf
Jan 1 00:01:04 conf_wan[997]: CONFIG_ACTION_READ - start
Jan 1 00:01:06 pppd[744]: Couldn't get PPP statistics: No such device
Jan 1 00:01:06 kernel: [17179636.760000] RT305x_ESW: Link Status Changed
Jan 1 00:01:06 resident_phy_link_handler: phy - 4, status - 1
Jan 1 00:01:06 eth_status_IrqHandler: Change switch status
Jan 1 00:01:07 kernel: [17179637.532000] RT305x_ESW: Link Status Changed
Jan 1 00:01:07 eth_status_IrqHandler: Change switch status
Jan 1 00:01:07 resident_phy_link_handler: phy - 4, status - 0
Jan 1 00:01:12 kernel: [17179642.596000] RT305x_ESW: Link Status Changed
Jan 1 00:01:12 eth_status_IrqHandler: Change switch status
Jan 1 00:01:12 resident_phy_link_handler: phy - 4, status - 1
Jan 1 00:01:13 kernel: [17179643.392000] RT305x_ESW: Link Status Changed
Jan 1 00:01:13 eth_status_IrqHandler: Change switch status
Jan 1 00:01:13 resident_phy_link_handler: phy - 4, status - 0
Jan 1 00:01:13 conf_wan[1014]: begin wan_conf
Jan 1 00:01:13 conf_wan[1014]: CONFIG_ACTION_READ - start
Jan 1 00:01:21 pppd[744]: Timeout waiting for PADO packets
Jan 1 00:01:21 pppd[744]: Unable to complete PPPoE Discovery
Jan 1 00:01:21 pppd[744]: lcp_close: unit:0, reason: "" (status: 0, state: 1, was_ip_down: 0)
Jan 1 00:01:21 resident_ppp_handler: record = action:down;iface:ppp0;status:0;link:eth2.5_4;
Jan 1 00:01:21 resident: resident_ipoe_handler - Set default type: ipv4
Jan 1 00:01:21 resident: resident_ppp_handler - l2:eth2.5, l3:eth2.5_4, tun:
Jan 1 00:01:21 ppp_handler: ppp state 0
Jan 1 00:01:21 ppp_handler: Restart pppd
Jan 1 00:01:21 ppp_handler: sleep to 10 sec
Jan 1 00:01:21 resident: stop pppd on eth2.5_4
Jan 1 00:01:21 stop_pppd: Error type
Jan 1 00:01:25 kernel: [17179655.280000] RT305x_ESW: Link Status Changed
Jan 1 00:01:25 eth_status_IrqHandler: Change switch status
Jan 1 00:01:25 resident_phy_link_handler: phy - 4, status - 1
Jan 1 00:01:25 kernel: [17179656.048000] RT305x_ESW: Link Status Changed
Jan 1 00:01:25 eth_status_IrqHandler: Change switch status
Jan 1 00:01:25 resident_phy_link_handler: phy - 4, status - 0
Jan 1 00:01:26 pppd[744]: Couldn't get PPP statistics: No such device
Jan 1 00:01:27 conf_wan[1027]: begin wan_conf
Jan 1 00:01:27 conf_wan[1027]: CONFIG_ACTION_READ - start