Topic: pppd hangup after successfull authentification

The content of this topic has been archived on 23 Apr 2018. There are no obvious gaps in this topic, but there may still be some posts missing at the end.

Page 1 of 1

Post #1

mforkel

10 Jul 2011, 09:54

Hello,

I'm trying to use a Huawei E1750 UMTS dongle to setup a WAN connection to Vodafone Websessions in Germany. My router is a Fonera 2.0g running Backfire 10.0.3.1 RC4.

Unfortenately, after the serial connection is established and authentication has succeeded, pppd always performs a hangup:

Jul  7 11:36:01 fon4 local2.info chat[3367]: send (ATD*99***1#^M)
Jul  7 11:36:01 fon4 local2.info chat[3367]: expect (CONNECT)
Jul  7 11:36:01 fon4 local2.info chat[3367]: ^M
Jul  7 11:36:01 fon4 local2.info chat[3367]: ATD*99***1#^M^M
Jul  7 11:36:01 fon4 local2.info chat[3367]: CONNECT
Jul  7 11:36:01 fon4 local2.info chat[3367]:  -- got it
Jul  7 11:36:01 fon4 local2.info chat[3367]: send ( ^M)
Jul  7 11:36:01 fon4 daemon.info pppd[3362]: Serial connection established.
Jul  7 11:36:01 fon4 daemon.info pppd[3362]: Using interface 3g-wan
Jul  7 11:36:01 fon4 daemon.notice pppd[3362]: Connect: 3g-wan <--> /dev/ttyUSB0
Jul  7 11:36:02 fon4 daemon.info pppd[3362]: CHAP authentication succeeded
Jul  7 11:36:02 fon4 daemon.notice pppd[3362]: CHAP authentication succeeded
Jul  7 11:36:19 fon4 daemon.info pppd[3362]: Hangup (SIGHUP)
Jul  7 11:36:19 fon4 daemon.notice pppd[3362]: Modem hangup
Jul  7 11:36:19 fon4 daemon.notice pppd[3362]: Connection terminated.

I'm following the instructions in Use 3g/UMTS USB Dongle for WAN connection in the OpenWrt Wiki. My interface configuration is

config 'interface' 'wan'
        option 'ifname' 'ppp0'
        option 'proto' '3g'
        option 'service' 'umts'
        option 'apn' 'event.vodafone.de'
        option 'pincode' '1234'
        option 'device' '/dev/ttyUSB0'
        option 'username' 'vodafone'
        option 'password' 'vodafone'

Dongle and SIM card are fine. I can establish a connection in Ubuntu 11.04 and Windows. What might I be doing wrong in OpenWrt? Could somebody help me, please?

Thanks, Malte

(Last edited by mforkel on 11 Jul 2011, 09:20)

Post #2

mforkel

12 Jul 2011, 12:51

In case you encounter the same problem, here is my solution.

After adding the pppd option debug

config 'interface' 'wan'
        ...
        option 'pppd_options' 'debug'

to the interface configuration, I realized that the IPCP negotiation failed:

Jul 11 17:36:01 fon4 daemon.info pppd[1057]: CHAP authentication succeeded
Jul 11 17:36:01 fon4 daemon.notice pppd[1057]: CHAP authentication succeeded
Jul 11 17:36:01 fon4 daemon.debug pppd[1057]: sent [IPCP ConfReq id=0x1 <addr 10.0.1.4> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
Jul 11 17:36:02 fon4 daemon.info pppd[1057]: Hangup (SIGHUP)
Jul 11 17:36:02 fon4 daemon.notice pppd[1057]: Modem hangup
Jul 11 17:36:02 fon4 daemon.notice pppd[1057]: Connection terminated.

Changing the local address only made for a longer negotiation

Jul 12 01:03:08 fon4 daemon.info pppd[1090]: CHAP authentication succeeded
Jul 12 01:03:08 fon4 daemon.notice pppd[1090]: CHAP authentication succeeded
Jul 12 01:03:08 fon4 daemon.debug pppd[1090]: sent [IPCP ConfReq id=0x1 <addr 192.168.10.1> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
Jul 12 01:03:09 fon4 daemon.debug pppd[1090]: sent [LCP EchoReq id=0x1 magic=0x86ca2654]
Jul 12 01:03:09 fon4 daemon.debug pppd[1090]: rcvd [LCP EchoRep id=0x1 magic=0xf66dc8 86 ca 26 54]
Jul 12 01:03:09 fon4 daemon.debug pppd[1090]: rcvd [IPCP ConfNak id=0x1 <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>]
Jul 12 01:03:09 fon4 daemon.debug pppd[1090]: sent [IPCP ConfReq id=0x2 <addr 192.168.10.1> <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Jul 12 01:03:10 fon4 daemon.debug pppd[1090]: sent [LCP EchoReq id=0x2 magic=0x86ca2654]
Jul 12 01:03:10 fon4 daemon.debug pppd[1090]: rcvd [LCP EchoRep id=0x2 magic=0xf66dc8 86 ca 26 54]
Jul 12 01:03:10 fon4 daemon.debug pppd[1090]: rcvd [IPCP ConfNak id=0x2 <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>]
Jul 12 01:03:10 fon4 daemon.debug pppd[1090]: sent [IPCP ConfReq id=0x3 <addr 192.168.10.1> <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Jul 12 01:03:10 fon4 daemon.info pppd[1090]: Hangup (SIGHUP)
Jul 12 01:03:10 fon4 daemon.notice pppd[1090]: Modem hangup
Jul 12 01:03:10 fon4 daemon.notice pppd[1090]: Connection terminated.

So I reverted to my old local address and added the pppd option noipdefault

config 'interface' 'wan'
        ...
        option 'pppd_options' 'debug noipdefault'

Now the connection can be properly established:

Jul 12 13:14:12 fon4 daemon.info pppd[2802]: CHAP authentication succeeded
Jul 12 13:14:12 fon4 daemon.notice pppd[2802]: CHAP authentication succeeded
Jul 12 13:14:12 fon4 daemon.debug pppd[2802]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
Jul 12 13:14:13 fon4 daemon.debug pppd[2802]: sent [LCP EchoReq id=0x1 magic=0x7e357d3c]
Jul 12 13:14:13 fon4 daemon.debug pppd[2802]: rcvd [LCP EchoRep id=0x1 magic=0xfeb5e2 7e 35 7d 3c]
Jul 12 13:14:13 fon4 daemon.debug pppd[2802]: rcvd [IPCP ConfNak id=0x1 <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>]
Jul 12 13:14:13 fon4 daemon.debug pppd[2802]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Jul 12 13:14:14 fon4 daemon.debug pppd[2802]: rcvd [IPCP ConfReq id=0x0]
Jul 12 13:14:14 fon4 daemon.debug pppd[2802]: sent [IPCP ConfNak id=0x0 <addr 0.0.0.0>]
Jul 12 13:14:14 fon4 daemon.debug pppd[2802]: rcvd [IPCP ConfNak id=0x2 <addr 10.230.6.29> <ms-dns1 139.7.30.125> <ms-dns3 139.7.30.126>]
Jul 12 13:14:14 fon4 daemon.debug pppd[2802]: sent [IPCP ConfReq id=0x3 <addr 10.230.6.29> <ms-dns1 139.7.30.125> <ms-dns3 139.7.30.126>]
Jul 12 13:14:14 fon4 daemon.debug pppd[2802]: rcvd [IPCP ConfReq id=0x1]
Jul 12 13:14:14 fon4 daemon.debug pppd[2802]: sent [IPCP ConfAck id=0x1]
Jul 12 13:14:14 fon4 daemon.debug pppd[2802]: rcvd [IPCP ConfAck id=0x3 <addr 10.230.6.29> <ms-dns1 139.7.30.125> <ms-dns3 139.7.30.126>]
Jul 12 13:14:14 fon4 daemon.warn pppd[2802]: Could not determine remote IP address: defaulting to 10.64.64.64
Jul 12 13:14:14 fon4 daemon.notice pppd[2802]: local  IP address 10.230.6.29
Jul 12 13:14:14 fon4 daemon.notice pppd[2802]: remote IP address 10.64.64.64
Jul 12 13:14:14 fon4 daemon.notice pppd[2802]: primary   DNS address 139.7.30.125
Jul 12 13:14:14 fon4 daemon.notice pppd[2802]: secondary DNS address 139.7.30.126
Jul 12 13:14:14 fon4 daemon.debug pppd[2802]: Script /etc/ppp/ip-up started (pid 2821)
Jul 12 13:14:17 fon4 user.notice root: adding wan (3g-wan) to firewall zone wan
Jul 12 13:14:19 fon4 user.notice rdate: No usable time server found
Jul 12 13:14:19 fon4 daemon.debug pppd[2802]: Script /etc/ppp/ip-up finished (pid 2821), status = 0x1
Jul 12 13:14:22 fon4 daemon.info dnsmasq[2467]: reading /tmp/resolv.conf.auto
Jul 12 13:14:22 fon4 daemon.info dnsmasq[2467]: using nameserver 139.7.30.126#53
Jul 12 13:14:22 fon4 daemon.info dnsmasq[2467]: using nameserver 139.7.30.125#53
Jul 12 13:14:22 fon4 daemon.info dnsmasq[2467]: using local addresses only for domain lan

Post #3

regedit

10 Jan 2012, 04:19

Thank you, thank you, thank you for posting your update!!!!

option 'pppd_options' 'debug noipdefault'

got me up and running reliably.Connection negotiation is much faster and the connection automatically reconnects if the link drops (like it's supposed to).

Thank you again -

(Last edited by regedit on 10 Jan 2012, 04:20)

Post #4

ammaree

12 Feb 2012, 18:19

You are my hero, after 2 weeks of struggling the problem is solved. Sierra Wireless MC8801 on RouterStation Pro coming up consistently and reliably.

Thanks Malte....

Andre

Post #5

danielholm

5 Mar 2012, 20:12

Thank you so much, mforkel!

It's been driving me crazy!

Post #6

cindy.wijaya

24 May 2012, 00:04

option 'pppd_options' 'debug noipdefault'

solve my problem too

(Last edited by cindy.wijaya on 24 May 2012, 00:04)

Post #7

valentt

26 Jun 2012, 14:09

I has also an issue that my provides supports PAP only, and I found an answer:
option 'pppd_options' 'noipdefault refuse-chap refuse-mschap refuse-mschap-v2 refuse-eap'

and also added it to WIKI: http://wiki.openwrt.org/doc/recipes/3gd … figuration

Post #8

ltrifonov

22 Aug 2012, 08:17

Hi all!

thanks @mforkel for this exellent solution.
just for the record, when everything is already working, you do not need "debug" option anymore.

you just need:

option 'pppd_options' 'noipdefault'

(Last edited by ltrifonov on 22 Aug 2012, 08:18)

Post #9

pavelb

19 Mar 2013, 23:00

Thanks mforkel!!! You helped me to save much time!!!
Thank you.

Post #10

Crazy

21 Jul 2014, 14:28

option 'pppd_options' 'debug noipdefault'

solve my problem too

Many thanks

Post #11

omar.t.alazawi

26 Jul 2015, 21:34

dears

i have a problem here
I have an SQL DB that is linked to SBR 6.2
and a ASR1002 working as a BAS
if the customer is having a static IP address, and tries to authenticate, USR/PWD authenticate successfully, but once it receive the IP address i get the log as below:

*Jul 26 20:29:53.674: ppp423 PAP: I AUTH-REQ id 1 len 29 from "net/test.saneer"
*Jul 26 20:29:53.674: ppp423 PAP: Authenticating peer net/test.saneer
*Jul 26 20:29:53.674: ppp423 PPP: Phase is FORWARDING, Attempting Forward
*Jul 26 20:29:53.674: ppp423 LCP: State is Open
*Jul 26 20:29:53.674: ppp423 PPP: Phase is AUTHENTICATING, Unauthenticated User
*Jul 26 20:29:53.697: ppp423 PPP: Phase is FORWARDING, Attempting Forward
*Jul 26 20:29:53.699: Vi2.36 PPP: Phase is AUTHENTICATING, Authenticated User
*Jul 26 20:29:53.699: Vi2.36 PAP: O AUTH-ACK id 1 len 5
*Jul 26 20:29:53.699: Vi2.36 PPP: Phase is UP
*Jul 26 20:29:53.699: Vi2.36 IPCP: Protocol configured, start CP. state[Initial]
*Jul 26 20:29:53.699: Vi2.36 IPCP: Event[OPEN] State[Initial to Starting]
*Jul 26 20:29:53.699: Vi2.36 IPCP: O CONFREQ [Starting] id 1 len 10
*Jul 26 20:29:53.699: Vi2.36 IPCP: Address 192.168.77.1 (0x0306C0A84D01)
*Jul 26 20:29:53.699: Vi2.36 IPCP: Event[UP] State[Starting to REQsent]
*Jul 26 20:29:53.701: Vi2.36 IPCP: I CONFREQ [REQsent] id 1 len 22
*Jul 26 20:29:53.701: Vi2.36 IPCP: Address 0.0.0.0 (0x030600000000)
*Jul 26 20:29:53.701: Vi2.36 IPCP: PrimaryDNS 0.0.0.0 (0x810600000000)
*Jul 26 20:29:53.701: Vi2.36 IPCP: SecondaryDNS 0.0.0.0 (0x830600000000)
*Jul 26 20:29:53.701: Vi2.36 IPCP AUTHOR: Start. Her address 0.0.0.0, we want 0.0.0.0
*Jul 26 20:29:53.701: Vi2.36 set_ip_peer: new(5): xx.xx.xx.xx prior(0): 0.0.0.0
*Jul 26 20:29:53.701: Vi2.36 IPCP AUTHOR: Done. Her address 0.0.0.0, we want xx.xx.xx.xx
*Jul 26 20:29:53.701: Vi2.36 IPCP: Address authorization failed. Closing IPCP
*Jul 26 20:29:53.701: Vi2.36 IPCP: O TERMREQ [REQsent] id 2 len 4
*Jul 26 20:29:53.701: Vi2.36 IPCP: Event[CLOSE] State[REQsent to Closing]
*Jul 26 20:29:53.701: Vi2.36 IPCP: I CONFACK [Closing] id 1 len 10
*Jul 26 20:29:53.701: Vi2.36 IPCP: Address 192.168.77.1 (0x0306C0A84D01)
*Jul 26 20:29:53.701: Vi2.36 IPCP: Event[Receive ConfAck] State[Closing to Closing]
*Jul 26 20:29:53.701: Vi2.36 IPCP: I TERMACK [Closing] id 2 len 4
*Jul 26 20:29:53.701: Vi2.36 IPCP: Event[Receive TermAck] State[Closing to Closed]
*Jul 26 20:29:56.701: Vi2.36 IPCP: I CONFREQ [Closed] id 1 len 22
*Jul 26 20:29:56.701: Vi2.36 IPCP: Address 0.0.0.0 (0x030600000000)
*Jul 26 20:29:56.701: Vi2.36 IPCP: PrimaryDNS 0.0.0.0 (0x810600000000)
*Jul 26 20:29:56.701: Vi2.36 IPCP: SecondaryDNS 0.0.0.0 (0x830600000000)
*Jul 26 20:29:56.701: Vi2.36 LCP: O PROTREJ [Open] id 2 len 28 protocol IPCP
*Jul 26 20:29:56.701: Vi2.36 LCP: (0x01010018030600000000810600000000)

need your help please.

The discussion might have continued from here.

Page 1 of 1