Jan  1 00:06:12 wan_down[2069]: name = ppp0
Jan  1 00:06:12 update_wan_firewall[2069]: firewall enable
Jan  1 00:06:12 get_gr_name_by_l3_key[2069]: start
Jan  1 00:06:12 get_gr_name_by_l3_key[2069]: br0
Jan  1 00:06:12 get_gr_index[2069]: gr=|0| 
Jan  1 00:06:12 pppd[1921]: Connect time 0.3 minutes.
Jan  1 00:06:12 pppd[1921]: Sent 21385 bytes, received 19262 bytes.
Jan  1 00:06:12 pppd[1921]: Down ppp with status (5)5
Jan  1 00:06:12 miniupnpd[211]: ioctl(s, SIOCGIFADDR, ...): Cannot assign requested address
Jan  1 00:06:12 miniupnpd[211]: Failed to get IP for interface ppp0
Jan  1 00:06:12 miniupnpd[211]: SendNATPMPPublicAddressChangeNotification: cannot get public IP address, stopping
Jan  1 00:06:12 resident_ppp_handler[2080]: record = action:trouble;iface:ppp0;status:5;link:vc0_2;
Jan  1 00:06:12 resident_ppp_handler[2080]: Set default type: ipv4
Jan  1 00:06:12 event[2081]:  ppp_down - do event
Jan  1 00:06:12 resident_ppp_handler[2084]: record = action:down;iface:ppp0;link:vc0_2;status:5;
Jan  1 00:06:12 resident_ppp_handler[2084]: Set default type: ipv4
Jan  1 00:06:12 DMS_NL_API[2069]: Rtnetlink answer: Success
Jan  1 00:06:12 DMS_ROUTE_SUCCESS[2069]: DEL default via (null) dev (null) metr 0 table 254 (wan_iptables_rules)
Jan  1 00:06:12 rlx_modem[2069]: only_bridges  routed connectons exist
Jan  1 00:06:12 triggerPingRespond[2069]: OK
Jan  1 00:06:12 update_igmpx[2069]: begin
Jan  1 00:06:12 get_gr_name_by_l3_key[2069]: start
Jan  1 00:06:12 update_igmpx[2069]: igmpx cfg: upstream list empty downstream list empty
Jan  1 00:06:12 stop_igmpx[2069]: stop igmpx
Jan  1 00:06:12 stop_process_t[2069]: not found pid process 'igmpx'
Jan  1 00:06:12 resident[2069]: stopped link(contype:ppp, iface:vc0_2)
Jan  1 00:06:12 after_stop_pppd[2069]: 
Jan  1 00:06:12 start_stop_wan_link_on_l2[2069]: End iface: vc0, status: 0
Jan  1 00:06:12 pppd[1921]: Modem hangup
Jan  1 00:06:12 pppd[1921]: Connection terminated.
Jan  1 00:06:12 kernel: Enter _rtl865x_detachMasterNetif (slave : ppp0 ) 
Jan  1 00:06:12 kernel: _rtl865x_detachMasterNetif 418 ppp0
Jan  1 00:06:12 kernel: (_rtl865x_detachMasterNetif) slave_netif : ppp0 
Jan  1 00:06:12 kernel: detach ppp0 success.
Jan  1 00:06:12 kernel: Leave _rtl865x_detachMasterNetif
Jan  1 00:06:12 kernel: _rtl865x_getVlanFilterDatabaseId(336):the vlan is invalid!!!BUG!!!!
Jan  1 00:06:12 kernel: ATM OAM F5 initialized.
Jan  1 00:06:12 kernel: ATM OAM F4 initialized.
Jan  1 00:06:12 kernel: Enable 8671G 1 function
Jan  1 00:06:12 kernel: Enable 8671 0 function
Jan  1 00:06:12 kernel: Enable 8672 function 
Jan  1 00:06:12 kernel: applying workaround...done
Jan  1 00:06:12 kernel: sar_close
Jan  1 00:06:12 rlx_modem[2069]: remove atm =|vc0| done!!! 
Jan  1 00:06:12 kernel: Enter _rtl865x_detachMasterNetif (slave : ppp0 ) 
Jan  1 00:06:12 kernel: _rtl865x_detachMasterNetif 418 ppp0
Jan  1 00:06:12 kernel: (_rtl865x_detachMasterNetif) slave_netif : ppp0 
Jan  1 00:06:12 kernel: detach ppp0 success.
Jan  1 00:06:12 pppd[1921]: sendPacket: send: No such device or address
Jan  1 00:06:12 pppd[1921]: Exit.(1)
Jan  1 00:06:12 pppd[1921]: Function die(). Reinit connection (req_unit 0)
Jan  1 00:06:12 resident_ppp_handler[2108]: record = action:down;iface:ppp0;status:1;link:vc0_2;
Jan  1 00:06:12 kernel: Leave _rtl865x_detachMasterNetif
Jan  1 00:06:12 kernel: vcc_sock_destruct: rmem leakage (-172720 bytes) detected.
Jan  1 00:06:12 resident_ppp_handler[2108]: Set default type: ipv4
Jan  1 00:06:13 resident_ppp_handler[2080]: l2:vc0, l3:vc0_2, tun:||
Jan  1 00:06:13 resident_ppp_handler[2084]: l2:vc0, l3:vc0_2, tun:||
Jan  1 00:06:13 resident_ppp_handler[2084]: ppp state 5
Jan  1 00:06:13 resident_ppp_handler[2084]: status: 5 (5)
Jan  1 00:06:13 resident[2084]: ppp_is_down on vc0_2
Jan  1 00:06:13 wan_down[2084]: name = ppp0
Jan  1 00:06:13 update_igmpx[2084]: begin
Jan  1 00:06:13 get_gr_name_by_l3_key[2084]: start
Jan  1 00:06:13 update_igmpx[2084]: igmpx cfg: upstream list empty downstream list empty
Jan  1 00:06:13 stop_igmpx[2084]: stop igmpx
Jan  1 00:06:13 stop_process_t[2084]: not found pid process 'igmpx'
Jan  1 00:06:13 rlx_modem[2084]: only_bridges  routed connectons exist
Jan  1 00:06:13 autoupdate[2084]: Config reset
Jan  1 00:06:13 resident_ppp_handler[2108]: l2:vc0, l3:vc0_2, tun:||
Jan  1 00:06:13 resident_ppp_handler[2108]: ppp state 1
Jan  1 00:06:13 resident_ppp_handler[2108]: Restart pppd
Jan  1 00:06:20 start_pppd[2108]: start pppd on vc0_2
Jan  1 00:06:20 stop_pppd[2108]: stop pppd on vc0_2
Jan  1 00:06:20 resident[2108]: set lock on vc0_2, with "/var/lock/vc0_2.lock"
Jan  1 00:06:20 wan_down[2108]: name = ppp0
Jan  1 00:06:20 update_igmpx[2108]: begin
Jan  1 00:06:20 get_gr_name_by_l3_key[2108]: start
Jan  1 00:06:20 update_igmpx[2108]: igmpx cfg: upstream list empty downstream list empty
Jan  1 00:06:20 stop_igmpx[2108]: stop igmpx
Jan  1 00:06:20 stop_process_t[2108]: not found pid process 'igmpx'
Jan  1 00:06:20 resident[2108]: stopped link(contype:ppp, iface:vc0_2)
Jan  1 00:06:20 after_stop_pppd[2108]: 
Jan  1 00:06:20 before_start_pppd[2108]: 
Jan  1 00:06:22 start_pppd[2108]: using old session parameters: 63592:F8:75:88:86:CC:90
Jan  1 00:06:22 pppd[2145]: Plugin /usr/lib/pppd/rp-pppoe.so loaded.
Jan  1 00:06:22 pppd[2145]: In file /etc/ppp/peers/vc0_2: unrecognized option 'vc0'
Jan  1 00:06:24 kernel: SachemAccess::reset()
Jan  1 00:06:25 stop_pppd[2154]: stop pppd on vc0_2
Jan  1 00:06:25 resident[2154]: set lock on vc0_2, with "/var/lock/vc0_2.lock"
Jan  1 00:06:26 wan_down[2154]: name = ppp0
Jan  1 00:06:26 update_igmpx[2154]: begin
Jan  1 00:06:26 get_gr_name_by_l3_key[2154]: start
Jan  1 00:06:26 update_igmpx[2154]: igmpx cfg: upstream list empty downstream list empty
Jan  1 00:06:26 stop_igmpx[2154]: stop igmpx
Jan  1 00:06:26 stop_process_t[2154]: not found pid process 'igmpx'
Jan  1 00:06:26 resident[2154]: stopped link(contype:ppp, iface:vc0_2)
Jan  1 00:06:26 after_stop_pppd[2154]: 
Jan  1 00:06:26 start_pppd[2154]: start pppd on vc0_2
Jan  1 00:06:26 stop_pppd[2154]: stop pppd on vc0_2
Jan  1 00:06:26 resident[2154]: set lock on vc0_2, with "/var/lock/vc0_2.lock"
Jan  1 00:06:26 wan_down[2154]: name = ppp0
Jan  1 00:06:26 update_igmpx[2154]: begin
Jan  1 00:06:26 get_gr_name_by_l3_key[2154]: start
Jan  1 00:06:26 update_igmpx[2154]: igmpx cfg: upstream list empty downstream list empty
Jan  1 00:06:26 stop_igmpx[2154]: stop igmpx
Jan  1 00:06:26 stop_process_t[2154]: not found pid process 'igmpx'
Jan  1 00:06:26 resident[2154]: stopped link(contype:ppp, iface:vc0_2)
Jan  1 00:06:26 after_stop_pppd[2154]: 
Jan  1 00:06:26 before_start_pppd[2154]: 
Jan  1 00:06:28 start_pppd[2154]: using old session parameters: 63592:F8:75:88:86:CC:90
Jan  1 00:06:28 pppd[2166]: Plugin /usr/lib/pppd/rp-pppoe.so loaded.
Jan  1 00:06:28 pppd[2166]: In file /etc/ppp/peers/vc0_2: unrecognized option 'vc0'
Jan  1 00:06:31 save config[2172]: saving... at line 176
Jan  1 00:06:31 write[2172]: line 1321
Jan  1 00:06:31 save_to_flash[2172]: file size is 3374
Jan  1 00:06:31 save config[2172]: saving... at line 197
Jan  1 00:06:35 kernel: SachemAccess::reset()
Jan  1 00:06:36 kernel: SachemAccess::reset()
Jan  1 00:06:37 kernel: SachemAccess::reset()
Jan  1 00:06:50 kernel: SachemAccess::reset()
Jan  1 00:06:52 kernel: SachemAccess::reset()
Jan  1 00:07:00 update_ntpclient[2304]: start
Jan  1 00:07:00 update_ntpclient[2304]: ntpclient is disable
Jan  1 00:07:10 kernel: ATM OAM F5 initialized.
Jan  1 00:07:10 kernel: ATM OAM F4 initialized.
Jan  1 00:07:10 kernel: Enable 8671G 1 function
Jan  1 00:07:10 kernel: Enable 8671 0 function
Jan  1 00:07:10 kernel: Enable 8672 function 
Jan  1 00:07:10 kernel: applying workaround...done
Jan  1 00:07:10 kernel: Upstream Rate : 1021 Kbps (line rate : 1228)
Jan  1 00:07:10 kernel: Downstream Rate : 11145 Kbps (line rate : 12184)
Jan  1 00:07:19 resident[2377]: resident_adsl_link_handler start!!! 
Jan  1 00:07:19 resident[2377]: record = type:atm
Jan  1 00:07:19 rlx_modem[2377]: create atm start!!! 
Jan  1 00:07:19 rlx_modem[2377]: atmnum=1
Jan  1 00:07:19 rlx_modem[2377]: cmd sarctl pvcnumber 1!!! 
Jan  1 00:07:19 kernel: sar_ioctl: called. cmd=0x8a05, arg=7f95709c
Jan  1 00:07:19 kernel: PVC Number = 1. Set Desc number per VC = 126
Jan  1 00:07:19 rlx_modem[2377]: cmd mpoactl add vc0 pvc 0.35 encaps 1 qos ubr:pcr=6000!!! 
Jan  1 00:07:19 syslog: Interface "vc0" created sucessfully
Jan  1 00:07:19 syslog: Communicating over ATM 0.0.35, encapsulation: 1
Jan  1 00:07:19 kernel: fixme atm_find_ci in sar_open!
Jan  1 00:07:19 kernel: (itf 0): open 0.35
Jan  1 00:07:19 kernel: create: ch0 (0/35) 6000,0
Jan  1 00:07:19 kernel: ATM OAM F5 initialized.
Jan  1 00:07:19 kernel: ATM OAM F4 initialized.
Jan  1 00:07:19 kernel: Enable 8671G 1 function
Jan  1 00:07:19 kernel: Enable 8671 0 function
Jan  1 00:07:19 kernel: Enable 8672 function 
Jan  1 00:07:19 kernel: create: ch0 (0/35) 6000,0
Jan  1 00:07:19 kernel: net_device: 2160497664
Jan  1 00:07:19 kernel: applying workaround...done
Jan  1 00:07:19 kernel: sar_ioctl: called. cmd=0x8a0f, arg=00415008
Jan  1 00:07:19 kernel: sar_ioctl: SAR_SET_SARHDR called.
Jan  1 00:07:19 kernel: vpi=0, vci = 35
Jan  1 00:07:19 kernel: Set CH 0 PPPoE mode on CKS register
Jan  1 00:07:19 kernel: sar_ioctl: SAR_SET_SARHDR done.
Jan  1 00:07:19 syslog: Interface configured
Jan  1 00:07:19 kernel: sar_ioctl: called. cmd=0x8a08, arg=817fbb88
Jan  1 00:07:19 kernel: sar_ioctl: called. cmd=0x8a10, arg=(null)
Jan  1 00:07:19 rlx_modem[2377]:  mpoactl set vc0 vlan 0 vid 0 vprio 0
Jan  1 00:07:19 rlx_modem[2377]:  ifconfig vc0 mtu 1500
Jan  1 00:07:20 start_stop_wan_link_on_l2[2377]: start with iface: vc0, status: 1
Jan  1 00:07:20 resident[2377]: start ipoe 
Jan  1 00:07:20 before_start_ip[2377]: 
Jan  1 00:07:20 start_ip[2377]: begin (vc0 -> vc0_1)
Jan  1 00:07:20 resident[2377]: start ipoe (v0) on vc0
Jan  1 00:07:20 start_ip[2377]: pos = 11
Jan  1 00:07:20 start_pppd[2377]: start pppd on vc0_2
Jan  1 00:07:20 stop_pppd[2377]: stop pppd on vc0_2
Jan  1 00:07:20 resident[2377]: set lock on vc0_2, with "/var/lock/vc0_2.lock"
Jan  1 00:07:20 udhcpc[2424]: UDHCP start..
Jan  1 00:07:20 udhcpc[2424]: udhcp client (v0.9.8) started (iface: vc0, connect: 1)
Jan  1 00:07:20 udhcpc[2424]: interface vc0 index 25
Jan  1 00:07:20 wan_down[2377]: name = ppp0
Jan  1 00:07:20 update_igmpx[2377]: begin
Jan  1 00:07:20 udhcpc[2424]: interface vc0 hwaddr 78:32:1b:5a:6e:48
Jan  1 00:07:20 udhcpc[2424]: interface vc0 mtu is 1500
Jan  1 00:07:20 udhcpc[2426]: execle'ing /tmp/udhcpc with name deconfig
Jan  1 00:07:20 event[2426]: send event "ipoe down"
Jan  1 00:07:20 resident[218]: record = action:down;iface:vc0;contag:1;
Jan  1 00:07:20 resident[218]: phys_iface vc0
Jan  1 00:07:20 resident_ipoe_handler[218]: ip_type = <null>
Jan  1 00:07:20 resident[218]: resident_ipoe_handler - Set default type: ipv4
Jan  1 00:07:20 resident[218]: phys_iface vc0
Jan  1 00:07:20 udhcpc[2424]: Opening raw socket on ifindex 25
Jan  1 00:07:20 udhcpc[2424]: Sending discover...
Jan  1 00:07:20 get_gr_name_by_l3_key[2377]: start
Jan  1 00:07:20 resident[218]: resident_ipoe_handler - 2
Jan  1 00:07:20 update_igmpx[2377]: igmpx cfg: upstream list empty downstream list empty
Jan  1 00:07:20 resident_ipoe_handler[218]: name: vc0_1
Jan  1 00:07:20 stop_igmpx[2377]: stop igmpx
Jan  1 00:07:20 stop_process_t[2377]: not found pid process 'igmpx'
Jan  1 00:07:20 resident[2377]: stopped link(contype:ppp, iface:vc0_2)
Jan  1 00:07:20 after_stop_pppd[2377]: 
Jan  1 00:07:20 before_start_pppd[2377]: 
Jan  1 00:07:22 udhcpc[2424]: Sending discover...
Jan  1 00:07:22 start_pppd[2377]: using old session parameters: 63592:F8:75:88:86:CC:90
Jan  1 00:07:22 pppd[2433]: Plugin /usr/lib/pppd/rp-pppoe.so loaded.
Jan  1 00:07:22 pppd[2434]: pppd 2.4.4 started by admin, uid 0
Jan  1 00:07:25 start_stop_wan_link_on_l2[2377]: End iface: vc0, status: 1
Jan  1 00:07:25 resident[218]: resident_ipoe_handler - l2:vc0, l3:vc0_1, tun:, ip_type:0
Jan  1 00:07:25 resident[218]: ip_is_down on vc0_1
Jan  1 00:07:25 resident_ipoe_handler[218]: is done on iface vc0_1 with action down
Jan  1 00:07:25 resident_ipoe_handler[218]: exit
Jan  1 00:07:25 stop_pppd[2455]: stop pppd on vc0_2
Jan  1 00:07:25 resident[2455]: set lock on vc0_2, with "/var/lock/vc0_2.lock"
Jan  1 00:07:25 pppd[2434]: Sent PADT
Jan  1 00:07:25 wan_down[2455]: name = ppp0
Jan  1 00:07:25 update_igmpx[2455]: begin
Jan  1 00:07:26 get_gr_name_by_l3_key[2455]: start
Jan  1 00:07:26 update_igmpx[2455]: igmpx cfg: upstream list empty downstream list empty
Jan  1 00:07:26 stop_igmpx[2455]: stop igmpx
Jan  1 00:07:26 stop_process_t[2455]: not found pid process 'igmpx'
Jan  1 00:07:26 resident[2455]: stopped link(contype:ppp, iface:vc0_2)
Jan  1 00:07:26 after_stop_pppd[2455]: 
Jan  1 00:07:26 start_pppd[2455]: start pppd on vc0_2
Jan  1 00:07:26 stop_pppd[2455]: stop pppd on vc0_2
Jan  1 00:07:26 resident[2455]: set lock on vc0_2, with "/var/lock/vc0_2.lock"
Jan  1 00:07:26 wan_down[2455]: name = ppp0
Jan  1 00:07:26 update_igmpx[2455]: begin
Jan  1 00:07:26 get_gr_name_by_l3_key[2455]: start
Jan  1 00:07:26 update_igmpx[2455]: igmpx cfg: upstream list empty downstream list empty
Jan  1 00:07:26 stop_igmpx[2455]: stop igmpx
Jan  1 00:07:26 stop_process_t[2455]: not found pid process 'igmpx'
Jan  1 00:07:26 resident[2455]: stopped link(contype:ppp, iface:vc0_2)
Jan  1 00:07:26 after_stop_pppd[2455]: 
Jan  1 00:07:26 before_start_pppd[2455]: 
Jan  1 00:07:26 udhcpc[2424]: Sending discover...
Jan  1 00:07:28 start_pppd[2455]: using old session parameters: 63592:F8:75:88:86:CC:90
Jan  1 00:07:28 pppd[2468]: Plugin /usr/lib/pppd/rp-pppoe.so loaded.
Jan  1 00:07:28 pppd[2469]: pppd 2.4.4 started by admin, uid 0
Jan  1 00:07:30 resident_ppp_handler[2472]: record = action:trouble;iface:ppp0;status:101;link:vc0_2;
Jan  1 00:07:30 resident_ppp_handler[2472]: Set default type: ipv4
Jan  1 00:07:31 resident_ppp_handler[2472]: l2:vc0, l3:vc0_2, tun:||
Jan  1 00:07:31 dms_get_wanport_status[2472]: L2 iface: vc0
Jan  1 00:07:31 dms_get_wanport_status[2472]: WAN port phy type: DSL
Jan  1 00:07:31 dms_get_wanport_status[2472]: Link status: Up
Jan  1 00:07:31 save config[2473]: saving... at line 176
Jan  1 00:07:31 write[2473]: line 1321
Jan  1 00:07:31 save_to_flash[2473]: file size is 3361
Jan  1 00:07:31 save config[2473]: saving... at line 197
Jan  1 00:07:35 pppd[2469]: Sent PADT
Jan  1 00:07:35 kernel: SachemAccess::reset()
Jan  1 00:07:35 resident[2534]: resident_adsl_unlink_handler start!!! 
Jan  1 00:07:35 resident[2534]: record = type:atm
Jan  1 00:07:35 resident[2534]: remove_atm vc0
Jan  1 00:07:35 start_stop_wan_link_on_l2[2534]: start with iface: vc0, status: 0
Jan  1 00:07:35 resident[2534]: stop ipoe on vc0_1
Jan  1 00:07:35 resident[2534]: set lock /var/lock/vc0_1.lock
Jan  1 00:07:35 udhcpc[2424]: Sending release...
Jan  1 00:07:35 udhcpc[2424]: kernel_packet: Socket Result = 532
Jan  1 00:07:35 dnsmasq-dhcp[180]: no address range available for DHCP request via lo
Jan  1 00:07:35 wan_down[2534]: name = vc0
Jan  1 00:07:35 update_igmpx[2534]: begin
Jan  1 00:07:35 udhcpc[2535]: execle'ing /tmp/udhcpc with name deconfig
Jan  1 00:07:35 event[2535]: file "/var/lock/vc0_1.lock" is set
Jan  1 00:07:35 event[2535]: udhcp_down fake
Jan  1 00:07:35 get_gr_name_by_l3_key[2534]: start
Jan  1 00:07:35 update_igmpx[2534]: igmpx cfg: upstream list empty downstream list empty
Jan  1 00:07:35 stop_igmpx[2534]: stop igmpx
Jan  1 00:07:35 udhcpc[2424]: Received SIGTERM
Jan  1 00:07:35 stop_process_t[2534]: not found pid process 'igmpx'
Jan  1 00:07:35 dms_reset_uptime[2534]: enter key vc0_1
Jan  1 00:07:35 dms_reset_uptime[2534]: unable to remove file /tmp/stat.vc0_1
Jan  1 00:07:35 after_stop_ip[2534]: 
Jan  1 00:07:35 stop_pppd[2534]: stop pppd on vc0_2
Jan  1 00:07:35 resident[2534]: set lock on vc0_2, with "/var/lock/vc0_2.lock"
Jan  1 00:07:35 wan_down[2534]: name = ppp0
Jan  1 00:07:35 update_igmpx[2534]: begin
Jan  1 00:07:35 get_gr_name_by_l3_key[2534]: start
Jan  1 00:07:35 update_igmpx[2534]: igmpx cfg: upstream list empty downstream list empty
Jan  1 00:07:35 stop_igmpx[2534]: stop igmpx
Jan  1 00:07:35 stop_process_t[2534]: not found pid process 'igmpx'
Jan  1 00:07:35 resident[2534]: stopped link(contype:ppp, iface:vc0_2)
Jan  1 00:07:35 after_stop_pppd[2534]: 
Jan  1 00:07:35 start_stop_wan_link_on_l2[2534]: End iface: vc0, status: 0
Jan  1 00:07:35 pppd[2469]: receivePacket: recv: Network is down
Jan  1 00:07:35 pppd[2469]: Exit.(1)
Jan  1 00:07:35 pppd[2469]: Function die(). Reinit connection (req_unit 0)
Jan  1 00:07:35 pppd[2434]: receivePacket: recv: Network is down
Jan  1 00:07:35 pppd[2434]: Exit.(1)
Jan  1 00:07:35 pppd[2434]: Function die(). Reinit connection (req_unit 0)
Jan  1 00:07:35 resident_ppp_handler[2551]: record = action:down;iface:ppp0;status:1;link:vc0_2;
Jan  1 00:07:35 resident_ppp_handler[2551]: Set default type: ipv4
Jan  1 00:07:35 resident_ppp_handler[2553]: record = action:down;iface:ppp0;status:1;link:vc0_2;
Jan  1 00:07:35 resident_ppp_handler[2553]: Set default type: ipv4
Jan  1 00:07:36 kernel: ATM OAM F5 initialized.
Jan  1 00:07:36 kernel: ATM OAM F4 initialized.
Jan  1 00:07:36 kernel: Enable 8671G 1 function
Jan  1 00:07:36 kernel: Enable 8671 0 function
Jan  1 00:07:36 kernel: Enable 8672 function 
Jan  1 00:07:36 kernel: applying workaround...done
Jan  1 00:07:36 kernel: sar_close
Jan  1 00:07:36 rlx_modem[2534]: remove atm =|vc0| done!!! 
Jan  1 00:07:36 kernel: vcc_sock_destruct: rmem leakage (-2032 bytes) detected.
Jan  1 00:07:37 resident_ppp_handler[2551]: l2:vc0, l3:vc0_2, tun:||
Jan  1 00:07:37 resident_ppp_handler[2551]: ppp state 1
Jan  1 00:07:37 resident_ppp_handler[2551]: Restart pppd
Jan  1 00:07:37 resident_ppp_handler[2553]: l2:vc0, l3:vc0_2, tun:||
Jan  1 00:07:37 resident_ppp_handler[2553]: ppp state 1
Jan  1 00:07:37 resident_ppp_handler[2553]: Restart pppd
Jan  1 00:07:44 start_pppd[2551]: start pppd on vc0_2
Jan  1 00:07:44 stop_pppd[2551]: stop pppd on vc0_2
Jan  1 00:07:44 resident[2551]: set lock on vc0_2, with "/var/lock/vc0_2.lock"
Jan  1 00:07:44 wan_down[2551]: name = ppp0
Jan  1 00:07:44 update_igmpx[2551]: begin
Jan  1 00:07:44 get_gr_name_by_l3_key[2551]: start
Jan  1 00:07:44 update_igmpx[2551]: igmpx cfg: upstream list empty downstream list empty
Jan  1 00:07:44 stop_igmpx[2551]: stop igmpx
Jan  1 00:07:44 stop_process_t[2551]: not found pid process 'igmpx'
Jan  1 00:07:44 resident[2551]: stopped link(contype:ppp, iface:vc0_2)
Jan  1 00:07:44 after_stop_pppd[2551]: 
Jan  1 00:07:44 before_start_pppd[2551]: 
Jan  1 00:07:46 start_pppd[2551]: using old session parameters: 63592:F8:75:88:86:CC:90
Jan  1 00:07:46 pppd[2591]: Plugin /usr/lib/pppd/rp-pppoe.so loaded.
Jan  1 00:07:46 pppd[2591]: In file /etc/ppp/peers/vc0_2: unrecognized option 'vc0'
Jan  1 00:07:48 kernel: SachemAccess::reset()
Jan  1 00:07:49 start_pppd[2553]: start pppd on vc0_2
Jan  1 00:07:49 stop_pppd[2553]: stop pppd on vc0_2
Jan  1 00:07:49 resident[2553]: set lock on vc0_2, with "/var/lock/vc0_2.lock"
Jan  1 00:07:49 wan_down[2553]: name = ppp0
Jan  1 00:07:49 update_igmpx[2553]: begin
Jan  1 00:07:49 get_gr_name_by_l3_key[2553]: start
Jan  1 00:07:49 update_igmpx[2553]: igmpx cfg: upstream list empty downstream list empty
Jan  1 00:07:49 stop_igmpx[2553]: stop igmpx
Jan  1 00:07:49 stop_process_t[2553]: not found pid process 'igmpx'
Jan  1 00:07:49 resident[2553]: stopped link(contype:ppp, iface:vc0_2)
Jan  1 00:07:49 after_stop_pppd[2553]: 
Jan  1 00:07:49 before_start_pppd[2553]: 
Jan  1 00:07:50 kernel: SachemAccess::reset()
Jan  1 00:07:50 kernel: SachemAccess::reset()
Jan  1 00:07:50 kernel: SachemAccess::reset()
Jan  1 00:07:50 kernel: SachemAccess::reset()
Jan  1 00:07:50 kernel: SachemAccess::reset()
Jan  1 00:07:51 start_pppd[2553]: using old session parameters: 63592:F8:75:88:86:CC:90
Jan  1 00:07:51 pppd[2603]: Plugin /usr/lib/pppd/rp-pppoe.so loaded.
Jan  1 00:07:51 pppd[2603]: In file /etc/ppp/peers/vc0_2: unrecognized option 'vc0'
Jan  1 00:07:53 kernel: SachemAccess::reset()
Jan  1 00:07:53 kernel: xtm_elapse() = 465910
Jan  1 00:07:53 kernel: LD Power down to avoid huge current
Jan  1 00:07:53 kernel: TxIdxTxIdx = 0
Jan  1 00:07:53 kernel: OKnum=512
Jan  1 00:07:53 kernel: DDR phase 0xd OK
Jan  1 00:07:53 kernel: OKnum=512
Jan  1 00:07:53 kernel: DDR phase 0x1 OK
Jan  1 00:07:53 kernel: OKnum=22
Jan  1 00:07:53 kernel: OKnum=22
Jan  1 00:07:53 kernel: OKnum=34
Jan  1 00:07:53 kernel: OKnum=512
Jan  1 00:07:58 kernel: Time_elapse(1) = 471110
Jan  1 00:07:58 kernel: SachemAccess::reset()
Jan  1 00:08:00 update_ntpclient[2627]: start
Jan  1 00:08:00 update_ntpclient[2627]: ntpclient is disable
Jan  1 00:08:11 kernel: SachemAccess::reset()
Jan  1 00:08:25 kernel: SachemAccess::reset()
Jan  1 00:08:42 kernel: ATM OAM F5 initialized.
Jan  1 00:08:42 kernel: ATM OAM F4 initialized.
Jan  1 00:08:42 kernel: Enable 8671G 1 function
Jan  1 00:08:42 kernel: Enable 8671 0 function
Jan  1 00:08:42 kernel: Enable 8672 function 
Jan  1 00:08:42 kernel: applying workaround...done
Jan  1 00:08:42 kernel: Upstream Rate : 1021 Kbps (line rate : 1228)
Jan  1 00:08:42 kernel: Downstream Rate : 12292 Kbps (line rate : 13168)
Jan  1 00:08:44 kernel: Into the RxOAMF5Cell 128
Jan  1 00:08:44 kernel: Leave the OAMRxF5Cell
Jan  1 00:08:50 resident[2820]: resident_adsl_link_handler start!!! 
Jan  1 00:08:50 resident[2820]: record = type:atm
Jan  1 00:08:50 rlx_modem[2820]: create atm start!!! 
Jan  1 00:08:50 rlx_modem[2820]: atmnum=1
Jan  1 00:08:50 rlx_modem[2820]: cmd sarctl pvcnumber 1!!! 
Jan  1 00:08:50 kernel: sar_ioctl: called. cmd=0x8a05, arg=7fb45f2c
Jan  1 00:08:50 kernel: PVC Number = 1. Set Desc number per VC = 126
Jan  1 00:08:50 rlx_modem[2820]: cmd mpoactl add vc0 pvc 0.35 encaps 1 qos ubr:pcr=6000!!! 
Jan  1 00:08:50 syslog: Interface "vc0" created sucessfully
Jan  1 00:08:50 syslog: Communicating over ATM 0.0.35, encapsulation: 1
Jan  1 00:08:50 kernel: fixme atm_find_ci in sar_open!
Jan  1 00:08:50 kernel: (itf 0): open 0.35
Jan  1 00:08:50 kernel: create: ch0 (0/35) 6000,0
Jan  1 00:08:50 kernel: ATM OAM F5 initialized.
Jan  1 00:08:50 kernel: ATM OAM F4 initialized.
Jan  1 00:08:50 kernel: Enable 8671G 1 function
Jan  1 00:08:50 kernel: Enable 8671 0 function
Jan  1 00:08:50 kernel: Enable 8672 function 
Jan  1 00:08:50 kernel: create: ch0 (0/35) 6000,0
Jan  1 00:08:50 kernel: net_device: 2160493568
Jan  1 00:08:50 kernel: applying workaround...done
Jan  1 00:08:50 kernel: sar_ioctl: called. cmd=0x8a0f, arg=00415008
Jan  1 00:08:50 kernel: sar_ioctl: SAR_SET_SARHDR called.
Jan  1 00:08:50 kernel: vpi=0, vci = 35
Jan  1 00:08:50 kernel: Set CH 0 PPPoE mode on CKS register
Jan  1 00:08:50 kernel: sar_ioctl: SAR_SET_SARHDR done.
Jan  1 00:08:50 syslog: Interface configured
Jan  1 00:08:50 kernel: sar_ioctl: called. cmd=0x8a08, arg=817fbb88
Jan  1 00:08:50 kernel: sar_ioctl: called. cmd=0x8a10, arg=(null)
Jan  1 00:08:50 rlx_modem[2820]:  mpoactl set vc0 vlan 0 vid 0 vprio 0
Jan  1 00:08:50 rlx_modem[2820]:  ifconfig vc0 mtu 1500
Jan  1 00:08:51 start_stop_wan_link_on_l2[2820]: start with iface: vc0, status: 1
Jan  1 00:08:51 resident[2820]: start ipoe 
Jan  1 00:08:51 before_start_ip[2820]: 
Jan  1 00:08:51 start_ip[2820]: begin (vc0 -> vc0_1)
Jan  1 00:08:51 resident[2820]: start ipoe (v0) on vc0
Jan  1 00:08:51 start_ip[2820]: pos = 11
Jan  1 00:08:51 start_pppd[2820]: start pppd on vc0_2
Jan  1 00:08:51 stop_pppd[2820]: stop pppd on vc0_2
Jan  1 00:08:51 resident[2820]: set lock on vc0_2, with "/var/lock/vc0_2.lock"
Jan  1 00:08:51 udhcpc[2867]: UDHCP start..
Jan  1 00:08:51 udhcpc[2867]: udhcp client (v0.9.8) started (iface: vc0, connect: 1)
Jan  1 00:08:51 udhcpc[2867]: interface vc0 index 26
Jan  1 00:08:51 udhcpc[2867]: interface vc0 hwaddr 78:32:1b:5a:6e:48
Jan  1 00:08:51 udhcpc[2867]: interface vc0 mtu is 1500
Jan  1 00:08:51 udhcpc[2868]: execle'ing /tmp/udhcpc with name deconfig
Jan  1 00:08:51 wan_down[2820]: name = ppp0
Jan  1 00:08:51 update_igmpx[2820]: begin
Jan  1 00:08:51 event[2868]: send event "ipoe down"
Jan  1 00:08:51 udhcpc[2867]: Opening raw socket on ifindex 26
Jan  1 00:08:51 udhcpc[2867]: Sending discover...
Jan  1 00:08:51 resident[218]: record = action:down;iface:vc0;contag:1;
Jan  1 00:08:51 resident[218]: phys_iface vc0
Jan  1 00:08:51 resident_ipoe_handler[218]: ip_type = <null>
Jan  1 00:08:51 resident[218]: resident_ipoe_handler - Set default type: ipv4
Jan  1 00:08:51 resident[218]: phys_iface vc0
Jan  1 00:08:51 resident[218]: resident_ipoe_handler - 2
Jan  1 00:08:51 resident_ipoe_handler[218]: name: vc0_1
Jan  1 00:08:51 get_gr_name_by_l3_key[2820]: start
Jan  1 00:08:51 update_igmpx[2820]: igmpx cfg: upstream list empty downstream list empty
Jan  1 00:08:51 stop_igmpx[2820]: stop igmpx
Jan  1 00:08:51 stop_process_t[2820]: not found pid process 'igmpx'
Jan  1 00:08:51 resident[2820]: stopped link(contype:ppp, iface:vc0_2)
Jan  1 00:08:51 after_stop_pppd[2820]: 
Jan  1 00:08:51 before_start_pppd[2820]: 
Jan  1 00:08:53 udhcpc[2867]: Sending discover...
Jan  1 00:08:53 start_pppd[2820]: using old session parameters: 63592:F8:75:88:86:CC:90
Jan  1 00:08:53 pppd[2876]: Plugin /usr/lib/pppd/rp-pppoe.so loaded.
Jan  1 00:08:53 pppd[2877]: pppd 2.4.4 started by admin, uid 0
Jan  1 00:08:56 start_stop_wan_link_on_l2[2820]: End iface: vc0, status: 1
Jan  1 00:08:56 resident[218]: resident_ipoe_handler - l2:vc0, l3:vc0_1, tun:, ip_type:0
Jan  1 00:08:56 resident[218]: ip_is_down on vc0_1
Jan  1 00:08:56 resident_ipoe_handler[218]: is done on iface vc0_1 with action down
Jan  1 00:08:56 resident_ipoe_handler[218]: exit
Jan  1 00:08:57 udhcpc[2867]: Sending discover...
Jan  1 00:08:57 kernel: SachemAccess::reset()
Jan  1 00:08:58 resident[2909]: resident_adsl_unlink_handler start!!! 
Jan  1 00:08:58 resident[2909]: record = type:atm
Jan  1 00:08:58 resident[2909]: remove_atm vc0
Jan  1 00:08:58 start_stop_wan_link_on_l2[2909]: start with iface: vc0, status: 0
Jan  1 00:08:58 resident[2909]: stop ipoe on vc0_1
Jan  1 00:08:58 resident[2909]: set lock /var/lock/vc0_1.lock
Jan  1 00:08:58 udhcpc[2867]: Sending release...
Jan  1 00:08:58 udhcpc[2867]: kernel_packet: Socket Result = 532
Jan  1 00:08:58 dnsmasq-dhcp[180]: no address range available for DHCP request via lo
Jan  1 00:08:58 wan_down[2909]: name = vc0
Jan  1 00:08:58 update_igmpx[2909]: begin
Jan  1 00:08:58 udhcpc[2910]: execle'ing /tmp/udhcpc with name deconfig
Jan  1 00:08:58 event[2910]: file "/var/lock/vc0_1.lock" is set
Jan  1 00:08:58 event[2910]: udhcp_down fake
Jan  1 00:08:58 udhcpc[2867]: Received SIGTERM
Jan  1 00:08:58 get_gr_name_by_l3_key[2909]: start
Jan  1 00:08:58 update_igmpx[2909]: igmpx cfg: upstream list empty downstream list empty
Jan  1 00:08:58 stop_igmpx[2909]: stop igmpx
Jan  1 00:08:58 stop_process_t[2909]: not found pid process 'igmpx'
Jan  1 00:08:58 dms_reset_uptime[2909]: enter key vc0_1
Jan  1 00:08:58 dms_reset_uptime[2909]: unable to remove file /tmp/stat.vc0_1
Jan  1 00:08:58 after_stop_ip[2909]: 
Jan  1 00:08:58 stop_pppd[2909]: stop pppd on vc0_2
Jan  1 00:08:58 resident[2909]: set lock on vc0_2, with "/var/lock/vc0_2.lock"
Jan  1 00:08:58 pppd[2877]: Sent PADT
Jan  1 00:08:58 wan_down[2909]: name = ppp0
Jan  1 00:08:58 update_igmpx[2909]: begin
Jan  1 00:08:58 get_gr_name_by_l3_key[2909]: start
Jan  1 00:08:58 update_igmpx[2909]: igmpx cfg: upstream list empty downstream list empty
Jan  1 00:08:58 stop_igmpx[2909]: stop igmpx
Jan  1 00:08:58 stop_process_t[2909]: not found pid process 'igmpx'
Jan  1 00:08:58 resident[2909]: stopped link(contype:ppp, iface:vc0_2)
Jan  1 00:08:58 after_stop_pppd[2909]: 
Jan  1 00:08:58 start_stop_wan_link_on_l2[2909]: End iface: vc0, status: 0
Jan  1 00:08:58 pppd[2877]: receivePacket: recv: Network is down
Jan  1 00:08:58 pppd[2877]: Exit.(1)
Jan  1 00:08:58 pppd[2877]: Function die(). Reinit connection (req_unit 0)
Jan  1 00:08:58 resident_ppp_handler[2924]: record = action:down;iface:ppp0;status:1;link:vc0_2;
Jan  1 00:08:58 kernel: ATM OAM F5 initialized.
Jan  1 00:08:58 kernel: ATM OAM F4 initialized.
Jan  1 00:08:58 kernel: Enable 8671G 1 function
Jan  1 00:08:58 kernel: Enable 8671 0 function
Jan  1 00:08:58 kernel: Enable 8672 function 
Jan  1 00:08:58 kernel: applying workaround...done
Jan  1 00:08:58 kernel: sar_close
Jan  1 00:08:58 rlx_modem[2909]: remove atm =|vc0| done!!! 
Jan  1 00:08:58 resident_ppp_handler[2924]: Set default type: ipv4
Jan  1 00:08:59 resident_ppp_handler[2924]: l2:vc0, l3:vc0_2, tun:||
Jan  1 00:08:59 resident_ppp_handler[2924]: ppp state 1
Jan  1 00:08:59 resident_ppp_handler[2924]: Restart pppd
Jan  1 00:09:00 update_ntpclient[2935]: start
Jan  1 00:09:00 update_ntpclient[2935]: ntpclient is disable
Jan  1 00:09:02 kernel: SachemAccess::reset()
Jan  1 00:09:03 kernel: SachemAccess::reset()
Jan  1 00:09:06 start_pppd[2924]: start pppd on vc0_2
Jan  1 00:09:06 stop_pppd[2924]: stop pppd on vc0_2
Jan  1 00:09:06 resident[2924]: set lock on vc0_2, with "/var/lock/vc0_2.lock"
Jan  1 00:09:06 wan_down[2924]: name = ppp0
Jan  1 00:09:06 update_igmpx[2924]: begin
Jan  1 00:09:06 get_gr_name_by_l3_key[2924]: start
Jan  1 00:09:06 update_igmpx[2924]: igmpx cfg: upstream list empty downstream list empty
Jan  1 00:09:06 stop_igmpx[2924]: stop igmpx
Jan  1 00:09:06 stop_process_t[2924]: not found pid process 'igmpx'
Jan  1 00:09:06 resident[2924]: stopped link(contype:ppp, iface:vc0_2)
Jan  1 00:09:06 after_stop_pppd[2924]: 
Jan  1 00:09:06 before_start_pppd[2924]: 
Jan  1 00:09:08 start_pppd[2924]: using old session parameters: 63592:F8:75:88:86:CC:90
Jan  1 00:09:08 pppd[2974]: Plugin /usr/lib/pppd/rp-pppoe.so loaded.
Jan  1 00:09:08 pppd[2974]: In file /etc/ppp/peers/vc0_2: unrecognized option 'vc0'
Jan  1 00:09:18 kernel: SachemAccess::reset()
Jan  1 00:09:22 kernel: BIT(11) of 0xd2c = 1, 0xd2c = 0xcc979975
Jan  1 00:09:26 kernel: [br_handle_frame_finish 447]tmpOp=1.
Jan  1 00:09:26 kernel: [br_handle_frame_finish 447]tmpOp=2.
Jan  1 00:09:26 kernel: [br_handle_frame_finish 447]tmpOp=1.
Jan  1 00:09:26 kernel: [br_handle_frame_finish 447]tmpOp=1.
Jan  1 00:09:27 anweb[227]: check need_redirect started
Jan  1 00:09:27 anweb[227]: need_redirect: is defconf
Jan  1 00:09:28 kernel: [br_handle_frame_finish 447]tmpOp=1.
Jan  1 00:09:28 kernel: [br_handle_frame_finish 447]tmpOp=1.
Jan  1 00:09:31 kernel: SachemAccess::reset()
Jan  1 00:09:35 kernel: SachemAccess::reset()
Jan  1 00:09:39 kernel: SachemAccess::reset()
Jan  1 00:09:43 kernel: SachemAccess::reset()
Jan  1 00:09:43 kernel: xtm_elapse() = 575780
Jan  1 00:09:43 kernel: LD Power down to avoid huge current
Jan  1 00:09:43 kernel: TxIdxTxIdx = 0
Jan  1 00:09:43 kernel: OKnum=512
Jan  1 00:09:43 kernel: DDR phase 0xd OK
Jan  1 00:09:43 kernel: OKnum=512
Jan  1 00:09:43 kernel: DDR phase 0x1 OK
Jan  1 00:09:43 kernel: OKnum=19
Jan  1 00:09:43 kernel: OKnum=19
Jan  1 00:09:43 kernel: OKnum=36
Jan  1 00:09:43 kernel: OKnum=512
Jan  1 00:09:44 dms_etherwan_read[3101]: start
Jan  1 00:09:44 dms_get_etherwan_port[3101]: start
Jan  1 00:09:44 dms_get_etherwan_port[3101]: end: etherwan port 0
Jan  1 00:09:44 dms_get_etherwan_port_info[3101]: start 1
Jan  1 00:09:44 dms_get_etherwan_port_info[3101]: end
Jan  1 00:09:44 get_gr_name_by_lan_key[3101]: start
Jan  1 00:09:44 get_gr_name_by_lan_key[3101]: eth0.5: br0
Jan  1 00:09:44 dms_get_etherwan_port_info[3101]: start 2
Jan  1 00:09:44 dms_get_etherwan_port_info[3101]: end
Jan  1 00:09:44 get_gr_name_by_lan_key[3101]: start
Jan  1 00:09:44 get_gr_name_by_lan_key[3101]: eth0.4: br0
Jan  1 00:09:44 dms_get_etherwan_port_info[3101]: start 3
Jan  1 00:09:44 dms_get_etherwan_port_info[3101]: end
Jan  1 00:09:44 get_gr_name_by_lan_key[3101]: start
Jan  1 00:09:44 get_gr_name_by_lan_key[3101]: eth0.3: br0
Jan  1 00:09:44 dms_get_etherwan_port_info[3101]: start 4
Jan  1 00:09:44 dms_get_etherwan_port_info[3101]: end
Jan  1 00:09:44 get_gr_name_by_lan_key[3101]: start
Jan  1 00:09:44 get_gr_name_by_lan_key[3101]: eth0.2: br0
Jan  1 00:09:44 dms_etherwan_read[3101]: end
Jan  1 00:09:44 adsl_status[3106]: 1950
Jan  1 00:09:48 kernel: Time_elapse(1) = 580990
Jan  1 00:09:48 kernel: SachemAccess::reset()