gprs dosen't works in current

From: Stepan Zastupov <redchrom_at_gmail.com>
Date: Sat, 7 Oct 2006 08:35:38 +0900
Hello, I'm using freebsd 7 current and gprs dosen't works for me.
Here i s the ppp.conf:
default:
 set log Phase Chat LCP IPCP CCP tun command
 ident user-ppp VERSION (built COMPILATIONDATE)
 set timeout 180			# 3 minute idle timer (the default)
 enable dns				# request DNS info (for resolv.conf)

gprs:
 set device "!/usr/bin/rfcomm_sppd -a t630"
 set speed 115200
 set dial "ABORT BUSY ABORT NO\\sCARRIER TIMEOUT 5 \"\" AT \
 OK-AT-OK ATE1Q0 OK \
 AT+CGDCONT=1,\\\"IP\\\",\\\"internet.mts.ru\\\" OK \
 AT+CGATT=1 OK \\dATDT\\T TIMEOUT 40 CONNECT"
 set authname "mts"
 set authkey "mts"
 set phone "*99***1#"
 disable ipv6cp
 add default HISADDR

As you see I use bluetooth to connect the phone but I don't think that the problem in it. obexapp works fine in current.

ppp -ddial gprs && tail -f /var/log/ppp.log shom me the next lines:

