Jul 6 20:58:55 pm1700 ppp[1158]: Phase: Using interface: tun0
20:58:55 pm1700 ppp[1158]: Phase: deflink: Created in closed state
20:58:55 pm1700 ppp[1158]: tun0: Command: default: ident user-ppp VERSION (built
(( other commands appear here as in ppp.conf ))
20:58:56 pm1700 ppp[1158]: tun0: Phase: PPP Started (direct mode).
20:58:56 pm1700 ppp[1158]: tun0: Phase: bundle: Establish
20:58:56 pm1700 ppp[1158]: tun0: Phase: deflink: closed -> opening
20:58:56 pm1700 ppp[1158]: tun0: Phase: deflink: Connected!
20:58:56 pm1700 ppp[1158]: tun0: Phase: deflink: opening -> dial
20:58:56 pm1700 ppp[1158]: tun0: Chat: Phone: *99#
20:58:56 pm1700 ppp[1158]: tun0: Chat: deflink: Dial attempt 1 of 1
20:58:56 pm1700 ppp[1158]: tun0: Chat: Send: AT^M
20:58:56 pm1700 ppp[1158]: tun0: Chat: Expect(5): OK
20:58:56 pm1700 ppp[1158]: tun0: Chat: Received: AT^M^M
20:58:56 pm1700 ppp[1158]: tun0: Chat: Received: OK^M
20:58:56 pm1700 ppp[1158]: tun0: Chat: Send: AT+CGDCONT=1,"IP","internet.tele2.ru"^M
20:58:56 pm1700 ppp[1158]: tun0: Chat: Expect(5): OK
20:58:56 pm1700 ppp[1158]: tun0: Chat: Received: AT+CGDCONT=1,"IP","internet.tele2.ru"^M^M
20:58:56 pm1700 ppp[1158]: tun0: Chat: Received: OK^M
20:58:56 pm1700 ppp[1158]: tun0: Chat: Send: ATDT*99#^M
20:58:58 pm1700 ppp[1158]: tun0: Chat: Expect(40): CONNECT
20:58:58 pm1700 ppp[1158]: tun0: Chat: Received: ATDT*99#^M^M
20:58:58 pm1700 ppp[1158]: tun0: Chat: Received: CONNECT^M
20:58:58 pm1700 ppp[1158]: tun0: Phase: deflink: dial -> carrier
20:58:58 pm1700 ppp[1158]: tun0: Phase: deflink: carrier -> login
20:58:58 pm1700 ppp[1158]: tun0: Phase: deflink: login -> lcp
20:58:58 pm1700 ppp[1158]: tun0: LCP: FSM: Using "deflink" as a transport
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: State change Initial --> Closed
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: LayerStart
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: SendConfigReq(1) state = Closed
20:58:58 pm1700 ppp[1158]: tun0: LCP: ACFCOMP[2]
20:58:58 pm1700 ppp[1158]: tun0: LCP: ACCMAP[6] 0x00000000
20:58:58 pm1700 ppp[1158]: tun0: LCP: MRU[4] 1500
20:58:58 pm1700 ppp[1158]: tun0: LCP: MAGICNUM[6] 0x9af4a156
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: State change Closed --> Req-Sent
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: RecvConfigRej(1) state = Req-Sent
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: SendIdent(0) state = Req-Sent
20:58:58 pm1700 ppp[1158]: tun0: LCP: MAGICNUM 9af4a156
20:58:58 pm1700 ppp[1158]: tun0: LCP: TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
20:58:58 pm1700 ppp[1158]: tun0: LCP: ACFCOMP[2]
20:58:58 pm1700 ppp[1158]: tun0: LCP: MAGICNUM[6] 0x9af4a156
20:58:58 pm1700 ppp[1158]: tun0: LCP: Magic 0x9af4a156 is REJected!
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: SendConfigReq(2) state = Req-Sent
20:58:58 pm1700 ppp[1158]: tun0: LCP: ACCMAP[6] 0x00000000
20:58:58 pm1700 ppp[1158]: tun0: LCP: MRU[4] 1500
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: RecvCodeRej(0) state = Req-Sent
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: RecvConfigAck(2) state = Req-Sent
20:58:58 pm1700 ppp[1158]: tun0: LCP: ACCMAP[6] 0x00000000
20:58:58 pm1700 ppp[1158]: tun0: LCP: MRU[4] 1500
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: State change Req-Sent --> Ack-Rcvd
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: RecvConfigReq(0) state = Ack-Rcvd
20:58:58 pm1700 ppp[1158]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP)
20:58:58 pm1700 ppp[1158]: tun0: LCP: MRU[4] 1500
20:58:58 pm1700 ppp[1158]: tun0: LCP: ACCMAP[6] 0x000a0000
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: SendConfigAck(0) state = Ack-Rcvd
20:58:58 pm1700 ppp[1158]: tun0: LCP: AUTHPROTO[4] 0xc023 (PAP)
20:58:58 pm1700 ppp[1158]: tun0: LCP: MRU[4] 1500
20:58:58 pm1700 ppp[1158]: tun0: LCP: ACCMAP[6] 0x000a0000
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: State change Ack-Rcvd --> Opened
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: LayerUp
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: SendIdent(1) state = Opened
20:58:58 pm1700 ppp[1158]: tun0: LCP: MAGICNUM 00000000
20:58:58 pm1700 ppp[1158]: tun0: LCP: TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
20:58:58 pm1700 ppp[1158]: tun0: Phase: bundle: Authenticate
20:58:58 pm1700 ppp[1158]: tun0: Phase: deflink: his = PAP, mine = none
20:58:58 pm1700 ppp[1158]: tun0: Phase: Pap Output: USERNAME ********
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: RecvCodeRej(1) state = Opened
20:58:58 pm1700 ppp[1158]: tun0: Phase: Pap Input: SUCCESS ()
20:58:58 pm1700 ppp[1158]: tun0: CCP: FSM: Using "deflink" as a transport
20:58:58 pm1700 ppp[1158]: tun0: CCP: deflink: State change Initial --> Closed
20:58:58 pm1700 ppp[1158]: tun0: CCP: deflink: State change Closed --> Stopped
20:58:58 pm1700 ppp[1158]: tun0: Phase: deflink: lcp -> open
20:58:58 pm1700 ppp[1158]: tun0: Phase: bundle: Network
20:58:58 pm1700 ppp[1158]: tun0: IPCP: FSM: Using "deflink" as a transport
20:58:58 pm1700 ppp[1158]: tun0: IPCP: deflink: State change Initial --> Closed
20:58:58 pm1700 ppp[1158]: tun0: IPCP: deflink: LayerStart.
20:58:58 pm1700 ppp[1158]: tun0: IPCP: deflink: SendConfigReq(1) state = Closed
20:58:58 pm1700 ppp[1158]: tun0: IPCP: IPADDR[6] 10.0.0.1
20:58:58 pm1700 ppp[1158]: tun0: IPCP: PRIDNS[6] 192.168.1.1
20:58:58 pm1700 ppp[1158]: tun0: IPCP: SECDNS[6] 255.255.255.255
20:58:58 pm1700 ppp[1158]: tun0: IPCP: deflink: State change Closed --> Req-Sent
20:58:58 pm1700 ppp[1158]: tun0: IPCP: deflink: RecvConfigReq(0) state = Req-Sent
20:58:58 pm1700 ppp[1158]: tun0: IPCP: IPADDR[6] 10.6.6.6
20:58:58 pm1700 ppp[1158]: tun0: IPCP: deflink: SendConfigAck(0) state = Req-Sent
20:58:58 pm1700 ppp[1158]: tun0: IPCP: IPADDR[6] 10.6.6.6
20:58:58 pm1700 ppp[1158]: tun0: IPCP: deflink: State change Req-Sent --> Ack-Sent
-->20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: RecvTerminateReq(1) state = Opened
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: LayerDown
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: SendTerminateAck(1) state = Opened
20:58:58 pm1700 ppp[1158]: tun0: LCP: deflink: State change Opened --> Stopping
20:58:58 pm1700 ppp[1158]: tun0: CCP: deflink: State change Stopped --> Closed
20:58:58 pm1700 ppp[1158]: tun0: CCP: deflink: State change Closed --> Initial
20:58:58 pm1700 ppp[1158]: tun0: Phase: deflink: open -> lcp
20:58:58 pm1700 ppp[1158]: tun0: IPCP: deflink: State change Ack-Sent --> Starting
20:58:58 pm1700 ppp[1158]: tun0: IPCP: deflink: LayerFinish.
20:58:58 pm1700 ppp[1158]: tun0: IPCP: Connect time: 0 secs: 0 octets in, 0 octets out
20:58:58 pm1700 ppp[1158]: tun0: IPCP: 0 packets in, 0 packets out
20:58:58 pm1700 ppp[1158]: tun0: IPCP: total 0 bytes/sec, peak 0 bytes/sec on Tue 20:58:58 2010
20:58:58 pm1700 ppp[1158]: tun0: IPCP: deflink: State change Starting --> Initial
20:58:58 pm1700 ppp[1158]: tun0: Phase: bundle: Terminate
20:59:01 pm1700 ppp[1158]: tun0: LCP: deflink: LayerFinish
20:59:01 pm1700 ppp[1158]: tun0: LCP: deflink: State change Stopping --> Stopped
20:59:01 pm1700 ppp[1158]: tun0: LCP: deflink: State change Stopped --> Closed
20:59:01 pm1700 ppp[1158]: tun0: LCP: deflink: State change Closed --> Initial
20:59:01 pm1700 ppp[1158]: tun0: Phase: deflink: Disconnected!
20:59:01 pm1700 ppp[1158]: tun0: Phase: deflink: lcp -> logout
20:59:01 pm1700 ppp[1158]: tun0: Phase: deflink: logout -> hangup
20:59:01 pm1700 ppp[1158]: tun0: Phase: deflink: Disconnected!
20:59:01 pm1700 ppp[1158]: tun0: Phase: deflink: Connect time: 5 secs: 275 octets in, 343 octets out
20:59:01 pm1700 ppp[1158]: tun0: Phase: deflink: 7 packets in, 9 packets out
20:59:01 pm1700 ppp[1158]: tun0: Phase: total 123 bytes/sec, peak 153 bytes/sec on Tue 20:58:59 2010
20:59:01 pm1700 ppp[1158]: tun0: Phase: deflink: hangup -> closed
20:59:01 pm1700 ppp[1158]: tun0: Phase: bundle: Dead
20:59:01 pm1700 ppp[1158]: tun0: Phase: PPP Terminated (normal).