Subject: Internet connection using NetBSD/arm32.
To: Risc BSD mailing list <port-arm32@NetBSD.ORG>
From: Ib-Michael Martinsen <imm@nethotel.dk>
List: port-arm32
Date: 07/05/1998 22:21:14
Hi there,

Sorry for the following excessive posting, but I am desperate.

I have been using a NetBSD/arm32 server as an Internet gateway,
which has been working fine until recently when my ISP decided
to switch/upgrade some equipment. Unfortunately after this equipment
exchange it has been impossible to connect to my ISP using NetBSD/arm32
but I have no problems if I use a Windows95 PC running Netscape.
I have tried contacting my ISP without any success: I am told that
they can/will only support Windows95/PC and MacIntosh.

I have made some traces to see what is going on during connection,
but unfortunately I do not know enough about TCP/IP to interpret
the traces. I am hoping that maybe some of you can suggest me how
to re-establish my Internet connection, either by suggesting something
I could try or maybe even point out what I have to do to get it to
work.

Anyway, here are some Windows95 modem and ppp-traces from a successful
Windows95/Netscape connection:

Modem.trc:
----------

07-05-1998 15:13:26.15 - GVC External 28800 Fax Modem in use.
07-05-1998 15:13:26.19 - Modem type: GVC External 28800 Fax Modem
07-05-1998 15:13:26.19 - Modem inf path: MDMGVC.INF
07-05-1998 15:13:26.19 - Modem inf section: Modem1
07-05-1998 15:13:26.46 - 57600,N,8,1
07-05-1998 15:13:26.62 - 57600,N,8,1
07-05-1998 15:13:26.64 - Initializing modem.
07-05-1998 15:13:26.64 - Send: AT<cr>
07-05-1998 15:13:26.64 - Recv: AT<cr>
07-05-1998 15:13:26.74 - Recv: <cr><lf>OK<cr><lf>
07-05-1998 15:13:26.74 - Interpreted response: Ok
07-05-1998 15:13:26.74 - Send: AT &F E0 V1 \V1 S0=0<cr>
07-05-1998 15:13:26.77 - Recv: AT &F E0 V1 \V1 S0=0<cr>
07-05-1998 15:13:26.85 - Recv: <cr><lf>OK<cr><lf>
07-05-1998 15:13:26.85 - Interpreted response: Ok
07-05-1998 15:13:26.85 - Send: ATS7=60\T0L0M1\N6%C1&K3B0&E1X4<cr>
07-05-1998 15:13:26.97 - Recv: <cr><lf>OK<cr><lf>
07-05-1998 15:13:26.97 - Interpreted response: Ok
07-05-1998 15:13:26.97 - Dialing.
07-05-1998 15:13:26.97 - Send: ATDT;<cr>
07-05-1998 15:13:29.99 - Recv: <cr><lf>OK<cr><lf>
07-05-1998 15:13:29.99 - Interpreted response: Ok
07-05-1998 15:13:30.02 - Dialing.
07-05-1998 15:13:30.02 - Send: ATDT########<cr>
07-05-1998 15:13:47.10 - Recv: <cr><lf>CONNECT 28800/V42BIS<cr><lf>
07-05-1998 15:13:47.10 - Interpreted response: Connect
07-05-1998 15:13:47.10 - Connection established at 28800bps.
07-05-1998 15:13:47.10 - Error-control on.
07-05-1998 15:13:47.10 - Data compression on.
07-05-1998 15:13:50.96 - 57600,N,8,1
07-05-1998 15:21:54.17 - Hanging up the modem.
07-05-1998 15:21:54.17 - Hardware hangup by lowering DTR.
07-05-1998 15:21:55.38 - WARNING: The modem did not respond to lowering DTR.  Trying software hangup...
07-05-1998 15:21:55.38 - Send: +++
07-05-1998 15:21:57.38 - Send: ATH<cr>
07-05-1998 15:21:57.89 - Recv: <cr><lf>NO CARRIER<cr><lf>
07-05-1998 15:21:57.89 - Interpreted response: No Carrier
07-05-1998 15:21:57.99 - Session Statistics:
07-05-1998 15:21:57.99 -                Reads : 299465 bytes
07-05-1998 15:21:57.99 -                Writes: 28522 bytes
07-05-1998 15:21:57.99 - GVC External 28800 Fax Modem closed.



ppp.trc:
--------

