1
General Discussion / PPPoE not establishing a connection
« on: July 11, 2023, 11:56:49 pm »
Hello,
I first had trouble having the PPPoE connection set up correctly. After rebooting several times, everything has been working well for several weeks. Yesterday I disconnected the OPnsense box and I plugged it in today again. The WAN connection is not being established successfully. There have been no changes in the configuration, in fact, I have rolled back to a previous snapshot where everything was working as expected.
I have checked the PPPoE logs and I am still unsure about what might be causing this issue since the connection is being set up correctly.
I am using Proxmox 7.4-3 where OPnsense is running as a VM. The VM uses a Linux bridge to the physical interfaces (Intel i226). The ISP router serves as the modem and manages the VLANs for TV, voice and data. All the data traffic is relayed to Opnsense untagged.
Any ideas would be much appreciated.
Thanks,
Gerard
I first had trouble having the PPPoE connection set up correctly. After rebooting several times, everything has been working well for several weeks. Yesterday I disconnected the OPnsense box and I plugged it in today again. The WAN connection is not being established successfully. There have been no changes in the configuration, in fact, I have rolled back to a previous snapshot where everything was working as expected.
I have checked the PPPoE logs and I am still unsure about what might be causing this issue since the connection is being set up correctly.
Code: [Select]
2023-06-18T14:12:38 Informational ppp [wan_link0] PPPoE: Connecting to ''
2023-06-18T14:12:38 Informational ppp [wan_link0] Link: reconnection attempt 1
2023-06-18T14:12:34 Informational ppp [wan_link0] Link: reconnection attempt 1 in 4 seconds
2023-06-18T14:12:34 Informational ppp [wan_link0] LCP: Down event
2023-06-18T14:12:34 Informational ppp [wan_link0] Link: DOWN event
2023-06-18T14:12:34 Informational ppp [wan_link0] PPPoE connection timeout after 9 seconds
2023-06-18T14:12:25 Informational ppp [wan_link0] PPPoE: Connecting to ''
2023-06-18T14:12:25 Informational ppp [wan_link0] LCP: LayerStart
2023-06-18T14:12:25 Informational ppp [wan_link0] LCP: state change Initial --> Starting
2023-06-18T14:12:25 Informational ppp [wan_link0] LCP: Open event
2023-06-18T14:12:25 Informational ppp [wan_link0] Link: OPEN event
2023-06-18T14:12:25 Informational ppp [wan] Bundle: Interface ng0 created
2023-06-18T14:12:25 Informational ppp web: web is not running
2023-06-18T14:12:25 Informational ppp process 32749 started, version 5.9
2023-06-18T14:12:25 Informational ppp
2023-06-18T14:12:25 Informational ppp Multi-link PPP daemon for FreeBSD
2023-06-18T01:55:39 Informational ppp [wan] IFACE: Rename interface ng0 to pppoe0
2023-06-18T01:55:39 Informational ppp [wan] IFACE: Up event
2023-06-18T01:55:39 Informational ppp [wan] X.X.X.X -> 192.168.144.1
2023-06-18T01:55:39 Informational ppp [wan] IPCP: LayerUp
2023-06-18T01:55:39 Informational ppp [wan] IPCP: state change Ack-Sent --> Opened
2023-06-18T01:55:39 Informational ppp [wan] IPADDR X.X.X.X
2023-06-18T01:55:39 Informational ppp [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
2023-06-18T01:55:39 Informational ppp [wan] IPADDR X.X.X.X
2023-06-18T01:55:39 Informational ppp [wan] IPCP: SendConfigReq #3
2023-06-18T01:55:39 Informational ppp [wan] X.X.X.X is OK
2023-06-18T01:55:39 Informational ppp [wan] IPADDR X.X.X.X
2023-06-18T01:55:39 Informational ppp [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
2023-06-18T01:55:39 Informational ppp [wan] IPADDR 0.0.0.0
2023-06-18T01:55:39 Informational ppp [wan] IPCP: SendConfigReq #2
2023-06-18T01:55:39 Informational ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
2023-06-18T01:55:39 Informational ppp [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
2023-06-18T01:55:39 Informational ppp [wan] IPCP: state change Req-Sent --> Ack-Sent
2023-06-18T01:55:39 Informational ppp [wan] IPADDR 192.168.144.1
2023-06-18T01:55:39 Informational ppp [wan] IPCP: SendConfigAck #1
2023-06-18T01:55:39 Informational ppp [wan] 192.168.144.1 is OK
2023-06-18T01:55:39 Informational ppp [wan] IPADDR 192.168.144.1
2023-06-18T01:55:39 Informational ppp [wan] IPCP: rec'd Configure Request #1 (Req-Sent)
2023-06-18T01:55:39 Informational ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
2023-06-18T01:55:39 Informational ppp [wan] IPADDR 0.0.0.0
2023-06-18T01:55:39 Informational ppp [wan] IPCP: SendConfigReq #1
2023-06-18T01:55:39 Informational ppp [wan] IPCP: state change Starting --> Req-Sent
2023-06-18T01:55:39 Informational ppp [wan] IPCP: Up event
2023-06-18T01:55:39 Informational ppp [wan] IPCP: LayerStart
2023-06-18T01:55:39 Informational ppp [wan] IPCP: state change Initial --> Starting
2023-06-18T01:55:39 Informational ppp [wan] IPCP: Open event
2023-06-18T01:55:39 Informational ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
2023-06-18T01:55:39 Informational ppp [wan_link0] Link: Join bundle "wan"
2023-06-18T01:55:39 Informational ppp [wan_link0] Link: Matched action 'bundle "wan" ""'
2023-06-18T01:55:39 Informational ppp [wan_link0] LCP: authorization successful
2023-06-18T01:55:39 Informational ppp [wan_link0] MESG: CHAP authentication success
2023-06-18T01:55:39 Informational ppp [wan_link0] CHAP: rec'd SUCCESS #1 len: 31
2023-06-18T01:55:39 Informational ppp [wan_link0] CHAP: sending RESPONSE #1 len: 44
2023-06-18T01:55:39 Informational ppp [wan_link0] CHAP: Using authname "USER@DOM"
2023-06-18T01:55:39 Informational ppp [wan_link0] Name: "hl4vall1-101"
2023-06-18T01:55:39 Informational ppp [wan_link0] CHAP: rec'd CHALLENGE #1 len: 76
2023-06-18T01:55:39 Informational ppp [wan_link0] LCP: LayerUp
2023-06-18T01:55:39 Informational ppp [wan_link0] LCP: auth: peer wants CHAP, I want nothing
2023-06-18T01:55:39 Informational ppp [wan_link0] LCP: state change Ack-Sent --> Opened
2023-06-18T01:55:39 Informational ppp [wan_link0] MAGICNUM 0x1b9e0e76
2023-06-18T01:55:39 Informational ppp [wan_link0] MRU 1492
2023-06-18T01:55:39 Informational ppp [wan_link0] LCP: rec'd Configure Ack #2 (Ack-Sent)
2023-06-18T01:55:39 Informational ppp [wan_link0] MAGICNUM 0x1b9e0e76
2023-06-18T01:55:39 Informational ppp [wan_link0] MRU 1492
2023-06-18T01:55:39 Informational ppp [wan_link0] LCP: SendConfigReq #2
2023-06-18T01:55:39 Informational ppp [wan_link0] PROTOCOMP
2023-06-18T01:55:39 Informational ppp [wan_link0] LCP: rec'd Configure Reject #1 (Ack-Sent)
2023-06-18T01:55:39 Informational ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent
2023-06-18T01:55:39 Informational ppp [wan_link0] MAGICNUM 0x755c8adc
2023-06-18T01:55:39 Informational ppp [wan_link0] AUTHPROTO CHAP MD5
2023-06-18T01:55:39 Informational ppp [wan_link0] MRU 1500
2023-06-18T01:55:39 Informational ppp [wan_link0] LCP: SendConfigAck #66
2023-06-18T01:55:39 Informational ppp [wan_link0] MAGICNUM 0x755c8adc
2023-06-18T01:55:39 Informational ppp [wan_link0] AUTHPROTO CHAP MD5
2023-06-18T01:55:39 Informational ppp [wan_link0] MRU 1500
2023-06-18T01:55:39 Informational ppp [wan_link0] LCP: rec'd Configure Request #66 (Req-Sent)
2023-06-18T01:55:39 Informational ppp [wan_link0] MAGICNUM 0x1b9e0e76
2023-06-18T01:55:39 Informational ppp [wan_link0] MRU 1492
2023-06-18T01:55:39 Informational ppp [wan_link0] PROTOCOMP
2023-06-18T01:55:39 Informational ppp [wan_link0] LCP: SendConfigReq #1
2023-06-18T01:55:39 Informational ppp [wan_link0] LCP: state change Starting --> Req-Sent
2023-06-18T01:55:39 Informational ppp [wan_link0] LCP: Up event
2023-06-18T01:55:39 Informational ppp [wan_link0] Link: UP event
2023-06-18T01:55:39 Informational ppp [wan_link0] PPPoE: connection successful
2023-06-18T01:55:39 Informational ppp PPPoE: rec'd ACNAME "hl4vall1-101"
2023-06-18T01:55:39 Informational ppp [wan_link0] PPPoE: Connecting to ''
2023-06-18T01:55:39 Informational ppp [wan_link0] LCP: LayerStart
2023-06-18T01:55:39 Informational ppp [wan_link0] LCP: state change Initial --> Starting
2023-06-18T01:55:39 Informational ppp [wan_link0] LCP: Open event
2023-06-18T01:55:39 Informational ppp [wan_link0] Link: OPEN event
2023-06-18T01:55:39 Informational ppp [wan] Bundle: Interface ng0 created
2023-06-18T01:55:39 Informational ppp web: web is not running
2023-06-18T01:55:39 Informational ppp process 10793 started, version 5.9
2023-06-18T01:55:39 Informational ppp
2023-06-18T01:55:39 Informational ppp Multi-link PPP daemon for FreeBSD
2023-06-18T01:42:54 Informational ppp [wan] IFACE: Rename interface ng0 to pppoe0
2023-06-18T01:42:54 Informational ppp [wan] IFACE: Up event
2023-06-18T01:42:54 Informational ppp [wan] Y.Y.Y.Y -> 192.168.144.1
2023-06-18T01:42:54 Informational ppp [wan] IPCP: LayerUp
2023-06-18T01:42:54 Informational ppp [wan] IPCP: state change Ack-Sent --> Opened
2023-06-18T01:42:54 Informational ppp [wan] IPADDR Y.Y.Y.Y
2023-06-18T01:42:54 Informational ppp [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
2023-06-18T01:42:54 Informational ppp [wan] IPADDR Y.Y.Y.Y
2023-06-18T01:42:54 Informational ppp [wan] IPCP: SendConfigReq #3
2023-06-18T01:42:54 Informational ppp [wan] Y.Y.Y.Y is OK
2023-06-18T01:42:54 Informational ppp [wan] IPADDR Y.Y.Y.Y
2023-06-18T01:42:54 Informational ppp [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
2023-06-18T01:42:54 Informational ppp [wan] IPADDR 0.0.0.0
2023-06-18T01:42:54 Informational ppp [wan] IPCP: SendConfigReq #2
2023-06-18T01:42:54 Informational ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
2023-06-18T01:42:54 Informational ppp [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
2023-06-18T01:42:54 Informational ppp [wan] IPCP: state change Req-Sent --> Ack-Sent
2023-06-18T01:42:54 Informational ppp [wan] IPADDR 192.168.144.1
2023-06-18T01:42:54 Informational ppp [wan] IPCP: SendConfigAck #200
2023-06-18T01:42:54 Informational ppp [wan] 192.168.144.1 is OK
2023-06-18T01:42:54 Informational ppp [wan] IPADDR 192.168.144.1
2023-06-18T01:42:54 Informational ppp [wan] IPCP: rec'd Configure Request #200 (Req-Sent)
2023-06-18T01:42:54 Informational ppp [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
2023-06-18T01:42:54 Informational ppp [wan] IPADDR 0.0.0.0
2023-06-18T01:42:54 Informational ppp [wan] IPCP: SendConfigReq #1
2023-06-18T01:42:54 Informational ppp [wan] IPCP: state change Starting --> Req-Sent
2023-06-18T01:42:54 Informational ppp [wan] IPCP: Up event
2023-06-18T01:42:54 Informational ppp [wan] IPCP: LayerStart
2023-06-18T01:42:54 Informational ppp [wan] IPCP: state change Initial --> Starting
2023-06-18T01:42:54 Informational ppp [wan] IPCP: Open event
2023-06-18T01:42:54 Informational ppp [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
2023-06-18T01:42:54 Informational ppp [wan_link0] Link: Join bundle "wan"
2023-06-18T01:42:54 Informational ppp [wan_link0] Link: Matched action 'bundle "wan" ""'
2023-06-18T01:42:54 Informational ppp [wan_link0] LCP: authorization successful
2023-06-18T01:42:54 Informational ppp [wan_link0] MESG: CHAP authentication success
2023-06-18T01:42:54 Informational ppp [wan_link0] CHAP: rec'd SUCCESS #1 len: 31
2023-06-18T01:42:54 Informational ppp [wan_link0] CHAP: sending RESPONSE #1 len: 44
2023-06-18T01:42:54 Informational ppp [wan_link0] CHAP: Using authname "USER@DOM"
2023-06-18T01:42:54 Informational ppp [wan_link0] Name: "hl4vall1-101"
2023-06-18T01:42:54 Informational ppp [wan_link0] CHAP: rec'd CHALLENGE #1 len: 70
2023-06-18T01:42:54 Informational ppp [wan_link0] LCP: LayerUp
2023-06-18T01:42:54 Informational ppp [wan_link0] LCP: auth: peer wants CHAP, I want nothing
2023-06-18T01:42:54 Informational ppp [wan_link0] LCP: state change Ack-Sent --> Opened
2023-06-18T01:42:54 Informational ppp [wan_link0] MAGICNUM 0x308e74e0
2023-06-18T01:42:54 Informational ppp [wan_link0] MRU 1492
2023-06-18T01:42:54 Informational ppp [wan_link0] LCP: rec'd Configure Ack #2 (Ack-Sent)
2023-06-18T01:42:54 Informational ppp [wan_link0] MAGICNUM 0x308e74e0
2023-06-18T01:42:54 Informational ppp [wan_link0] MRU 1492
2023-06-18T01:42:54 Informational ppp [wan_link0] LCP: SendConfigReq #2
2023-06-18T01:42:54 Informational ppp [wan_link0] PROTOCOMP
2023-06-18T01:42:54 Informational ppp [wan_link0] LCP: rec'd Configure Reject #1 (Ack-Sent)
2023-06-18T01:42:54 Informational ppp [wan_link0] LCP: state change Req-Sent --> Ack-Sent
2023-06-18T01:42:54 Informational ppp [wan_link0] MAGICNUM 0x6dda6f2d
2023-06-18T01:42:54 Informational ppp [wan_link0] AUTHPROTO CHAP MD5
2023-06-18T01:42:54 Informational ppp [wan_link0] MRU 1500
2023-06-18T01:42:54 Informational ppp [wan_link0] LCP: SendConfigAck #254
2023-06-18T01:42:54 Informational ppp [wan_link0] MAGICNUM 0x6dda6f2d
2023-06-18T01:42:54 Informational ppp [wan_link0] AUTHPROTO CHAP MD5
2023-06-18T01:42:54 Informational ppp [wan_link0] MRU 1500
2023-06-18T01:42:54 Informational ppp [wan_link0] LCP: rec'd Configure Request #254 (Req-Sent)
2023-06-18T01:42:54 Informational ppp [wan_link0] MAGICNUM 0x308e74e0
2023-06-18T01:42:54 Informational ppp [wan_link0] MRU 1492
2023-06-18T01:42:54 Informational ppp [wan_link0] PROTOCOMP
2023-06-18T01:42:54 Informational ppp [wan_link0] LCP: SendConfigReq #1
2023-06-18T01:42:54 Informational ppp [wan_link0] LCP: state change Starting --> Req-Sent
2023-06-18T01:42:54 Informational ppp [wan_link0] LCP: Up event
2023-06-18T01:42:54 Informational ppp [wan_link0] Link: UP event
2023-06-18T01:42:54 Informational ppp [wan_link0] PPPoE: connection successful
2023-06-18T01:42:54 Informational ppp PPPoE: rec'd ACNAME "hl4vall1-101"
2023-06-18T01:42:54 Informational ppp [wan_link0] PPPoE: Connecting to ''
2023-06-18T01:42:54 Informational ppp [wan_link0] LCP: LayerStart
2023-06-18T01:42:54 Informational ppp [wan_link0] LCP: state change Initial --> Starting
2023-06-18T01:42:54 Informational ppp [wan_link0] LCP: Open event
2023-06-18T01:42:54 Informational ppp [wan_link0] Link: OPEN event
2023-06-18T01:42:53 Informational ppp [wan] Bundle: Interface ng0 created
2023-06-18T01:42:53 Informational ppp web: web is not running
2023-06-18T01:42:53 Informational ppp process 61912 started, version 5.9
2023-06-18T01:42:53 Informational ppp
2023-06-18T01:42:53 Informational ppp Multi-link PPP daemon for FreeBSD
I am using Proxmox 7.4-3 where OPnsense is running as a VM. The VM uses a Linux bridge to the physical interfaces (Intel i226). The ISP router serves as the modem and manages the VLANs for TV, voice and data. All the data traffic is relayed to Opnsense untagged.
Any ideas would be much appreciated.
Thanks,
Gerard