Sep 25 15:28:34 bob pppd[3739]: pppd 2.4.2 started by root, uid 0 Sep 25 15:28:34 bob pppd[3739]: Serial connection established. Sep 25 15:28:34 bob pppd: Serial connection established. Sep 25 15:28:34 bob pppd: using channel 5 Sep 25 15:28:34 bob pptp[3740]: anon log[main:pptp.c:243]: The synchronous pptp option is NOT activated Sep 25 15:28:34 bob pppd[3739]: Using interface ppp0 Sep 25 15:28:34 bob pppd[3739]: Connect: ppp0 <--> /dev/pts/0 Sep 25 15:28:34 bob pptp[3744]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 1 'Start-Control-Connection-Request' Sep 25 15:28:34 bob pppd: Using interface ppp0 Sep 25 15:28:34 bob pppd: Connect: ppp0 <--> /dev/pts/0 Sep 25 15:28:34 bob pptp[3744]: anon log[ctrlp_disp:pptp_ctrl.c:721]: Received Start Control Connection Reply Sep 25 15:28:34 bob pptp[3744]: anon log[ctrlp_disp:pptp_ctrl.c:755]: Client connection established. Sep 25 15:28:35 bob pptp[3744]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 7 'Outgoing-Call-Request' Sep 25 15:28:35 bob pppd: sent [LCP ConfReq id=0x1 ] Sep 25 15:28:35 bob pptp[3744]: anon log[ctrlp_disp:pptp_ctrl.c:841]: Received Outgoing Call Reply. Sep 25 15:28:35 bob pptp[3744]: anon log[ctrlp_disp:pptp_ctrl.c:880]: Outgoing call established (call ID 0, peer's call ID 8960). Sep 25 15:28:35 bob pppd: rcvd [LCP ConfReq id=0x1 ] Sep 25 15:28:35 bob pppd: sent [LCP ConfNak id=0x1 ] Sep 25 15:28:35 bob pppd: rcvd [LCP ConfNak id=0x1 ] Sep 25 15:28:35 bob pppd: sent [LCP ConfReq id=0x2 ] Sep 25 15:28:35 bob pppd: rcvd [LCP ConfReq id=0x1 ] Sep 25 15:28:35 bob pppd: sent [LCP ConfRej id=0x1 ] Sep 25 15:28:35 bob pppd: rcvd [LCP ConfAck id=0x2 ] Sep 25 15:28:35 bob pppd: rcvd [LCP ConfReq id=0x2 ] Sep 25 15:28:35 bob pppd: sent [LCP ConfAck id=0x2 ] Sep 25 15:28:35 bob pppd: rcvd [CHAP Challenge id=0x55 , name = "gamuza.status-m.ru"] Sep 25 15:28:35 bob pppd: sent [CHAP Response id=0x55 <06f2d7449d70b7b2afc785a629db873200000000000000006b3cd8747cf43ee8c9087343708dcb897b732557360ba16d00>, name = "bobah"] Sep 25 15:28:35 bob pppd: rcvd [CHAP Success id=0x55 "S=AE96E865A76AD7E9F6EB93B5545BB6B0BB25D132"] Sep 25 15:28:35 bob pppd: sent [CCP ConfReq id=0x1 ] Sep 25 15:28:35 bob pppd: sent [IPCP ConfReq id=0x1 ] Sep 25 15:28:35 bob pppd: rcvd [CCP ConfReq id=0x1 ] Sep 25 15:28:35 bob pppd: sent [CCP ConfRej id=0x1 ] Sep 25 15:28:35 bob pppd: rcvd [IPCP ConfReq id=0x1 ] Sep 25 15:28:35 bob pppd: sent [IPCP ConfAck id=0x1 ] Sep 25 15:28:35 bob pppd: rcvd [CCP ConfAck id=0x1 ] Sep 25 15:28:35 bob pppd: rcvd [IPCP ConfNak id=0x1 ] Sep 25 15:28:35 bob pppd: sent [IPCP ConfReq id=0x2 ] Sep 25 15:28:35 bob pppd[3739]: Deflate (15) compression enabled Sep 25 15:28:35 bob pppd: rcvd [CCP ConfReq id=0x2 ] Sep 25 15:28:35 bob pppd: sent [CCP ConfAck id=0x2 ] Sep 25 15:28:35 bob pppd: Deflate (15) compression enabled Sep 25 15:28:35 bob pppd: rcvd [IPCP ConfAck id=0x2 ] Sep 25 15:28:35 bob pppd[3739]: local IP address 10.0.10.10 Sep 25 15:28:35 bob pppd[3739]: remote IP address 10.0.2.100 Sep 25 15:28:35 bob pppd: local IP address 10.0.10.10 Sep 25 15:28:35 bob pppd: remote IP address 10.0.2.100 Sep 25 15:28:35 bob pptptunnel: pppd startup succeeded Sep 25 15:28:38 bob pppd[3751]: Deflate (15) compression enabled Sep 25 15:29:02 bob last message repeated 8 times Sep 25 15:29:35 bob pptp[3744]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 5 'Echo-Request' Sep 25 15:29:35 bob pptp[3744]: anon log[logecho:pptp_ctrl.c:659]: Echo Reply received. Sep 25 15:30:35 bob pptp[3744]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 5 'Echo-Request' Sep 25 15:30:35 bob pptp[3744]: anon log[logecho:pptp_ctrl.c:659]: Echo Reply received. Sep 25 15:31:35 bob pptp[3744]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 5 'Echo-Request' Sep 25 15:31:35 bob pptp[3744]: anon log[logecho:pptp_ctrl.c:659]: Echo Reply received. Sep 25 15:31:52 bob pppd[3751]: Terminating on signal 15. Sep 25 15:31:52 bob pppd[3751]: Connection terminated. Sep 25 15:31:52 bob pppd[3751]: Connect time 3.3 minutes. Sep 25 15:31:52 bob pppd[3751]: Sent 670 bytes, received 363 bytes. Sep 25 15:31:52 bob pptp[3740]: anon warn[decaps_hdlc:pptp_gre.c:197]: short read (-1): Input/output error Sep 25 15:31:52 bob pptp[3740]: anon warn[decaps_hdlc:pptp_gre.c:209]: pppd may have shutdown, see pppd log Sep 25 15:31:52 bob pptp[3744]: anon log[callmgr_main:pptp_callmgr.c:228]: Closing connection Sep 25 15:31:52 bob pptp[3744]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 12 'Call-Clear-Request' Sep 25 15:31:52 bob pptp[3744]: anon log[ctrlp_disp:pptp_ctrl.c:912]: Call disconnect notification received (call id 8960) Sep 25 15:31:52 bob pptp[3744]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 12 'Call-Clear-Request' Sep 25 15:31:52 bob pptp[3744]: anon log[pptp_conn_close:pptp_ctrl.c:425]: Closing PPTP connection Sep 25 15:31:52 bob pptp[3744]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 3 'Stop-Control-Connection-Request' Sep 25 15:31:52 bob pppd[3751]: Connect time 3.3 minutes. Sep 25 15:31:52 bob pppd[3751]: Sent 670 bytes, received 363 bytes. Sep 25 15:31:52 bob pppd[3751]: Exit. Sep 25 15:31:52 bob pptp[3744]: anon log[ctrlp_disp:pptp_ctrl.c:912]: Call disconnect notification received (call id 65535) Sep 25 15:31:53 bob pptptunnel: pppd shutdown succeeded Sep 25 15:31:54 bob pptp[3744]: anon log[call_callback:pptp_callmgr.c:77]: Closing connection Sep 25 15:32:01 bob pppd[3996]: pppd 2.4.2 started by root, uid 0 Sep 25 15:32:01 bob pppd[3996]: Serial connection established. Sep 25 15:32:01 bob pppd: Serial connection established. Sep 25 15:32:01 bob pppd: using channel 6 Sep 25 15:32:01 bob pppd[3996]: Using interface ppp0 Sep 25 15:32:01 bob pppd[3996]: Connect: ppp0 <--> /dev/pts/0 Sep 25 15:32:01 bob pppd: Using interface ppp0 Sep 25 15:32:01 bob pppd: Connect: ppp0 <--> /dev/pts/0 Sep 25 15:32:01 bob pptp[3997]: anon log[main:pptp.c:243]: The synchronous pptp option is NOT activated Sep 25 15:32:01 bob pptp[4002]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 1 'Start-Control-Connection-Request' Sep 25 15:32:01 bob pptp[4002]: anon log[ctrlp_disp:pptp_ctrl.c:721]: Received Start Control Connection Reply Sep 25 15:32:01 bob pptp[4002]: anon log[ctrlp_disp:pptp_ctrl.c:755]: Client connection established. Sep 25 15:32:02 bob pptp[4002]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 7 'Outgoing-Call-Request' Sep 25 15:32:02 bob pppd: sent [LCP ConfReq id=0x1 ] Sep 25 15:32:02 bob pptp[4002]: anon log[ctrlp_disp:pptp_ctrl.c:841]: Received Outgoing Call Reply. Sep 25 15:32:02 bob pptp[4002]: anon log[ctrlp_disp:pptp_ctrl.c:880]: Outgoing call established (call ID 0, peer's call ID 9088). Sep 25 15:32:02 bob pppd: rcvd [LCP ConfReq id=0x1 ] Sep 25 15:32:02 bob pppd: sent [LCP ConfNak id=0x1 ] Sep 25 15:32:02 bob pppd: rcvd [LCP ConfNak id=0x1 ] Sep 25 15:32:02 bob pppd: sent [LCP ConfReq id=0x2 ] Sep 25 15:32:02 bob pppd: rcvd [LCP ConfReq id=0x1 ] Sep 25 15:32:02 bob pppd: sent [LCP ConfRej id=0x1 ] Sep 25 15:32:02 bob pppd: rcvd [LCP ConfAck id=0x2 ] Sep 25 15:32:02 bob pppd: rcvd [LCP ConfReq id=0x2 ] Sep 25 15:32:02 bob pppd: sent [LCP ConfAck id=0x2 ] Sep 25 15:32:02 bob pppd: rcvd [CHAP Challenge id=0xa6 <93ca74419ce37d572642f0c2b999af85>, name = "gamuza.status-m.ru"] Sep 25 15:32:02 bob pppd: sent [CHAP Response id=0xa6 <24dc8c3cde337bb258c4fd2b02da1c9f000000000000000006229321f0c9470f8e6dfb606515e867cc135722f94ff80100>, name = "bobah"] Sep 25 15:32:02 bob pppd: rcvd [CHAP Success id=0xa6 "S=F4916B53A62F77C60D2CE709260DE95AE6D09E34"] Sep 25 15:32:02 bob pppd: sent [CCP ConfReq id=0x1 ] Sep 25 15:32:02 bob pppd: sent [IPCP ConfReq id=0x1 ] Sep 25 15:32:02 bob pppd: rcvd [CCP ConfReq id=0x1 ] Sep 25 15:32:02 bob pppd: sent [CCP ConfRej id=0x1 ] Sep 25 15:32:02 bob pppd: rcvd [IPCP ConfReq id=0x1 ] Sep 25 15:32:02 bob pppd: sent [IPCP ConfAck id=0x1 ] Sep 25 15:32:02 bob pppd: rcvd [CCP ConfAck id=0x1 ] Sep 25 15:32:02 bob pppd: rcvd [IPCP ConfNak id=0x1 ] Sep 25 15:32:02 bob pppd: sent [IPCP ConfReq id=0x2 ] Sep 25 15:32:02 bob pppd[3996]: Deflate (15) compression enabled Sep 25 15:32:02 bob pppd: rcvd [CCP ConfReq id=0x2 ] Sep 25 15:32:02 bob pppd: sent [CCP ConfAck id=0x2 ] Sep 25 15:32:02 bob pppd: Deflate (15) compression enabled Sep 25 15:32:03 bob pppd: rcvd [IPCP ConfAck id=0x2 ] Sep 25 15:32:03 bob pppd[3996]: local IP address 10.0.10.10 Sep 25 15:32:03 bob pppd[3996]: remote IP address 10.0.2.100 Sep 25 15:32:03 bob pppd: local IP address 10.0.10.10 Sep 25 15:32:03 bob pppd: remote IP address 10.0.2.100 Sep 25 15:32:03 bob pptptunnel: pppd startup succeeded Sep 25 15:32:06 bob pppd[4008]: Deflate (15) compression enabled Sep 25 15:32:30 bob last message repeated 8 times Sep 25 15:33:02 bob pptp[4002]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 5 'Echo-Request' Sep 25 15:33:02 bob pptp[4002]: anon log[logecho:pptp_ctrl.c:659]: Echo Reply received. Sep 25 15:34:02 bob pptp[4002]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 5 'Echo-Request' Sep 25 15:34:02 bob pptp[4002]: anon log[logecho:pptp_ctrl.c:659]: Echo Reply received. Sep 25 15:35:02 bob pptp[4002]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 5 'Echo-Request' Sep 25 15:35:02 bob pptp[4002]: anon log[logecho:pptp_ctrl.c:659]: Echo Reply received. Sep 25 15:36:02 bob pptp[4002]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 5 'Echo-Request' Sep 25 15:36:02 bob pptp[4002]: anon log[logecho:pptp_ctrl.c:659]: Echo Reply received. Sep 25 15:36:19 bob pppd[4008]: Terminating on signal 15. Sep 25 15:36:19 bob pppd[4008]: Connection terminated. Sep 25 15:36:19 bob pppd[4008]: Connect time 4.3 minutes. Sep 25 15:36:19 bob pppd[4008]: Sent 2170 bytes, received 451 bytes. Sep 25 15:36:19 bob pptp[3997]: anon warn[decaps_hdlc:pptp_gre.c:197]: short read (-1): Input/output error Sep 25 15:36:19 bob pptp[3997]: anon warn[decaps_hdlc:pptp_gre.c:209]: pppd may have shutdown, see pppd log Sep 25 15:36:19 bob pptp[4002]: anon log[callmgr_main:pptp_callmgr.c:228]: Closing connection Sep 25 15:36:19 bob pptp[4002]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 12 'Call-Clear-Request' Sep 25 15:36:19 bob pppd[4008]: Connect time 4.3 minutes. Sep 25 15:36:19 bob pppd[4008]: Sent 2170 bytes, received 451 bytes. Sep 25 15:36:19 bob pppd[4008]: Exit. Sep 25 15:36:19 bob pptp[4002]: anon log[ctrlp_disp:pptp_ctrl.c:912]: Call disconnect notification received (call id 9088) Sep 25 15:36:19 bob pptp[4002]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 12 'Call-Clear-Request' Sep 25 15:36:19 bob pptp[4002]: anon log[pptp_conn_close:pptp_ctrl.c:425]: Closing PPTP connection Sep 25 15:36:19 bob pptp[4002]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 3 'Stop-Control-Connection-Request' Sep 25 15:36:19 bob pptp[4002]: anon log[ctrlp_disp:pptp_ctrl.c:912]: Call disconnect notification received (call id 65535) Sep 25 15:36:19 bob pptptunnel: pppd shutdown succeeded Sep 25 15:36:21 bob pptp[4002]: anon log[call_callback:pptp_callmgr.c:77]: Closing connection Sep 25 15:36:26 bob pppd[4104]: pppd 2.4.2 started by root, uid 0 Sep 25 15:36:26 bob pppd[4104]: Serial connection established. Sep 25 15:36:26 bob pppd: Serial connection established. Sep 25 15:36:26 bob pppd: using channel 7 Sep 25 15:36:26 bob pptp[4105]: anon log[main:pptp.c:243]: The synchronous pptp option is NOT activated Sep 25 15:36:26 bob pppd[4104]: Using interface ppp0 Sep 25 15:36:26 bob pppd: Using interface ppp0 Sep 25 15:36:26 bob pppd[4104]: Connect: ppp0 <--> /dev/pts/0 Sep 25 15:36:26 bob pppd: Connect: ppp0 <--> /dev/pts/0 Sep 25 15:36:26 bob pptp[4110]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 1 'Start-Control-Connection-Request' Sep 25 15:36:26 bob pptp[4110]: anon log[ctrlp_disp:pptp_ctrl.c:721]: Received Start Control Connection Reply Sep 25 15:36:26 bob pptp[4110]: anon log[ctrlp_disp:pptp_ctrl.c:755]: Client connection established. Sep 25 15:36:27 bob pptp[4110]: anon log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 7 'Outgoing-Call-Request' Sep 25 15:36:27 bob pppd: sent [LCP ConfReq id=0x1 ] Sep 25 15:36:27 bob pptp[4110]: anon log[ctrlp_disp:pptp_ctrl.c:841]: Received Outgoing Call Reply. Sep 25 15:36:27 bob pptp[4110]: anon log[ctrlp_disp:pptp_ctrl.c:880]: Outgoing call established (call ID 0, peer's call ID 9216). Sep 25 15:36:27 bob pppd: rcvd [LCP ConfReq id=0x1 ] Sep 25 15:36:27 bob pppd: sent [LCP ConfNak id=0x1 ] Sep 25 15:36:27 bob pppd: rcvd [LCP ConfReq id=0x1 ] Sep 25 15:36:27 bob pppd: sent [LCP ConfRej id=0x1 ] Sep 25 15:36:27 bob pppd: rcvd [LCP ConfReq id=0x2 ] Sep 25 15:36:27 bob pppd: sent [LCP ConfRej id=0x2 ] Sep 25 15:36:27 bob pppd: rcvd [LCP ConfReq id=0x3 ] Sep 25 15:36:27 bob pppd: sent [LCP ConfAck id=0x3 ] Sep 25 15:36:30 bob pppd: sent [LCP ConfReq id=0x1 ] Sep 25 15:36:30 bob pppd: rcvd [LCP ConfReq id=0x3 ] Sep 25 15:36:30 bob pppd: sent [LCP ConfAck id=0x3 ] Sep 25 15:36:30 bob pppd: rcvd [LCP ConfAck id=0x1 ] Sep 25 15:36:30 bob pppd: rcvd [CHAP Challenge id=0xdd , name = "gamuza.status-m.ru"] Sep 25 15:36:30 bob pppd: sent [CHAP Response id=0xdd <2ef5176fd0329662c807298df0f099c60000000000000000e90aa8b576a1d6e5a70bc30023758675fda04cd7a38ce48e00>, name = "bobah"] Sep 25 15:36:30 bob pppd: rcvd [CHAP Success id=0xdd "S=8F708F508D9B2B034DB3E6775E119A19B7DD8166"] Sep 25 15:36:30 bob pppd: sent [CCP ConfReq id=0x1 ] Sep 25 15:36:30 bob pppd: sent [IPCP ConfReq id=0x1 ] Sep 25 15:36:30 bob pppd: rcvd [CCP ConfReq id=0x1 ] Sep 25 15:36:30 bob pppd: sent [CCP ConfRej id=0x1 ] Sep 25 15:36:30 bob pppd[4104]: Deflate (15) compression enabled Sep 25 15:36:30 bob pppd: rcvd [IPCP ConfReq id=0x1 ] Sep 25 15:36:30 bob pppd: sent [IPCP ConfAck id=0x1 ] Sep 25 15:36:30 bob pppd: rcvd [CCP ConfAck id=0x1 ] Sep 25 15:36:30 bob pppd: rcvd [IPCP ConfNak id=0x1 ] Sep 25 15:36:30 bob pppd: sent [IPCP ConfReq id=0x2 ] Sep 25 15:36:30 bob pppd: rcvd [CCP ConfReq id=0x2 ] Sep 25 15:36:30 bob pppd: sent [CCP ConfAck id=0x2 ] Sep 25 15:36:30 bob pppd: Deflate (15) compression enabled Sep 25 15:36:30 bob pppd: rcvd [IPCP ConfAck id=0x2 ] Sep 25 15:36:30 bob pppd[4104]: local IP address 10.0.10.10 Sep 25 15:36:30 bob pppd[4104]: remote IP address 10.0.2.100 Sep 25 15:36:30 bob pppd: local IP address 10.0.10.10 Sep 25 15:36:30 bob pppd: remote IP address 10.0.2.100 Sep 25 15:36:30 bob pptptunnel: pppd startup succeeded