core icon indicating copy to clipboard operation
core copied to clipboard
verified publisher icon opnsense

Sudden nightly reconnect using PPPoE since version 24.1.6

Open bentalexanderhaase opened this issue 1 year ago • 3 comments

Important notices

Before you add a new report, we ask you kindly to acknowledge the following:

  • [x] I have read the contributing guide lines at https://github.com/opnsense/core/blob/master/CONTRIBUTING.md
  • [x] I am convinced that my issue is new after having checked both open and closed issues at https://github.com/opnsense/core/issues?q=is%3Aissue

Describe the bug

Since upgrading this specific virtual OPNsense appliance from version 22.7.9 to 24.1.6 (through multiple stages), there has been a nightly PPPoE reconnect at approximately 03:01 every day. This issue was not present before, and there were no nightly reconnects prior to the upgrade.

The ISP in use is 'Deutsche Telekom,' which does not officially mandate nightly reconnects (at most every 180 days). Similar setups at other locations using the same ISP do not experience nightly reconnects either.

There appears to be only one cronjob running at 03:01 (on the first of each month), updating bogons lists. However, this job would only be relevant today (01.05.2024), but the issue has persisted since the update on 27.04.2024.

root@router:~ # crontab -l
# or /usr/local/etc/cron.d and follow the same format as
# /etc/crontab, see the crontab(5) manual page.
SHELL=/bin/sh
PATH=/etc:/bin:/sbin:/usr/bin:/usr/sbin:/usr/local/bin:/usr/local/sbin
REQUESTS_CA_BUNDLE=/etc/ssl/cert.pem
#minute	hour	mday	month	wday	command
1	*	*	*	*	(/usr/local/sbin/configctl -d syslog archive) > /dev/null
2	*	*	*	*	(/usr/local/sbin/expiretable -v -t 3600 sshlockout) > /dev/null
3	*	*	*	*	(/usr/local/sbin/expiretable -v -t 3600 virusprot) > /dev/null
4	*	*	*	*	(/usr/local/etc/rc.expireaccounts) > /dev/null
*/4	*	*	*	*	(/usr/local/sbin/ping_hosts.sh) > /dev/null
0	22	*	*	*	(/usr/local/sbin/configctl -d firmware changelog cron) > /dev/null
1	3	1	*	*	(/usr/local/sbin/configctl -d filter schedule bogons) > /dev/null
*	*	*	*	*	(/usr/local/bin/flock -n -E 0 -o /tmp/filter_update_tables.lock /usr/local/opnsense/scripts/filter/update_tables.py) > /dev/null

There are no further cronjobs in the GUI.

No relevant config changes have been made since the upgrade.

Question: Has something changed regarding PPPoE, or is there a setting or cronjob for a different user that I may be overlooking, which is running at 03:01 every night?

To Reproduce

Hardly reproducible due to the specificity of external technical requirements (ISP, environment, etc.).

Expected behavior

No nightly reconnects as observed previously.

Describe alternatives you considered

Checking Telekom Forum, OPNsense Forum, Reddit, and my other similar setups for the same issue; ruling out automated backups, cronjobs, and other possible changes to the system.

Relevant log files

Here are logs from two the past two days showing the 'issue' and the reconnect.

