PPPoE does not want to connect

Yamadron · August 7, 2020, 3:17am

I have got a new VDSL line running at 100Mbps. I can connect with PPPoE credentials just fine using TPLink VR600. However, when I bridge the VR600 and try connect using PPPoE on WRT1900ACS v2, it never works. I am running OpenWRT v19.07.3 with PATCH 4.14 06/46 flashed using a Snapshot. The credentials are perfectly correct, but I always get NEGOTIATION Failed Error.

Tue Aug  4 15:49:30 2020 daemon.notice netifd: Interface 'wan' is setting up now
Tue Aug  4 15:49:30 2020 kern.info kernel: [ 1168.546555] mvneta f1070000.ethernet eth1: configuring for fixed/rgmii-id link mode
Tue Aug  4 15:49:30 2020 kern.info kernel: [ 1168.554375] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
Tue Aug  4 15:49:30 2020 kern.info kernel: [ 1168.560255] mvneta f1070000.ethernet eth1: Link is Up - 1Gbps/Full - flow control off
Tue Aug  4 15:49:30 2020 kern.info kernel: [ 1168.569846] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
Tue Aug  4 15:49:30 2020 daemon.err insmod: module is already loaded - slhc
Tue Aug  4 15:49:30 2020 daemon.err insmod: module is already loaded - ppp_generic
Tue Aug  4 15:49:30 2020 daemon.err insmod: module is already loaded - pppox
Tue Aug  4 15:49:30 2020 daemon.err insmod: module is already loaded - pppoe
Tue Aug  4 15:49:30 2020 daemon.info pppd[23465]: Plugin rp-pppoe.so loaded.
Tue Aug  4 15:49:30 2020 daemon.info pppd[23465]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Tue Aug  4 15:49:30 2020 daemon.notice pppd[23465]: pppd 2.4.7 started by root, uid 0
Tue Aug  4 15:49:30 2020 daemon.debug pppd[23465]: Send PPPOE Discovery V1T1 PADI session 0x0 length 12
Tue Aug  4 15:49:30 2020 daemon.debug pppd[23465]:  dst ff:ff:ff:ff:ff:ff  src xx:xx:xx:xx:xx:xx
Tue Aug  4 15:49:30 2020 daemon.debug pppd[23465]:  [service-name] [host-uniq  a9 5b 00 00]
Tue Aug  4 15:49:30 2020 daemon.debug pppd[23465]: Recv PPPOE Discovery V1T1 PADO session 0x0 length 52
Tue Aug  4 15:49:30 2020 daemon.debug pppd[23465]:  dst xx:xx:xx:xx:xx:xx  src 24:21:24:b4:1b:80
Tue Aug  4 15:49:30 2020 daemon.debug pppd[23465]:  [service-name] [AC-name HARAM-R37A-GZ-EG] [host-uniq  a9 5b 00 00] [AC-cookie  0e 3d 57 19 bf de 76 67 95 07 02 06 03 a2 87 b0]
Tue Aug  4 15:49:30 2020 daemon.debug pppd[23465]: Send PPPOE Discovery V1T1 PADR session 0x0 length 32
Tue Aug  4 15:49:30 2020 daemon.debug pppd[23465]:  dst 24:21:24:b4:1b:80  src 14:91:82:2a:64:cf
Tue Aug  4 15:49:30 2020 daemon.debug pppd[23465]:  [service-name] [host-uniq  a9 5b 00 00] [AC-cookie  0e 3d 57 19 bf de 76 67 95 07 02 06 03 a2 87 b0]
Tue Aug  4 15:49:30 2020 daemon.debug pppd[23465]: Recv PPPOE Discovery V1T1 PADS session 0x1 length 12
Tue Aug  4 15:49:30 2020 daemon.debug pppd[23465]:  dst 14:91:82:2a:64:cf  src 24:21:24:b4:1b:80
Tue Aug  4 15:49:30 2020 daemon.debug pppd[23465]:  [service-name] [host-uniq  a9 5b 00 00]
Tue Aug  4 15:49:30 2020 daemon.debug pppd[23465]: PADS: Service-Name: ''
Tue Aug  4 15:49:30 2020 daemon.info pppd[23465]: PPP session is 1
Tue Aug  4 15:49:30 2020 daemon.warn pppd[23465]: Connected to 24:21:24:b4:1b:80 via interface eth1.2
Tue Aug  4 15:49:30 2020 daemon.debug pppd[23465]: using channel 35
Tue Aug  4 15:49:30 2020 kern.info kernel: [ 1168.739498] pppoe-wan: renamed from ppp0
Tue Aug  4 15:49:30 2020 daemon.info pppd[23465]: Renamed interface ppp0 to pppoe-wan
Tue Aug  4 15:49:30 2020 daemon.info pppd[23465]: Using interface pppoe-wan
Tue Aug  4 15:49:30 2020 daemon.notice pppd[23465]: Connect: pppoe-wan <--> eth1.2
Tue Aug  4 15:49:30 2020 daemon.debug pppd[23465]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0xb9f26655>]
Tue Aug  4 15:49:33 2020 daemon.debug pppd[23465]: rcvd [LCP ConfReq id=0xfb <mru 1492> <auth pap> <magic 0xe912148>]
Tue Aug  4 15:49:33 2020 daemon.debug pppd[23465]: sent [LCP ConfAck id=0xfb <mru 1492> <auth pap> <magic 0xe912148>]
Tue Aug  4 15:49:33 2020 daemon.debug pppd[23465]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0xb9f26655>]
Tue Aug  4 15:49:35 2020 daemon.debug pppd[23465]: rcvd [LCP ConfReq id=0xfb <mru 1492> <auth pap> <magic 0xe912148>]
Tue Aug  4 15:49:35 2020 daemon.debug pppd[23465]: sent [LCP ConfAck id=0xfb <mru 1492> <auth pap> <magic 0xe912148>]
Tue Aug  4 15:49:36 2020 daemon.debug pppd[23465]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0xb9f26655>]
Tue Aug  4 15:49:38 2020 daemon.debug pppd[23465]: rcvd [LCP ConfReq id=0xfb <mru 1492> <auth pap> <magic 0xe912148>]
Tue Aug  4 15:49:38 2020 daemon.debug pppd[23465]: sent [LCP ConfAck id=0xfb <mru 1492> <auth pap> <magic 0xe912148>]
Tue Aug  4 15:49:39 2020 daemon.debug pppd[23465]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0xb9f26655>]
Tue Aug  4 15:49:41 2020 daemon.debug pppd[23465]: rcvd [LCP ConfReq id=0xfb <mru 1492> <auth pap> <magic 0xe912148>]
Tue Aug  4 15:49:41 2020 daemon.debug pppd[23465]: sent [LCP ConfAck id=0xfb <mru 1492> <auth pap> <magic 0xe912148>]
Tue Aug  4 15:49:42 2020 daemon.debug pppd[23465]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0xb9f26655>]
Tue Aug  4 15:49:44 2020 daemon.debug pppd[23465]: rcvd [LCP ConfReq id=0xfb <mru 1492> <auth pap> <magic 0xe912148>]
Tue Aug  4 15:49:44 2020 daemon.debug pppd[23465]: sent [LCP ConfAck id=0xfb <mru 1492> <auth pap> <magic 0xe912148>]
Tue Aug  4 15:49:45 2020 daemon.debug pppd[23465]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0xb9f26655>]
Tue Aug  4 15:49:47 2020 daemon.debug pppd[23465]: rcvd [LCP ConfReq id=0xfb <mru 1492> <auth pap> <magic 0xe912148>]
Tue Aug  4 15:49:47 2020 daemon.debug pppd[23465]: sent [LCP ConfAck id=0xfb <mru 1492> <auth pap> <magic 0xe912148>]
Tue Aug  4 15:49:48 2020 daemon.debug pppd[23465]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0xb9f26655>

