PPPD 2.4.1 and 2.4.2 with wvdial 1.53 problem

From: Theophanis Kontogiannis (tkonto_at_aegean.gr)
Date: 02/15/04 

Date: Sun, 15 Feb 2004 22:31:50 +0200

Hello

I have downloaded pppd 2.4.2 (and 2.4.1 after I failed to connect with
2.4.1) and it compiled (both the kernel and the user parts) OK.

Without any changes to my wvdial 1.53 conf file, I try to use the new pppd.

Result:

Feb 13 15:53:16 gw wvdial[2629]: WvDial: Internet dialer version 1.53
Feb 13 15:53:16 gw wvdial[2629]: Initializing modem.
Feb 13 15:53:16 gw wvdial[2629]: Sending: AT
Feb 13 15:53:16 gw wvdial[2629]: ERROR
Feb 13 15:53:16 gw wvdial[2629]: ERROR
Feb 13 15:53:16 gw wvdial[2629]: OK
Feb 13 15:53:16 gw wvdial[2629]: Sending: ATB0
Feb 13 15:53:16 gw wvdial[2629]: ERROR
Feb 13 15:53:16 gw wvdial[2629]: Bad init string.
Feb 13 15:53:16 gw wvdial[2629]: Initializing modem.
Feb 13 15:53:16 gw wvdial[2629]: Sending: AT
Feb 13 15:53:17 gw wvdial[2629]: ERROR
Feb 13 15:53:17 gw wvdial[2629]: ERROR
Feb 13 15:53:17 gw wvdial[2629]: OK
Feb 13 15:53:17 gw wvdial[2629]: Sending: ATB0
Feb 13 15:53:17 gw wvdial[2629]: ERROR
Feb 13 15:53:17 gw wvdial[2629]: Bad init string.
Feb 13 15:53:17 gw wvdial[2629]: Initializing modem.
Feb 13 15:53:17 gw wvdial[2629]: Sending: AT
Feb 13 15:53:17 gw wvdial[2629]: ERROR
Feb 13 15:53:17 gw wvdial[2629]: OK
Feb 13 15:53:17 gw wvdial[2629]: Sending: ATB0
Feb 13 15:53:17 gw wvdial[2629]: ERROR
Feb 13 15:53:17 gw wvdial[2629]: Bad init string.
Feb 13 15:53:17 gw pppd[2618]: Connect script failed
Feb 13 15:53:44 gw pppd[2631]: tdb_store failed: IO Error
Feb 13 15:53:44 gw pppd[2632]: tdb_store key failed: IO Error
Feb 13 15:53:44 gw pppd[2632]: tdb_store failed: IO Error
Feb 13 15:53:44 gw pppd[2632]: pppd 2.4.2 started by root, uid 0
Feb 13 15:53:44 gw pppd[2632]: tdb_store failed: IO Error
Feb 13 15:53:44 gw pppd[2632]: tdb_store key failed: IO Error
Feb 13 15:53:44 gw pppd[2632]: tdb_store failed: IO Error
Feb 13 15:53:44 gw pppd[2632]: tdb_store key failed: IO Error
Feb 13 15:53:44 gw pppd[2632]: tdb_store failed: IO Error
Feb 13 15:53:44 gw pppd[2632]: Using interface ppp1
Feb 13 15:53:44 gw pppd[2632]: tdb_store key failed: IO Error
Feb 13 15:53:44 gw pppd[2632]: tdb_store failed: IO Error
Feb 13 15:53:44 gw pppd[2632]: not replacing existing default route to ppp0
[10.112.112.112]
_________________________________________________________
OR