2024-05-01T03:02:59	Informational	ppp	 [wan] IFACE: Rename interface ng0 to pppoe0
2024-05-01T03:02:59	Informational	ppp	 [wan] IFACE: Up event
2024-05-01T03:02:59	Informational	ppp	 [wan]   <ip_redacted> -> <ip_redacted>
2024-05-01T03:02:59	Informational	ppp	 [wan] IPCP: LayerUp
2024-05-01T03:02:59	Informational	ppp	 [wan] IPCP: state change Ack-Sent --> Opened
2024-05-01T03:02:59	Informational	ppp	 [wan]   IPADDR <ip_redacted>
2024-05-01T03:02:59	Informational	ppp	 [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
2024-05-01T03:02:59	Informational	ppp	 [wan]   IPADDR <ip_redacted>
2024-05-01T03:02:59	Informational	ppp	 [wan] IPCP: SendConfigReq #3
2024-05-01T03:02:59	Informational	ppp	 [wan]     <ip_redacted> is OK
2024-05-01T03:02:59	Informational	ppp	 [wan]   IPADDR <ip_redacted>
2024-05-01T03:02:59	Informational	ppp	 [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
2024-05-01T03:02:59	Informational	ppp	 [wan]   IPADDR 0.0.0.0
2024-05-01T03:02:59	Informational	ppp	 [wan] IPCP: SendConfigReq #2
2024-05-01T03:02:59	Informational	ppp	 [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
2024-05-01T03:02:59	Informational	ppp	 [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
2024-05-01T03:02:59	Informational	ppp	 [wan] IPCP: state change Req-Sent --> Ack-Sent
2024-05-01T03:02:59	Informational	ppp	 [wan]   IPADDR <ip_redacted>
2024-05-01T03:02:59	Informational	ppp	 [wan] IPCP: SendConfigAck #241
2024-05-01T03:02:59	Informational	ppp	 [wan]     <ip_redacted> is OK
2024-05-01T03:02:59	Informational	ppp	 [wan]   IPADDR <ip_redacted>
2024-05-01T03:02:59	Informational	ppp	 [wan] IPCP: rec'd Configure Request #241 (Req-Sent)
2024-05-01T03:02:59	Informational	ppp	 [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
2024-05-01T03:02:59	Informational	ppp	 [wan]   IPADDR 0.0.0.0
2024-05-01T03:02:59	Informational	ppp	 [wan] IPCP: SendConfigReq #1
2024-05-01T03:02:59	Informational	ppp	 [wan] IPCP: state change Starting --> Req-Sent
2024-05-01T03:02:59	Informational	ppp	 [wan] IPCP: Up event
2024-05-01T03:02:59	Informational	ppp	 [wan] IPCP: LayerStart
2024-05-01T03:02:59	Informational	ppp	 [wan] IPCP: state change Initial --> Starting
2024-05-01T03:02:59	Informational	ppp	 [wan] IPCP: Open event
2024-05-01T03:02:59	Informational	ppp	 [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] Link: Join bundle "wan"
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] Link: Matched action 'bundle "wan" ""'
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] LCP: authorization successful
2024-05-01T03:02:59	Informational	ppp	 [wan_link0]   MESG: SRU=60000#SRD=275000#
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] PAP: rec'd ACK #1 len: 26
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] LCP: LayerUp
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] PAP: sending REQUEST #1 len: 56
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] PAP: using authname "XXXXXXXXXXX#XXXXXXXXXXX#[email protected]"
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] LCP: auth: peer wants PAP, I want nothing
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] LCP: state change Ack-Sent --> Opened
2024-05-01T03:02:59	Informational	ppp	 [wan_link0]   MAGICNUM 0x7e7de81d
2024-05-01T03:02:59	Informational	ppp	 [wan_link0]   MRU 1492
2024-05-01T03:02:59	Informational	ppp	 [wan_link0]   PROTOCOMP
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent)
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] LCP: state change Req-Sent --> Ack-Sent
2024-05-01T03:02:59	Informational	ppp	 [wan_link0]   MAGICNUM 0x581796c5
2024-05-01T03:02:59	Informational	ppp	 [wan_link0]   AUTHPROTO PAP
2024-05-01T03:02:59	Informational	ppp	 [wan_link0]   MRU 1492
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] LCP: SendConfigAck #159
2024-05-01T03:02:59	Informational	ppp	 [wan_link0]   MAGICNUM 0x581796c5
2024-05-01T03:02:59	Informational	ppp	 [wan_link0]   AUTHPROTO PAP
2024-05-01T03:02:59	Informational	ppp	 [wan_link0]   MRU 1492
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] LCP: rec'd Configure Request #159 (Req-Sent)
2024-05-01T03:02:59	Informational	ppp	 [wan_link0]   MAGICNUM 0x7e7de81d
2024-05-01T03:02:59	Informational	ppp	 [wan_link0]   MRU 1492
2024-05-01T03:02:59	Informational	ppp	 [wan_link0]   PROTOCOMP
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] LCP: SendConfigReq #1
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] LCP: state change Starting --> Req-Sent
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] LCP: Up event
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] Link: UP event
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] PPPoE: connection successful
2024-05-01T03:02:59	Informational	ppp	 PPPoE: rec'd ACNAME "LUNJ02"
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] PPPoE: Connecting to ''
2024-05-01T03:02:59	Informational	ppp	 [wan_link0] Link: reconnection attempt 7
2024-05-01T03:02:56	Informational	ppp	 [wan_link0] Link: reconnection attempt 7 in 3 seconds
2024-05-01T03:02:56	Informational	ppp	 [wan_link0] LCP: Down event
2024-05-01T03:02:56	Informational	ppp	 [wan_link0] Link: DOWN event
2024-05-01T03:02:56	Informational	ppp	 [wan_link0] PPPoE connection timeout after 9 seconds
2024-05-01T03:02:47	Informational	ppp	 [wan_link0] PPPoE: Connecting to ''
2024-05-01T03:02:47	Informational	ppp	 [wan_link0] Link: reconnection attempt 6
2024-05-01T03:02:44	Informational	ppp	 [wan_link0] Link: reconnection attempt 6 in 3 seconds
2024-05-01T03:02:44	Informational	ppp	 [wan_link0] LCP: Down event
2024-05-01T03:02:44	Informational	ppp	 [wan_link0] Link: DOWN event
2024-05-01T03:02:44	Informational	ppp	 [wan_link0] PPPoE connection timeout after 9 seconds
2024-05-01T03:02:35	Informational	ppp	 [wan_link0] PPPoE: Connecting to ''
2024-05-01T03:02:35	Informational	ppp	 [wan_link0] Link: reconnection attempt 5
2024-05-01T03:02:32	Informational	ppp	 [wan_link0] Link: reconnection attempt 5 in 2 seconds
2024-05-01T03:02:32	Informational	ppp	 [wan_link0] LCP: Down event
2024-05-01T03:02:32	Informational	ppp	 [wan_link0] Link: DOWN event
2024-05-01T03:02:32	Informational	ppp	 [wan_link0] PPPoE connection timeout after 9 seconds
2024-05-01T03:02:23	Informational	ppp	 [wan_link0] PPPoE: Connecting to ''
2024-05-01T03:02:23	Informational	ppp	 [wan_link0] Link: reconnection attempt 4
2024-05-01T03:02:20	Informational	ppp	 [wan_link0] Link: reconnection attempt 4 in 3 seconds
2024-05-01T03:02:20	Informational	ppp	 [wan_link0] LCP: Down event
2024-05-01T03:02:20	Informational	ppp	 [wan_link0] Link: DOWN event
2024-05-01T03:02:20	Informational	ppp	 [wan_link0] PPPoE connection timeout after 9 seconds
2024-05-01T03:02:11	Informational	ppp	 [wan_link0] PPPoE: Connecting to ''
2024-05-01T03:02:11	Informational	ppp	 [wan_link0] Link: reconnection attempt 3
2024-05-01T03:02:08	Informational	ppp	 [wan_link0] Link: reconnection attempt 3 in 3 seconds
2024-05-01T03:02:08	Informational	ppp	 [wan_link0] LCP: Down event
2024-05-01T03:02:08	Informational	ppp	 [wan_link0] Link: DOWN event
2024-05-01T03:02:08	Informational	ppp	 [wan_link0] PPPoE connection timeout after 9 seconds
2024-05-01T03:01:59	Informational	ppp	 [wan_link0] PPPoE: Connecting to ''
2024-05-01T03:01:59	Informational	ppp	 [wan_link0] Link: reconnection attempt 2
2024-05-01T03:01:58	Informational	ppp	 [wan_link0] Link: reconnection attempt 2 in 1 seconds
2024-05-01T03:01:58	Informational	ppp	 [wan_link0] LCP: Down event
2024-05-01T03:01:58	Informational	ppp	 [wan_link0] Link: DOWN event
2024-05-01T03:01:58	Informational	ppp	 [wan_link0] PPPoE connection timeout after 9 seconds
2024-05-01T03:01:49	Informational	ppp	 [wan_link0] PPPoE: Connecting to ''
2024-05-01T03:01:49	Informational	ppp	 [wan_link0] Link: reconnection attempt 1
2024-05-01T03:01:48	Informational	ppp	 [wan_link0] Link: reconnection attempt 1 in 1 seconds
2024-05-01T03:01:48	Informational	ppp	 [wan_link0] LCP: Down event
2024-05-01T03:01:48	Informational	ppp	 [wan_link0] Link: DOWN event
2024-05-01T03:01:48	Informational	ppp	 [wan_link0] PPPoE connection timeout after 9 seconds
2024-05-01T03:01:39	Informational	ppp	 [wan_link0] PPPoE: Connecting to ''
2024-05-01T03:01:39	Informational	ppp	 [wan_link0] LCP: LayerStart
2024-05-01T03:01:39	Informational	ppp	 [wan_link0] LCP: state change Initial --> Starting
2024-05-01T03:01:39	Informational	ppp	 [wan_link0] LCP: Open event
2024-05-01T03:01:39	Informational	ppp	 [wan_link0] Link: OPEN event
2024-05-01T03:01:39	Informational	ppp	 [wan] Bundle: Interface ng0 created
2024-05-01T03:01:39	Informational	ppp	 web: web is not running
2024-05-01T03:01:39	Informational	ppp	 MppcTestCap: can't create socket node: File exists
2024-05-01T03:01:39	Informational	ppp	 process 50021 started, version 5.9
2024-05-01T03:01:39	Informational	ppp	
2024-05-01T03:01:39	Informational	ppp	 Multi-link PPP daemon for FreeBSD
2024-04-30T03:02:58	Informational	ppp	 [wan] IFACE: Rename interface ng0 to pppoe0
2024-04-30T03:02:58	Informational	ppp	 [wan] IFACE: Up event
2024-04-30T03:02:58	Informational	ppp	 [wan]   <ip_redacted> -> <ip_redacted>
2024-04-30T03:02:58	Informational	ppp	 [wan] IPCP: LayerUp
2024-04-30T03:02:58	Informational	ppp	 [wan] IPCP: state change Ack-Sent --> Opened
2024-04-30T03:02:58	Informational	ppp	 [wan]   IPADDR <ip_redacted>
2024-04-30T03:02:58	Informational	ppp	 [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
2024-04-30T03:02:58	Informational	ppp	 [wan]   IPADDR <ip_redacted>
2024-04-30T03:02:58	Informational	ppp	 [wan] IPCP: SendConfigReq #3
2024-04-30T03:02:58	Informational	ppp	 [wan]     <ip_redacted> is OK
2024-04-30T03:02:58	Informational	ppp	 [wan]   IPADDR <ip_redacted>
2024-04-30T03:02:58	Informational	ppp	 [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
2024-04-30T03:02:58	Informational	ppp	 [wan]   IPADDR 0.0.0.0
2024-04-30T03:02:58	Informational	ppp	 [wan] IPCP: SendConfigReq #2
2024-04-30T03:02:58	Informational	ppp	 [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
2024-04-30T03:02:58	Informational	ppp	 [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
2024-04-30T03:02:58	Informational	ppp	 [wan] IPCP: state change Req-Sent --> Ack-Sent
2024-04-30T03:02:58	Informational	ppp	 [wan]   IPADDR <ip_redacted>
2024-04-30T03:02:58	Informational	ppp	 [wan] IPCP: SendConfigAck #79
2024-04-30T03:02:58	Informational	ppp	 [wan]     <ip_redacted> is OK
2024-04-30T03:02:58	Informational	ppp	 [wan]   IPADDR <ip_redacted>
2024-04-30T03:02:58	Informational	ppp	 [wan] IPCP: rec'd Configure Request #79 (Req-Sent)
2024-04-30T03:02:58	Informational	ppp	 [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
2024-04-30T03:02:58	Informational	ppp	 [wan]   IPADDR 0.0.0.0
2024-04-30T03:02:58	Informational	ppp	 [wan] IPCP: SendConfigReq #1
2024-04-30T03:02:58	Informational	ppp	 [wan] IPCP: state change Starting --> Req-Sent
2024-04-30T03:02:58	Informational	ppp	 [wan] IPCP: Up event
2024-04-30T03:02:58	Informational	ppp	 [wan] IPCP: LayerStart
2024-04-30T03:02:58	Informational	ppp	 [wan] IPCP: state change Initial --> Starting
2024-04-30T03:02:58	Informational	ppp	 [wan] IPCP: Open event
2024-04-30T03:02:58	Informational	ppp	 [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] Link: Join bundle "wan"
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] Link: Matched action 'bundle "wan" ""'
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] LCP: authorization successful
2024-04-30T03:02:58	Informational	ppp	 [wan_link0]   MESG: SRU=60000#SRD=275000#
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] PAP: rec'd ACK #1 len: 26
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] LCP: LayerUp
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] PAP: sending REQUEST #1 len: 56
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] PAP: using authname "XXXXXXXXXXX#XXXXXXXXXXX#[email protected]"
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] LCP: auth: peer wants PAP, I want nothing
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] LCP: state change Ack-Sent --> Opened
2024-04-30T03:02:58	Informational	ppp	 [wan_link0]   MAGICNUM 0xf64bb0da
2024-04-30T03:02:58	Informational	ppp	 [wan_link0]   MRU 1492
2024-04-30T03:02:58	Informational	ppp	 [wan_link0]   PROTOCOMP
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent)
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] LCP: state change Req-Sent --> Ack-Sent
2024-04-30T03:02:58	Informational	ppp	 [wan_link0]   MAGICNUM 0x24c2a1a9
2024-04-30T03:02:58	Informational	ppp	 [wan_link0]   AUTHPROTO PAP
2024-04-30T03:02:58	Informational	ppp	 [wan_link0]   MRU 1492
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] LCP: SendConfigAck #6
2024-04-30T03:02:58	Informational	ppp	 [wan_link0]   MAGICNUM 0x24c2a1a9
2024-04-30T03:02:58	Informational	ppp	 [wan_link0]   AUTHPROTO PAP
2024-04-30T03:02:58	Informational	ppp	 [wan_link0]   MRU 1492
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] LCP: rec'd Configure Request #6 (Req-Sent)
2024-04-30T03:02:58	Informational	ppp	 [wan_link0]   MAGICNUM 0xf64bb0da
2024-04-30T03:02:58	Informational	ppp	 [wan_link0]   MRU 1492
2024-04-30T03:02:58	Informational	ppp	 [wan_link0]   PROTOCOMP
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] LCP: SendConfigReq #1
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] LCP: state change Starting --> Req-Sent
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] LCP: Up event
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] Link: UP event
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] PPPoE: connection successful
2024-04-30T03:02:58	Informational	ppp	 PPPoE: rec'd ACNAME "LUNJ02"
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] PPPoE: Connecting to ''
2024-04-30T03:02:58	Informational	ppp	 [wan_link0] Link: reconnection attempt 7
2024-04-30T03:02:55	Informational	ppp	 [wan_link0] Link: reconnection attempt 7 in 3 seconds
2024-04-30T03:02:55	Informational	ppp	 [wan_link0] LCP: Down event
2024-04-30T03:02:55	Informational	ppp	 [wan_link0] Link: DOWN event
2024-04-30T03:02:55	Informational	ppp	 [wan_link0] PPPoE connection timeout after 9 seconds
2024-04-30T03:02:46	Informational	ppp	 [wan_link0] PPPoE: Connecting to ''
2024-04-30T03:02:46	Informational	ppp	 [wan_link0] Link: reconnection attempt 6
2024-04-30T03:02:44	Informational	ppp	 [wan_link0] Link: reconnection attempt 6 in 2 seconds
2024-04-30T03:02:44	Informational	ppp	 [wan_link0] LCP: Down event
2024-04-30T03:02:44	Informational	ppp	 [wan_link0] Link: DOWN event
2024-04-30T03:02:44	Informational	ppp	 [wan_link0] PPPoE connection timeout after 9 seconds
2024-04-30T03:02:35	Informational	ppp	 [wan_link0] PPPoE: Connecting to ''
2024-04-30T03:02:35	Informational	ppp	 [wan_link0] Link: reconnection attempt 5
2024-04-30T03:02:33	Informational	ppp	 [wan_link0] Link: reconnection attempt 5 in 1 seconds
2024-04-30T03:02:33	Informational	ppp	 [wan_link0] LCP: Down event
2024-04-30T03:02:33	Informational	ppp	 [wan_link0] Link: DOWN event
2024-04-30T03:02:33	Informational	ppp	 [wan_link0] PPPoE connection timeout after 9 seconds
2024-04-30T03:02:24	Informational	ppp	 [wan_link0] PPPoE: Connecting to ''
2024-04-30T03:02:24	Informational	ppp	 [wan_link0] Link: reconnection attempt 4
2024-04-30T03:02:21	Informational	ppp	 [wan_link0] Link: reconnection attempt 4 in 3 seconds
2024-04-30T03:02:21	Informational	ppp	 [wan_link0] LCP: Down event
2024-04-30T03:02:21	Informational	ppp	 [wan_link0] Link: DOWN event
2024-04-30T03:02:21	Informational	ppp	 [wan_link0] PPPoE connection timeout after 9 seconds
2024-04-30T03:02:12	Informational	ppp	 [wan_link0] PPPoE: Connecting to ''
2024-04-30T03:02:12	Informational	ppp	 [wan_link0] Link: reconnection attempt 3
2024-04-30T03:02:11	Informational	ppp	 [wan_link0] Link: reconnection attempt 3 in 1 seconds
2024-04-30T03:02:11	Informational	ppp	 [wan_link0] LCP: Down event
2024-04-30T03:02:11	Informational	ppp	 [wan_link0] Link: DOWN event
2024-04-30T03:02:11	Informational	ppp	 [wan_link0] PPPoE connection timeout after 9 seconds
2024-04-30T03:02:02	Informational	ppp	 [wan_link0] PPPoE: Connecting to ''
2024-04-30T03:02:02	Informational	ppp	 [wan_link0] Link: reconnection attempt 2
2024-04-30T03:02:00	Informational	ppp	 [wan_link0] Link: reconnection attempt 2 in 2 seconds
2024-04-30T03:02:00	Informational	ppp	 [wan_link0] LCP: Down event
2024-04-30T03:02:00	Informational	ppp	 [wan_link0] Link: DOWN event
2024-04-30T03:02:00	Informational	ppp	 [wan_link0] PPPoE connection timeout after 9 seconds
2024-04-30T03:01:51	Informational	ppp	 [wan_link0] PPPoE: Connecting to ''
2024-04-30T03:01:51	Informational	ppp	 [wan_link0] Link: reconnection attempt 1
2024-04-30T03:01:48	Informational	ppp	 [wan_link0] Link: reconnection attempt 1 in 3 seconds
2024-04-30T03:01:48	Informational	ppp	 [wan_link0] LCP: Down event
2024-04-30T03:01:48	Informational	ppp	 [wan_link0] Link: DOWN event
2024-04-30T03:01:48	Informational	ppp	 [wan_link0] PPPoE connection timeout after 9 seconds
2024-04-30T03:01:39	Informational	ppp	 [wan_link0] PPPoE: Connecting to ''
2024-04-30T03:01:39	Informational	ppp	 [wan_link0] LCP: LayerStart
2024-04-30T03:01:39	Informational	ppp	 [wan_link0] LCP: state change Initial --> Starting
2024-04-30T03:01:39	Informational	ppp	 [wan_link0] LCP: Open event
2024-04-30T03:01:39	Informational	ppp	 [wan_link0] Link: OPEN event
2024-04-30T03:01:39	Informational	ppp	 [wan] Bundle: Interface ng0 created
2024-04-30T03:01:39	Informational	ppp	 web: web is not running
2024-04-30T03:01:39	Informational	ppp	 process 23378 started, version 5.9
2024-04-30T03:01:39	Informational	ppp	
2024-04-30T03:01:39	Informational	ppp	 Multi-link PPP daemon for FreeBSD

