Jan 1 13:43:42 syslogd started: BusyBox v1.19.2
Jan 1 13:43:45 save_to_flash[4244]: file size is 2117
Jan 1 13:45:23 pptp[3768]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Jan 1 13:46:23 pptp[3768]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Jan 1 13:47:23 pptp[3768]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Jan 1 13:48:23 pptp[3768]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Jan 1 13:49:23 pptp[3768]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Jan 1 13:50:23 pptp[3768]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Jan 1 13:50:23 pptp[3768]: anon log[logecho:pptp_ctrl.c:679]: no more Echo Reply/Request packets will be reported.
Jan 1 14:13:42 conf_ipfilter[5562]: create default conf
Jan 1 16:02:05 kernel: wlan0: A wireless client is associated - 70:72:0D:31:CA:C5
Jan 1 16:02:05 kernel: BIT(11) of 0xd2c = 1, 0xd2c = 0xcc979975
Jan 1 16:02:05 kernel: wlan0: WPA2-AES PSK authentication in progress...
Jan 1 16:02:05 kernel: wlan0: A wireless client is associated - 70:72:0D:31:CA:C5
Jan 1 16:02:05 kernel: wlan0: Open and authenticated
Jan 1 16:08:49 resident_phy_link_handler[5606]: record = phy:1;status:0
Jan 1 16:08:49 resident_phy_link_handler[5606]: phy - 1, status - 0
Jan 1 16:08:49 kernel: Wan link is down
Jan 1 16:08:49 resident_phy_link_handler[5606]: etherwan key_l2=eth1
Jan 1 16:08:49 resident[5606]: stop pppd on eth1_3
Jan 1 16:08:49 resident[5606]: wan_down: name = ppp1
Jan 1 16:08:49 resident[5606]: set lock on eth1_3, with "/var/lock/eth1_3.lock"
Jan 1 16:08:49 stop_pppd[5606]: buffer for tunnel ifaces/eth1/services/eth1_4/tunnels/eth1_3/
Jan 1 16:08:49 pppd[3758]: Connect time 148.4 minutes.
Jan 1 16:08:49 pppd[3758]: Sent 91106687 bytes, received 2293674497 bytes.
Jan 1 16:08:49 stop_pppd[5606]: waiting, until pppd is finished
Jan 1 16:08:49 stop_pppd[5606]: pppd proc dir: /proc/3758
Jan 1 16:08:49 stop_pppd[5606]: wait time 0 msec
Jan 1 16:08:49 pppd[3758]: Connection terminated.
Jan 1 16:08:49 event[5624]: ppp_down - do event
Jan 1 16:08:49 miniupnpd[246]: Failed to get IP for interface ppp1
Jan 1 16:08:49 miniupnpd[246]: SendNATPMPPublicAddressChangeNotification: cannot get public IP address, stopping
Jan 1 16:08:49 resident_ppp_handler[5629]: [5629] record = action:down;iface:ppp1;link:eth1_3;
Jan 1 16:08:49 resident_ppp_handler[5629]: Set default type: ipv4
Jan 1 16:08:50 stop_pppd[5606]: wait time 200 msec
Jan 1 16:08:50 stop_pppd[5606]: wait time 400 msec
Jan 1 16:08:50 stop_pppd[5606]: wait time 600 msec
Jan 1 16:08:50 stop_pppd[5606]: wait time 800 msec
Jan 1 16:08:50 stop_pppd[5606]: wait time 1000 msec
Jan 1 16:08:51 kernel: Wan link is up!
Jan 1 16:08:51 resident_phy_link_handler[5631]: record = phy:1;status:1
Jan 1 16:08:51 resident_phy_link_handler[5631]: phy - 1, status - 1
Jan 1 16:08:51 stop_pppd[5606]: wait time 1200 msec
Jan 1 16:08:51 stop_pppd[5606]: wait time 1400 msec
Jan 1 16:08:51 stop_pppd[5606]: wait time 1600 msec
Jan 1 16:08:51 stop_pppd[5606]: wait time 1800 msec
Jan 1 16:08:52 stop_pppd[5606]: wait time 2000 msec
Jan 1 16:08:52 stop_pppd[5606]: wait time 2200 msec
Jan 1 16:08:52 stop_pppd[5606]: wait time 2400 msec
Jan 1 16:08:52 stop_pppd[5606]: wait time 2600 msec
Jan 1 16:08:52 stop_pppd[5606]: wait time 2800 msec
Jan 1 16:08:52 pppd[3758]: Connection terminated.
Jan 1 16:08:53 stop_pppd[5606]: wait time 3000 msec
Jan 1 16:08:53 pppd[3758]: send_event_to_daemon
Jan 1 16:08:53 pptp[3760]: anon warn[decaps_hdlc:pptp_gre.c:216]: pppd may have shutdown, see pppd log
Jan 1 16:08:53 pptp[3768]: anon log[callmgr_main:pptp_callmgr.c:234]: Closing connection (unhandled)
Jan 1 16:08:53 pptp[3768]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 12 'Call-Clear-Request'
Jan 1 16:08:53 pptp[3768]: anon log[call_callback:pptp_callmgr.c:79]: Closing connection (call state)
Jan 1 16:08:53 pppd[3758]: Exit.
Jan 1 16:08:53 stop_pppd[5606]: wait time 3200 msec
Jan 1 16:08:53 resident_ppp_handler[5638]: [5638] record = action:down;iface:ppp1;status:5;link:eth1_3;
Jan 1 16:08:53 resident_ppp_handler[5638]: Set default type: ipv4
Jan 1 16:08:53 stop_pppd[5606]: wait time 3400 msec
Jan 1 16:08:53 stop_pppd[5606]: pppd is finished good
Jan 1 16:08:53 resident[5606]: stopped link(contype:pptp, iface:eth1_3)
Jan 1 16:08:55 resident[5606]: stop ipoe on eth1_4
Jan 1 16:08:55 resident[5606]: set lock /var/lock/eth1_4.lock
Jan 1 16:08:55 resident[5606]: wan_down: name = eth1
Jan 1 16:08:55 event[5643]: file "/var/lock/eth1_4.lock" is set
Jan 1 16:08:55 event[5643]: udhcp_down fake
Jan 1 16:08:57 resident_phy_link_handler[5606]: Exit
Jan 1 16:08:57 resident_ppp_handler[5629]: l2:eth1, l3:eth1_4, tun:eth1_3
Jan 1 16:08:57 ppp_is_down[5629]: echo 1 > /proc/fast_pptp
Jan 1 16:09:00 resident[5629]: ppp_is_down on eth1_3
Jan 1 16:09:00 resident[5629]: wan_down: name = ppp1
Jan 1 16:09:00 triggerPingRespond[5629]: OK
Jan 1 16:09:00 autoupdate[5629]: Config reset
Jan 1 16:09:00 resident_ppp_handler[5638]: l2:eth1, l3:eth1_4, tun:eth1_3
Jan 1 16:09:00 resident_ppp_handler[5638]: ppp state 5
Jan 1 16:09:00 resident_ppp_handler[5638]: status: 5 (5)
Jan 1 16:09:00 ppp_is_down[5638]: echo 1 > /proc/fast_pptp
Jan 1 16:09:03 resident[5638]: ppp_is_down on eth1_3
Jan 1 16:09:03 resident[5638]: wan_down: name = ppp1
Jan 1 16:09:04 triggerPingRespond[5638]: OK
Jan 1 16:09:04 autoupdate[5638]: Config reset
Jan 1 16:09:04 resident_phy_link_handler[5631]: etherwan key_l2=eth1
Jan 1 16:09:04 resident[5631]: start ipoe
Jan 1 16:09:04 start_ip[5631]: begin (eth1 -> eth1_4)
Jan 1 16:09:04 resident[5631]: start ipoe (v0) on eth1
Jan 1 16:09:04 event[5681]: send event "ipoe down"
Jan 1 16:09:04 resident[231]: record = action:down;iface:eth1;contag:4;
Jan 1 16:09:04 resident[231]: phys_iface eth1
Jan 1 16:09:04 resident_ipoe_handler[231]: ip_type = (null)
Jan 1 16:09:04 resident[231]: resident_ipoe_handler - Set default type: ipv4
Jan 1 16:09:04 resident[231]: phys_iface
Jan 1 16:09:04 resident[231]: resident_ipoe_handler - 2
Jan 1 16:09:04 resident[231]: resident_ipoe_handler - 3, name: eth1_4
Jan 1 16:09:04 event[5686]: send event "ipoe up"
Jan 1 16:09:06 resident_phy_link_handler[5631]: Exit
Jan 1 16:09:06 resident[231]: resident_ipoe_handler - l2:eth1, l3:eth1_4, tun:eth1_3, ip_type:0
Jan 1 16:09:06 resident[231]: ip_is_down on eth1_4
Jan 1 16:09:06 resident[231]: wan_down: name = eth1_4
Jan 1 16:09:06 triggerPingRespond[231]: OK
Jan 1 16:09:06 autoupdate[231]: Config reset
Jan 1 16:09:06 resident[231]: record = action:up;iface:eth1;contag:4;ipaddr:10.31.5.150 ;netmask:255.255.255.0 ;gateway:10.31.5.1 ;dns1:10.31.255.1;
Jan 1 16:09:06 resident[231]: phys_iface eth1
Jan 1 16:09:06 resident_ipoe_handler[231]: ip_type = (null)
Jan 1 16:09:06 resident[231]: resident_ipoe_handler - Set default type: ipv4
Jan 1 16:09:06 resident[231]: phys_iface
Jan 1 16:09:06 resident[231]: resident_ipoe_handler - 2
Jan 1 16:09:06 resident[231]: resident_ipoe_handler - 3, name: eth1_4
Jan 1 16:09:06 resident[231]: resident_ipoe_handler - l2:eth1, l3:eth1_4, tun:eth1_3, ip_type:0
Jan 1 16:09:06 resident[231]: resident_ipoe_handler - ipv4
Jan 1 16:09:06 resident[231]: start ip_is_up on iface eth1_4
Jan 1 16:09:06 resident[231]: ip_is_up for link eth1_4
Jan 1 16:09:06 set_dhcp_routes[231]: set routes for iface eth1
Jan 1 16:09:06 DMS_NL_API[231]: Rtnetlink answer: No such process
Jan 1 16:09:06 DMS_NL_API[231]: Error deleting route
Jan 1 16:09:06 resident[231]: update rip
Jan 1 16:09:07 resident[231]: update igmpx
Jan 1 16:09:07 resident[231]: stop igmpx
Jan 1 16:09:07 resident[231]: igmpx
Jan 1 16:09:07 syslog: Loading liblogger v2.0.2
Jan 1 16:09:07 syslog: upstream ifaces:
Jan 1 16:09:07 syslog: downstream ifaces: br0
Jan 1 16:09:07 syslog: hews interval = 120.0 sec
Jan 1 16:09:07 syslog: doublet join enabled
Jan 1 16:09:07 syslog: Using old argv style
Jan 1 16:09:07 syslog: daemonizing ...
Jan 1 16:09:07 syslog: kernel multicast version is 305 (v3.05 ?)
Jan 1 16:09:07 syslog: kernel mroute inited
Jan 1 16:09:07 syslog: proxy prepared
Jan 1 16:09:07 syslog: vifi #0 added for br0
Jan 1 16:09:07 syslog: valid VIF's configured: upstreams=0, downstreams=1. Bad.
Jan 1 16:09:07 resident[231]: update igmpx compiled
Jan 1 16:09:07 resident[231]: update ntpclient
Jan 1 16:09:07 update_ntpclient[231]: ntpclient is disable
Jan 1 16:09:07 start_pppd[231]: tunnel: ifaces/eth1/services/eth1_4/tunnels/eth1_3/
Jan 1 16:09:07 resident[231]: start pppd on eth1_3
Jan 1 16:09:07 resident[231]: stop pppd on eth1_3
Jan 1 16:09:07 resident[231]: wan_down: name = ppp1
Jan 1 16:09:07 resident[231]: set lock on eth1_3, with "/var/lock/eth1_3.lock"
Jan 1 16:09:07 stop_pppd[231]: buffer for tunnel ifaces/eth1/services/eth1_4/tunnels/eth1_3/
Jan 1 16:09:07 resident[231]: stopped link(contype:pptp, iface:eth1_3)
Jan 1 16:09:07 pppd[5771]: entering dlock, dfd = -1
Jan 1 16:09:07 pppd[5771]: dlock: locked
Jan 1 16:09:07 pppd[5771]: pppd 2.4.4 started by admin, uid 0
Jan 1 16:09:07 pppd[5771]: Using interface ppp1
Jan 1 16:09:07 pppd[5771]: Connect: ppp1 <--> /dev/pts/0
Jan 1 16:09:07 pptp[5775]: anon log[main:pptp.c:331]: The synchronous pptp option is NOT activated
Jan 1 16:09:07 pptp[5781]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 1 'Start-Control-Connection-Request'
Jan 1 16:09:07 pptp[5781]: anon log[ctrlp_disp:pptp_ctrl.c:739]: Received Start Control Connection Reply
Jan 1 16:09:07 pptp[5781]: anon log[ctrlp_disp:pptp_ctrl.c:773]: Client connection established.
Jan 1 16:09:08 pptp[5781]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 7 'Outgoing-Call-Request'
Jan 1 16:09:08 pptp[5781]: anon log[ctrlp_disp:pptp_ctrl.c:858]: Received Outgoing Call Reply.
Jan 1 16:09:08 pptp[5781]: anon log[ctrlp_disp:pptp_ctrl.c:897]: Outgoing call established (call ID 0, peer's call ID 12677).
Jan 1 16:09:09 pppd[5771]: Remote message: Authentication succeeded
Jan 1 16:09:09 pppd[5771]: PAP authentication succeeded
Jan 1 16:09:10 triggerPingRespond[231]: OK
Jan 1 16:09:12 pppd[5771]: local IP address 172.16.207.254
Jan 1 16:09:12 pppd[5771]: remote IP address 172.16.0.1
Jan 1 16:09:12 pppd[5771]: primary DNS address 10.31.255.1
Jan 1 16:09:12 pppd[5771]: secondary DNS address 8.8.8.8
Jan 1 16:09:14 autoupdate[231]: Downloading file: fwupdate.dlink.ru/dislocation
Jan 1 16:09:14 resident_ppp_handler[5810]: [5810] record = action:up;iface:ppp1;link:eth1_3;ipaddr:172.16.207.254;netmask:255.255.255.255;gateway:172.16.0.1;dns1:10.31.255.1;dns2:8.8.8.8;sess_id:;
Jan 1 16:09:14 resident_ppp_handler[5810]: Set default type: ipv4
Jan 1 16:09:14 resident_ppp_handler[5810]: l2:eth1, l3:eth1_4, tun:eth1_3
Jan 1 16:09:14 resident[5810]: ppp_is_up on eth1_3
Jan 1 16:09:14 resident[5810]: update rip
Jan 1 16:09:14 resident[5810]: update ntpclient
Jan 1 16:09:14 update_ntpclient[5810]: ntpclient is disable
Jan 1 16:09:14 triggerPingRespond[5810]: OK
Jan 1 16:09:18 autoupdate[5810]: Downloading file: fwupdate.dlink.ru/dislocation
Jan 1 16:09:18 autoupdate[5810]: Downloading file: fwupdate.dlink.ru/Router/DIR-615_A_M1/Firmware/exclude
Jan 1 16:09:18 autoupdate[5810]: Not found exception
Jan 1 16:09:18 autoupdate[5810]: Downloading file: fwupdate.dlink.ru/Router/DIR-615_A_M1/Firmware/latest
Jan 1 16:09:18 autoupdate[5810]: Downloading file: fwupdate.dlink.ru/Router/DIR-615_A_M1/Firmware/20140114_1031_DIR_615A_2.0.17_sdk-master.des
Jan 1 16:09:18 autoupdate[5810]: Update not needed
Jan 1 16:10:08 pptp[5781]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Jan 1 16:11:08 pptp[5781]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Jan 1 16:12:08 pptp[5781]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Jan 1 16:13:08 pptp[5781]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Jan 1 16:14:08 pptp[5781]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Jan 1 16:15:08 pptp[5781]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Jan 1 16:16:08 pptp[5781]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Jan 1 16:17:09 pptp[5781]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Jan 1 16:18:09 pptp[5781]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Jan 1 16:19:09 pptp[5781]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Jan 1 16:19:09 pptp[5781]: anon log[logecho:pptp_ctrl.c:679]: no more Echo Reply/Request packets will be reported.
Jan 1 16:32:37 kernel: wlan0: A wireless client is deauthenticated - 70:72:0D:31:CA:C5
Jan 1 16:58:59 kernel: Wan link is down
Jan 1 16:58:59 resident_phy_link_handler[5860]: record = phy:1;status:0
Jan 1 16:58:59 resident_phy_link_handler[5860]: phy - 1, status - 0
Jan 1 16:58:59 resident_phy_link_handler[5860]: etherwan key_l2=eth1
Jan 1 16:58:59 resident[5860]: stop pppd on eth1_3
Jan 1 16:58:59 resident[5860]: wan_down: name = ppp1
Jan 1 16:58:59 resident[5860]: set lock on eth1_3, with "/var/lock/eth1_3.lock"
Jan 1 16:58:59 stop_pppd[5860]: buffer for tunnel ifaces/eth1/services/eth1_4/tunnels/eth1_3/
Jan 1 16:58:59 pppd[5771]: Terminating on signal 15
Jan 1 16:58:59 pppd[5771]: Connect time 49.8 minutes.
Jan 1 16:58:59 pppd[5771]: Sent 57661937 bytes, received 924902348 bytes.
Jan 1 16:58:59 stop_pppd[5860]: waiting, until pppd is finished
Jan 1 16:58:59 stop_pppd[5860]: pppd proc dir: /proc/5771
Jan 1 16:58:59 stop_pppd[5860]: wait time 0 msec
Jan 1 16:58:59 pppd[5771]: Connection terminated.
Jan 1 16:58:59 event[5878]: ppp_down - do event
Jan 1 16:58:59 miniupnpd[246]: ioctl(s, SIOCGIFADDR, ...): Cannot assign requested address
Jan 1 16:58:59 miniupnpd[246]: Failed to get IP for interface ppp1
Jan 1 16:58:59 miniupnpd[246]: SendNATPMPPublicAddressChangeNotification: cannot get public IP address, stopping
Jan 1 16:58:59 resident_ppp_handler[5883]: [5883] record = action:down;iface:ppp1;link:eth1_3;
Jan 1 16:58:59 resident_ppp_handler[5883]: Set default type: ipv4
Jan 1 16:59:00 stop_pppd[5860]: wait time 200 msec
Jan 1 16:59:00 stop_pppd[5860]: wait time 400 msec
Jan 1 16:59:00 stop_pppd[5860]: wait time 600 msec
Jan 1 16:59:00 stop_pppd[5860]: wait time 800 msec
Jan 1 16:59:00 stop_pppd[5860]: wait time 1000 msec
Jan 1 16:59:01 kernel: Wan link is up!
Jan 1 16:59:01 resident_phy_link_handler[5885]: record = phy:1;status:1
Jan 1 16:59:01 resident_phy_link_handler[5885]: phy - 1, status - 1
Jan 1 16:59:01 stop_pppd[5860]: wait time 1200 msec
Jan 1 16:59:01 stop_pppd[5860]: wait time 1400 msec
Jan 1 16:59:01 stop_pppd[5860]: wait time 1600 msec
Jan 1 16:59:01 stop_pppd[5860]: wait time 1800 msec
Jan 1 16:59:01 stop_pppd[5860]: wait time 2000 msec
Jan 1 16:59:02 stop_pppd[5860]: wait time 2200 msec
Jan 1 16:59:02 stop_pppd[5860]: wait time 2400 msec
Jan 1 16:59:02 stop_pppd[5860]: wait time 2600 msec
Jan 1 16:59:02 stop_pppd[5860]: wait time 2800 msec
Jan 1 16:59:02 pppd[5771]: Connection terminated.
Jan 1 16:59:02 stop_pppd[5860]: wait time 3000 msec
Jan 1 16:59:03 stop_pppd[5860]: wait time 3200 msec
Jan 1 16:59:03 pppd[5771]: send_event_to_daemon
Jan 1 16:59:03 pptp[5775]: anon warn[decaps_hdlc:pptp_gre.c:204]: short read (-1): Input/output error
Jan 1 16:59:03 pptp[5775]: anon warn[decaps_hdlc:pptp_gre.c:216]: pppd may have shutdown, see pppd log
Jan 1 16:59:03 pptp[5781]: anon log[callmgr_main:pptp_callmgr.c:234]: Closing connection (unhandled)
Jan 1 16:59:03 pptp[5781]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 12 'Call-Clear-Request'
Jan 1 16:59:03 pptp[5781]: anon log[call_callback:pptp_callmgr.c:79]: Closing connection (call state)
Jan 1 16:59:03 pppd[5771]: Exit.
Jan 1 16:59:03 resident_ppp_handler[5892]: [5892] record = action:down;iface:ppp1;status:5;link:eth1_3;
Jan 1 16:59:03 resident_ppp_handler[5892]: Set default type: ipv4
Jan 1 16:59:03 stop_pppd[5860]: wait time 3400 msec
Jan 1 16:59:03 stop_pppd[5860]: pppd is finished good
Jan 1 16:59:03 resident[5860]: stopped link(contype:pptp, iface:eth1_3)
Jan 1 16:59:05 resident[5860]: stop ipoe on eth1_4
Jan 1 16:59:05 resident[5860]: set lock /var/lock/eth1_4.lock
Jan 1 16:59:05 resident[5860]: wan_down: name = eth1
Jan 1 16:59:05 event[5897]: file "/var/lock/eth1_4.lock" is set
Jan 1 16:59:05 event[5897]: udhcp_down fake
Jan 1 16:59:07 resident_phy_link_handler[5860]: Exit
Jan 1 16:59:07 resident_ppp_handler[5883]: l2:eth1, l3:eth1_4, tun:eth1_3
Jan 1 16:59:07 ppp_is_down[5883]: echo 1 > /proc/fast_pptp
Jan 1 16:59:10 resident[5883]: ppp_is_down on eth1_3
Jan 1 16:59:10 resident[5883]: wan_down: name = ppp1
Jan 1 16:59:10 triggerPingRespond[5883]: OK
Jan 1 16:59:10 autoupdate[5883]: Config reset
Jan 1 16:59:10 resident_ppp_handler[5892]: l2:eth1, l3:eth1_4, tun:eth1_3
Jan 1 16:59:10 resident_ppp_handler[5892]: ppp state 5
Jan 1 16:59:10 resident_ppp_handler[5892]: status: 5 (5)
Jan 1 16:59:10 ppp_is_down[5892]: echo 1 > /proc/fast_pptp
Jan 1 16:59:13 resident[5892]: ppp_is_down on eth1_3
Jan 1 16:59:13 resident[5892]: wan_down: name = ppp1
Jan 1 16:59:14 triggerPingRespond[5892]: OK
Jan 1 16:59:14 autoupdate[5892]: Config reset
Jan 1 16:59:14 resident_phy_link_handler[5885]: etherwan key_l2=eth1
Jan 1 16:59:14 resident[5885]: start ipoe
Jan 1 16:59:14 start_ip[5885]: begin (eth1 -> eth1_4)
Jan 1 16:59:14 resident[5885]: start ipoe (v0) on eth1
Jan 1 16:59:14 event[5935]: send event "ipoe down"
Jan 1 16:59:14 resident[231]: record = action:down;iface:eth1;contag:4;
Jan 1 16:59:14 resident[231]: phys_iface eth1
Jan 1 16:59:14 resident_ipoe_handler[231]: ip_type = (null)
Jan 1 16:59:14 resident[231]: resident_ipoe_handler - Set default type: ipv4
Jan 1 16:59:14 resident[231]: phys_iface
Jan 1 16:59:14 resident[231]: resident_ipoe_handler - 2
Jan 1 16:59:14 resident[231]: resident_ipoe_handler - 3, name: eth1_4
Jan 1 16:59:14 event[5940]: send event "ipoe up"
Jan 1 16:59:16 resident_phy_link_handler[5885]: Exit
Jan 1 16:59:16 resident[231]: resident_ipoe_handler - l2:eth1, l3:eth1_4, tun:eth1_3, ip_type:0
Jan 1 16:59:16 resident[231]: ip_is_down on eth1_4
Jan 1 16:59:16 resident[231]: wan_down: name = eth1_4
Jan 1 16:59:16 triggerPingRespond[231]: OK
Jan 1 16:59:16 autoupdate[231]: Config reset
Jan 1 16:59:16 resident[231]: record = action:up;iface:eth1;contag:4;ipaddr:10.31.5.150 ;netmask:255.255.255.0 ;gateway:10.31.5.1 ;dns1:10.31.255.1;
Jan 1 16:59:16 resident[231]: phys_iface eth1
Jan 1 16:59:16 resident_ipoe_handler[231]: ip_type = (null)
Jan 1 16:59:16 resident[231]: resident_ipoe_handler - Set default type: ipv4
Jan 1 16:59:16 resident[231]: phys_iface
Jan 1 16:59:16 resident[231]: resident_ipoe_handler - 2
Jan 1 16:59:16 resident[231]: resident_ipoe_handler - 3, name: eth1_4
Jan 1 16:59:16 resident[231]: resident_ipoe_handler - l2:eth1, l3:eth1_4, tun:eth1_3, ip_type:0
Jan 1 16:59:16 resident[231]: resident_ipoe_handler - ipv4
Jan 1 16:59:16 resident[231]: start ip_is_up on iface eth1_4
Jan 1 16:59:16 resident[231]: ip_is_up for link eth1_4
Jan 1 16:59:16 set_dhcp_routes[231]: set routes for iface eth1
Jan 1 16:59:16 DMS_NL_API[231]: Rtnetlink answer: No such process
Jan 1 16:59:16 DMS_NL_API[231]: Error deleting route
Jan 1 16:59:16 resident[231]: update rip
Jan 1 16:59:16 resident[231]: update igmpx
Jan 1 16:59:16 resident[231]: stop igmpx
Jan 1 16:59:17 resident[231]: igmpx
Jan 1 16:59:17 syslog: Loading liblogger v2.0.2
Jan 1 16:59:17 syslog: upstream ifaces:
Jan 1 16:59:17 syslog: downstream ifaces: br0
Jan 1 16:59:17 syslog: hews interval = 120.0 sec
Jan 1 16:59:17 syslog: doublet join enabled
Jan 1 16:59:17 syslog: Using old argv style
Jan 1 16:59:17 syslog: daemonizing ...
Jan 1 16:59:17 syslog: kernel multicast version is 305 (v3.05 ?)
Jan 1 16:59:17 syslog: kernel mroute inited
Jan 1 16:59:17 syslog: proxy prepared
Jan 1 16:59:17 syslog: vifi #0 added for br0
Jan 1 16:59:17 syslog: valid VIF's configured: upstreams=0, downstreams=1. Bad.
Jan 1 16:59:17 resident[231]: update igmpx compiled
Jan 1 16:59:17 resident[231]: update ntpclient
Jan 1 16:59:17 update_ntpclient[231]: ntpclient is disable
Jan 1 16:59:17 start_pppd[231]: tunnel: ifaces/eth1/services/eth1_4/tunnels/eth1_3/
Jan 1 16:59:17 resident[231]: start pppd on eth1_3
Jan 1 16:59:17 resident[231]: stop pppd on eth1_3
Jan 1 16:59:17 resident[231]: wan_down: name = ppp1
Jan 1 16:59:17 resident[231]: set lock on eth1_3, with "/var/lock/eth1_3.lock"
Jan 1 16:59:17 stop_pppd[231]: buffer for tunnel ifaces/eth1/services/eth1_4/tunnels/eth1_3/
Jan 1 16:59:17 resident[231]: stopped link(contype:pptp, iface:eth1_3)
Jan 1 16:59:17 pppd[6025]: entering dlock, dfd = -1
Jan 1 16:59:17 pppd[6025]: dlock: locked
Jan 1 16:59:17 pppd[6025]: pppd 2.4.4 started by admin, uid 0
Jan 1 16:59:17 pptp[6027]: anon log[main:pptp.c:331]: The synchronous pptp option is NOT activated
Jan 1 16:59:17 pppd[6025]: Using interface ppp1
Jan 1 16:59:17 pppd[6025]: Connect: ppp1 <--> /dev/pts/0
Jan 1 16:59:17 pptp[6035]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 1 'Start-Control-Connection-Request'
Jan 1 16:59:17 pptp[6035]: anon log[ctrlp_disp:pptp_ctrl.c:739]: Received Start Control Connection Reply
Jan 1 16:59:17 pptp[6035]: anon log[ctrlp_disp:pptp_ctrl.c:773]: Client connection established.
Jan 1 16:59:18 pptp[6035]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 7 'Outgoing-Call-Request'
Jan 1 16:59:18 pptp[6035]: anon log[ctrlp_disp:pptp_ctrl.c:858]: Received Outgoing Call Reply.
Jan 1 16:59:18 pptp[6035]: anon log[ctrlp_disp:pptp_ctrl.c:897]: Outgoing call established (call ID 0, peer's call ID 25408).
Jan 1 16:59:19 pppd[6025]: CHAP authentication succeeded: Authentication succeeded
Jan 1 16:59:19 pppd[6025]: CHAP authentication succeeded
Jan 1 16:59:21 triggerPingRespond[231]: OK
Jan 1 16:59:22 pppd[6025]: local IP address 172.16.5.150
Jan 1 16:59:22 pppd[6025]: remote IP address 172.16.0.1
Jan 1 16:59:22 pppd[6025]: primary DNS address 10.31.255.1
Jan 1 16:59:22 pppd[6025]: secondary DNS address 8.8.8.8
Jan 1 17:00:09 autoupdate[231]: Downloading file: fwupdate.dlink.ru/dislocation
Jan 1 17:00:09 resident_ppp_handler[6074]: [6074] record = action:up;iface:ppp1;link:eth1_3;ipaddr:172.16.5.150;netmask:255.255.255.255;gateway:172.16.0.1;dns1:10.31.255.1;dns2:8.8.8.8;sess_id:;
Jan 1 17:00:09 resident_ppp_handler[6074]: Set default type: ipv4
Jan 1 17:00:09 resident_ppp_handler[6074]: l2:eth1, l3:eth1_4, tun:eth1_3
Jan 1 17:00:09 resident[6074]: ppp_is_up on eth1_3
Jan 1 17:00:09 resident[6074]: update rip
Jan 1 17:00:09 resident[6074]: update ntpclient
Jan 1 17:00:09 update_ntpclient[6074]: ntpclient is disable
Jan 1 17:00:09 triggerPingRespond[6074]: OK
Jan 1 17:00:13 autoupdate[6074]: Downloading file: fwupdate.dlink.ru/dislocation
Jan 1 17:00:13 autoupdate[6074]: Downloading file: fwupdate.dlink.ru/Router/DIR-615_A_M1/Firmware/exclude
Jan 1 17:00:13 autoupdate[6074]: Not found exception
Jan 1 17:00:13 autoupdate[6074]: Downloading file: fwupdate.dlink.ru/Router/DIR-615_A_M1/Firmware/latest
Jan 1 17:00:13 autoupdate[6074]: Downloading file: fwupdate.dlink.ru/Router/DIR-615_A_M1/Firmware/20140114_1031_DIR_615A_2.0.17_sdk-master.des
Jan 1 17:00:13 autoupdate[6074]: Update not needed
Jan 1 17:00:18 pptp[6035]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.

Jan 1 19:10:11 syslogd started: BusyBox v1.19.2
Jan 1 19:10:15 save_to_flash[6649]: file size is 2117
Jan 1 19:41:32 kernel: wlan0: A wireless client is associated - 70:72:0D:31:CA:C5
Jan 1 19:41:32 kernel: BIT(11) of 0xd2c = 1, 0xd2c = 0xcc979975
Jan 1 19:41:32 kernel: wlan0: WPA2-AES PSK authentication in progress...
Jan 1 19:41:32 kernel: wlan0: A wireless client is associated - 70:72:0D:31:CA:C5
Jan 1 19:41:32 kernel: wlan0: Open and authenticated
Jan 1 20:02:36 kernel: wlan0: A wireless client is deauthenticated - 70:72:0D:31:CA:C5
Jan 1 20:02:56 miniupnpd[246]: remove port mapping 48496 TCP because it has expired
Jan 1 20:21:40 resident_phy_link_handler[6692]: record = phy:1;status:0
Jan 1 20:21:40 resident_phy_link_handler[6692]: phy - 1, status - 0
Jan 1 20:21:40 kernel: Wan link is down
Jan 1 20:21:40 resident_phy_link_handler[6692]: etherwan key_l2=eth1
Jan 1 20:21:40 resident[6692]: stop pppd on eth1_3
Jan 1 20:21:40 resident[6692]: wan_down: name = ppp1
Jan 1 20:21:41 resident[6692]: set lock on eth1_3, with "/var/lock/eth1_3.lock"
Jan 1 20:21:41 stop_pppd[6692]: buffer for tunnel ifaces/eth1/services/eth1_4/tunnels/eth1_3/
Jan 1 20:21:41 pppd[6025]: Connect time 202.4 minutes.
Jan 1 20:21:41 pppd[6025]: Sent 129497835 bytes, received 1381957318 bytes.
Jan 1 20:21:41 stop_pppd[6692]: waiting, until pppd is finished
Jan 1 20:21:41 stop_pppd[6692]: pppd proc dir: /proc/6025
Jan 1 20:21:41 stop_pppd[6692]: wait time 0 msec
Jan 1 20:21:41 pppd[6025]: Connection terminated.
Jan 1 20:21:41 event[6711]: ppp_down - do event
Jan 1 20:21:41 miniupnpd[246]: ioctl(s, SIOCGIFADDR, ...): Cannot assign requested address
Jan 1 20:21:41 miniupnpd[246]: Failed to get IP for interface ppp1
Jan 1 20:21:41 miniupnpd[246]: SendNATPMPPublicAddressChangeNotification: cannot get public IP address, stopping
Jan 1 20:21:41 resident_ppp_handler[6715]: [6715] record = action:down;iface:ppp1;link:eth1_3;
Jan 1 20:21:41 resident_ppp_handler[6715]: Set default type: ipv4
Jan 1 20:21:41 stop_pppd[6692]: wait time 200 msec
Jan 1 20:21:41 stop_pppd[6692]: wait time 400 msec
Jan 1 20:21:41 stop_pppd[6692]: wait time 600 msec
Jan 1 20:21:42 stop_pppd[6692]: wait time 800 msec
Jan 1 20:21:42 stop_pppd[6692]: wait time 1000 msec
Jan 1 20:21:42 kernel: Wan link is up!
Jan 1 20:21:42 resident_phy_link_handler[6717]: record = phy:1;status:1
Jan 1 20:21:42 resident_phy_link_handler[6717]: phy - 1, status - 1
Jan 1 20:21:42 stop_pppd[6692]: wait time 1200 msec
Jan 1 20:21:42 stop_pppd[6692]: wait time 1400 msec
Jan 1 20:21:42 stop_pppd[6692]: wait time 1600 msec
Jan 1 20:21:43 stop_pppd[6692]: wait time 1800 msec
Jan 1 20:21:43 stop_pppd[6692]: wait time 2000 msec
Jan 1 20:21:43 stop_pppd[6692]: wait time 2200 msec
Jan 1 20:21:43 stop_pppd[6692]: wait time 2400 msec
Jan 1 20:21:43 stop_pppd[6692]: wait time 2600 msec
Jan 1 20:21:44 stop_pppd[6692]: wait time 2800 msec
Jan 1 20:21:44 pppd[6025]: Connection terminated.
Jan 1 20:21:44 stop_pppd[6692]: wait time 3000 msec
Jan 1 20:21:44 pppd[6025]: send_event_to_daemon
Jan 1 20:21:44 pptp[6027]: anon warn[decaps_hdlc:pptp_gre.c:216]: pppd may have shutdown, see pppd log
Jan 1 20:21:44 pptp[6035]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 12 'Call-Clear-Request'
Jan 1 20:21:44 pptp[6035]: anon log[call_callback:pptp_callmgr.c:79]: Closing connection (call state)
Jan 1 20:21:44 stop_pppd[6692]: wait time 3200 msec
Jan 1 20:21:44 pppd[6025]: Exit.
Jan 1 20:21:44 resident_ppp_handler[6724]: [6724] record = action:down;iface:ppp1;status:5;link:eth1_3;
Jan 1 20:21:44 resident_ppp_handler[6724]: Set default type: ipv4
Jan 1 20:21:44 stop_pppd[6692]: wait time 3400 msec
Jan 1 20:21:44 stop_pppd[6692]: pppd is finished good
Jan 1 20:21:44 resident[6692]: stopped link(contype:pptp, iface:eth1_3)
Jan 1 20:21:46 resident[6692]: stop ipoe on eth1_4
Jan 1 20:21:46 resident[6692]: set lock /var/lock/eth1_4.lock
Jan 1 20:21:46 resident[6692]: wan_down: name = eth1
Jan 1 20:21:46 event[6729]: file "/var/lock/eth1_4.lock" is set
Jan 1 20:21:46 event[6729]: udhcp_down fake
Jan 1 20:21:49 resident_phy_link_handler[6692]: Exit
Jan 1 20:21:49 resident_ppp_handler[6715]: l2:eth1, l3:eth1_4, tun:eth1_3
Jan 1 20:21:49 ppp_is_down[6715]: echo 1 > /proc/fast_pptp
Jan 1 20:21:52 resident[6715]: ppp_is_down on eth1_3
Jan 1 20:21:52 resident[6715]: wan_down: name = ppp1
Jan 1 20:21:52 triggerPingRespond[6715]: OK
Jan 1 20:21:52 autoupdate[6715]: Config reset
Jan 1 20:21:52 resident_ppp_handler[6724]: l2:eth1, l3:eth1_4, tun:eth1_3
Jan 1 20:21:52 resident_ppp_handler[6724]: ppp state 5
Jan 1 20:21:52 resident_ppp_handler[6724]: status: 5 (5)
Jan 1 20:21:52 ppp_is_down[6724]: echo 1 > /proc/fast_pptp
Jan 1 20:21:55 resident[6724]: ppp_is_down on eth1_3
Jan 1 20:21:55 resident[6724]: wan_down: name = ppp1
Jan 1 20:21:55 triggerPingRespond[6724]: OK
Jan 1 20:21:55 autoupdate[6724]: Config reset
Jan 1 20:21:55 resident_phy_link_handler[6717]: etherwan key_l2=eth1
Jan 1 20:21:55 resident[6717]: start ipoe
Jan 1 20:21:55 start_ip[6717]: begin (eth1 -> eth1_4)
Jan 1 20:21:55 resident[6717]: start ipoe (v0) on eth1
Jan 1 20:21:55 event[6767]: send event "ipoe down"
Jan 1 20:21:55 resident[231]: record = action:down;iface:eth1;contag:4;
Jan 1 20:21:55 resident[231]: phys_iface eth1
Jan 1 20:21:55 resident_ipoe_handler[231]: ip_type = (null)
Jan 1 20:21:55 resident[231]: resident_ipoe_handler - Set default type: ipv4
Jan 1 20:21:55 resident[231]: phys_iface
Jan 1 20:21:55 resident[231]: resident_ipoe_handler - 2
Jan 1 20:21:55 resident[231]: resident_ipoe_handler - 3, name: eth1_4
Jan 1 20:21:55 event[6772]: send event "ipoe up"
Jan 1 20:21:57 resident_phy_link_handler[6717]: Exit
Jan 1 20:21:57 resident[231]: resident_ipoe_handler - l2:eth1, l3:eth1_4, tun:eth1_3, ip_type:0
Jan 1 20:21:57 resident[231]: ip_is_down on eth1_4
Jan 1 20:21:57 resident[231]: wan_down: name = eth1_4
Jan 1 20:21:57 triggerPingRespond[231]: OK
Jan 1 20:21:57 autoupdate[231]: Config reset
Jan 1 20:21:57 resident[231]: record = action:up;iface:eth1;contag:4;ipaddr:10.31.5.150 ;netmask:255.255.255.0 ;gateway:10.31.5.1 ;dns1:10.31.255.1;
Jan 1 20:21:57 resident[231]: phys_iface eth1
Jan 1 20:21:57 resident_ipoe_handler[231]: ip_type = (null)
Jan 1 20:21:57 resident[231]: resident_ipoe_handler - Set default type: ipv4
Jan 1 20:21:57 resident[231]: phys_iface
Jan 1 20:21:57 resident[231]: resident_ipoe_handler - 2
Jan 1 20:21:57 resident[231]: resident_ipoe_handler - 3, name: eth1_4
Jan 1 20:21:57 resident[231]: resident_ipoe_handler - l2:eth1, l3:eth1_4, tun:eth1_3, ip_type:0
Jan 1 20:21:57 resident[231]: resident_ipoe_handler - ipv4
Jan 1 20:21:57 resident[231]: start ip_is_up on iface eth1_4
Jan 1 20:21:57 resident[231]: ip_is_up for link eth1_4
Jan 1 20:21:57 set_dhcp_routes[231]: set routes for iface eth1
Jan 1 20:21:57 DMS_NL_API[231]: Rtnetlink answer: No such process
Jan 1 20:21:57 DMS_NL_API[231]: Error deleting route
Jan 1 20:21:58 resident[231]: update rip
Jan 1 20:21:58 resident[231]: update igmpx
Jan 1 20:21:58 resident[231]: stop igmpx
Jan 1 20:21:58 resident[231]: igmpx
Jan 1 20:21:58 syslog: Loading liblogger v2.0.2
Jan 1 20:21:58 syslog: upstream ifaces:
Jan 1 20:21:58 syslog: downstream ifaces: br0
Jan 1 20:21:58 syslog: hews interval = 120.0 sec
Jan 1 20:21:58 syslog: doublet join enabled
Jan 1 20:21:58 syslog: Using old argv style
Jan 1 20:21:58 syslog: daemonizing ...
Jan 1 20:21:58 syslog: kernel multicast version is 305 (v3.05 ?)
Jan 1 20:21:58 syslog: kernel mroute inited
Jan 1 20:21:58 syslog: proxy prepared
Jan 1 20:21:58 syslog: vifi #0 added for br0
Jan 1 20:21:58 syslog: valid VIF's configured: upstreams=0, downstreams=1. Bad.
Jan 1 20:21:58 resident[231]: update igmpx compiled
Jan 1 20:21:58 resident[231]: update ntpclient
Jan 1 20:21:58 update_ntpclient[231]: ntpclient is disable
Jan 1 20:21:58 start_pppd[231]: tunnel: ifaces/eth1/services/eth1_4/tunnels/eth1_3/
Jan 1 20:21:58 resident[231]: start pppd on eth1_3
Jan 1 20:21:58 resident[231]: stop pppd on eth1_3
Jan 1 20:21:58 resident[231]: wan_down: name = ppp1
Jan 1 20:21:58 resident[231]: set lock on eth1_3, with "/var/lock/eth1_3.lock"
Jan 1 20:21:58 stop_pppd[231]: buffer for tunnel ifaces/eth1/services/eth1_4/tunnels/eth1_3/
Jan 1 20:21:59 resident[231]: stopped link(contype:pptp, iface:eth1_3)
Jan 1 20:21:59 pppd[6857]: entering dlock, dfd = -1
Jan 1 20:21:59 pppd[6857]: dlock: locked
Jan 1 20:21:59 pppd[6857]: pppd 2.4.4 started by admin, uid 0
Jan 1 20:21:59 pptp[6859]: anon log[main:pptp.c:331]: The synchronous pptp option is NOT activated
Jan 1 20:21:59 pppd[6857]: Using interface ppp1
Jan 1 20:21:59 pppd[6857]: Connect: ppp1 <--> /dev/pts/0
Jan 1 20:21:59 pptp[6867]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 1 'Start-Control-Connection-Request'
Jan 1 20:21:59 pptp[6867]: anon log[ctrlp_disp:pptp_ctrl.c:739]: Received Start Control Connection Reply
Jan 1 20:21:59 pptp[6867]: anon log[ctrlp_disp:pptp_ctrl.c:773]: Client connection established.
Jan 1 20:22:00 pptp[6867]: anon log[ctrlp_rep:pptp_ctrl.c:251]: Sent control packet type is 7 'Outgoing-Call-Request'
Jan 1 20:22:00 pptp[6867]: anon log[ctrlp_disp:pptp_ctrl.c:858]: Received Outgoing Call Reply.
Jan 1 20:22:00 pptp[6867]: anon log[ctrlp_disp:pptp_ctrl.c:897]: Outgoing call established (call ID 0, peer's call ID 13312).
Jan 1 20:22:00 pppd[6857]: Remote message: Authentication succeeded
Jan 1 20:22:00 pppd[6857]: PAP authentication succeeded
Jan 1 20:22:02 triggerPingRespond[231]: OK
Jan 1 20:22:03 pppd[6857]: local IP address 172.16.5.150
Jan 1 20:22:03 pppd[6857]: remote IP address 172.16.0.1
Jan 1 20:22:03 pppd[6857]: primary DNS address 10.31.255.1
Jan 1 20:22:03 pppd[6857]: secondary DNS address 8.8.8.8
Jan 1 20:22:05 autoupdate[231]: Downloading file: fwupdate.dlink.ru/dislocation
Jan 1 20:22:05 resident_ppp_handler[6903]: [6903] record = action:up;iface:ppp1;link:eth1_3;ipaddr:172.16.5.150;netmask:255.255.255.255;gateway:172.16.0.1;dns1:10.31.255.1;dns2:8.8.8.8;sess_id:;
Jan 1 20:22:05 resident_ppp_handler[6903]: Set default type: ipv4
Jan 1 20:22:05 resident_ppp_handler[6903]: l2:eth1, l3:eth1_4, tun:eth1_3
Jan 1 20:22:05 resident[6903]: ppp_is_up on eth1_3
Jan 1 20:22:06 resident[6903]: update rip
Jan 1 20:22:06 resident[6903]: update ntpclient
Jan 1 20:22:06 update_ntpclient[6903]: ntpclient is disable
Jan 1 20:22:06 triggerPingRespond[6903]: OK
Jan 1 20:22:09 autoupdate[6903]: Downloading file: fwupdate.dlink.ru/dislocation
Jan 1 20:22:09 autoupdate[6903]: Downloading file: fwupdate.dlink.ru/Router/DIR-615_A_M1/Firmware/exclude
Jan 1 20:22:09 autoupdate[6903]: Not found exception
Jan 1 20:22:10 autoupdate[6903]: Downloading file: fwupdate.dlink.ru/Router/DIR-615_A_M1/Firmware/latest
Jan 1 20:22:10 autoupdate[6903]: Downloading file: fwupdate.dlink.ru/Router/DIR-615_A_M1/Firmware/20140114_1031_DIR_615A_2.0.17_sdk-master.des
Jan 1 20:22:10 autoupdate[6903]: Update not needed
Jan 1 20:23:00 pptp[6867]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Jan 1 20:24:00 pptp[6867]: anon log[logecho:pptp_ctrl.c:677]: Echo Reply received.
Обновить
Экспорт