07-05-1998 15:13:50.95 - Remote access driver log opened.
07-05-1998 15:13:50.95 - Installable CP VxD SPAP     is loaded
07-05-1998 15:13:50.95 - Server type is  PPP (Point to Point Protocol).
07-05-1998 15:13:50.95 - FSA : Adding Control Protocol 80fd (CCP) to control protocol chain.
07-05-1998 15:13:50.95 - FSA : Protocol not bound - skipping control protocol 803f (NBFCP).
07-05-1998 15:13:50.95 - FSA : Adding Control Protocol 8021 (IPCP) to control protocol chain.
07-05-1998 15:13:50.96 - FSA : Protocol not bound - skipping control protocol 802b (IPXCP).
07-05-1998 15:13:50.96 - FSA : Adding Control Protocol c029 (CallbackCP) to control protocol chain.
07-05-1998 15:13:50.96 - FSA : Adding Control Protocol c027 (no description) to control protocol chain.
07-05-1998 15:13:50.96 - FSA : Adding Control Protocol c023 (PAP) to control protocol chain.
07-05-1998 15:13:50.96 - FSA : Adding Control Protocol c223 (CHAP) to control protocol chain.
07-05-1998 15:13:50.96 - FSA : Adding Control Protocol c021 (LCP) to control protocol chain.
07-05-1998 15:13:50.96 - LCP : Callback negotiation enabled.
07-05-1998 15:13:50.96 - LCP : Layer started.
07-05-1998 15:13:54.15 - LCP : Received and accepted ACCM of a0000.
07-05-1998 15:13:54.15 - LCP : Received and accepted authentication protocol c223 (CHAP).
07-05-1998 15:13:54.15 - LCP : Received and accepted magic number 579537be.
07-05-1998 15:13:54.15 - LCP : Received and accepted protocol field compression option.
07-05-1998 15:13:54.15 - LCP : Received and accepted address+control field compression option.
07-05-1998 15:13:54.15 - LCP : Received configure reject for callback control protocol option.
07-05-1998 15:13:54.28 - LCP : Layer up.
07-05-1998 15:13:54.28 - CHAP : Layer started.
07-05-1998 15:13:54.55 - CHAP : Login was successful.
07-05-1998 15:13:54.55 - CHAP : Layer up.
07-05-1998 15:13:54.55 - IPCP : Layer started.
07-05-1998 15:13:54.55 - IPCP : IP address is 0.
07-05-1998 15:13:54.55 - CCP : Layer started.
07-05-1998 15:13:54.67 - IPCP : Received and accepted compression protocol request f 0.
07-05-1998 15:13:54.67 - IPCP : Received and accepted IP address of c3f96181.
07-05-1998 15:13:54.68 - CCP : Received and accepted compression value 1.
07-05-1998 15:13:54.82 - IPCP : Changing IP address from 0 to c3f961bb.
07-05-1998 15:13:54.82 - IPCP : Accepting primary DNS c1a299a4.
07-05-1998 15:13:54.94 - IPCP : Layer up.
07-05-1998 15:13:57.02 - CCP : Received and accepted compression value 1.
07-05-1998 15:13:57.68 - CCP : Layer up.
07-05-1998 15:13:57.68 - FSA : Last control protocol is up.
07-05-1998 15:21:54.03 - Remote access driver is shutting down.
07-05-1998 15:21:54.03 - CRC Errors             1
07-05-1998 15:21:54.04 - Timeout Errors         0
07-05-1998 15:21:54.04 - Alignment Errors       0
07-05-1998 15:21:54.04 - Overrun Errors         0
07-05-1998 15:21:54.04 - Framing Errors         0
07-05-1998 15:21:54.04 - Buffer Overrun Errors  0
07-05-1998 15:21:54.04 - Incomplete Packets     0
07-05-1998 15:21:54.04 - Bytes Received         299367
07-05-1998 15:21:54.04 - Bytes Transmittted     28424
07-05-1998 15:21:54.04 - Frames Received        461
07-05-1998 15:21:54.04 - Frames Transmitted     497
07-05-1998 15:21:54.04 - LCP : Layer down.
07-05-1998 15:21:54.04 - CHAP : Layer down.
07-05-1998 15:21:54.04 - IPCP : Layer down.
07-05-1998 15:21:54.04 - CCP : Layer down.
07-05-1998 15:21:54.14 - LCP : Received terminate acknowledgement.
07-05-1998 15:21:54.14 - LCP : Layer finished.
07-05-1998 15:21:54.14 - Remote access driver log closed.



The following is a trace from my NetBSD/arm32 system when trying to
establish a connection using pppd and the following chat-script. I
have inserted ******** instead of my password:

