opnsense / core

OPNsense GUI, API and systems backend
https://opnsense.org/
BSD 2-Clause "Simplified" License
3.23k stars 721 forks source link

Sudden nightly reconnect using PPPoE since version 24.1.6 #7425

Open BentHaase opened 4 months ago

BentHaase commented 4 months ago

Important notices

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

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#0001@t-online.de"
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#0001@t-online.de"
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

fichtner commented 4 months ago

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?

BentHaase commented 4 months ago

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?

fichtner commented 4 months ago

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