May 7 22:46:45 kernel: error!!add nexthop error! retval (-9) May 7 22:46:45 route[3025]: delete route May 7 22:46:45 route[3025]: create_command May 7 22:46:46 pppd[1752]: calltimeout:do it May 7 22:47:14 pptp[1761]: anon log[pptp_handle_timer:pptp_ctrl.c:1127]: ------> Sending PPTP request!!! May 7 22:47:15 route[3041]: delete route May 7 22:47:15 route[3041]: create_command May 7 22:47:15 kernel: error!!add nexthop error! retval (-9) May 7 22:47:15 route[3041]: delete route May 7 22:47:15 route[3041]: create_command May 7 22:47:16 pppd[1752]: calltimeout:do it May 7 22:47:16 pppd[1752]: calltimeout:dump session info May 7 22:47:45 route[3057]: delete route May 7 22:47:45 route[3057]: create_command May 7 22:47:45 kernel: error!!add nexthop error! retval (-9) May 7 22:47:45 route[3057]: delete route May 7 22:47:45 route[3057]: create_command May 7 22:47:46 pppd[1752]: calltimeout:do it May 7 22:47:46 pppd[1752]: No response to 3 echo-requests May 7 22:47:46 pppd[1752]: Serial link appears to be disconnected. May 7 22:47:46 pppd[1752]: Connect time 32.6 minutes. May 7 22:47:46 pppd[1752]: Sent 15773571 bytes, received 130474379 bytes. May 7 22:47:46 pppd[1752]: Connection terminated. May 7 22:47:46 event[3073]: ppp_down - do event May 7 22:47:46 pppd[1752]: Script /etc/ppp/ip-down started (pid 3073) May 7 22:47:46 pppd[1752]: sent [LCP TermReq id=0x4 "Peer not responding"] May 7 22:47:46 pppd[1752]: calltimeout:do it May 7 22:47:46 pppd[1752]: Script /etc/ppp/ip-down finished (pid 3073), status = 0x0 May 7 22:47:46 resident_ppp_handler[3076]: record = action:down;iface:ppp0;link:eth1_2; May 7 22:47:46 resident_ppp_handler[3076]: Set default type: ipv4 May 7 22:47:46 resident_ppp_handler[3076]: l2:eth1, l3:eth1_1, tun:eth1_2 May 7 22:47:46 ppp_is_down[3076]: echo 1 > /proc/fast_pptp May 7 22:47:49 pppd[1752]: calltimeout:do it May 7 22:47:49 pppd[1752]: sent [LCP TermReq id=0x5 "Peer not responding"] May 7 22:47:49 resident[3076]: ppp_is_down on eth1_2 May 7 22:47:49 wan_down[3076]: name = ppp0 May 7 22:47:49 triggerPingRespond[3076]: OK May 7 22:47:49 autoupdate[3076]: Config reset May 7 22:47:49 IPSEC[3076]: Updating config May 7 22:47:52 pppd[1752]: calltimeout:do it May 7 22:47:52 pppd[1752]: Connection terminated. May 7 22:47:52 pppd[1752]: Modem hangup May 7 22:47:52 event[3091]: ppp_down - do event May 7 22:47:52 pptp[1754]: anon warn[decaps_hdlc:pptp_gre.c:204]: short read (-1): Input/output error May 7 22:47:52 pptp[1754]: anon warn[decaps_hdlc:pptp_gre.c:216]: pppd may have shutdown, see pppd log May 7 22:47:52 pptp[1761]: anon log[callmgr_main:pptp_callmgr.c:234]: Closing connection (unhandled) May 7 22:47:52 pptp[1761]: anon log[ctrlp_rep:pptp_ctrl.c:263]: Sent control packet type is 12 'Call-Clear-Request' May 7 22:47:52 pptp[1761]: anon log[call_callback:pptp_callmgr.c:79]: Closing connection (call state) May 7 22:47:52 resident_ppp_handler[3094]: record = action:down;iface:ppp0;link:eth1_2;status:8; May 7 22:47:52 resident_ppp_handler[3094]: Set default type: ipv4 May 7 22:47:52 resident_ppp_handler[3094]: l2:eth1, l3:eth1_1, tun:eth1_2 May 7 22:47:52 resident_ppp_handler[3094]: ppp state 1 May 7 22:47:52 resident_ppp_handler[3094]: Restart pppd May 7 22:47:52 resident_ppp_handler[3094]: pppd retry file not exist May 7 22:47:52 resident_ppp_handler[3094]: retry_cnt - 0, retry_block_cnt 1 May 7 22:47:52 resident[3094]: stop pppd on eth1_2 May 7 22:47:52 wan_down[3094]: name = ppp0 May 7 22:47:52 pppd[1752]: Script /etc/ppp/ip-down started (pid 3091) May 7 22:47:52 pppd[1752]: send_event_to_daemon May 7 22:47:53 pppd[1752]: set ALREADY May 7 22:47:53 resident_ppp_handler[3099]: record = action:down;iface:ppp0;status:16;link:eth1_2;already:1; May 7 22:47:53 resident_ppp_handler[3099]: Set default type: ipv4 May 7 22:47:53 resident_ppp_handler[3099]: l2:eth1, l3:eth1_1, tun:eth1_2 May 7 22:47:53 resident_ppp_handler[3099]: ppp state 1 May 7 22:47:53 resident_ppp_handler[3099]: Restart pppd May 7 22:47:53 resident_ppp_handler[3099]: retry_cnt - 0, retry_block_cnt 2 May 7 22:47:53 pppd[1752]: Exit. May 7 22:47:53 resident[3099]: stop pppd on eth1_2 May 7 22:47:53 wan_down[3099]: name = ppp0 May 7 22:47:53 triggerPingRespond[3094]: OK May 7 22:47:53 resident[3094]: set lock on eth1_2, with "/var/lock/eth1_2.lock" May 7 22:47:53 stop_pppd[3094]: buffer for tunnel ifaces/eth1/services/eth1_1/tunnels/eth1_2/ May 7 22:47:53 triggerPingRespond[3099]: OK May 7 22:47:53 resident[3094]: stopped link(contype:pptp, iface:eth1_2) May 7 22:47:53 resident_ppp_handler[3094]: sleep - 2 sec May 7 22:47:53 resident[3099]: set lock on eth1_2, with "/var/lock/eth1_2.lock" May 7 22:47:53 stop_pppd[3099]: buffer for tunnel ifaces/eth1/services/eth1_1/tunnels/eth1_2/ May 7 22:47:53 resident[3099]: stopped link(contype:pptp, iface:eth1_2) May 7 22:47:53 resident_ppp_handler[3099]: sleep - 4 sec May 7 22:48:02 start_pppd[3094]: [3094] kill retry proccess 3099 May 7 22:48:02 start_pppd[3094]: tunnel: ifaces/eth1/services/eth1_1/tunnels/eth1_2/ May 7 22:48:02 resident[3094]: start pppd on eth1_2 May 7 22:48:02 resident[3094]: stop pppd on eth1_2 May 7 22:48:02 wan_down[3094]: name = ppp0 May 7 22:48:02 resident_rpc[1027]: RPC 0 with pid 3099 killed by signal 9 May 7 22:48:02 triggerPingRespond[3094]: OK May 7 22:48:02 resident[3094]: set lock on eth1_2, with "/var/lock/eth1_2.lock" May 7 22:48:02 stop_pppd[3094]: buffer for tunnel ifaces/eth1/services/eth1_1/tunnels/eth1_2/ May 7 22:48:02 resident[3094]: stopped link(contype:pptp, iface:eth1_2) May 7 22:48:02 write_resolvable_vpn_server_address[3094]: servicename 89.222.128.125 is IP-adrress May 7 22:48:02 pppd[3146]: entering dlock, dfd = -1 May 7 22:48:02 pppd[3146]: dlock: locked May 7 22:48:02 pppd[3146]: pppd 2.4.4 started by admin, uid 0 May 7 22:48:02 pppd[3146]: using channel 4 May 7 22:48:02 pptp[3148]: anon log[main:pptp.c:331]: The synchronous pptp option is NOT activated May 7 22:48:02 pppd[3146]: Using interface ppp0 May 7 22:48:02 pppd[3146]: Connect: ppp0 <--> /dev/pts/0 May 7 22:48:02 pptp[3155]: anon log[ctrlp_rep:pptp_ctrl.c:263]: Sent control packet type is 1 'Start-Control-Connection-Request' May 7 22:48:02 pptp[3155]: anon log[ctrlp_disp:pptp_ctrl.c:760]: Received Start Control Connection Reply May 7 22:48:02 pptp[3155]: anon log[ctrlp_disp:pptp_ctrl.c:794]: Client connection established. May 7 22:48:03 pppd[3146]: calltimeout:do it May 7 22:48:03 pppd[3146]: calltimeout:dump session info May 7 22:48:03 pppd[3146]: sent [LCP ConfReq id=0x1 ] May 7 22:48:03 pptp[3155]: anon log[ctrlp_rep:pptp_ctrl.c:263]: Sent control packet type is 7 'Outgoing-Call-Request' May 7 22:48:03 pptp[3155]: anon log[ctrlp_disp:pptp_ctrl.c:879]: Received Outgoing Call Reply. May 7 22:48:03 pptp[3155]: anon log[ctrlp_disp:pptp_ctrl.c:918]: Outgoing call established (call ID 0, peer's call ID 1103). May 7 22:48:03 pppd[3146]: calltimeout:do it May 7 22:48:03 pppd[3146]: rcvd [LCP ConfReq id=0x1 ] May 7 22:48:03 pppd[3146]: sent [LCP ConfRej id=0x1 ] May 7 22:48:03 pppd[3146]: calltimeout:do it May 7 22:48:03 pppd[3146]: rcvd [LCP ConfRej id=0x1 ] May 7 22:48:03 pppd[3146]: sent [LCP ConfReq id=0x2 ] May 7 22:48:03 pppd[3146]: calltimeout:do it May 7 22:48:03 pppd[3146]: rcvd [LCP ConfAck id=0x2 ] May 7 22:48:03 pppd[3146]: calltimeout:do it May 7 22:48:03 pppd[3146]: rcvd [LCP ConfReq id=0x2 ] May 7 22:48:03 pppd[3146]: sent [LCP ConfAck id=0x2 ] May 7 22:48:03 pppd[3146]: sent [LCP EchoReq id=0x0 magic=0xdfa32721] May 7 22:48:03 pppd[3146]: calltimeout:do it May 7 22:48:03 pppd[3146]: rcvd [CHAP Challenge id=0x1 , name = "MikroTik"] May 7 22:48:03 pppd[3146]: sent [CHAP Response id=0x1 <25c2300bdd4a0d268b37df9e88534dde>, name = "neskushaika"] May 7 22:48:03 pppd[3146]: calltimeout:do it May 7 22:48:03 pppd[3146]: rcvd [CHAP Success id=0x1 "Welcome."] May 7 22:48:03 pppd[3146]: CHAP authentication succeeded: Welcome. May 7 22:48:03 pppd[3146]: CHAP authentication succeeded May 7 22:48:03 pppd[3146]: sent [CCP ConfReq id=0x1 ] May 7 22:48:03 pppd[3146]: sent [IPCP ConfReq id=0x1 ] May 7 22:48:03 pppd[3146]: calltimeout:do it May 7 22:48:03 pppd[3146]: rcvd [IPCP ConfReq id=0x1 ] May 7 22:48:03 pppd[3146]: sent [IPCP ConfAck id=0x1 ] May 7 22:48:03 pppd[3146]: calltimeout:do it May 7 22:48:03 pppd[3146]: rcvd [proto=0x8031] 01 01 00 0c 03 03 01 08 03 01 09 02 May 7 22:48:03 pppd[3146]: Unsupported protocol 'Bridging NCP' (0x8031) received May 7 22:48:03 pppd[3146]: sent [LCP ProtRej id=0x3 80 31 01 01 00 0c 03 03 01 08 03 01 09 02] May 7 22:48:03 pppd[3146]: calltimeout:do it May 7 22:48:03 pppd[3146]: rcvd [CCP ConfReq id=0x1] May 7 22:48:03 pppd[3146]: sent [CCP ConfAck id=0x1] May 7 22:48:03 pppd[3146]: calltimeout:do it May 7 22:48:03 pppd[3146]: rcvd [CCP ConfRej id=0x1 ] May 7 22:48:03 pppd[3146]: sent [CCP ConfReq id=0x2] May 7 22:48:03 pppd[3146]: calltimeout:do it May 7 22:48:03 pppd[3146]: rcvd [IPCP ConfNak id=0x1 ] May 7 22:48:03 pppd[3146]: sent [IPCP ConfReq id=0x2 ] May 7 22:48:03 pppd[3146]: calltimeout:do it May 7 22:48:03 pppd[3146]: rcvd [CCP ConfAck id=0x2] May 7 22:48:03 pppd[3146]: calltimeout:do it May 7 22:48:03 pppd[3146]: rcvd [CCP TermReq id=0x2"No compression negotiated"] May 7 22:48:03 pppd[3146]: CCP terminated by peer (No compression negotiated) May 7 22:48:03 pppd[3146]: sent [CCP TermAck id=0x2] May 7 22:48:03 pppd[3146]: Compression disabled by peer. May 7 22:48:03 pppd[3146]: calltimeout:do it May 7 22:48:03 pppd[3146]: rcvd [IPCP ConfAck id=0x2 ] May 7 22:48:03 pppd[3146]: local IP address 192.168.0.215 May 7 22:48:03 pppd[3146]: remote IP address 192.168.0.1 May 7 22:48:03 pppd[3146]: primary DNS address 192.168.0.1 May 7 22:48:03 pppd[3146]: secondary DNS address 89.222.130.6 May 7 22:48:03 pppd[3146]: Script /etc/ppp/ip-up started (pid 3160) May 7 22:48:03 resident_ppp_handler[3163]: record = action:up;iface:ppp0;link:eth1_2;ipaddr:192.168.0.215;netmask:255.255.255.255;gateway:192.168.0.1;dns1:192.168.0.1;dns2:89.222.130.6;sess_id:; May 7 22:48:03 resident_ppp_handler[3163]: Set default type: ipv4 May 7 22:48:03 resident_ppp_handler[3163]: l2:eth1, l3:eth1_1, tun:eth1_2 May 7 22:48:03 resident[3163]: ppp_is_up on eth1_2 May 7 22:48:03 DMS_ROUTE_SUCCESS[3163]: ADD default via (null) dev ppp0 metr 0 table 253 (ppp_is_up) May 7 22:48:03 pppd[3146]: calltimeout:do it May 7 22:48:03 pppd[3146]: Script /etc/ppp/ip-up finished (pid 3160), status = 0x0 May 7 22:48:03 triggerPingRespond[3163]: OK May 7 22:48:03 DMS_ROUTE[3163]: try add user route: ip ro a 89.222.128.125/32 via 192.168.8.1 May 7 22:48:03 DMS_ROUTE[3163]: try add user route: ip ro a 0.0.0.0/0 via 192.168.0.1 May 7 22:48:03 resident[3163]: update rip May 7 22:48:04 recheck_dnsmasq[3163]: MD5 sum of /tmp/dnsmasq.conf before and after matching, not restarting DNSMASQ May 7 22:48:04 autoupdate[3163]: Downloading file: fwupdate.dlink.ru/dislocation May 7 22:48:04 autoupdate[3163]: No dislocation May 7 22:48:04 IPSEC[3163]: Updating config May 7 22:48:06 pppd[3146]: calltimeout:do it May 7 22:48:15 route[3198]: delete route May 7 22:48:15 route[3198]: create_command May 7 22:48:15 kernel: error!!add nexthop error! retval (-9) А вот так он разрывает и создает туннель.
|