PPPOE wan will not connect -

[gerryatric]

@stephenw10 this is a fresh log from today after I gave up last night. Nothing seems to have changed in settings. there is no dial on demand enabled or timeout value set. I did try to insert a 0 as it said this would stop it timing out, but it won't let me set that. I am lost. it just keeps trying to reconnect. now it is not even attempting to give IP even though last night it was

Sep 10 16:13:33 check_reload_status 374 Syncing firewall
Sep 10 16:13:37 ppp 85335 caught fatal signal TERM
Sep 10 16:13:37 ppp 85335 [wan] IFACE: Close event
Sep 10 16:13:37 ppp 85335 [wan] IPCP: Close event
Sep 10 16:13:37 ppp 85335 [wan] IPV6CP: Close event
Sep 10 16:13:39 ppp 85335 [wan] Bundle: Shutdown
Sep 10 16:13:39 ppp 85335 [wan_link0] Link: Shutdown
Sep 10 16:13:39 ppp 85335 process 85335 terminated
Sep 10 16:13:39 php-fpm 61638 /interfaces.php: The command '/sbin/ifconfig 'pppoe0' inet6 -accept_rtadv' returned exit code '1', the output was 'ifconfig: interface pppoe0 does not exist'
Sep 10 16:13:40 php-fpm 61638 /interfaces.php: Shutting down Router Advertisment daemon cleanly
Sep 10 16:13:40 ppp 1617 Multi-link PPP daemon for FreeBSD
Sep 10 16:13:40 ppp 1617 process 1617 started, version 5.9
Sep 10 16:13:40 ppp 1617 web: web is not running
Sep 10 16:13:40 ppp 1617 [wan] Bundle: Interface ng0 created
Sep 10 16:13:40 ppp 1617 [wan_link0] Link: OPEN event
Sep 10 16:13:40 kernel ng0: changing name to 'pppoe0'
Sep 10 16:13:40 ppp 1617 [wan_link0] LCP: Open event
Sep 10 16:13:40 ppp 1617 [wan_link0] LCP: state change Initial --> Starting
Sep 10 16:13:40 ppp 1617 [wan_link0] LCP: LayerStart
Sep 10 16:13:40 ppp 1617 [wan_link0] PPPoE: Connecting to 'iinet'
Sep 10 16:13:43 php-fpm 61638 /interfaces.php: calling interface_dhcpv6_configure.
Sep 10 16:13:43 php-fpm 61638 /interfaces.php: Accept router advertisements on interface em0.2
Sep 10 16:13:43 php-fpm 61638 /interfaces.php: Starting rtsold process
Sep 10 16:13:45 php-fpm 61638 /interfaces.php: Gateway, none 'available' for inet, use the first one configured. 'WAN_PPPOE'
Sep 10 16:13:45 php-fpm 61638 /interfaces.php: Gateway, none 'available' for inet6, use the first one configured. 'WAN_DHCP6'
Sep 10 16:13:45 check_reload_status 374 Restarting ipsec tunnels
Sep 10 16:13:49 ppp 1617 [wan_link0] PPPoE connection timeout after 9 seconds
Sep 10 16:13:49 ppp 1617 [wan_link0] Link: DOWN event
Sep 10 16:13:49 ppp 1617 [wan_link0] LCP: Down event
Sep 10 16:13:49 ppp 1617 [wan_link0] Link: reconnection attempt 1 in 1 seconds
Sep 10 16:13:50 ppp 1617 [wan_link0] Link: reconnection attempt 1
Sep 10 16:13:50 ppp 1617 [wan_link0] PPPoE: Connecting to 'iinet'
Sep 10 16:13:52 ppp 1617 PPPoE: rec'd ACNAME "bri-apt-wic-bras209"
Sep 10 16:13:59 ppp 1617 [wan_link0] PPPoE connection timeout after 9 seconds
Sep 10 16:13:59 ppp 1617 [wan_link0] Link: DOWN event
Sep 10 16:13:59 ppp 1617 [wan_link0] LCP: Down event
Sep 10 16:13:59 ppp 1617 [wan_link0] Link: reconnection attempt 2 in 2 seconds
Sep 10 16:14:01 ppp 1617 [wan_link0] Link: reconnection attempt 2
Sep 10 16:14:01 ppp 1617 [wan_link0] PPPoE: Connecting to 'iinet'
Sep 10 16:14:10 ppp 1617 [wan_link0] PPPoE connection timeout after 9 seconds
Sep 10 16:14:10 ppp 1617 [wan_link0] Link: DOWN event
Sep 10 16:14:10 ppp 1617 [wan_link0] LCP: Down event
Sep 10 16:14:10 ppp 1617 [wan_link0] Link: reconnection attempt 3 in 3 seconds
Sep 10 16:14:13 ppp 1617 [wan_link0] Link: reconnection attempt 3
Sep 10 16:14:13 ppp 1617 [wan_link0] PPPoE: Connecting to 'iinet'
Sep 10 16:14:22 ppp 1617 [wan_link0] PPPoE connection timeout after 9 seconds
Sep 10 16:14:22 ppp 1617 [wan_link0] Link: DOWN event
Sep 10 16:14:22 ppp 1617 [wan_link0] LCP: Down event
Sep 10 16:14:22 ppp 1617 [wan_link0] Link: reconnection attempt 4 in 4 seconds
Sep 10 16:14:26 ppp 1617 [wan_link0] Link: reconnection attempt 4
Sep 10 16:14:26 ppp 1617 [wan_link0] PPPoE: Connecting to 'iinet'
Sep 10 16:14:35 ppp 1617 [wan_link0] PPPoE connection timeout after 9 seconds
Sep 10 16:14:35 ppp 1617 [wan_link0] Link: DOWN event
Sep 10 16:14:35 ppp 1617 [wan_link0] LCP: Down event
Sep 10 16:14:35 ppp 1617 [wan_link0] Link: reconnection attempt 5 in 4 seconds
Sep 10 16:14:39 ppp 1617 [wan_link0] Link: reconnection attempt 5
Sep 10 16:14:39 ppp 1617 [wan_link0] PPPoE: Connecting to 'iinet'
Sep 10 16:14:39 check_reload_status 374 updating dyndns wan
Sep 10 16:14:41 check_reload_status 374 Reloading filter
Sep 10 16:14:41 php-fpm 61638 /interfaces.php: Creating rrd update script
Sep 10 16:14:48 ppp 1617 [wan_link0] PPPoE connection timeout after 9 seconds
Sep 10 16:14:48 ppp 1617 [wan_link0] Link: DOWN event
Sep 10 16:14:48 ppp 1617 [wan_link0] LCP: Down event
Sep 10 16:14:48 ppp 1617 [wan_link0] Link: reconnection attempt 6 in 3 seconds
Sep 10 16:14:51 ppp 1617 [wan_link0] Link: reconnection attempt 6
Sep 10 16:14:51 ppp 1617 [wan_link0] PPPoE: Connecting to 'iinet'
Sep 10 16:15:00 ppp 1617 [wan_link0] PPPoE connection timeout after 9 seconds
Sep 10 16:15:00 ppp 1617 [wan_link0] Link: DOWN event
Sep 10 16:15:00 ppp 1617 [wan_link0] LCP: Down event
Sep 10 16:15:00 ppp 1617 [wan_link0] Link: reconnection attempt 7 in 3 seconds
Sep 10 16:15:03 ppp 1617 [wan_link0] Link: reconnection attempt 7
Sep 10 16:15:03 ppp 1617 [wan_link0] PPPoE: Connecting to 'iinet'
Sep 10 16:15:12 ppp 1617 [wan_link0] PPPoE connection timeout after 9 seconds
Sep 10 16:15:12 ppp 1617 [wan_link0] Link: DOWN event
Sep 10 16:15:12 ppp 1617 [wan_link0] LCP: Down event
Sep 10 16:15:12 ppp 1617 [wan_link0] Link: reconnection attempt 8 in 3 seconds
Sep 10 16:15:15 ppp 1617 [wan_link0] Link: reconnection attempt 8
Sep 10 16:15:15 ppp 1617 [wan_link0] PPPoE: Connecting to 'iinet'
Sep 10 16:15:24 ppp 1617 [wan_link0] PPPoE connection timeout after 9 seconds
Sep 10 16:15:24 ppp 1617 [wan_link0] Link: DOWN event
Sep 10 16:15:24 ppp 1617 [wan_link0] LCP: Down event
Sep 10 16:15:24 ppp 1617 [wan_link0] Link: reconnection attempt 9 in 2 seconds
Sep 10 16:15:26 ppp 1617 [wan_link0] Link: reconnection attempt 9
Sep 10 16:15:26 ppp 1617 [wan_link0] PPPoE: Connecting to 'iinet'
Sep 10 16:15:35 ppp 1617 [wan_link0] PPPoE connection timeout after 9 seconds
Sep 10 16:15:35 ppp 1617 [wan_link0] Link: DOWN event
Sep 10 16:15:35 ppp 1617 [wan_link0] LCP: Down event
Sep 10 16:15:35 ppp 1617 [wan_link0] Link: reconnection attempt 10 in 1 seconds
Sep 10 16:15:36 ppp 1617 [wan_link0] Link: reconnection attempt 10
Sep 10 16:15:36 ppp 1617 [wan_link0] PPPoE: Connecting to 'iinet'