The log with LCP ConfAcks and LCP ConfReqs is too long till a Modem Hangup error happens and loop continues again. I left it for 20 mins with no avail. It never connects.

I tried MAC overriding but didn't work either.

Did the ISP block that MAC address? or something it different on this line? The last time I tried the router on my old, it was working with no problems at all.

Dynamo · August 7, 2020, 3:26am

Maybe your ISP requires a VLAN to be tagged at the WAN interface?

Yamadron · August 7, 2020, 4:04am

The ISP never mentioned anything regarding VLAN at all. I am using the same ISP as I used to with the old line. The configuration seems to be straightforward for me. I just changed the username/password and tried. Am I missing something? Is it better to start from factory defaults?

Dynamo · August 7, 2020, 4:34am

Well, I honestly don't know. That was my long shot.
I'll wait until someone else replies.

ISPs generally won't mention those tecnical details unless asked, though. I'd browse the modem's config and see if you can enable its own VLAN tagging - that's what fixed my PPPoE.
Or maybe call the ISP and ask?

Handyman · August 7, 2020, 7:42am

First I just have to say this, the TP-Link VR600 is way overkill for a dummy modem in bridge mode.
From your log you never get to the authentication phase.
I have seen this before, you need to disconnect the line from the wall outlet for at least 30min then try again.
Let me know how it goes.

Yamadron · August 7, 2020, 2:37pm

I have the VLAN disabled for now. I will enable it and check. But I remember that both failed.