Feb 13 15:54:49 gw wvdial[2675]: WvDial: Internet dialer version 1.53
Feb 13 15:54:49 gw wvdial[2675]: Initializing modem.
Feb 13 15:54:49 gw wvdial[2675]: Sending: AT
Feb 13 15:54:49 gw wvdial[2675]: ERROR
Feb 13 15:54:49 gw last message repeated 2 times
Feb 13 15:54:49 gw wvdial[2675]: OK
Feb 13 15:54:49 gw wvdial[2675]: Modem initialized.
Feb 13 15:54:49 gw wvdial[2675]: Sending: ATD8962518180
Feb 13 15:54:49 gw wvdial[2675]: Waiting for carrier.
Feb 13 15:54:49 gw wvdial[2675]: ERROR
Feb 13 15:54:49 gw wvdial[2675]: Voice line detected. Trying again.
Feb 13 15:54:49 gw wvdial[2675]: Sending: ATD8962518180
Feb 13 15:54:49 gw wvdial[2675]: Waiting for carrier.
Feb 13 15:54:50 gw wvdial[2675]: ERROR
Feb 13 15:54:50 gw wvdial[2675]: Voice line detected. Trying again.
Feb 13 15:54:50 gw wvdial[2675]: Sending: ATD8962518180
Feb 13 15:54:50 gw wvdial[2675]: Waiting for carrier.
Feb 13 15:54:50 gw wvdial[2675]: NO CARRIER
Feb 13 15:54:50 gw wvdial[2675]: No Carrier! Trying again.
Feb 13 15:54:51 gw wvdial[2675]: Sending: ATD8962518180
Feb 13 15:54:51 gw wvdial[2675]: Waiting for carrier.
Feb 13 15:54:51 gw wvdial[2675]: NO CARRIER
Feb 13 15:54:51 gw wvdial[2675]: No Carrier! Trying again.
Feb 13 15:54:51 gw wvdial[2675]: Sending: ATD8962518180
Feb 13 15:54:51 gw wvdial[2675]: Waiting for carrier.
Feb 13 15:54:51 gw wvdial[2675]: NO CARRIER
Feb 13 15:54:51 gw wvdial[2675]: No Carrier! Trying again.
Feb 13 15:54:52 gw wvdial[2675]: Sending: ATD8962518180
Feb 13 15:54:52 gw wvdial[2675]: Waiting for carrier.
Feb 13 15:54:52 gw wvdial[2675]: NO CARRIER
Feb 13 15:54:52 gw wvdial[2675]: No Carrier! Trying again.
Feb 13 15:54:52 gw wvdial[2675]: Sending: ATD8962518180
Feb 13 15:54:52 gw wvdial[2675]: Waiting for carrier.
Feb 13 15:54:52 gw wvdial[2675]: NO CARRIER
Feb 13 15:54:52 gw wvdial[2675]: No Carrier! Trying again.
Feb 13 15:54:53 gw wvdial[2675]: Sending: ATD8962518180
Feb 13 15:54:53 gw wvdial[2675]: Waiting for carrier.
Feb 13 15:54:53 gw wvdial[2675]: NO CARRIER
Feb 13 15:54:53 gw wvdial[2675]: No Carrier! Trying again.
Feb 13 15:54:54 gw wvdial[2675]: Sending: ATD8962518180
Feb 13 15:54:54 gw wvdial[2675]: Waiting for carrier.
Feb 13 15:54:54 gw wvdial[2675]: NO CARRIER
Feb 13 15:54:54 gw wvdial[2675]: No Carrier! Trying again.
Feb 13 15:54:54 gw wvdial[2675]: Sending: ATD8962518180
Feb 13 15:54:54 gw wvdial[2675]: Waiting for carrier.
Feb 13 15:54:54 gw wvdial[2675]: NO CARRIER
Feb 13 15:54:54 gw wvdial[2675]: No Carrier! Trying again.
Feb 13 15:54:54 gw wvdial[2675]: Maximum Attempts Exceeded..Aborting!!
Feb 13 15:54:54 gw wvdial[2675]: Disconnecting at Fri Feb 13 15:54:54 2004
Feb 13 15:54:54 gw pppd[2665]: Serial connection established.
Feb 13 15:54:54 gw pppd[2665]: tdb_store failed: IO Error
Feb 13 15:54:54 gw pppd[2665]: using channel 6
Feb 13 15:54:54 gw pppd[2665]: Connect: ppp0 <--> /dev/input/ttyACM0
Feb 13 15:54:54 gw pppd[2665]: tdb_store failed: IO Error
Feb 13 15:54:54 gw last message repeated 2 times
Feb 13 15:54:55 gw pppd[2665]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<magic 0x57d1a2e> <pcomp> <accomp>
]
Feb 13 15:55:22 gw last message repeated 9 times
Feb 13 15:55:25 gw pppd[2665]: LCP: timeout sending Config-Requests
Feb 13 15:55:25 gw pppd[2665]: Connection terminated.
Feb 13 15:55:25 gw pppd[2665]: Receive serial link is not 8-bit clean:
Feb 13 15:55:25 gw pppd[2665]: Problem: all had bit 7 set to 0
Feb 13 15:55:38 gw pppd[2665]: Terminating on signal 15.
Feb 13 15:55:38 gw pppd[2665]: Exit.
:
_________________________________________________________