chat-script:
------------

ABORT BUSY
ABORT 'NO DIALTONE'
ABORT 'NO CARRIER'
'' ATD87311111
CONNECT '\r'
sername:-\r\n-sername: 123232347229
assword: ********
'' ppp


I execute the following line as root for initiating the connection:

pppd tty00 57600 connect "chat -v -f /root/chat" mru 1500 lock debug \
     crtscts modem defaultroute kdebug 7



The kdebug-option gives the following output in the system-log:

/var/log/messages:
------------------

Jul  5 16:50:34 nethotel pppd[259]: pppd 2.3.1 started by root, uid 0
Jul  5 16:50:36 nethotel chat[262]: abort on (BUSY) 
Jul  5 16:50:36 nethotel chat[262]: abort on (NO DIALTONE) 
Jul  5 16:50:36 nethotel chat[262]: abort on (NO CARRIER) 
Jul  5 16:50:36 nethotel chat[262]: send (ATD87311111^M) 
Jul  5 16:50:36 nethotel chat[262]: expect (CONNECT) 
Jul  5 16:50:56 nethotel chat[262]: ATD87311111^M^M 
Jul  5 16:50:56 nethotel chat[262]: CONNECT -- got it 
Jul  5 16:50:56 nethotel chat[262]: send (^M^M) 
Jul  5 16:50:56 nethotel chat[262]: expect (sername:) 
Jul  5 16:50:56 nethotel chat[262]:  28800/V42BIS^M 
Jul  5 16:50:56 nethotel chat[262]: ^M 
Jul  5 16:50:56 nethotel chat[262]: ^M 
Jul  5 16:50:56 nethotel chat[262]: User Access Verification^M 
Jul  5 16:50:56 nethotel chat[262]: ^M 
Jul  5 16:50:56 nethotel chat[262]: Username: -- got it 
Jul  5 16:50:56 nethotel chat[262]: send (123232347229^M) 
Jul  5 16:50:56 nethotel chat[262]: expect (assword:) 
Jul  5 16:50:56 nethotel chat[262]:  123232347229^M 
Jul  5 16:50:56 nethotel chat[262]: Password: -- got it 
Jul  5 16:50:56 nethotel chat[262]: send (********^M) 
Jul  5 16:50:56 nethotel chat[262]: send (ppp^M) 
Jul  5 16:50:57 nethotel pppd[259]: Serial connection established.
Jul  5 16:50:58 nethotel pppd[259]: Using interface ppp0
Jul  5 16:50:58 nethotel pppd[259]: Connect: ppp0 <--> /dev/tty00
Jul  5 16:50:58 nethotel /netbsd: ppp0 output: ff03c0210101000e05065f38eda807020802 
Jul  5 16:51:01 nethotel /netbsd: ppp0 output: ff03c0210101000e05065f38eda807020802 
Jul  5 16:51:01 nethotel /netbsd: ppp0: bad fcs 62e
Jul  5 16:51:01 nethotel /netbsd: ppp0: bad protocol 820
Jul  5 16:51:04 nethotel /netbsd: ppp0 output: ff03c0210101000e05065f38eda807020802 
Jul  5 16:51:06 nethotel /netbsd: ppp0: bad fcs cb4f
Jul  5 16:51:06 nethotel /netbsd: ppp0: bad fcs 4a16
Jul  5 16:51:07 nethotel /netbsd: ppp0 output: ff03c0210101000e05065f38eda807020802 
Jul  5 16:51:07 nethotel /netbsd: ppp0: got 18 bytes
Jul  5 16:51:07 nethotel /netbsd: ff03c0210201000e05065f38eda807020802 
Jul  5 16:51:08 nethotel /netbsd: ppp0: got 29 bytes
Jul  5 16:51:08 nethotel /netbsd: ff03c021013800190206000a00000305c22305050657eca18d07020802 
Jul  5 16:51:08 nethotel /netbsd: ppp0 output: ff03c021043800090305c22305 
Jul  5 16:51:08 nethotel /netbsd: ppp0: got 28 bytes
Jul  5 16:51:08 nethotel /netbsd: ff03c021013900180206000a00000304c023050657eca18d07020802 
Jul  5 16:51:08 nethotel /netbsd: ppp0 output: ff03c021043900080304c023 
Jul  5 16:51:09 nethotel /netbsd: ppp0: got 29 bytes
Jul  5 16:51:09 nethotel /netbsd: ff03c021013a00190206000a00000305c22305050657eca18d07020802 
Jul  5 16:51:09 nethotel /netbsd: ppp0 output: ff03c021043a00090305c22305 
Jul  5 16:51:09 nethotel /netbsd: ppp0: got 28 bytes
Jul  5 16:51:09 nethotel /netbsd: ff03c021013b00180206000a00000304c023050657eca18d07020802 
Jul  5 16:51:09 nethotel /netbsd: ppp0 output: ff03c021043b00080304c023 
Jul  5 16:51:09 nethotel /netbsd: ppp0: got 29 bytes
Jul  5 16:51:09 nethotel /netbsd: ff03c021013c00190206000a00000305c22305050657eca18d07020802 
Jul  5 16:51:09 nethotel /netbsd: ppp0 output: ff03c021043c00090305c22305 
Jul  5 16:51:10 nethotel /netbsd: ppp0 output: ff03c0210101000e05065f38eda807020802 
Jul  5 16:51:20 nethotel pppd[259]: Modem hangup
Jul  5 16:51:20 nethotel pppd[259]: Connection terminated.
Jul  5 16:51:22 nethotel pppd[259]: Exit.