Environment

  • OPNsense 24.1.6 (amd64)
  • Virtualized on Proxmox VE 8.x (two virtio nic's on separate bridges, one bridge 1:1 connected to ONT, second bridge connected to 'LAN')
  • ISP/Service: Telekom Fiber via VLAN7, directly connected to ONT (VLAN tagging within OPNsense)

bentalexanderhaase avatar May 01 '24 14:05 bentalexanderhaase

22.x to 24.x are thousands of commits. It’s impossible to trace this. Also I think for Telekom the nightly reconnect can still be the norm, maybe you simply didn’t notice before?

fichtner avatar May 01 '24 15:05 fichtner

22.x to 24.x are thousands of commits. It’s impossible to trace this. Also I think for Telekom the nightly reconnect can still be the norm, maybe you simply didn’t notice before?

Thank you for the swift response. I can delve into logs dating back at least one year, and this behavior distinctly emerged following the upgrades. Initially, I entertained the possibility of overlooking it previously, but the timing aligns precisely with the update. While it's plausible that some alteration occurred on the ISP's end coincidentally, it would seem rather arbitrary.

As for the nightly reconnect, it appears to deviate from the norm, at least in comparison to my other Telekom setups. Until now, this particular setup has mirrored others in its stability.

Could there be a new cronjob scheduled to run at that time?

bentalexanderhaase avatar May 01 '24 15:05 bentalexanderhaase

I won't rule out there is a possibility that something changed, but my main question is which operational problem arises that needs to be fixed?

If we are just looking for why this happened and how it can be avoided (personally I doubt it) this goes far beyond community support scope.

Cheers, Franco

fichtner avatar May 02 '24 07:05 fichtner