[stephenw10]

@gerryatric said in PPPOE wan will not connect -:

Sep 10 16:13:52 ppp 1617 PPPoE: rec'd ACNAME "bri-apt-wic-bras209"

That's the only thing shown there that looks like it's connected at all.

Check the ppp logs. It would be better to see the logs covering it connecting correctly and then disconnecting. If it's been trying to connect all night though those logs have probably been lost.

Steve

[gerryatric]

@stephenw10 hi Stephen
it is definitely connecting and reconnecting
I can't attach the log, it keeps flagging it as spam by Aksimet plugin on the Netgate site

[Gertjan]

@gerryatric said in PPPOE wan will not connect -:

I can't attach the log

https://pastebin.com/ !?

[gerryatric]

@gertjan
ok i don't know how I did this as I couldn't quite work pastebin out lol

see if this worked
paste.txt

ok it did work. somehow. its a long log

[biggsy]

@gerryatric

Is there a particular reason you're with iiNet?

I'm with Aussie Broadband and it's just plain, simple DHCP.

[gerryatric]

@biggsy yes, I have been a long term IINET customer. I transferred to this house and it had Fibre to the premise which means I now have gigabit ethernet. Didn't see any point looking around as IINET worked for me always.
IINET NBN is DHCP yes. But on the Fibre network it uses a Vlan

