PPP Startup
Changfeng Wang
> to connect from home to school by a modem (Telepathy II, 14400).
> I need to pass the network authorization first and then switch to PPP
> by answering three prompts after dialing through:
>
> (Messages after dialing through)
>
> Network ID: fwang
> Password: passwrd
> Annex: PPP
>
>
> I tried the following command line:
>
> % pppd connect 'chat -v " ATDT8980834 CONNECT ID:fwang
> word: passwrd nnex: ppp '\ /dev/cua0 14400 ...
>
> It dials OK, but does not send "fwang" to "Network ID:" prompt and
> so on. Part of the "messages" file looks like this:
>
> ........
>
> I used several variations in the command line, including adding TIMEOUT
> changing "ID: fwang" to "ID:--ID:fwang", and a lot of others, but none
> worked. The problem seems to be that the command line does not answer
> the prompt at the right time.
Thanks to those kind souls who replied. It is now dialing. The problem
now is that pppd would hang there after the connection, and I still cannot
proceed to use the network. When I tried ftp etc., I received the message
"network unreachable ..."
I have read Net_2 HOWTO, documents on PPP (Readme, howto etc.),
and Network User's Guide. I followed essentially all the instructions
in those materials for configurations, but still can not fix it.
The Boot messages, the relevant part of ppplog, syslog, and debug files
follow this message. I will appreciate it very much if some one can point
out to me the problem.
-- Changfeng Wang
:::::::::::::
Command line
:::::::::::::
% pppd connect "chat -v "" ATDT8980834 CONNECT "" "etwork ID:" AAAAA assword: good09Luck nnex: ppp '\
/dev/cua0 38400 debug crtscts modem defaultroute
::::::::::::::
Boot message
::::::::::::::
Mar 7 22:50:55 scarllet kernel: PPP: version 0.2.7 (4 channels) NEW_TTY_DRIVERS OPTIMIZE_FLAGS
Mar 7 22:50:55 scarllet kernel: TCP compression code copyright 1989 Regents of the University of Cali
fornia
Mar 7 22:50:55 scarllet kernel: PPP line discipline registered.
Mar 7 22:50:55 scarllet kernel: SLIP: version 0.7.5-NET3.014-NEWTTY (4 channels)
Mar 7 22:50:55 scarllet kernel: CSLIP: code copyright 1989 Regents of the University of California
Mar 7 22:50:55 scarllet kernel: eth0: 3c505 not found
Mar 7 22:50:55 scarllet kernel: eth0: D-Link DE-600 pocket adapter: not at I/O 0x378.
Mar 7 22:50:55 scarllet kernel: D-Link DE-620 pocket adapter not identified in the printer port
Mar 7 22:50:55 scarllet kernel: Checking 386/387 coupling... Ok, fpu using exception 16 error reporti::::::::::::::
syslog
::::::::::::::
Mar 7 22:53:09 scarllet pppd[74]: ioctl(SIOCSIFNETMASK): Invalid argument
Mar 7 22:53:09 scarllet pppd[74]: sifaddr failed
::::::::::::::
messages
::::::::::::::
Mar 7 22:50:54 scarllet syslogd: restart
Mar 7 22:50:55 scarllet kernel: Kernel logging (proc) started.
Mar 7 22:50:55 scarllet kernel: Console: colour EGA+ 80x25, 1 virtual console (max 63)
Mar 7 22:50:55 scarllet kernel: bios32_init : BIOS32 Service Directory structure at 0x000f0120
Mar 7 22:50:55 scarllet kernel: bios32_init : BIOS32 Service Directory entry at 0xf13ec
Mar 7 22:50:55 scarllet kernel: pcibios_init : PCI BIOS revision 2.10 entry at 0xf13d0
Mar 7 22:50:55 scarllet kernel: Serial driver version 4.00 with no serial options enabled
Mar 7 22:50:55 scarllet kernel: tty00 at 0x03f8 (irq = 4) is a 16550A
Mar 7 22:50:55 scarllet kernel: tty01 at 0x02f8 (irq = 3) is a 16550A
Mar 7 22:50:55 scarllet kernel: tty02 at 0x03e8 (irq = 4) is a 16550A
Mar 7 22:50:55 scarllet kernel: lp_init: lp1 exists, using polling driver
Mar 7 22:50:55 scarllet kernel: PS/2 auxiliary pointing device detected -- driver installed.
Mar 7 22:50:55 scarllet kernel: ftape: allocated 3 buffers aligned at: 00228000
Mar 7 22:50:55 scarllet kernel: nec260: registered with major #26
Mar 7 22:50:55 scarllet kernel: Calibrating delay loop.. ok - 36.08 BogoMips
Mar 7 22:50:55 scarllet kernel: scsi : 0 hosts.
Mar 7 22:50:55 scarllet kernel: Memory: 14700k/16384k available (948k kernel code, 384k reserved, 352k data)
Mar 7 22:50:55 scarllet kernel: This processor honours the WP bit even when in supervisor mode. Good.
Mar 7 22:50:55 scarllet kernel: Floppy drive(s): fd0 is 1.44M
Mar 7 22:50:55 scarllet kernel: FDC 0 is a post-1991 82077
Mar 7 22:50:55 scarllet kernel: Swansea University Computer Society NET3.017
Mar 7 22:50:55 scarllet kernel: Swansea University Computer Society TCP/IP for NET3.017
Mar 7 22:50:55 scarllet kernel: IP Protocols: ICMP, UDP, TCP
Mar 7 22:50:55 scarllet kernel: PPP: version 0.2.7 (4 channels) NEW_TTY_DRIVERS OPTIMIZE_FLAGS
Mar 7 22:50:55 scarllet kernel: TCP compression code copyright 1989 Regents of the University of California
Mar 7 22:50:55 scarllet kernel: PPP line discipline registered.
Mar 7 22:50:55 scarllet kernel: SLIP: version 0.7.5-NET3.014-NEWTTY (4 channels)
Mar 7 22:50:55 scarllet kernel: CSLIP: code copyright 1989 Regents of the University of California
Mar 7 22:50:55 scarllet kernel: eth0: 3c505 not found
Mar 7 22:50:55 scarllet kernel: eth0: D-Link DE-600 pocket adapter: not at I/O 0x378.
Mar 7 22:50:55 scarllet kernel: D-Link DE-620 pocket adapter not identified in the printer port
Mar 7 22:50:55 scarllet kernel: Checking 386/387 coupling... Ok, fpu using exception 16 error reporting.
Mar 7 22:50:55 scarllet kernel: Checking 'hlt' instruction... Ok.
Mar 7 22:50:55 scarllet kernel: Linux version 1.1.59 (root@fuzzy) (gcc version 2.5.8) #5 Sat Oct 29 14:34:24 CDT 1994
Mar 7 22:50:55 scarllet kernel: Partition check:
Mar 7 22:50:55 scarllet kernel: hda: WDC AC2700H, 696MB w/128KB Cache, CHS=1416/16/63, MaxMult=16
Mar 7 22:50:55 scarllet kernel: hda: hda1 hda2 hda3
Mar 7 22:50:55 scarllet kernel: VFS: Mounted root (ext2 filesystem) readonly.
Mar 7 22:50:55 scarllet kernel: Adding Swap: 9068k swap-space
Mar 7 22:50:55 scarllet kernel: nec260: controller not ready
Mar 7 22:50:55 scarllet kernel: NEC IDE CD-ROM I/O error
Mar 7 22:50:55 scarllet kernel: dev 1A00, sector 0
Mar 7 22:50:55 scarllet kernel: nec260: disk change 0
Mar 7 22:50:55 scarllet kernel: nec260: controller not ready
Mar 7 22:50:55 scarllet kernel: NEC IDE CD-ROM I/O error
Mar 7 22:50:55 scarllet kernel: dev 1A00, sector 64
Mar 7 22:50:55 scarllet kernel: isofs_read_super: bread failed, dev 0x1a00 iso_blknum 16
Mar 7 22:52:39 scarllet pppd[74]: pppd 2.1.2 started by AAAAA, uid 501
Mar 7 22:52:39 scarllet pppd[74]: Connecting with <chat -v "" ATDT8980834 CONNECT "" "etwork ID:" AAAAA assword: good09Luck nnex: ppp >
Mar 7 22:52:41 scarllet chat[75]: send (ATDT8980834^M)
Mar 7 22:52:41 scarllet chat[75]: expect (CONNECT)
Mar 7 22:52:57 scarllet chat[75]: ATDT8980834^M^M
Mar 7 22:52:57 scarllet chat[75]: CONNECT -- got it
Mar 7 22:52:57 scarllet chat[75]: send (^M)
Mar 7 22:52:57 scarllet chat[75]: expect (etwork ID:)
Mar 7 22:52:57 scarllet chat[75]: 14400/ARQ/V32/LAPM/V42BIS^M
Mar 7 22:52:58 scarllet chat[75]: ^M
Mar 7 22:52:58 scarllet chat[75]: ^M
Mar 7 22:52:58 scarllet chat[75]: Annex Command Line Interpreter * Copyright 1991 Xylogics, Inc.^M
Mar 7 22:52:58 scarllet chat[75]: ^M
Mar 7 22:52:58 scarllet chat[75]: Checking authorization, Please wait...^M
Mar 7 22:52:58 scarllet chat[75]: ^M
Mar 7 22:52:58 scarllet chat[75]: NOTICE: For University of Pennsylvania authorized users only.^M
Mar 7 22:52:58 scarllet chat[75]: ^M
Mar 7 22:52:58 scarllet chat[75]: Network ID: -- got it
Mar 7 22:52:58 scarllet chat[75]: send (AAAAA^M)
Mar 7 22:52:58 scarllet chat[75]: expect (assword:)
Mar 7 22:52:59 scarllet chat[75]: AAAAA^M
Mar 7 22:52:59 scarllet chat[75]: Network password: -- got it
Mar 7 22:52:59 scarllet chat[75]: send (good09Luck^M)
Mar 7 22:52:59 scarllet chat[75]: expect (nnex:)
Mar 7 22:52:59 scarllet chat[75]: ^M
Mar 7 22:53:01 scarllet chat[75]: ^M
Mar 7 22:53:01 scarllet chat[75]: Last login on Mar 06, 1995 at 22:56 from TS5.UPENN.EDU^M
Mar 7 22:53:01 scarllet chat[75]: ^M
Mar 7 22:53:01 scarllet chat[75]: Type MENU for a list of network services.^M
Mar 7 22:53:01 scarllet chat[75]: ^M
Mar 7 22:53:01 scarllet chat[75]: ^M
Mar 7 22:53:01 scarllet chat[75]: annex: -- got it
Mar 7 22:53:01 scarllet chat[75]: send (ppp^M)
Mar 7 22:53:01 scarllet pppd[74]: Connected...
Mar 7 22:53:02 scarllet kernel: ppp: channel ppp0 mtu = 1500, mru = 1500
Mar 7 22:53:02 scarllet kernel: ppp: channel ppp0 open
Mar 7 22:53:02 scarllet pppd[74]: set kernel debugging level to 0
Mar 7 22:53:02 scarllet pppd[74]: Using interface ppp0
Mar 7 22:53:02 scarllet pppd[74]: Connect: ppp0 <--> /dev/cua0
Mar 7 22:53:02 scarllet pppd[74]: fsm_sdata(LCP): Sent code 1, id 1.
Mar 7 22:53:02 scarllet pppd[74]: LCP: sending Configure-Request, id 1
Mar 7 22:53:03 scarllet pppd[74]: fsm_rconfreq(LCP): Rcvd id 1.
Mar 7 22:53:03 scarllet pppd[74]: lcp_reqci: rcvd ASYNCMAP
Mar 7 22:53:03 scarllet pppd[74]: (0)
Mar 7 22:53:03 scarllet pppd[74]: (ACK)
Mar 7 22:53:03 scarllet pppd[74]: lcp_reqci: rcvd PCOMPRESSION
Mar 7 22:53:03 scarllet pppd[74]: (ACK)
Mar 7 22:53:03 scarllet pppd[74]: lcp_reqci: rcvd ACCOMPRESSION
Mar 7 22:53:03 scarllet pppd[74]: (ACK)
Mar 7 22:53:03 scarllet pppd[74]: lcp_reqci: returning CONFACK.
Mar 7 22:53:03 scarllet pppd[74]: fsm_sdata(LCP): Sent code 2, id 1.
Mar 7 22:53:05 scarllet pppd[74]: fsm_sdata(LCP): Sent code 1, id 1.
Mar 7 22:53:05 scarllet pppd[74]: LCP: sending Configure-Request, id 1
Mar 7 22:53:05 scarllet pppd[74]: fsm_rconfnakrej(LCP): Rcvd id 1.
Mar 7 22:53:05 scarllet pppd[74]: lcp_rejci rejected long opt 5
Mar 7 22:53:05 scarllet pppd[74]: fsm_sdata(LCP): Sent code 1, id 2.
Mar 7 22:53:05 scarllet pppd[74]: LCP: sending Configure-Request, id 2
Mar 7 22:53:06 scarllet pppd[74]: fsm_rconfack(LCP): Rcvd id 2.
Mar 7 22:53:06 scarllet pppd[74]: fsm_sdata(IPCP): Sent code 1, id 1.
Mar 7 22:53:06 scarllet pppd[74]: IPCP: sending Configure-Request, id 1
Mar 7 22:53:06 scarllet pppd[74]: fsm_rconfnakrej(IPCP): Rcvd id 1.
Mar 7 22:53:06 scarllet pppd[74]: local IP address 128.91.200.133
Mar 7 22:53:06 scarllet pppd[74]: fsm_sdata(IPCP): Sent code 1, id 2.
Mar 7 22:53:06 scarllet pppd[74]: IPCP: sending Configure-Request, id 2
Mar 7 22:53:06 scarllet pppd[74]: fsm_rconfack(IPCP): Rcvd id 2.
Mar 7 22:53:09 scarllet pppd[74]: fsm_rconfreq(IPCP): Rcvd id 2.
Mar 7 22:53:09 scarllet pppd[74]: ipcp: received COMPRESSTYPE
Mar 7 22:53:09 scarllet pppd[74]: (45)
Mar 7 22:53:09 scarllet pppd[74]: (ACK)
Mar 7 22:53:09 scarllet pppd[74]: ipcp: received ADDR
Mar 7 22:53:09 scarllet pppd[74]: (128.91.200.51)
Mar 7 22:53:09 scarllet pppd[74]: (ACK)
Mar 7 22:53:09 scarllet pppd[74]: ipcp: returning Configure-ACK
Mar 7 22:53:09 scarllet pppd[74]: fsm_sdata(IPCP): Sent code 2, id 2.
Mar 7 22:53:09 scarllet pppd[74]: ipcp: up
Mar 7 22:53:09 scarllet pppd[74]: local IP address 128.91.200.133
Mar 7 22:53:09 scarllet pppd[74]: remote IP address 128.91.200.51
Mar 7 22:53:09 scarllet pppd[74]: ipcp: down
Mar 7 22:53:09 scarllet pppd[74]: fsm_sdata(IPCP): Sent code 5, id 3.
Mar 7 22:53:09 scarllet pppd[74]: fsm_rtermack(IPCP).
Mar 7 22:53:12 scarllet pppd[74]: IPCP: Timeout event in state 2!
Mar 7 22:57:40 scarllet su: AAAAA on /dev/ttyp1
Mar 7 22:57:42 scarllet kernel: VFS: Disk change detected on device 2/0
::::::::::::::
ppplog
::::::::::::::
Mar 7 22:52:39 scarllet pppd[74]: pppd 2.1.2 started by AAAAA, uid 501
Mar 7 22:52:39 scarllet pppd[74]: Connecting with <chat -v "" ATDT8980834 CONNECT "" "etwork ID:" AAAAA assword: PPPPPP nnex: ppp >
Mar 7 22:52:41 scarllet chat[75]: send (ATDT8980834^M)
Mar 7 22:52:41 scarllet chat[75]: expect (CONNECT)
Mar 7 22:52:57 scarllet chat[75]: ATDT8980834^M^M
Mar 7 22:52:57 scarllet chat[75]: CONNECT -- got it
Mar 7 22:52:57 scarllet chat[75]: send (^M)
Mar 7 22:52:57 scarllet chat[75]: expect (etwork ID:)
Mar 7 22:52:57 scarllet chat[75]: 14400/ARQ/V32/LAPM/V42BIS^M
Mar 7 22:52:58 scarllet chat[75]: ^M
Mar 7 22:52:58 scarllet chat[75]: ^M
Mar 7 22:52:58 scarllet chat[75]: Annex Command Line Interpreter * Copyright 1991 Xylogics, Inc.^M
Mar 7 22:52:58 scarllet chat[75]: ^M
Mar 7 22:52:58 scarllet chat[75]: Checking authorization, Please wait...^M
Mar 7 22:52:58 scarllet chat[75]: ^M
Mar 7 22:52:58 scarllet chat[75]: NOTICE: For University of Pennsylvania authorized users only.^M
Mar 7 22:52:58 scarllet chat[75]: ^M
Mar 7 22:52:58 scarllet chat[75]: Network ID: -- got it
Mar 7 22:52:58 scarllet chat[75]: send (AAAAA^M)
Mar 7 22:52:58 scarllet chat[75]: expect (assword:)
Mar 7 22:52:59 scarllet chat[75]: AAAAA^M
Mar 7 22:52:59 scarllet chat[75]: Network password: -- got it
Mar 7 22:52:59 scarllet chat[75]: send (PPPPPP^M)
Mar 7 22:52:59 scarllet chat[75]: expect (nnex:)
Mar 7 22:52:59 scarllet chat[75]: ^M
Mar 7 22:53:01 scarllet chat[75]: ^M
Mar 7 22:53:01 scarllet chat[75]: Last login on Mar 06, 1995 at 22:56 from TS5.UPENN.EDU^M
Mar 7 22:53:01 scarllet chat[75]: ^M
Mar 7 22:53:01 scarllet chat[75]: Type MENU for a list of network services.^M
Mar 7 22:53:01 scarllet chat[75]: ^M
Mar 7 22:53:01 scarllet chat[75]: ^M
Mar 7 22:53:01 scarllet chat[75]: annex: -- got it
Mar 7 22:53:01 scarllet chat[75]: send (ppp^M)
Mar 7 22:53:01 scarllet pppd[74]: Connected...
Mar 7 22:53:02 scarllet pppd[74]: set kernel debugging level to 0
Mar 7 22:53:02 scarllet pppd[74]: Using interface ppp0
Mar 7 22:53:02 scarllet pppd[74]: Connect: ppp0 <--> /dev/cua0
Mar 7 22:53:02 scarllet pppd[74]: set_xaccm: 00000000 00000000 00000000 60000000
Mar 7 22:53:02 scarllet pppd[74]: send_config: mtu = 1500
Mar 7 22:53:02 scarllet pppd[74]: send_config: asyncmap = ffffffff
Mar 7 22:53:02 scarllet pppd[74]: send_config: flags = f000000
Mar 7 22:53:02 scarllet pppd[74]: recv_config: mru = 1500
Mar 7 22:53:02 scarllet pppd[74]: recv_config: asyncmap = 0
Mar 7 22:53:02 scarllet pppd[74]: recv_config: flags = f000010
Mar 7 22:53:02 scarllet pppd[74]: sent [LCP ConfReq id=0x1 <mru 1500> <magic 0x74d36f6d> <pcomp> <accomp>]
Mar 7 22:53:02 scarllet pppd[74]: fsm_sdata(LCP): Sent code 1, id 1.
Mar 7 22:53:02 scarllet pppd[74]: Timeout 2194:10910 in 3 seconds.
Mar 7 22:53:02 scarllet pppd[74]: Setting itimer for 3 seconds in timeout.
Mar 7 22:53:02 scarllet pppd[74]: LCP: sending Configure-Request, id 1
Mar 7 22:53:03 scarllet pppd[74]: IO signal received
Mar 7 22:53:03 scarllet pppd[74]: rcvd [LCP ConfReq id=0x1 <asyncmap 0x0> <pcomp> <accomp>] 70 34
Mar 7 22:53:03 scarllet pppd[74]: fsm_rconfreq(LCP): Rcvd id 1.
Mar 7 22:53:03 scarllet pppd[74]: lcp_reqci: rcvd ASYNCMAP
Mar 7 22:53:03 scarllet pppd[74]: (0)
Mar 7 22:53:03 scarllet pppd[74]: (ACK)
Mar 7 22:53:03 scarllet pppd[74]: lcp_reqci: rcvd PCOMPRESSION
Mar 7 22:53:03 scarllet pppd[74]: (ACK)
Mar 7 22:53:03 scarllet pppd[74]: lcp_reqci: rcvd ACCOMPRESSION
Mar 7 22:53:03 scarllet pppd[74]: (ACK)
Mar 7 22:53:03 scarllet pppd[74]: lcp_reqci: returning CONFACK.
Mar 7 22:53:03 scarllet pppd[74]: sent [LCP ConfAck id=0x1 <asyncmap 0x0> <pcomp> <accomp>]
Mar 7 22:53:03 scarllet pppd[74]: fsm_sdata(LCP): Sent code 2, id 1.
Mar 7 22:53:05 scarllet pppd[74]: Alarm
Mar 7 22:53:05 scarllet pppd[74]: sent [LCP ConfReq id=0x1 <mru 1500> <magic 0x74d36f6d> <pcomp> <accomp>]
Mar 7 22:53:05 scarllet pppd[74]: fsm_sdata(LCP): Sent code 1, id 1.
Mar 7 22:53:05 scarllet pppd[74]: Timeout 2194:10910 in 3 seconds.
Mar 7 22:53:05 scarllet pppd[74]: Setting itimer for 3 seconds in timeout.
Mar 7 22:53:05 scarllet pppd[74]: LCP: sending Configure-Request, id 1
Mar 7 22:53:05 scarllet pppd[74]: IO signal received
Mar 7 22:53:05 scarllet pppd[74]: rcvd [LCP ConfRej id=0x1 <magic 0x74d36f6d>] 8e ed
Mar 7 22:53:05 scarllet pppd[74]: fsm_rconfnakrej(LCP): Rcvd id 1.
Mar 7 22:53:05 scarllet pppd[74]: lcp_rejci rejected long opt 5
Mar 7 22:53:05 scarllet pppd[74]: Untimeout 2194:10910.
Mar 7 22:53:05 scarllet pppd[74]: Setting itimer for 0 seconds in untimeout.
Mar 7 22:53:05 scarllet pppd[74]: sent [LCP ConfReq id=0x2 <mru 1500> <pcomp> <accomp>]
Mar 7 22:53:05 scarllet pppd[74]: fsm_sdata(LCP): Sent code 1, id 2.
Mar 7 22:53:05 scarllet pppd[74]: Timeout 2194:10910 in 3 seconds.
Mar 7 22:53:05 scarllet pppd[74]: Setting itimer for 3 seconds in timeout.
Mar 7 22:53:05 scarllet pppd[74]: LCP: sending Configure-Request, id 2
Mar 7 22:53:06 scarllet pppd[74]: IO signal received
Mar 7 22:53:06 scarllet pppd[74]: rcvd [LCP ConfAck id=0x2 <mru 1500> <pcomp> <accomp>] c6 2c
Mar 7 22:53:06 scarllet pppd[74]: fsm_rconfack(LCP): Rcvd id 2.
Mar 7 22:53:06 scarllet pppd[74]: Untimeout 2194:10910.
Mar 7 22:53:06 scarllet pppd[74]: Setting itimer for 0 seconds in untimeout.
Mar 7 22:53:06 scarllet pppd[74]: send_config: mtu = 1500
Mar 7 22:53:06 scarllet pppd[74]: send_config: asyncmap = 0
Mar 7 22:53:06 scarllet pppd[74]: send_config: flags = f000013
Mar 7 22:53:06 scarllet pppd[74]: recv_config: mru = 1500
Mar 7 22:53:06 scarllet pppd[74]: recv_config: asyncmap = 0
Mar 7 22:53:06 scarllet pppd[74]: recv_config: flags = f000003
Mar 7 22:53:06 scarllet pppd[74]: sent [IPCP ConfReq id=0x1 <addr 128.91.200.63> <compress VJ 0f 01>]
Mar 7 22:53:06 scarllet pppd[74]: fsm_sdata(IPCP): Sent code 1, id 1.
Mar 7 22:53:06 scarllet pppd[74]: Timeout 2194:114e0 in 3 seconds.
Mar 7 22:53:06 scarllet pppd[74]: Setting itimer for 3 seconds in timeout.
Mar 7 22:53:06 scarllet pppd[74]: IPCP: sending Configure-Request, id 1
Mar 7 22:53:06 scarllet pppd[74]: IO signal received
Mar 7 22:53:06 scarllet pppd[74]: rcvd [IPCP ConfNak id=0x1 <addr 128.91.200.133>] b9 20
Mar 7 22:53:06 scarllet pppd[74]: fsm_rconfnakrej(IPCP): Rcvd id 1.
Mar 7 22:53:06 scarllet pppd[74]: local IP address 128.91.200.133
Mar 7 22:53:06 scarllet pppd[74]: Untimeout 2194:114e0.
Mar 7 22:53:06 scarllet pppd[74]: Setting itimer for 0 seconds in untimeout.
Mar 7 22:53:06 scarllet pppd[74]: sent [IPCP ConfReq id=0x2 <addr 128.91.200.133> <compress VJ 0f 01>]
Mar 7 22:53:06 scarllet pppd[74]: fsm_sdata(IPCP): Sent code 1, id 2.
Mar 7 22:53:06 scarllet pppd[74]: Timeout 2194:114e0 in 3 seconds.
Mar 7 22:53:06 scarllet pppd[74]: Setting itimer for 3 seconds in timeout.
Mar 7 22:53:06 scarllet pppd[74]: IPCP: sending Configure-Request, id 2
Mar 7 22:53:06 scarllet pppd[74]: IO signal received
Mar 7 22:53:06 scarllet pppd[74]: rcvd [IPCP ConfAck id=0x2 <addr 128.91.200.133> <compress VJ 0f 01>] 58 07
Mar 7 22:53:06 scarllet pppd[74]: fsm_rconfack(IPCP): Rcvd id 2.
Mar 7 22:53:09 scarllet pppd[74]: IO signal received
Mar 7 22:53:09 scarllet pppd[74]: rcvd [IPCP ConfReq id=0x2 <compress VJ 0f 01> <addr 128.91.200.51>] 0a c1
Mar 7 22:53:09 scarllet pppd[74]: fsm_rconfreq(IPCP): Rcvd id 2.
Mar 7 22:53:09 scarllet pppd[74]: ipcp: received COMPRESSTYPE
Mar 7 22:53:09 scarllet pppd[74]: (45)
Mar 7 22:53:09 scarllet pppd[74]: (ACK)
Mar 7 22:53:09 scarllet pppd[74]: ipcp: received ADDR
Mar 7 22:53:09 scarllet pppd[74]: (128.91.200.51)
Mar 7 22:53:09 scarllet pppd[74]: (ACK)
Mar 7 22:53:09 scarllet pppd[74]: ipcp: returning Configure-ACK
Mar 7 22:53:09 scarllet pppd[74]: sent [IPCP ConfAck id=0x2 <compress VJ 0f 01> <addr 128.91.200.51>]
Mar 7 22:53:09 scarllet pppd[74]: fsm_sdata(IPCP): Sent code 2, id 2.
Mar 7 22:53:09 scarllet pppd[74]: Untimeout 2194:114e0.
Mar 7 22:53:09 scarllet pppd[74]: Setting itimer for 0 seconds in untimeout.
Mar 7 22:53:09 scarllet pppd[74]: ipcp: up
Mar 7 22:53:09 scarllet pppd[74]: local IP address 128.91.200.133
Mar 7 22:53:09 scarllet pppd[74]: remote IP address 128.91.200.51
Mar 7 22:53:09 scarllet pppd[74]: ioctl(SIOCSIFNETMASK): Invalid argument
Mar 7 22:53:09 scarllet pppd[74]: sifaddr failed
Mar 7 22:53:09 scarllet pppd[74]: ipcp: down
Mar 7 22:53:09 scarllet pppd[74]: Script /etc/ppp/ip-down started; pid = 86
Mar 7 22:53:09 scarllet pppd[74]: sent [IPCP TermReq id=0x3]
Mar 7 22:53:09 scarllet pppd[74]: fsm_sdata(IPCP): Sent code 5, id 3.
Mar 7 22:53:09 scarllet pppd[74]: Timeout 2194:114e0 in 3 seconds.
Mar 7 22:53:09 scarllet pppd[74]: Setting itimer for 3 seconds in timeout.
Mar 7 22:53:09 scarllet pppd[74]: IO signal received
Mar 7 22:53:09 scarllet pppd[74]: rcvd [IPCP TermAck id=0x3] 99 55
Mar 7 22:53:09 scarllet pppd[74]: fsm_rtermack(IPCP).
Mar 7 22:53:12 scarllet pppd[74]: Alarm
Mar 7 22:53:12 scarllet pppd[74]: IPCP: Timeout event in state 2!