I know. I know. It is just what I have in the meantime. It provides the best sync speeds for my line though. I will check this out. Also, I thought about holding off the connection retires a bit. I think they are too quick for ISP on that line. The line usually takes around 2-3 mins to finish the PPPoE Authentication on the VR600 opposed the old line.

Handyman · August 7, 2020, 2:55pm

Your problem is not with vlans. Your isp don't use vlans.
Your problem is with authentication, do what I told you to do. Disconnect the phone line from your vr600 for 30min+ and if you configured everything correctly you will authenticate fine when you hook it back again.

Yamadron · August 9, 2020, 4:38am

It didn't work. Disconnected the cable for 50 mins. Reconnected and same problem.

Sun Aug  9 04:04:33 2020 daemon.notice netifd: Interface 'wan' is enabled
Sun Aug  9 04:04:33 2020 daemon.notice netifd: Interface 'wan' is setting up now
Sun Aug  9 04:04:33 2020 daemon.err insmod: module is already loaded - slhc
Sun Aug  9 04:04:33 2020 daemon.err insmod: module is already loaded - ppp_generic
Sun Aug  9 04:04:33 2020 daemon.err insmod: module is already loaded - pppox
Sun Aug  9 04:04:33 2020 daemon.err insmod: module is already loaded - pppoe
Sun Aug  9 04:04:33 2020 daemon.info pppd[4045]: Plugin rp-pppoe.so loaded.
Sun Aug  9 04:04:33 2020 daemon.info pppd[4045]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Sun Aug  9 04:04:33 2020 daemon.notice pppd[4045]: pppd 2.4.7 started by root, uid 0
Sun Aug  9 04:04:33 2020 daemon.err pppd[4045]: Interface eth1.2 has MTU of 1492 -- should be at least 1500.
Sun Aug  9 04:04:33 2020 daemon.err pppd[4045]: This may cause serious connection problems.
Sun Aug  9 04:04:33 2020 daemon.debug pppd[4045]: Send PPPOE Discovery V1T1 PADI session 0x0 length 12
Sun Aug  9 04:04:33 2020 daemon.debug pppd[4045]:  dst ff:ff:ff:ff:ff:ff  src xx:xx:xx:xx:xx:xx
Sun Aug  9 04:04:33 2020 daemon.debug pppd[4045]:  [service-name] [host-uniq  cd 0f 00 00]
Sun Aug  9 04:04:33 2020 daemon.debug pppd[4045]: Recv PPPOE Discovery V1T1 PADO session 0x0 length 52
Sun Aug  9 04:04:33 2020 daemon.debug pppd[4045]:  dst xx:xx:xx:xx:xx:xx  src 24:21:24:b4:1b:80
Sun Aug  9 04:04:33 2020 daemon.debug pppd[4045]:  [service-name] [AC-name HARAM-R37A-GZ-EG] [host-uniq  cd 0f 00 00] [AC-cookie  0e 3d 57 19 bf de 76 67 95 07 02 06 03 a2 87 b0]
Sun Aug  9 04:04:33 2020 daemon.debug pppd[4045]: Send PPPOE Discovery V1T1 PADR session 0x0 length 32
Sun Aug  9 04:04:33 2020 daemon.debug pppd[4045]:  dst 24:21:24:b4:1b:80  src xx:xx:xx:xx:xx:xx
Sun Aug  9 04:04:33 2020 daemon.debug pppd[4045]:  [service-name] [host-uniq  cd 0f 00 00] [AC-cookie  0e 3d 57 19 bf de 76 67 95 07 02 06 03 a2 87 b0]
Sun Aug  9 04:04:33 2020 daemon.debug pppd[4045]: Recv PPPOE Discovery V1T1 PADS session 0x1 length 12
Sun Aug  9 04:04:33 2020 daemon.debug pppd[4045]:  dst xx:xx:xx:xx:xx:xx  src 24:21:24:b4:1b:80
Sun Aug  9 04:04:33 2020 daemon.debug pppd[4045]:  [service-name] [host-uniq  cd 0f 00 00]
Sun Aug  9 04:04:33 2020 daemon.debug pppd[4045]: PADS: Service-Name: ''
Sun Aug  9 04:04:33 2020 daemon.info pppd[4045]: PPP session is 1
Sun Aug  9 04:04:33 2020 daemon.warn pppd[4045]: Connected to 24:21:24:b4:1b:80 via interface eth1.2
Sun Aug  9 04:04:33 2020 daemon.debug pppd[4045]: using channel 2
Sun Aug  9 04:04:33 2020 kern.info kernel: [   43.052424] pppoe-wan: renamed from ppp0
Sun Aug  9 04:04:33 2020 daemon.info pppd[4045]: Renamed interface ppp0 to pppoe-wan
Sun Aug  9 04:04:33 2020 daemon.info pppd[4045]: Using interface pppoe-wan
Sun Aug  9 04:04:33 2020 daemon.notice pppd[4045]: Connect: pppoe-wan <--> eth1.2
Sun Aug  9 04:04:33 2020 daemon.debug pppd[4045]: sent [LCP ConfReq id=0x1 <mru 1484> <magic 0xf461b875>]
Sun Aug  9 04:04:36 2020 daemon.debug pppd[4045]: sent [LCP ConfReq id=0x1 <mru 1484> <magic 0xf461b875>]
Sun Aug  9 04:04:36 2020 daemon.debug pppd[4045]: rcvd [LCP ConfReq id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:04:36 2020 daemon.debug pppd[4045]: sent [LCP ConfAck id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:04:39 2020 daemon.debug pppd[4045]: sent [LCP ConfReq id=0x1 <mru 1484> <magic 0xf461b875>]
Sun Aug  9 04:04:39 2020 daemon.debug pppd[4045]: rcvd [LCP ConfReq id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:04:39 2020 daemon.debug pppd[4045]: sent [LCP ConfAck id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:04:42 2020 daemon.debug pppd[4045]: sent [LCP ConfReq id=0x1 <mru 1484> <magic 0xf461b875>]
Sun Aug  9 04:04:43 2020 daemon.debug pppd[4045]: rcvd [LCP ConfReq id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:04:43 2020 daemon.debug pppd[4045]: sent [LCP ConfAck id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:04:45 2020 daemon.debug pppd[4045]: sent [LCP ConfReq id=0x1 <mru 1484> <magic 0xf461b875>]
Sun Aug  9 04:04:46 2020 daemon.debug pppd[4045]: rcvd [LCP ConfReq id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:04:46 2020 daemon.debug pppd[4045]: sent [LCP ConfAck id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:04:48 2020 daemon.debug pppd[4045]: sent [LCP ConfReq id=0x1 <mru 1484> <magic 0xf461b875>]
Sun Aug  9 04:04:48 2020 daemon.debug pppd[4045]: rcvd [LCP ConfReq id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:04:48 2020 daemon.debug pppd[4045]: sent [LCP ConfAck id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:04:51 2020 daemon.debug pppd[4045]: sent [LCP ConfReq id=0x1 <mru 1484> <magic 0xf461b875>]
Sun Aug  9 04:04:51 2020 daemon.debug pppd[4045]: rcvd [LCP ConfReq id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:04:51 2020 daemon.debug pppd[4045]: sent [LCP ConfAck id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:04:54 2020 daemon.debug pppd[4045]: sent [LCP ConfReq id=0x1 <mru 1484> <magic 0xf461b875>]
Sun Aug  9 04:04:54 2020 daemon.debug pppd[4045]: rcvd [LCP ConfReq id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:04:54 2020 daemon.debug pppd[4045]: sent [LCP ConfAck id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:04:57 2020 daemon.debug pppd[4045]: sent [LCP ConfReq id=0x1 <mru 1484> <magic 0xf461b875>]
Sun Aug  9 04:04:58 2020 daemon.debug pppd[4045]: rcvd [LCP ConfReq id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:04:58 2020 daemon.debug pppd[4045]: sent [LCP ConfAck id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:05:00 2020 daemon.debug pppd[4045]: sent [LCP ConfReq id=0x1 <mru 1484> <magic 0xf461b875>]
Sun Aug  9 04:05:01 2020 daemon.debug pppd[4045]: rcvd [LCP ConfReq id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:05:01 2020 daemon.debug pppd[4045]: sent [LCP ConfAck id=0xd4 <mru 1492> <auth pap> <magic 0x3980e636>]
Sun Aug  9 04:05:03 2020 daemon.warn pppd[4045]: LCP: timeout sending Config-Requests
Sun Aug  9 04:05:03 2020 daemon.notice pppd[4045]: Connection terminated.
Sun Aug  9 04:05:03 2020 daemon.debug pppd[4045]: Send PPPOE Discovery V1T1 PADT session 0x1 length 28
Sun Aug  9 04:05:03 2020 daemon.debug pppd[4045]:  dst 24:21:24:b4:1b:80  src xx:xx:xx:xx:xx:xx
Sun Aug  9 04:05:03 2020 daemon.debug pppd[4045]:  [host-uniq  cd 0f 00 00] [AC-cookie  0e 3d 57 19 bf de 76 67 95 07 02 06 03 a2 87 b0]
Sun Aug  9 04:05:03 2020 daemon.info pppd[4045]: Sent PADT
Sun Aug  9 04:05:03 2020 daemon.notice pppd[4045]: Modem hangup
Sun Aug  9 04:05:03 2020 daemon.info pppd[4045]: Exit.

LCP: timeout sending Config-Requests...What may cause this problem?

Handyman · August 9, 2020, 5:26am

It means they couldn't agree on handshake parameters.
Can you post your network config?

Yamadron · August 9, 2020, 6:26am

etc/config/network


config interface 'loopback'
	option ifname 'lo'
	option proto 'static'
	option ipaddr '127.0.0.1'
	option netmask '255.0.0.0'

config globals 'globals'

config interface 'lan'
	option type 'bridge'
	option ifname 'eth0.1'
	option proto 'static'
	option ipaddr '192.168.1.1'
	option netmask '255.255.255.0'
	option ip6assign '60'
	option mtu '1492'

config interface 'wan'
	option ifname 'eth1.2'
	option proto 'pppoe'
	option username 'xxxxxx@xxxxxxx'
	option password 'xxxxxxxx'
	option mtu '1492'
	option ipv6 'auto'

config switch
	option name 'switch0'
	option reset '1'
	option enable_vlan '1'

config switch_vlan
	option device 'switch0'
	option vlan '1'
	option ports '5t 3 2 1 0'
	option vid '1'

config switch_vlan
	option device 'switch0'
	option vlan '2'
	option ports '6t 4'
	option vid '2'

etc/ppp/options

debug
logfile /dev/null
noipdefault
noaccomp
nopcomp
nocrtscts
lock
persist 1
holdoff 5
maxfail 0

I saved that log from the VR600 when connected successfully. It may help

2016-01-01 02:00:43 [5] System: DSL Training G.993 Channel Analysis
2016-01-01 02:00:44 [5] System: DSL Link Up us/ds 4095/36864 kbps Type PTM
2016-01-01 02:00:46 [6] PPP: ppp0 sent [PADI Host-Uniq(0x00000ae8)]
2016-01-01 02:00:46 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xd70f0ff6>]
2016-01-01 02:00:48 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:00:48 [6] PPP: ppp0 sent [LCP ConfAck id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:00:49 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xd70f0ff6>]
2016-01-01 02:00:51 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:00:51 [6] PPP: ppp0 sent [LCP ConfAck id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:00:52 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xd70f0ff6>]
2016-01-01 02:00:54 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:00:54 [6] PPP: ppp0 sent [LCP ConfAck id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:00:55 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xd70f0ff6>]
2016-01-01 02:00:57 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:00:57 [6] PPP: ppp0 sent [LCP ConfAck id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:00:58 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xd70f0ff6>]
2016-01-01 02:01:00 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:01:00 [6] PPP: ppp0 sent [LCP ConfAck id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:01:01 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xd70f0ff6>]
2016-01-01 02:01:03 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:01:03 [6] PPP: ppp0 sent [LCP ConfAck id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:01:04 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xd70f0ff6>]
2016-01-01 02:01:06 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:01:06 [6] PPP: ppp0 sent [LCP ConfAck id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:01:07 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xd70f0ff6>]
2016-01-01 02:01:09 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:01:09 [6] PPP: ppp0 sent [LCP ConfAck id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:01:10 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xd70f0ff6>]
2016-01-01 02:01:12 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:01:12 [6] PPP: ppp0 sent [LCP ConfAck id=0x72 <mru 1492> <auth pap> <magic 0x528e45b5>]
2016-01-01 02:01:13 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xd70f0ff6>]
2016-01-01 02:01:16 [3] PPP: ppp0 
2016-01-01 02:01:29 [5] DHCPD: Recv REQUEST from D0:25:98:33:B6:29
2016-01-01 02:01:30 [5] DHCPD: Send ACK to 192.168.1.104
2016-01-01 02:01:36 [6] PPP: ppp0 sent [PADI Host-Uniq(0x00000e30)]
2016-01-01 02:01:36 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x5a4c951c>]
2016-01-01 02:01:39 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:01:39 [6] PPP: ppp0 sent [LCP ConfAck id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:01:39 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x5a4c951c>]
2016-01-01 02:01:42 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x5a4c951c>]
2016-01-01 02:01:42 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:01:42 [6] PPP: ppp0 sent [LCP ConfAck id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:01:45 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x5a4c951c>]
2016-01-01 02:01:45 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:01:45 [6] PPP: ppp0 sent [LCP ConfAck id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:01:48 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x5a4c951c>]
2016-01-01 02:01:48 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:01:48 [6] PPP: ppp0 sent [LCP ConfAck id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:01:51 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x5a4c951c>]
2016-01-01 02:01:51 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:01:51 [6] PPP: ppp0 sent [LCP ConfAck id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:01:54 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x5a4c951c>]
2016-01-01 02:01:55 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:01:55 [6] PPP: ppp0 sent [LCP ConfAck id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:01:57 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x5a4c951c>]
2016-01-01 02:01:57 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:01:57 [6] PPP: ppp0 sent [LCP ConfAck id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:02:00 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x5a4c951c>]
2016-01-01 02:02:01 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:02:01 [6] PPP: ppp0 sent [LCP ConfAck id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:02:03 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x5a4c951c>]
2016-01-01 02:02:04 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:02:04 [6] PPP: ppp0 sent [LCP ConfAck id=0x7a <mru 1492> <auth pap> <magic 0x592591c4>]
2016-01-01 02:02:06 [3] PPP: ppp0 
2016-01-01 02:02:22 [6] PPP: ppp0 sent [PADI Host-Uniq(0x00000f47)]
2016-01-01 02:02:22 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x74ba1b4d>]
2016-01-01 02:02:25 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:25 [6] PPP: ppp0 sent [LCP ConfAck id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:25 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x74ba1b4d>]
2016-01-01 02:02:28 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:28 [6] PPP: ppp0 sent [LCP ConfAck id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:28 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x74ba1b4d>]
2016-01-01 02:02:31 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:31 [6] PPP: ppp0 sent [LCP ConfAck id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:31 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x74ba1b4d>]
2016-01-01 02:02:34 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:34 [6] PPP: ppp0 sent [LCP ConfAck id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:34 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x74ba1b4d>]
2016-01-01 02:02:37 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:37 [6] PPP: ppp0 sent [LCP ConfAck id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:37 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x74ba1b4d>]
2016-01-01 02:02:40 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:40 [6] PPP: ppp0 sent [LCP ConfAck id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:40 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x74ba1b4d>]
2016-01-01 02:02:43 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x74ba1b4d>]
2016-01-01 02:02:43 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:43 [6] PPP: ppp0 sent [LCP ConfAck id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:46 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:46 [6] PPP: ppp0 sent [LCP ConfAck id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:46 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x74ba1b4d>]
2016-01-01 02:02:49 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:49 [6] PPP: ppp0 sent [LCP ConfAck id=0x27 <mru 1492> <auth pap> <magic 0x1e06bd3>]
2016-01-01 02:02:49 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x74ba1b4d>]
2016-01-01 02:02:52 [3] PPP: ppp0 
2016-01-01 02:03:11 [6] PPP: ppp0 sent [PADI Host-Uniq(0x00000fe7)]
2016-01-01 02:03:11 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x8d9e8020>]
2016-01-01 02:03:14 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x8d9e8020>]
2016-01-01 02:03:14 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:14 [6] PPP: ppp0 sent [LCP ConfAck id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:17 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x8d9e8020>]
2016-01-01 02:03:17 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:17 [6] PPP: ppp0 sent [LCP ConfAck id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:20 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x8d9e8020>]
2016-01-01 02:03:20 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:20 [6] PPP: ppp0 sent [LCP ConfAck id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:23 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x8d9e8020>]
2016-01-01 02:03:23 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:23 [6] PPP: ppp0 sent [LCP ConfAck id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:26 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x8d9e8020>]
2016-01-01 02:03:27 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:27 [6] PPP: ppp0 sent [LCP ConfAck id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:29 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x8d9e8020>]
2016-01-01 02:03:30 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:30 [6] PPP: ppp0 sent [LCP ConfAck id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:32 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x8d9e8020>]
2016-01-01 02:03:33 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:33 [6] PPP: ppp0 sent [LCP ConfAck id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:35 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x8d9e8020>]
2016-01-01 02:03:36 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:36 [6] PPP: ppp0 sent [LCP ConfAck id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:38 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x8d9e8020>]
2016-01-01 02:03:39 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:39 [6] PPP: ppp0 sent [LCP ConfAck id=0x14 <mru 1492> <auth pap> <magic 0x3f181947>]
2016-01-01 02:03:41 [3] PPP: ppp0 
2016-01-01 02:03:48 [6] PPP: ppp0 sent [PADI Host-Uniq(0x000010da)]
2016-01-01 02:03:48 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xb883739b>]
2016-01-01 02:03:51 [6] PPP: ppp0 rcvd [LCP ConfReq id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:03:51 [6] PPP: ppp0 sent [LCP ConfAck id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:03:51 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xb883739b>]
2016-01-01 02:03:54 [6] PPP: ppp0 rcvd [LCP ConfReq id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:03:54 [6] PPP: ppp0 sent [LCP ConfAck id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:03:54 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xb883739b>]
2016-01-01 02:03:57 [6] PPP: ppp0 rcvd [LCP ConfReq id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:03:57 [6] PPP: ppp0 sent [LCP ConfAck id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:03:57 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xb883739b>]
2016-01-01 02:04:00 [6] PPP: ppp0 rcvd [LCP ConfReq id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:04:00 [6] PPP: ppp0 sent [LCP ConfAck id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:04:00 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xb883739b>]
2016-01-01 02:04:03 [6] PPP: ppp0 rcvd [LCP ConfReq id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:04:03 [6] PPP: ppp0 sent [LCP ConfAck id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:04:03 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xb883739b>]
2016-01-01 02:04:06 [6] PPP: ppp0 rcvd [LCP ConfReq id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:04:06 [6] PPP: ppp0 sent [LCP ConfAck id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:04:06 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xb883739b>]
2016-01-01 02:04:09 [6] PPP: ppp0 rcvd [LCP ConfReq id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:04:09 [6] PPP: ppp0 sent [LCP ConfAck id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:04:09 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xb883739b>]
2016-01-01 02:04:12 [6] PPP: ppp0 rcvd [LCP ConfReq id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:04:12 [6] PPP: ppp0 sent [LCP ConfAck id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:04:12 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xb883739b>]
2016-01-01 02:04:15 [6] PPP: ppp0 rcvd [LCP ConfReq id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:04:15 [6] PPP: ppp0 sent [LCP ConfAck id=0xa4 <mru 1492> <auth pap> <magic 0x325aaba1>]
2016-01-01 02:04:15 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0xb883739b>]
2016-01-01 02:04:18 [3] PPP: ppp0 
2016-01-01 02:04:38 [6] PPP: ppp0 sent [PADI Host-Uniq(0x000011f2)]
2016-01-01 02:04:38 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x224d209b>]
2016-01-01 02:04:41 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:04:41 [6] PPP: ppp0 sent [LCP ConfAck id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:04:41 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x224d209b>]
2016-01-01 02:04:44 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:04:44 [6] PPP: ppp0 sent [LCP ConfAck id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:04:44 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x224d209b>]
2016-01-01 02:04:46 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:04:46 [6] PPP: ppp0 sent [LCP ConfAck id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:04:47 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x224d209b>]
2016-01-01 02:04:49 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:04:49 [6] PPP: ppp0 sent [LCP ConfAck id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:04:50 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x224d209b>]
2016-01-01 02:04:52 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:04:52 [6] PPP: ppp0 sent [LCP ConfAck id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:04:53 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x224d209b>]
2016-01-01 02:04:55 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:04:55 [6] PPP: ppp0 sent [LCP ConfAck id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:04:56 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x224d209b>]
2016-01-01 02:04:58 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:04:58 [6] PPP: ppp0 sent [LCP ConfAck id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:04:59 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x224d209b>]
2016-01-01 02:05:01 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:05:01 [6] PPP: ppp0 sent [LCP ConfAck id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:05:02 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x224d209b>]
2016-01-01 02:05:04 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:05:04 [6] PPP: ppp0 sent [LCP ConfAck id=0x2 <mru 1492> <auth pap> <magic 0x173dd1fb>]
2016-01-01 02:05:05 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x224d209b>]
2016-01-01 02:05:08 [3] PPP: ppp0 
2016-01-01 02:05:27 [6] PPP: ppp0 sent [PADI Host-Uniq(0x00001252)]
2016-01-01 02:05:27 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x61102f58>]
2016-01-01 02:05:30 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x61102f58>]
2016-01-01 02:05:30 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:30 [6] PPP: ppp0 sent [LCP ConfAck id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:33 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x61102f58>]
2016-01-01 02:05:33 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:33 [6] PPP: ppp0 sent [LCP ConfAck id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:36 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x61102f58>]
2016-01-01 02:05:36 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:36 [6] PPP: ppp0 sent [LCP ConfAck id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:39 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x61102f58>]
2016-01-01 02:05:40 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:40 [6] PPP: ppp0 sent [LCP ConfAck id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:42 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x61102f58>]
2016-01-01 02:05:42 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:42 [6] PPP: ppp0 sent [LCP ConfAck id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:45 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x61102f58>]
2016-01-01 02:05:45 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:45 [6] PPP: ppp0 sent [LCP ConfAck id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:48 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x61102f58>]
2016-01-01 02:05:48 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:48 [6] PPP: ppp0 sent [LCP ConfAck id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:51 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:51 [6] PPP: ppp0 sent [LCP ConfAck id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:51 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x61102f58>]
2016-01-01 02:05:54 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:54 [6] PPP: ppp0 sent [LCP ConfAck id=0x5b <mru 1492> <auth pap> <magic 0x58786ed0>]
2016-01-01 02:05:54 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x61102f58>]
2016-01-01 02:05:57 [3] PPP: ppp0 
2016-01-01 02:06:17 [6] PPP: ppp0 sent [PADI Host-Uniq(0x000012c2)]
2016-01-01 02:06:17 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x8035e391>]
2016-01-01 02:06:17 [6] PPP: ppp0 rcvd [LCP ConfAck id=0x1 <mru 1480> <magic 0x8035e391>]
2016-01-01 02:06:20 [6] PPP: ppp0 sent [LCP ConfReq id=0x1 <mru 1480> <magic 0x8035e391>]
2016-01-01 02:06:20 [6] PPP: ppp0 rcvd [LCP ConfAck id=0x1 <mru 1480> <magic 0x8035e391>]
2016-01-01 02:06:20 [6] PPP: ppp0 rcvd [LCP ConfReq id=0x4f <mru 1492> <auth pap> <magic 0x648e029b>]
2016-01-01 02:06:20 [6] PPP: ppp0 sent [LCP ConfAck id=0x4f <mru 1492> <auth pap> <magic 0x648e029b>]
2016-01-01 02:06:20 [6] PPP: ppp0 sent [LCP EchoReq id=0x0 magic=0x8035e391]
2016-01-01 02:06:20 [6] PPP: ppp0 sent [PAP AuthReq id=0x1 user="xxxxxx@xxxxxxx" password=<hidden>]
2016-01-01 02:06:20 [6] PPP: ppp0 rcvd [LCP EchoRep id=0x0 magic=0x648e029b]
2016-01-01 02:06:20 [6] PPP: ppp0 rcvd [PAP AuthAck id=0x1 "Login ok"]
2016-01-01 02:06:20 [6] PPP: ppp0 sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns2 0.0.0.0>]
2016-01-01 02:06:20 [6] PPP: ppp0 rcvd [IPCP ConfReq id=0x60 <addr xx.xxx.xxx.xxx>]
2016-01-01 02:06:20 [6] PPP: ppp0 sent [IPCP ConfAck id=0x60 <addr xx.xxx.xxx.xxx>]
2016-01-01 02:06:20 [6] PPP: ppp0 rcvd [IPCP ConfNak id=0x1 <addr xx.xxx.xxx.xxx> <ms-dns1 163.121.128.134> <ms-dns2 163.121.
2016-01-01 02:06:20 [6] PPP: ppp0 sent [IPCP ConfReq id=0x2 <addr xx.xxx.xxx.xxx> <ms-dns1 163.121.128.134> <ms-dns2 163.121.
2016-01-01 02:06:20 [6] PPP: ppp0 rcvd [IPCP ConfAck id=0x2 <addr xx.xxx.xxx.xxx> <ms-dns1 163.121.128.134> <ms-dns2 163.121.

Handyman · August 9, 2020, 6:34am

You are using vlan 2 for wan. Don't do that.
Try the modifications I made below.

config interface 'lan'
	option type 'bridge'
	option ifname 'eth0.1'
	option proto 'static'
	option ipaddr '192.168.1.1'
	option netmask '255.255.255.0'
	option ip6assign '60'

config interface 'wan'
	option ifname 'eth1'
	option proto 'pppoe'
	option username 'xxxxxx@xxxxxxx'
	option password 'xxxxxxxx'
	option ipv6 'auto'

Yamadron · August 9, 2020, 6:48am

It times out waiting for PADO packets now.
Unable to complete PPPoE discovery.

Handyman · August 9, 2020, 7:10am

Good, disconnect the phone line from your vr600 for 60min+ then try again.

Yamadron · August 9, 2020, 7:21am

Handyman:

config interface 'lan'
	option type 'bridge'
	option ifname 'eth0.1'
	option proto 'static'
	option ipaddr '192.168.1.1'
	option netmask '255.255.255.0'
	option ip6assign '60'

config interface 'wan'
	option ifname 'eth1'
	option proto 'pppoe'
	option username 'xxxxxx@xxxxxxx'
	option password 'xxxxxxxx'
	option ipv6 'auto'

Until all this time passes, May I ask why shan't I use VLAN 2 for WAN?

eduperez · August 9, 2020, 9:08am

That means you should use a VLAN, your ISP requires this.

eduperez · August 9, 2020, 9:10am

This does not look good, there is a lot of traffic before the connection is stablished, that is not normal. Looks like one router gives up before the other, both if them have to try too many times.

Handyman · August 9, 2020, 12:05pm

Cause according to your logs you are using an Egyptian ISP, and they don't use Vlans there. Check OpenWRT webpage for settings by country.

No it doesn't, these are his own settings not the ISP.

eduperez · August 9, 2020, 1:17pm

If he reaches the authentication phase using VLANs, and he does not receive PADO packed not using VLANs, then he definitively needs to use VLANs.

Handyman · August 9, 2020, 1:44pm

No he doesn't need to use VLANs, his DNS belongs to an Egyptian ISP named tedata.net.
I looked it up, that ISP doesn't use VLANs unless you know otherwise.

eduperez · August 9, 2020, 3:55pm

Keep using "eth1.2", and add a "option timeout 100" (or even larger) line to the WAN section, then try again and post the logs here.