The debug-option gives the following output:

/var/log/pppdmsg:
-----------------

Jul  5 16:50:34 nethotel pppd[259]: pppd 2.3.1 started by root, uid 0
Jul  5 16:50:57 nethotel pppd[259]: Serial connection established.
Jul  5 16:50:58 nethotel pppd[259]: Using interface ppp0
Jul  5 16:50:58 nethotel pppd[259]: Connect: ppp0 <--> /dev/tty00
Jul  5 16:50:58 nethotel pppd[259]: sent [LCP ConfReq id=0x1 <magic 0x5f38eda8> <pcomp> <accomp>]
Jul  5 16:51:07 nethotel last message repeated 3 times
Jul  5 16:51:07 nethotel pppd[259]: rcvd [LCP ConfAck id=0x1 <magic 0x5f38eda8> <pcomp> <accomp>]
Jul  5 16:51:08 nethotel pppd[259]: rcvd [LCP ConfReq id=0x38 <asyncmap 0xa0000> <auth chap 05> <magic 0x57eca18d> <pcomp> <accomp>]
Jul  5 16:51:08 nethotel pppd[259]: sent [LCP ConfRej id=0x38 <auth chap 05>]
Jul  5 16:51:08 nethotel pppd[259]: rcvd [LCP ConfReq id=0x39 <asyncmap 0xa0000> <auth pap> <magic 0x57eca18d> <pcomp> <accomp>]
Jul  5 16:51:08 nethotel pppd[259]: sent [LCP ConfRej id=0x39 <auth pap>]
Jul  5 16:51:09 nethotel pppd[259]: rcvd [LCP ConfReq id=0x3a <asyncmap 0xa0000> <auth chap 05> <magic 0x57eca18d> <pcomp> <accomp>]
Jul  5 16:51:09 nethotel pppd[259]: sent [LCP ConfRej id=0x3a <auth chap 05>]
Jul  5 16:51:09 nethotel pppd[259]: rcvd [LCP ConfReq id=0x3b <asyncmap 0xa0000> <auth pap> <magic 0x57eca18d> <pcomp> <accomp>]
Jul  5 16:51:09 nethotel pppd[259]: sent [LCP ConfRej id=0x3b <auth pap>]
Jul  5 16:51:09 nethotel pppd[259]: rcvd [LCP ConfReq id=0x3c <asyncmap 0xa0000> <auth chap 05> <magic 0x57eca18d> <pcomp> <accomp>]
Jul  5 16:51:09 nethotel pppd[259]: sent [LCP ConfRej id=0x3c <auth chap 05>]
Jul  5 16:51:10 nethotel pppd[259]: sent [LCP ConfReq id=0x1 <magic 0x5f38eda8> <pcomp> <accomp>]
Jul  5 16:51:19 nethotel last message repeated 3 times
Jul  5 16:51:20 nethotel pppd[259]: Modem hangup
Jul  5 16:51:20 nethotel pppd[259]: Connection terminated.
Jul  5 16:51:22 nethotel pppd[259]: Exit.



I really do hope that someone can help me with this problem,
if so please reply to both my home and work address.

Best regards
   Ib-Michael
-- 
Ib-Michael Martinsen		Email at work: dtpimm@dsg.dk
Fidomail:      2:234/181.9	Email at home: imm@nethotel.dk

Running RiscBSD v1.3a on an Acorn RiscPC with a 202.4 MHz StrongArm processor.