Oct  6 14:02:02 stepan ppp[3614]: Phase: Using interface: tun0
Oct  6 14:02:02 stepan ppp[3614]: Phase: deflink: Created in closed state
Oct  6 14:02:02 stepan ppp[3614]: tun0: Command: default: ident user-ppp VERSION (built COMPILATIONDATE)
Oct  6 14:02:02 stepan ppp[3614]: tun0: Command: default: set timeout 180
Oct  6 14:02:02 stepan ppp[3614]: tun0: Command: default: enable dns
Oct  6 14:02:02 stepan ppp[3614]: tun0: Command: gprs: set device !/usr/bin/rfcomm_sppd -a t630
Oct  6 14:02:02 stepan ppp[3614]: tun0: Command: gprs: set speed 115200
Oct  6 14:02:02 stepan ppp[3614]: tun0: Command: gprs: set dial ABORT BUSY ABORT NO\sCARRIER TIMEOUT 5 "" AT  OK-AT-OK ATE1Q0 OK  AT+CGDCONT=1,\"IP\",\"internet.mts.ru\" OK  AT+CGATT=1 OK \dATDT\T TIMEOUT 40 CONNECT
Oct  6 14:02:02 stepan ppp[3614]: tun0: Command: gprs: set authname mts
Oct  6 14:02:02 stepan ppp[3614]: tun0: Command: gprs: set authkey ********
Oct  6 14:02:02 stepan ppp[3614]: tun0: Command: gprs: set phone *99***1#
Oct  6 14:02:02 stepan ppp[3614]: tun0: Command: gprs: disable ipv6cp
Oct  6 14:02:02 stepan ppp[3614]: tun0: Command: gprs: add default HISADDR
Oct  6 14:02:02 stepan ppp[3615]: tun0: Phase: PPP Started (ddial mode).
Oct  6 14:02:02 stepan ppp[3615]: tun0: Phase: bundle: Establish
Oct  6 14:02:02 stepan ppp[3615]: tun0: Phase: deflink: closed -> opening
Oct  6 14:02:02 stepan ppp[3615]: tun0: Phase: deflink: Connected!
Oct  6 14:02:02 stepan ppp[3615]: tun0: Phase: deflink: opening -> dial
Oct  6 14:02:02 stepan ppp[3615]: tun0: Chat: Phone: *99***1#
Oct  6 14:02:02 stepan ppp[3615]: tun0: Chat: Send: AT^M
Oct  6 14:02:02 stepan ppp[3615]: tun0: Chat: Expect(5): OK
Oct  6 14:02:04 stepan ppp[3615]: tun0: Chat: Received: rfcomm_sppd[3618]: Starting on stdin/stdout...
Oct  6 14:02:04 stepan ppp[3615]: tun0: Chat: Received: AT^M^M
Oct  6 14:02:04 stepan ppp[3615]: tun0: Chat: Received: OK^M
Oct  6 14:02:04 stepan ppp[3615]: tun0: Chat: Send: ATE1Q0^M
Oct  6 14:02:04 stepan ppp[3615]: tun0: Chat: Expect(5): OK
Oct  6 14:02:04 stepan ppp[3615]: tun0: Chat: Received: ATE1Q0^M^M
Oct  6 14:02:04 stepan ppp[3615]: tun0: Chat: Received: OK^M
Oct  6 14:02:04 stepan ppp[3615]: tun0: Chat: Send: AT+CGDCONT=1,"IP","internet.mts.ru"^M
Oct  6 14:02:04 stepan ppp[3615]: tun0: Chat: Expect(5): OK
Oct  6 14:02:04 stepan ppp[3615]: tun0: Chat: Received: AT+CGDCONT=1,"IP","internet.mts.ru"^M^M
Oct  6 14:02:04 stepan ppp[3615]: tun0: Chat: Received: OK^M
Oct  6 14:02:04 stepan ppp[3615]: tun0: Chat: Send: AT+CGATT=1^M
Oct  6 14:02:04 stepan ppp[3615]: tun0: Chat: Expect(5): OK
Oct  6 14:02:05 stepan ppp[3615]: tun0: Chat: Received: AT+CGATT=1^M^M
Oct  6 14:02:05 stepan ppp[3615]: tun0: Chat: Received: OK^M
Oct  6 14:02:05 stepan ppp[3615]: tun0: Chat: Send: ATDT*99***1#^M
Oct  6 14:02:07 stepan ppp[3615]: tun0: Chat: Expect(40): CONNECT
Oct  6 14:02:07 stepan ppp[3615]: tun0: Chat: Received: ATDT*99***1#^M^M
Oct  6 14:02:07 stepan ppp[3615]: tun0: Chat: Received: CONNECT^M
Oct  6 14:02:07 stepan ppp[3615]: tun0: Phase: deflink: dial -> carrier
Oct  6 14:02:07 stepan ppp[3615]: tun0: Phase: deflink: carrier -> login
Oct  6 14:02:07 stepan ppp[3615]: tun0: Phase: deflink: login -> lcp
Oct  6 14:02:07 stepan ppp[3615]: tun0: LCP: FSM: Using "deflink" as a transport
Oct  6 14:02:07 stepan ppp[3615]: tun0: LCP: deflink: State change Initial --> Closed
Oct  6 14:02:07 stepan ppp[3615]: tun0: LCP: deflink: State change Closed --> Stopped
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP: deflink: LayerStart
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  ACFCOMP[2]
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  PROTOCOMP[2]
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  ACCMAP[6] 0x00000000
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  MRU[4] 1500
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  MAGICNUM[6] 0xaa5135f3
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP: deflink: State change Stopped --> Req-Sent
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP: deflink: RecvConfigReq(1) state = Req-Sent
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  ACCMAP[6] 0x00000000
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  PROTOCOMP[2]
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  ACFCOMP[2]
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  AUTHPROTO[4] 0xc023 (PAP)
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP: deflink: SendConfigAck(1) state = Req-Sent
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  ACCMAP[6] 0x00000000
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  PROTOCOMP[2]
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  ACFCOMP[2]
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  AUTHPROTO[4] 0xc023 (PAP)
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP: deflink: State change Req-Sent --> Ack-Sent
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP: deflink: RecvConfigRej(1) state = Ack-Sent
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(0) state = Ack-Sent
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  MAGICNUM aa5135f3
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  MAGICNUM[6] 0xaa5135f3
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  Magic 0xaa5135f3 is REJected!
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP: deflink: SendConfigReq(2) state = Ack-Sent
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  ACFCOMP[2]
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  PROTOCOMP[2]
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  ACCMAP[6] 0x00000000
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  MRU[4] 1500
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(3) state = Ack-Sent
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP: deflink: RecvConfigAck(2) state = Ack-Sent
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  ACFCOMP[2]
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  PROTOCOMP[2]
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  ACCMAP[6] 0x00000000
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  MRU[4] 1500
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP: deflink: State change Ack-Sent --> Opened
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP: deflink: LayerUp
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(1) state = Opened
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:08 stepan ppp[3615]: tun0: Phase: bundle: Authenticate
Oct  6 14:02:08 stepan ppp[3615]: tun0: Phase: deflink: his = PAP, mine = none
Oct  6 14:02:08 stepan ppp[3615]: tun0: Phase: Pap Output: mts ********
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(4) state = Opened
Oct  6 14:02:08 stepan ppp[3615]: tun0: Phase: Pap Input: SUCCESS ()
Oct  6 14:02:08 stepan ppp[3615]: tun0: CCP: FSM: Using "deflink" as a transport
Oct  6 14:02:08 stepan ppp[3615]: tun0: CCP: deflink: State change Initial --> Closed
Oct  6 14:02:08 stepan ppp[3615]: tun0: CCP: deflink: LayerStart.
Oct  6 14:02:08 stepan ppp[3615]: tun0: CCP: MPPE: Not usable without CHAP81
Oct  6 14:02:08 stepan ppp[3615]: tun0: CCP: deflink: SendConfigReq(1) state = Closed
Oct  6 14:02:08 stepan ppp[3615]: tun0: CCP:  DEFLATE[4] win 15
Oct  6 14:02:08 stepan ppp[3615]: tun0: CCP:  PRED1[2] 
Oct  6 14:02:08 stepan ppp[3615]: tun0: CCP: deflink: State change Closed --> Req-Sent
Oct  6 14:02:08 stepan ppp[3615]: tun0: Phase: deflink: lcp -> open
Oct  6 14:02:08 stepan ppp[3615]: tun0: Phase: bundle: Network
Oct  6 14:02:08 stepan ppp[3615]: tun0: IPCP: FSM: Using "deflink" as a transport
Oct  6 14:02:08 stepan ppp[3615]: tun0: IPCP: deflink: State change Initial --> Closed
Oct  6 14:02:08 stepan ppp[3615]: tun0: IPCP: deflink: LayerStart.
Oct  6 14:02:08 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigReq(1) state = Closed
Oct  6 14:02:08 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:08 stepan ppp[3615]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots with slot compression
Oct  6 14:02:08 stepan ppp[3615]: tun0: IPCP:  PRIDNS[6] 195.46.96.1
Oct  6 14:02:08 stepan ppp[3615]: tun0: IPCP:  SECDNS[6] 255.255.255.255
Oct  6 14:02:08 stepan ppp[3615]: tun0: IPCP: deflink: State change Closed --> Req-Sent
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP: deflink: RecvProtocolRej(5) state = Opened
Oct  6 14:02:08 stepan ppp[3615]: tun0: LCP: deflink: -- Protocol 0x80fd (Compression Control Protocol) was rejected!
Oct  6 14:02:08 stepan ppp[3615]: tun0: CCP: deflink: State change Req-Sent --> Stopped
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigReq(1) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:   [EMPTY]
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigNak(1) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigRej(1) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(2) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots with slot compression
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  PRIDNS[6] 195.46.96.1
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  SECDNS[6] 255.255.255.255
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigReq(2) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigReq(2) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigNak(2) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(6) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigRej(2) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(3) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigReq(3) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigReq(3) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigNak(3) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(7) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigRej(3) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(4) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigReq(4) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigReq(4) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigNak(4) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(8) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigRej(4) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(5) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigReq(5) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigReq(5) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigNak(5) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(9) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigRej(5) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(6) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigReq(6) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigReq(6) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigNak(6) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(10) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigRej(6) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(7) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigReq(7) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigReq(7) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigNak(7) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(11) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigRej(7) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(8) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigReq(8) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigReq(8) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigNak(8) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(12) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigRej(8) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(9) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigReq(9) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigReq(9) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigNak(9) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(13) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigRej(9) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(10) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigReq(10) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigReq(10) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigNak(10) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(14) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigRej(10) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(11) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigReq(11) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigReq(11) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigNak(11) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(15) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigRej(11) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(12) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigReq(12) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigReq(12) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigNak(12) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(16) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigRej(12) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(13) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigReq(13) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigReq(13) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigNak(13) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(17) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigRej(13) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(14) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigReq(14) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigReq(14) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigNak(14) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(18) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigRej(14) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(15) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigReq(15) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigReq(15) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigNak(15) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: Phase: deflink: Too many IPCP NAKs sent - abandoning negotiation
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(16) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: SendTerminateReq(15) state = Req-Sent
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: State change Req-Sent --> Closing
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(19) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigRej(15) state = Closing
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(17) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvConfigReq(16) state = Closing
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 0.0.0.0
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: Error: Got ConfigReq while state = Closing
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(20) state = Opened
Oct  6 14:02:09 stepan ppp[3615]: tun0: IPCP: deflink: RecvTerminateAck(15), dropped (expected 16)
Oct  6 14:02:09 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(21) state = Opened
Oct  6 14:02:12 stepan ppp[3615]: tun0: IPCP: deflink: SendTerminateReq(16) state = Closing
Oct  6 14:02:12 stepan ppp[3615]: tun0: IPCP: deflink: RecvTerminateAck(16) state = Closing
Oct  6 14:02:12 stepan ppp[3615]: tun0: IPCP: deflink: LayerFinish.
Oct  6 14:02:12 stepan ppp[3615]: tun0: IPCP: Connect time: 4 secs: 0 octets in, 0 octets out
Oct  6 14:02:12 stepan ppp[3615]: tun0: IPCP: 0 packets in, 0 packets out
Oct  6 14:02:12 stepan ppp[3615]: tun0: IPCP:  total 0 bytes/sec, peak 0 bytes/sec on Fri Oct  6 14:02:08 2006
Oct  6 14:02:12 stepan ppp[3615]: tun0: IPCP: deflink: State change Closing --> Closed
Oct  6 14:02:12 stepan ppp[3615]: tun0: Phase: bundle: Terminate
Oct  6 14:02:12 stepan ppp[3615]: tun0: CCP: deflink: State change Stopped --> Closed
Oct  6 14:02:12 stepan ppp[3615]: tun0: CCP: deflink: State change Closed --> Initial
Oct  6 14:02:12 stepan ppp[3615]: tun0: LCP: deflink: LayerDown
Oct  6 14:02:12 stepan ppp[3615]: tun0: LCP: deflink: SendTerminateReq(3) state = Opened
Oct  6 14:02:12 stepan ppp[3615]: tun0: LCP: deflink: State change Opened --> Closing
Oct  6 14:02:12 stepan ppp[3615]: tun0: Phase: deflink: open -> lcp
Oct  6 14:02:12 stepan ppp[3615]: tun0: IPCP: deflink: State change Closed --> Initial
Oct  6 14:02:12 stepan ppp[3615]: tun0: LCP: deflink: RecvTerminateAck(3) state = Closing
Oct  6 14:02:12 stepan ppp[3615]: tun0: LCP: deflink: LayerFinish
Oct  6 14:02:12 stepan ppp[3615]: tun0: LCP: deflink: State change Closing --> Closed
Oct  6 14:02:12 stepan ppp[3615]: tun0: LCP: deflink: State change Closed --> Initial
Oct  6 14:02:12 stepan ppp[3615]: tun0: Phase: deflink: Disconnected!
Oct  6 14:02:12 stepan ppp[3615]: tun0: Phase: deflink: lcp -> logout
Oct  6 14:02:12 stepan ppp[3615]: tun0: Phase: deflink: logout -> hangup
Oct  6 14:02:12 stepan ppp[3615]: tun0: Phase: deflink: Disconnected!
Oct  6 14:02:12 stepan ppp[3615]: tun0: Phase: deflink: Connect time: 10 secs: 1740 octets in, 1825 octets out
Oct  6 14:02:12 stepan ppp[3615]: tun0: Phase: deflink: 41 packets in, 56 packets out
Oct  6 14:02:12 stepan ppp[3615]: tun0: Phase:  total 356 bytes/sec, peak 702 bytes/sec on Fri Oct  6 14:02:10 2006
Oct  6 14:02:12 stepan ppp[3615]: tun0: Phase: deflink: HUPing 3618
Oct  6 14:02:12 stepan ppp[3615]: tun0: Phase: deflink: hangup -> opening
Oct  6 14:02:12 stepan ppp[3615]: tun0: Phase: bundle: Establish
Oct  6 14:02:12 stepan ppp[3615]: tun0: Phase: deflink: Enter pause (3) for redialing.
Oct  6 14:02:12 stepan ppp[3615]: tun0: Chat: deflink: Reconnect try 1 of 0
Oct  6 14:02:15 stepan ppp[3615]: tun0: Chat: deflink: Redial timer expired.
Oct  6 14:02:15 stepan ppp[3615]: tun0: Phase: deflink: Connected!
Oct  6 14:02:15 stepan ppp[3615]: tun0: Phase: deflink: opening -> dial
Oct  6 14:02:15 stepan ppp[3615]: tun0: Chat: Phone: *99***1#
Oct  6 14:02:15 stepan ppp[3615]: tun0: Chat: Send: AT^M
Oct  6 14:02:15 stepan ppp[3615]: tun0: Chat: Expect(5): OK
Oct  6 14:02:16 stepan ppp[3615]: tun0: Chat: Received: rfcomm_sppd[3621]: Starting on stdin/stdout...
Oct  6 14:02:16 stepan ppp[3615]: tun0: Chat: Received: AT^M^M
Oct  6 14:02:16 stepan ppp[3615]: tun0: Chat: Received: OK^M
Oct  6 14:02:16 stepan ppp[3615]: tun0: Chat: Send: ATE1Q0^M
Oct  6 14:02:16 stepan ppp[3615]: tun0: Chat: Expect(5): OK
Oct  6 14:02:16 stepan ppp[3615]: tun0: Chat: Received: ATE1Q0^M^M
Oct  6 14:02:16 stepan ppp[3615]: tun0: Chat: Received: OK^M
Oct  6 14:02:16 stepan ppp[3615]: tun0: Chat: Send: AT+CGDCONT=1,"IP","internet.mts.ru"^M
Oct  6 14:02:16 stepan ppp[3615]: tun0: Chat: Expect(5): OK
Oct  6 14:02:16 stepan ppp[3615]: tun0: Chat: Received: AT+CGDCONT=1,"IP","internet.mts.ru"^M^M
Oct  6 14:02:16 stepan ppp[3615]: tun0: Chat: Received: OK^M
Oct  6 14:02:16 stepan ppp[3615]: tun0: Chat: Send: AT+CGATT=1^M
Oct  6 14:02:16 stepan ppp[3615]: tun0: Chat: Expect(5): OK
Oct  6 14:02:16 stepan ppp[3615]: tun0: Chat: Received: AT+CGATT=1^M^M
Oct  6 14:02:16 stepan ppp[3615]: tun0: Chat: Received: OK^M
Oct  6 14:02:16 stepan ppp[3615]: tun0: Chat: Send: ATDT*99***1#^M
Oct  6 14:02:18 stepan ppp[3615]: tun0: Chat: Expect(40): CONNECT
Oct  6 14:02:18 stepan ppp[3615]: tun0: Chat: Received: ATDT*99***1#^M^M
Oct  6 14:02:18 stepan ppp[3615]: tun0: Chat: Received: CONNECT^M
Oct  6 14:02:18 stepan ppp[3615]: tun0: Phase: deflink: dial -> carrier
Oct  6 14:02:18 stepan ppp[3615]: tun0: Phase: deflink: carrier -> login
Oct  6 14:02:18 stepan ppp[3615]: tun0: Phase: deflink: login -> lcp
Oct  6 14:02:18 stepan ppp[3615]: tun0: LCP: FSM: Using "deflink" as a transport
Oct  6 14:02:18 stepan ppp[3615]: tun0: LCP: deflink: State change Initial --> Closed
Oct  6 14:02:18 stepan ppp[3615]: tun0: LCP: deflink: State change Closed --> Stopped
Oct  6 14:02:18 stepan ppp[3615]: tun0: Phase: Signal 15, terminate.
Oct  6 14:02:18 stepan ppp[3615]: tun0: LCP: deflink: State change Stopped --> Closed
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: LayerStart
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: SendConfigReq(4) state = Closed
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  ACFCOMP[2]
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  PROTOCOMP[2]
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  ACCMAP[6] 0x00000000
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  MRU[4] 1500
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  MAGICNUM[6] 0xcbd825b6
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: State change Closed --> Req-Sent
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: RecvConfigReq(1) state = Req-Sent
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  ACCMAP[6] 0x00000000
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  PROTOCOMP[2]
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  ACFCOMP[2]
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  AUTHPROTO[4] 0xc023 (PAP)
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: SendConfigAck(1) state = Req-Sent
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  ACCMAP[6] 0x00000000
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  PROTOCOMP[2]
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  ACFCOMP[2]
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  AUTHPROTO[4] 0xc023 (PAP)
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: State change Req-Sent --> Ack-Sent
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: RecvConfigRej(4) state = Ack-Sent
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(18) state = Ack-Sent
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  MAGICNUM cbd825b6
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  MAGICNUM[6] 0xcbd825b6
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  Magic 0xcbd825b6 is REJected!
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: SendConfigReq(5) state = Ack-Sent
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  ACFCOMP[2]
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  PROTOCOMP[2]
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  ACCMAP[6] 0x00000000
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  MRU[4] 1500
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(3) state = Ack-Sent
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: RecvConfigAck(5) state = Ack-Sent
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  ACFCOMP[2]
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  PROTOCOMP[2]
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  ACCMAP[6] 0x00000000
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  MRU[4] 1500
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: State change Ack-Sent --> Opened
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: LayerUp
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: SendIdent(19) state = Opened
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  MAGICNUM 00000000
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: bundle: Authenticate
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: deflink: his = PAP, mine = none
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: Pap Output: mts ********
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: RecvCodeRej(4) state = Opened
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: Pap Input: SUCCESS ()
Oct  6 14:02:19 stepan ppp[3615]: tun0: CCP: FSM: Using "deflink" as a transport
Oct  6 14:02:19 stepan ppp[3615]: tun0: CCP: deflink: State change Initial --> Closed
Oct  6 14:02:19 stepan ppp[3615]: tun0: CCP: deflink: LayerStart.
Oct  6 14:02:19 stepan ppp[3615]: tun0: CCP: MPPE: Not usable without CHAP81
Oct  6 14:02:19 stepan ppp[3615]: tun0: CCP: deflink: SendConfigReq(1) state = Closed
Oct  6 14:02:19 stepan ppp[3615]: tun0: CCP:  DEFLATE[4] win 15
Oct  6 14:02:19 stepan ppp[3615]: tun0: CCP:  PRED1[2] 
Oct  6 14:02:19 stepan ppp[3615]: tun0: CCP: deflink: State change Closed --> Req-Sent
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: deflink: lcp -> open
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: bundle: Network
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP: FSM: Using "deflink" as a transport
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP: deflink: State change Initial --> Closed
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP: deflink: LayerStart.
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP: deflink: SendConfigReq(17) state = Closed
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP:  IPADDR[6] 192.168.0.2
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP:  COMPPROTO[6] 16 VJ slots with slot compression
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP:  PRIDNS[6] 195.46.96.1
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP:  SECDNS[6] 255.255.255.255
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP: deflink: State change Closed --> Req-Sent
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: RecvProtocolRej(5) state = Opened
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: -- Protocol 0x80fd (Compression Control Protocol) was rejected!
Oct  6 14:02:19 stepan ppp[3615]: tun0: CCP: deflink: State change Req-Sent --> Stopped
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: Signal 15, terminate.
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP: deflink: SendTerminateReq(17) state = Req-Sent
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP: deflink: State change Req-Sent --> Closing
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP: deflink: RecvTerminateAck(17) state = Closing
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP: deflink: LayerFinish.
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP: Connect time: 0 secs: 0 octets in, 0 octets out
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP: 0 packets in, 0 packets out
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP:  total 0 bytes/sec, peak 0 bytes/sec on Fri Oct  6 14:02:19 2006
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP: deflink: State change Closing --> Closed
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: bundle: Terminate
Oct  6 14:02:19 stepan ppp[3615]: tun0: CCP: deflink: State change Stopped --> Closed
Oct  6 14:02:19 stepan ppp[3615]: tun0: CCP: deflink: State change Closed --> Initial
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: LayerDown
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: SendTerminateReq(6) state = Opened
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: State change Opened --> Closing
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: deflink: open -> lcp
Oct  6 14:02:19 stepan ppp[3615]: tun0: IPCP: deflink: State change Closed --> Initial
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: RecvTerminateAck(6) state = Closing
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: LayerFinish
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: State change Closing --> Closed
Oct  6 14:02:19 stepan ppp[3615]: tun0: LCP: deflink: State change Closed --> Initial
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: deflink: Disconnected!
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: deflink: lcp -> logout
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: deflink: logout -> hangup
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: deflink: Disconnected!
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: deflink: Connect time: 4 secs: 294 octets in, 356 octets out
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: deflink: 50 packets in, 66 packets out
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase:  total 162 bytes/sec, peak 114 bytes/sec on Fri Oct  6 14:02:19 2006
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: deflink: HUPing 3621
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: deflink: hangup -> closed
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: bundle: Dead
Oct  6 14:02:19 stepan ppp[3615]: tun0: Phase: PPP Terminated (normal).

-- 
Best regards, Stepan Zastupov aka RedChrom
ISPSystem
Received on Fri Oct 06 2006 - 21:35:46 UTC

This archive was generated by hypermail 2.4.0 : Wed May 19 2021 - 11:39:01 UTC