[stephenw10]

Hmm, so it appears it is communicating with something at the other end since you are authenticated at least once.

Does it ever complete the connection such that you can pass traffic over it?

If there any chance the private IP it's giving you (10.20.23.172) conflicts with something local?

Steve

[fireodo]

@stephenw10 said in PPPOE wan will not connect -:

If there any chance the private IP it's giving you (10.20.23.172) conflicts with something local?

... or that the ISP needs a specific MAC Adress?

[gerryatric]

@fireodo Hi all
No I have not seen that private IP before. so I have no idea where it is coming from.'
The local lan it is on is a 192 range
No it does not need a specific Mac address.
My Unifi USG is on the same lan and working fine without providing anything apart from name and pw and a vlan of 2.
and receives the IP 110.175.4.45
It should be simple

[fireodo]

@gerryatric said in PPPOE wan will not connect -:

My Unifi USG is on the same lan and working fine without providing anything apart from name and pw and a vlan of 2

But not on the same time with the pfsense box ... i hope ...

[gerryatric]

@fireodo
yes that works fine. I do it with my edgerouter and the USG at the same time. both units login on the network provided by IINET. I have been doing that for ages. one is my normal lan home business etc and the other is my test lan,
I was going to replace the USG with the pfsense box to fix the speed issue. but can not get it to work

[fireodo]

@gerryatric

I revue your logfile and i see 3x : [wan_link0] LCP: authorization failed
that leads me to think that something is wrong with your credentials - in your browser and on the pfsense GUI do not use autofill (just another hint)

[gerryatric]

@fireodo i do not use autofill. and I have verified the username and password so many times to make sure.
It is identical to my USG details

[fireodo]

@gerryatric said in PPPOE wan will not connect -:

I have verified the username and password so many times to make sure.
It is identical to my USG details

I have no doubt on that - but "LCP: authorization failed" means (as far as I know) that user/pwd-combination is not valid. Do you have, by chance, some special characters in your pwd that maybe pfsense is handling wrong? (DONT write it here)
Edit: I checked my own PPP-Log (yes I have a PPPOE connection) and in my case it says: "[wan_link0] LCP: authorization successful"

[gerryatric]

@fireodo Hi
Only an ! in the password.
Would pfsense be kicking up a fuss with that?

[fireodo]

@gerryatric said in PPPOE wan will not connect -:

Would pfsense be kicking up a fuss with that?

I remember a discussion a while a go (that was on DYNDNS if I remember well) whit some trouble on "special" Passwords ...
Can you change the PWD by chance?

[stephenw10]

I don't think this is an authentication issue dircetly because when it fails it's doing so because of a timeout:

Sep 11 05:04:43	ppp	785	[wan_link0] LCP: authorization timer expired
Sep 11 05:04:43	ppp	785	[wan_link0] LCP: authorization failed

It's not actually failing the auth and receiving an error from the other end. More like the process is out of sync for some reason, the client restarts and the server is still waiting for something else much further along.

Steve

[gerryatric]

@stephenw10 Hi Steve. I agree, Remebering that when we first got it working, it would stay connected. when I finally got the Assignment order around the right way for the ports.
now it does this and I have no clue.
The USG pro just works.
This was connecting for up to 2 minutes and then disconnecting.
Now it tries and fails, What could it be waiting for. is there something else it needs. seeing as there so many more options in PFSense than the USG

[biggsy]

@gerryatric

You said earlier that you had/have the USG and the ER both connecting to iiNet using the same PPPOE credentials. Is that on your FTTP or your old connection?

Were they each obtaining separate public IP addresses?

Are both the USG and the ER boxes disconnected from the network when you attempt to get PPPOE working on pfSense?

Sorry for all the questions but let's try to get to the bottom of this.