I have kept backup of the old binaries staff (also 2.4.1) and if I use the
old pppd I get (at the same period that the new pppd says no carrier:

Feb 15 21:26:01 gw wvdial[11140]: WvDial: Internet dialer version 1.53
Feb 15 21:26:01 gw wvdial[11140]: Initializing modem.
Feb 15 21:26:01 gw wvdial[11140]: Sending: AT
Feb 15 21:26:01 gw wvdial[11140]: OK
Feb 15 21:26:01 gw wvdial[11140]: Sending: ATB0
Feb 15 21:26:01 gw wvdial[11140]: OK
Feb 15 21:26:06 gw wvdial[11140]: Sending: ATDT8962518180
Feb 15 21:26:06 gw wvdial[11140]: Waiting for carrier.
Feb 15 21:26:07 gw wvdial[11140]: CONNECT
Feb 15 21:26:07 gw wvdial[11140]: Carrier detected. Chatmode finished.
Feb 15 21:26:07 gw pppd[11130]: Serial connection established.
Feb 15 21:26:07 gw pppd[11130]: tdb_store failed: IO Error
Feb 15 21:26:07 gw pppd[11130]: using channel 19
Feb 15 21:26:07 gw pppd[11130]: Connect: ppp0 <--> /dev/input/ttyACM0
Feb 15 21:26:07 gw pppd[11130]: tdb_store failed: IO Error
Feb 15 21:26:07 gw last message repeated 2 times
Feb 15 21:26:08 gw pppd[11130]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<magic 0x79a8ec84> <pcomp> <accom
p>]
Feb 15 21:26:08 gw pppd[11130]: rcvd [LCP ConfRej id=0x1 <pcomp> <accomp>]
Feb 15 21:26:08 gw pppd[11130]: sent [LCP ConfReq id=0x2 <asyncmap 0x0>
<magic 0x79a8ec84>]
Feb 15 21:26:08 gw pppd[11130]: rcvd [LCP ConfReq id=0x6b <auth pap> <magic
0x101b0f62> <asyncmap 0x0>]
Feb 15 21:26:08 gw pppd[11130]: sent [LCP ConfAck id=0x6b <auth pap> <magic
0x101b0f62> <asyncmap 0x0>]
Feb 15 21:26:08 gw pppd[11130]: rcvd [LCP ConfAck id=0x2 <asyncmap 0x0>
<magic 0x79a8ec84>]
Feb 15 21:26:08 gw pppd[11130]: sent [PAP AuthReq id=0x1 user="****"
password=<hidden>]
Feb 15 21:26:08 gw pppd[11130]: rcvd [PAP AuthAck id=0x1 ""]
Feb 15 21:26:08 gw pppd[11130]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0>
<compress VJ 0f 01> <ms-dns1 0.0
.0.0> <ms-dns3 0.0.0.0>]
Feb 15 21:26:08 gw pppd[11130]: sent [CCP ConfReq id=0x1 <deflate 15>
<deflate(old#) 15> <bsd v1 15>]
Feb 15 21:26:08 gw pppd[11130]: rcvd [IPCP ConfReq id=0x1 <compress VJ 0f
00> <addr 195.251.183.252>]
Feb 15 21:26:08 gw pppd[11130]: sent [IPCP ConfAck id=0x1 <compress VJ 0f
00> <addr 195.251.183.252>]
Feb 15 21:26:08 gw pppd[11130]: rcvd [IPCP ConfNak id=0x1 <addr
195.251.176.180> <ms-dns1 195.251.177.9>
<ms-dns3 195.251.128.5>]
Feb 15 21:26:08 gw pppd[11130]: sent [IPCP ConfReq id=0x2 <addr
195.251.176.180> <compress VJ 0f 01> <ms-
dns1 195.251.177.9> <ms-dns3 195.251.128.5>]
Feb 15 21:26:08 gw pppd[11130]: rcvd [LCP ProtRej id=0x1 80 fd 01 01 00 0f
1a 04 78 00 18 04 78 00 15 03
2f]
Feb 15 21:26:08 gw pppd[11130]: rcvd [IPCP ConfAck id=0x2 <addr
195.251.176.180> <compress VJ 0f 01> <ms-
dns1 195.251.177.9> <ms-dns3 195.251.128.5>]
Feb 15 21:26:08 gw pppd[11130]: tdb_store failed: IO Error
Feb 15 21:26:08 gw pppd[11130]: tdb_store key failed: IO Error
Feb 15 21:26:08 gw pppd[11130]: tdb_store failed: IO Error
Feb 15 21:26:08 gw last message repeated 3 times
Feb 15 21:26:08 gw pppd[11130]: Local IP address changed to 195.251.176.180
Feb 15 21:26:08 gw pppd[11130]: tdb_store failed: IO Error
Feb 15 21:26:08 gw pppd[11130]: Remote IP address changed to 195.251.183.252
Feb 15 21:26:08 gw pppd[11130]: tdb_store failed: IO Error
Feb 15 21:26:08 gw pppd[11130]: sent [IP data] 45 00 00 54 00 00 40 00 ...
Feb 15 21:26:08 gw pppd[11130]: Script /etc/ppp/ip-up started (pid 11141)
Feb 15 21:26:11 gw pppd[11130]: Script /etc/ppp/ip-up finished (pid 11141),
status = 0x0

_______________________________________________________

Any ideas of what is wrong???

I thought to blame wvdial 1.53 so I downloaded 1.54 but I can not get in
anyway wvstreams to compile ( a prerequisit)

I think I have done something wrong with compilation of pppd 2.4.2 & 2.4.1
and it must be the same mistake in both cases... otherwise why the old
binaries run and the new same version self compiled binaries are not?

Thank you for your time.

T. Kontogiannis
U. of the Aegean - SAMOS NOC
tkonto@aegean.gr
www.aegean.gr

Relevant Pages

  • Re: modem hang up after connection..
    ... ]but it just hangs up after connection then tried to reconnect ... ... ]--> Initializing modem. ... ]--> Waiting for carrier. ... Waiting for prompt. ...
    (comp.os.linux.networking)
  • modem hang up after connection..
    ... After installation of debian woody, I tried to connect to my ... ISP I used wvdial to connect to the Internet. ... --> Waiting for carrier. ... Waiting for prompt. ...
    (comp.os.linux.networking)
  • wvdial and gnome-ppp both stopped working
    ... --> Initializing modem. ... --> Waiting for carrier. ... --> Carrier detected.Starting PPP immediately. ...
    (Ubuntu)
  • CDMA call Waiting
    ... I am facing a strange problem with my TAPI application on Windows ... The device which I have is from the same carrier. ... I answer the waiting call by sending Hook ... flash as lineHold does not work. ...
    (microsoft.public.win32.programmer.tapi)
  • Re: PPPoE
    ... I have tried setting carrier / ... And until it gets NGM_PPPOE_, ppp will stay waiting ...
    (freebsd-net)