ppp vs mpd: ppp fails to keep connection, mpd works

Vladimir Grebenschikov vova at fbsd.ru
Sun Jun 15 17:59:18 UTC 2008 

Hi

I am trying to find why with same configuration ppp does not works with
my CDMA modem (connected over usb) but mpd works.

MPD log (it works as expected):
...
[umodem0] link: UP event
[umodem0] link: origination is remote
[umodem0] LCP: Up event
[umodem0] LCP: state change Starting --> Req-Sent
[umodem0] LCP: phase shift DEAD --> ESTABLISH
[umodem0] LCP: SendConfigReq #1
 ACFCOMP
 PROTOCOMP
 ACCMAP 0x000a0000
 MRU 1500
 MAGICNUM 04eb5958
 MP MRRU 1600
 MP SHORTSEQ
 ENDPOINTDISC [Magic] 41 a2 7a c0 29 08 7a c0
[umodem0] LCP: rec'd Configure Reject #1 link 0 (Req-Sent)
 MP MRRU 1600
 MP SHORTSEQ
[umodem0] LCP: SendConfigReq #2
 ACFCOMP
 PROTOCOMP
 ACCMAP 0x000a0000
 MRU 1500
 MAGICNUM 04eb5958
[umodem0] LCP: rec'd Configure Ack #2 link 0 (Req-Sent)
 ACFCOMP
 PROTOCOMP
 ACCMAP 0x000a0000
 MRU 1500
 MAGICNUM 04eb5958
[umodem0] LCP: state change Req-Sent --> Ack-Rcvd
[umodem0] LCP: rec'd Configure Request #8 link 0 (Ack-Rcvd)
 ACCMAP 0x00000000
 AUTHPROTO CHAP MD5
 MAGICNUM 43aeaeee
[umodem0] LCP: SendConfigNak #8
 AUTHPROTO PAP
[umodem0] LCP: rec'd Configure Request #9 link 0 (Ack-Rcvd)
 ACCMAP 0x00000000
 AUTHPROTO PAP
 MAGICNUM 43aeaeee
[umodem0] LCP: SendConfigAck #9
 ACCMAP 0x00000000
 AUTHPROTO PAP
 MAGICNUM 43aeaeee
[umodem0] LCP: state change Ack-Rcvd --> Opened
[umodem0] LCP: phase shift ESTABLISH --> AUTHENTICATE
[umodem0] LCP: auth: peer wants PAP, I want nothing
[umodem0] PAP: using authname "mobile"
[umodem0] PAP: sending REQUEST
[umodem0] LCP: LayerUp
[umodem0] PAP: rec'd ACK #1
[umodem0] LCP: authorization successful
[umodem0] LCP: phase shift AUTHENTICATE --> NETWORK
[skylink] setting interface ng0 MTU to 1500 bytes
[skylink] up: 1 link, total bandwidth 28800 bps
[skylink] IPCP: Up event
[skylink] IPCP: state change Starting --> Req-Sent
[skylink] IPCP: SendConfigReq #1
 IPADDR 0.0.0.0
 COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
[skylink] error writing len 20 frame to bypass: Network is down
[skylink] IPCP: rec'd Configure Request #2 link 0 (Req-Sent)
 IPADDR 212.119.106.147
   212.119.106.147 is OK
[skylink] IPCP: SendConfigAck #2
 IPADDR 212.119.106.147
[skylink] IPCP: state change Req-Sent --> Ack-Sent
[skylink] rec'd unexpected protocol CCP on link 0, rejecting
[skylink] IPCP: SendConfigReq #2
 IPADDR 0.0.0.0
 COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
[skylink] IPCP: rec'd Configure Reject #2 link 0 (Ack-Sent)
 COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
[skylink] IPCP: SendConfigReq #3
 IPADDR 0.0.0.0
[skylink] IPCP: rec'd Configure Nak #3 link 0 (Ack-Sent)
 IPADDR 92.36.111.152
   92.36.111.152 is OK
[skylink] IPCP: SendConfigReq #4
 IPADDR 92.36.111.152
[skylink] IPCP: rec'd Configure Ack #4 link 0 (Ack-Sent)
 IPADDR 92.36.111.152
[skylink] IPCP: state change Ack-Sent --> Opened
[skylink] IPCP: LayerUp
  92.36.111.152 -> 212.119.106.147
[skylink] IFACE: Up event
[skylink] setting interface ng0 MTU to 1500 bytes
[skylink] exec: /sbin/ifconfig ng0 92.36.111.152 212.119.106.147 netmask 0xffffffff -link0
[skylink] exec: /sbin/route add 92.36.111.152 -iface lo0
[skylink] exec: /sbin/route add 0.0.0.0 212.119.106.147
[skylink] IFACE: Up event
[skylink] rec'd unexpected protocol IP on link 0
[umodem0] NEW FRAME ERRS: FCS 1 RUNT 0 OVFL 0

Then it just works.

But ppp, closes connection just after negotiation (log below).
After pair of empty config requests. 
What may be wrong here ?
Any hints will be very appreciated.

Jun 15 20:03:54 vbook ppp[4771]: Phase: Using interface: tun0
Jun 15 20:03:54 vbook ppp[4771]: Phase: deflink: Created in closed state
Jun 15 20:03:54 vbook ppp[4771]: tun0: Command: default: ident user-ppp VERSION (built COMPILATIONDATE)
Jun 15 20:03:54 vbook ppp[4771]: tun0: Command: default: set speed 115200
Jun 15 20:03:54 vbook ppp[4771]: tun0: Command: default: set dial ABORT BUSY ABORT NO\sCARRIER TIMEOUT 5            "" AT OK-AT-OK ATE1Q0 OK \dATDT\T TIMEOUT 40 CONNECT
Jun 15 20:03:54 vbook ppp[4771]: tun0: Command: default: set timeout 0
Jun 15 20:03:54 vbook ppp[4771]: tun0: Command: skylink: set log Phase Chat LCP IPCP CCP tun command
Jun 15 20:03:54 vbook ppp[4771]: tun0: Command: skylink: disable pred1 deflate deflate24 protocomp acfcomp shortseq vj
Jun 15 20:03:54 vbook ppp[4771]: tun0: Command: skylink: deny pred1 deflate deflate24 protocomp acfcomp shortseq vj
Jun 15 20:03:54 vbook ppp[4771]: tun0: Command: skylink: set speed 460800
Jun 15 20:03:54 vbook ppp[4771]: tun0: Command: skylink: set timeout 180
Jun 15 20:03:54 vbook ppp[4771]: tun0: Command: skylink: enable dns
Jun 15 20:03:54 vbook ppp[4771]: tun0: Command: skylink: set device /dev/ttyU0
Jun 15 20:03:54 vbook ppp[4771]: tun0: Command: skylink: set phone #777
Jun 15 20:03:54 vbook ppp[4771]: tun0: Command: skylink: set dial ABORT BUSY ABORT NO\sCARRIER TIMEOUT 15 "" AT OK-AT-OK ATE1Q0 OK \dATDT\T TIMEOUT 40 CONNECT
Jun 15 20:03:54 vbook ppp[4771]: tun0: Command: skylink: set authname mobile
Jun 15 20:03:54 vbook ppp[4771]: tun0: Command: skylink: set authkey ********
Jun 15 20:03:54 vbook ppp[4771]: tun0: Command: skylink: add default HISADDR
Jun 15 20:03:54 vbook ppp[4771]: tun0: Phase: PPP Started (interactive mode).
Jun 15 20:03:57 vbook ppp[4771]: tun0: Command: /dev/ttyp1: dial
Jun 15 20:03:57 vbook ppp[4771]: tun0: Phase: bundle: Establish
Jun 15 20:03:57 vbook ppp[4771]: tun0: Phase: deflink: closed -> opening
Jun 15 20:03:57 vbook ppp[4771]: tun0: Phase: deflink: Connected!
Jun 15 20:03:57 vbook ppp[4771]: tun0: Phase: deflink: opening -> dial
Jun 15 20:03:57 vbook ppp[4771]: tun0: Chat: Phone: #777
Jun 15 20:03:57 vbook ppp[4771]: tun0: Chat: deflink: Dial attempt 1 of 1
Jun 15 20:03:57 vbook ppp[4771]: tun0: Chat: Send: AT^M
Jun 15 20:03:57 vbook ppp[4771]: tun0: Chat: Expect(15): OK
Jun 15 20:03:57 vbook ppp[4771]: tun0: Chat: Received: AT^M^M
Jun 15 20:03:57 vbook ppp[4771]: tun0: Chat: Received: OK^M
Jun 15 20:03:57 vbook ppp[4771]: tun0: Chat: Send: ATE1Q0^M
Jun 15 20:03:57 vbook ppp[4771]: tun0: Chat: Expect(15): OK
Jun 15 20:03:57 vbook ppp[4771]: tun0: Chat: Received: ATE1Q0^M^M
Jun 15 20:03:57 vbook ppp[4771]: tun0: Chat: Received: OK^M
Jun 15 20:03:57 vbook ppp[4771]: tun0: Chat: Send: ATDT#777^M
Jun 15 20:03:59 vbook ppp[4771]: tun0: Chat: Expect(40): CONNECT
Jun 15 20:03:59 vbook ppp[4771]: tun0: Chat: Received: ATDT#777^M^M
Jun 15 20:03:59 vbook ppp[4771]: tun0: Chat: Received: CONNECT^M
Jun 15 20:03:59 vbook ppp[4771]: tun0: Phase: deflink: dial -> carrier
Jun 15 20:04:00 vbook ppp[4771]: tun0: Phase: deflink: /dev/ttyU0 doesn't support CD
Jun 15 20:04:00 vbook ppp[4771]: tun0: Phase: deflink: carrier -> login
Jun 15 20:04:00 vbook ppp[4771]: tun0: Phase: deflink: login -> lcp
Jun 15 20:04:00 vbook ppp[4771]: tun0: LCP: FSM: Using "deflink" as a transport
Jun 15 20:04:00 vbook ppp[4771]: tun0: LCP: deflink: State change Initial --> Closed
Jun 15 20:04:00 vbook ppp[4771]: tun0: LCP: deflink: State change Closed --> Stopped
Jun 15 20:04:00 vbook ppp[4771]: tun0: LCP: deflink: RecvConfigReq(2) state = Stopped
Jun 15 20:04:00 vbook ppp[4771]: tun0: LCP:  ACCMAP[6] 0x00000000
Jun 15 20:04:00 vbook ppp[4771]: tun0: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x05)
Jun 15 20:04:00 vbook ppp[4771]: tun0: LCP:  MAGICNUM[6] 0x36bc6422
Jun 15 20:04:00 vbook ppp[4771]: tun0: LCP: deflink: SendConfigReq(1) state = Stopped
Jun 15 20:04:00 vbook ppp[4771]: tun0: LCP:  ACCMAP[6] 0x00000000
Jun 15 20:04:00 vbook ppp[4771]: tun0: LCP:  MRU[4] 1500
Jun 15 20:04:00 vbook ppp[4771]: tun0: LCP:  MAGICNUM[6] 0x031c8dad
Jun 15 20:04:00 vbook ppp[4771]: tun0: LCP: deflink: SendConfigAck(2) state = Stopped
Jun 15 20:04:00 vbook ppp[4771]: tun0: LCP:  ACCMAP[6] 0x00000000
Jun 15 20:04:00 vbook ppp[4771]: tun0: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x05)
Jun 15 20:04:00 vbook ppp[4771]: tun0: LCP:  MAGICNUM[6] 0x36bc6422
Jun 15 20:04:00 vbook ppp[4771]: tun0: LCP: deflink: LayerStart
Jun 15 20:04:00 vbook ppp[4771]: tun0: LCP: deflink: State change Stopped --> Ack-Sent
Jun 15 20:04:02 vbook ppp[4771]: tun0: LCP: deflink: RecvConfigReq(3) state = Ack-Sent
Jun 15 20:04:02 vbook ppp[4771]: tun0: LCP:  ACCMAP[6] 0x00000000
Jun 15 20:04:02 vbook ppp[4771]: tun0: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x05)
Jun 15 20:04:02 vbook ppp[4771]: tun0: LCP:  MAGICNUM[6] 0x36bc6422
Jun 15 20:04:02 vbook ppp[4771]: tun0: LCP: deflink: SendConfigAck(3) state = Ack-Sent
Jun 15 20:04:02 vbook ppp[4771]: tun0: LCP:  ACCMAP[6] 0x00000000
Jun 15 20:04:02 vbook ppp[4771]: tun0: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x05)
Jun 15 20:04:02 vbook ppp[4771]: tun0: LCP:  MAGICNUM[6] 0x36bc6422
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP: deflink: SendConfigReq(1) state = Ack-Sent
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP:  ACCMAP[6] 0x00000000
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP:  MRU[4] 1500
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP:  MAGICNUM[6] 0x031c8dad
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP: deflink: RecvConfigAck(1) state = Ack-Sent
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP:  ACCMAP[6] 0x00000000
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP:  MRU[4] 1500
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP:  MAGICNUM[6] 0x031c8dad
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP: deflink: State change Ack-Sent --> Opened
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP: deflink: LayerUp
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP: deflink: SendIdent(0) state = Opened
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP:  MAGICNUM 031c8dad
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Jun 15 20:04:03 vbook ppp[4771]: tun0: Phase: bundle: Authenticate
Jun 15 20:04:03 vbook ppp[4771]: tun0: Phase: deflink: his = CHAP 0x05, mine = none
Jun 15 20:04:03 vbook ppp[4771]: tun0: Phase: Chap Input: CHALLENGE (16 bytes from pdsn-m34-7cm6)
Jun 15 20:04:03 vbook ppp[4771]: tun0: Phase: Chap Output: RESPONSE (mobile)
Jun 15 20:04:03 vbook ppp[4771]: tun0: Phase: Chap Input: SUCCESS
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP: FSM: Using "deflink" as a transport
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP: deflink: State change Initial --> Closed
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP: deflink: LayerStart.
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP: MPPE: Not usable without CHAP81
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP: deflink: SendConfigReq(1) state = Closed
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP:   [EMPTY]
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP: deflink: State change Closed --> Req-Sent
Jun 15 20:04:03 vbook ppp[4771]: tun0: Phase: deflink: lcp -> open
Jun 15 20:04:03 vbook ppp[4771]: tun0: Phase: bundle: Network
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP: FSM: Using "deflink" as a transport
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP: deflink: State change Initial --> Closed
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP: deflink: LayerStart.
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP: deflink: SendConfigReq(1) state = Closed
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP:  IPADDR[6] 127.0.0.1
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP:  PRIDNS[6] 212.119.97.5
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP:  SECDNS[6] 255.255.255.255
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP: deflink: State change Closed --> Req-Sent
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP: deflink: RecvConfigReq(1) state = Req-Sent
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP:  IPADDR[6] 212.119.106.161
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP: deflink: SendConfigAck(1) state = Req-Sent
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP:  IPADDR[6] 212.119.106.161
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP: deflink: State change Req-Sent --> Ack-Sent
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP: deflink: RecvConfigReq(1) state = Req-Sent
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP:  MPPE[6] value 0x00000001 (0 bits, stateful, compressed)
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP: MPPE: Not usable without CHAP81
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP: deflink: SendConfigRej(1) state = Req-Sent
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP:  MPPE[6] value 0x00000001 (0 bits, stateful, compressed)
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP: deflink: SendIdent(1) state = Opened
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP:  MAGICNUM 031c8dad
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP: deflink: RecvConfigAck(1) state = Req-Sent
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP:   [EMPTY]
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP: deflink: State change Req-Sent --> Ack-Rcvd
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP: deflink: RecvConfigNak(1) state = Ack-Sent
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP:  IPADDR[6] 92.36.20.41
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP:  IPADDR[6] changing address: 127.0.0.1  --> 92.36.20.41
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP:  PRIDNS[6] 212.119.96.33
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP:  SECDNS[6] 212.119.97.5
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP: Primary nameserver set to 212.119.96.33
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP: Secondary nameserver set to 212.119.97.5
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP: deflink: SendConfigReq(2) state = Ack-Sent
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP:  IPADDR[6] 92.36.20.41
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP:  PRIDNS[6] 212.119.96.33
Jun 15 20:04:03 vbook ppp[4771]: tun0: IPCP:  SECDNS[6] 212.119.97.5
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP: deflink: RecvConfigReq(2) state = Ack-Rcvd
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP:  LZS-DCP[6] 
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP: deflink: SendConfigRej(2) state = Ack-Rcvd
Jun 15 20:04:03 vbook ppp[4771]: tun0: CCP:  LZS-DCP[6] 
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP: deflink: SendIdent(2) state = Opened
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP:  MAGICNUM 031c8dad
Jun 15 20:04:03 vbook ppp[4771]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Jun 15 20:04:04 vbook ppp[4771]: tun0: IPCP: deflink: RecvConfigAck(2) state = Ack-Sent
Jun 15 20:04:04 vbook ppp[4771]: tun0: IPCP:  IPADDR[6] 92.36.20.41
Jun 15 20:04:04 vbook ppp[4771]: tun0: IPCP:  PRIDNS[6] 212.119.96.33
Jun 15 20:04:04 vbook ppp[4771]: tun0: IPCP:  SECDNS[6] 212.119.97.5
Jun 15 20:04:04 vbook ppp[4771]: tun0: IPCP: deflink: State change Ack-Sent --> Opened
Jun 15 20:04:04 vbook ppp[4771]: tun0: IPCP: deflink: LayerUp.
Jun 15 20:04:04 vbook ppp[4771]: tun0: IPCP: myaddr 92.36.20.41 hisaddr = 212.119.106.161
Jun 15 20:04:04 vbook ppp[4771]: tun0: Warning: 0.0.0.0/0: Change route failed: errno: No such process
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP: deflink: RecvConfigReq(3) state = Ack-Rcvd
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP:  STAC[5] 
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP: deflink: SendConfigRej(3) state = Ack-Rcvd
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP:  STAC[5] 
Jun 15 20:04:04 vbook ppp[4771]: tun0: LCP: deflink: SendIdent(3) state = Opened
Jun 15 20:04:04 vbook ppp[4771]: tun0: LCP:  MAGICNUM 031c8dad
Jun 15 20:04:04 vbook ppp[4771]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP: deflink: RecvConfigReq(4) state = Ack-Rcvd
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP:  STAC[5] 
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP: deflink: SendConfigRej(4) state = Ack-Rcvd
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP:  STAC[5] 
Jun 15 20:04:04 vbook ppp[4771]: tun0: LCP: deflink: SendIdent(4) state = Opened
Jun 15 20:04:04 vbook ppp[4771]: tun0: LCP:  MAGICNUM 031c8dad
Jun 15 20:04:04 vbook ppp[4771]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP: deflink: RecvConfigReq(5) state = Ack-Rcvd
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP:  PRED1[2] 
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP: deflink: SendConfigRej(5) state = Ack-Rcvd
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP:  PRED1[2] 
Jun 15 20:04:04 vbook ppp[4771]: tun0: LCP: deflink: SendIdent(5) state = Opened
Jun 15 20:04:04 vbook ppp[4771]: tun0: LCP:  MAGICNUM 031c8dad
Jun 15 20:04:04 vbook ppp[4771]: tun0: LCP:  TEXT user-ppp 3.4.2 (built COMPILATIONDATE)
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP: deflink: RecvConfigReq(6) state = Ack-Rcvd
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP:   [EMPTY]
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP: deflink: SendConfigAck(6) state = Ack-Rcvd
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP:   [EMPTY]
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP: deflink: State change Ack-Rcvd --> Opened
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP: deflink: LayerUp.
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP: deflink: Out = none[-1], In = none[-1]
Jun 15 20:04:04 vbook ppp[4771]: tun0: Warning: 0.0.0.0/0: Change route failed: errno: No such process
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP: deflink: RecvTerminateReq(7) state = Opened
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP: deflink: LayerDown.
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP: deflink: SendTerminateAck(7) state = Opened
Jun 15 20:04:04 vbook ppp[4771]: tun0: CCP: deflink: State change Opened --> Stopping
Jun 15 20:04:04 vbook ppp[4771]: tun0: LCP: deflink: RecvEchoRequest(1) state = Opened
Jun 15 20:04:04 vbook ppp[4771]: tun0: LCP: deflink: SendEchoReply(1) state = Opened
Jun 15 20:04:07 vbook ppp[4771]: tun0: CCP: deflink: LayerFinish.
Jun 15 20:04:07 vbook ppp[4771]: tun0: CCP: deflink: State change Stopping --> Stopped
Jun 15 20:04:29 vbook ppp[4771]: tun0: Phase: /dev/ttyp1: Client connection closed.
Jun 15 20:04:29 vbook ppp[4771]: tun0: IPCP: deflink: LayerDown: 92.36.20.41
Jun 15 20:04:29 vbook ppp[4771]: tun0: IPCP: deflink: SendTerminateReq(3) state = Opened
Jun 15 20:04:29 vbook ppp[4771]: tun0: IPCP: deflink: State change Opened --> Closing
Jun 15 20:04:29 vbook ppp[4771]: tun0: IPCP: deflink: RecvTerminateAck(3) state = Closing
Jun 15 20:04:29 vbook ppp[4771]: tun0: IPCP: deflink: LayerFinish.


-- 
Vladimir B. Grebenschikov
vova at fbsd.ru

More information about the freebsd-net mailing list