search

opnsense / src

OPNsense operating system on top of FreeBSD
https://opnsense.org/
Other
356 stars 151 forks source link

nd6_dad_timer: called with non-tentative address #164

Closed janwiesemann closed 8 months ago

janwiesemann commented 2 years ago

Describe the bug

A few weeks ago I've notices that my backups for Proxmox are constantly failing. Interestingly just for a specific VM.

While the backup is uploading my OPNsense is dropping its WAN-Connection and thus killing my backup upload. OPNsense is running on a different node and is not backup at the same time. Noting interesting is shown inside the system.log. Just on the screen. A screenshot is attached below. It sows the message

nd6_dad_timer: called with non-tentative address fe80:9::28c:6cff:feßa: 1319 (pppoe)

I have absolutely no idea what this is related to. DuckDuckGo didn't gave me any useful information. Also no-one has commented on my previous reddit posts at r/OPNsense and r/OPNsenseFirewall.

To Reproduce

I have absolutely no idea how to reproduce this.

Expected behavior

Not dropping the WAN-Connection.

Describe alternatives you considered

None. I don't even know what this error Is trying to tell me.

Screenshots

Console:

Screenshot 2022-06-07 at 18 47 56

Relevant log files

Proxmox log:

111: 2022-06-07 18:10:35 INFO: Starting Backup of VM 111 (qemu)
111: 2022-06-07 18:10:35 INFO: status = running
111: 2022-06-07 18:10:35 INFO: VM Name: HomeAssisstant
111: 2022-06-07 18:10:35 INFO: include disk 'sata0' 'SSD:vm-111-disk-1' 32G
111: 2022-06-07 18:10:35 INFO: include disk 'efidisk0' 'SSD:vm-111-disk-0' 4M
111: 2022-06-07 18:10:36 INFO: backup mode: snapshot
111: 2022-06-07 18:10:36 INFO: ionice priority: 7
111: 2022-06-07 18:10:36 INFO: snapshots found (not included into backup)
111: 2022-06-07 18:10:36 INFO: creating Proxmox Backup Server archive 'vm/111/2022-06-07T16:10:35Z'
111: 2022-06-07 18:10:36 INFO: issuing guest-agent 'fs-freeze' command
111: 2022-06-07 18:10:38 INFO: issuing guest-agent 'fs-thaw' command
111: 2022-06-07 18:10:39 INFO: started backup task '47e6b8e6-b1c0-4a13-b6e0-897fd7b42adf'
111: 2022-06-07 18:10:39 INFO: resuming VM again
111: 2022-06-07 18:10:39 INFO: efidisk0: dirty-bitmap status: OK (drive clean)
111: 2022-06-07 18:10:39 INFO: sata0: dirty-bitmap status: OK (3.2 GiB of 32.0 GiB dirty)
111: 2022-06-07 18:10:39 INFO: using fast incremental mode (dirty-bitmap), 3.2 GiB dirty of 32.0 GiB total
111: 2022-06-07 18:10:42 INFO:   5% (192.0 MiB of 3.2 GiB) in 3s, read: 64.0 MiB/s, write: 62.7 MiB/s
111: 2022-06-07 18:10:45 INFO:   7% (240.0 MiB of 3.2 GiB) in 6s, read: 16.0 MiB/s, write: 14.7 MiB/s
111: 2022-06-07 18:10:50 INFO:   8% (264.0 MiB of 3.2 GiB) in 11s, read: 4.8 MiB/s, write: 4.8 MiB/s
111: 2022-06-07 18:10:53 INFO:   9% (296.0 MiB of 3.2 GiB) in 14s, read: 10.7 MiB/s, write: 10.7 MiB/s
111: 2022-06-07 18:10:58 INFO:  10% (332.0 MiB of 3.2 GiB) in 19s, read: 7.2 MiB/s, write: 7.2 MiB/s
111: 2022-06-07 18:11:01 INFO:  11% (384.0 MiB of 3.2 GiB) in 22s, read: 17.3 MiB/s, write: 17.3 MiB/s
111: 2022-06-07 18:11:04 INFO:  12% (408.0 MiB of 3.2 GiB) in 25s, read: 8.0 MiB/s, write: 8.0 MiB/s
111: 2022-06-07 18:11:07 INFO:  14% (468.0 MiB of 3.2 GiB) in 28s, read: 20.0 MiB/s, write: 20.0 MiB/s
111: 2022-06-07 18:11:10 INFO:  15% (512.0 MiB of 3.2 GiB) in 31s, read: 14.7 MiB/s, write: 14.7 MiB/s
111: 2022-06-07 18:11:13 INFO:  17% (572.0 MiB of 3.2 GiB) in 34s, read: 20.0 MiB/s, write: 20.0 MiB/s
111: 2022-06-07 18:11:16 INFO:  19% (620.0 MiB of 3.2 GiB) in 37s, read: 16.0 MiB/s, write: 16.0 MiB/s
111: 2022-06-07 18:11:19 INFO:  20% (660.0 MiB of 3.2 GiB) in 40s, read: 13.3 MiB/s, write: 13.3 MiB/s
111: 2022-06-07 18:11:22 INFO:  21% (704.0 MiB of 3.2 GiB) in 43s, read: 14.7 MiB/s, write: 14.7 MiB/s
111: 2022-06-07 18:11:25 INFO:  22% (740.0 MiB of 3.2 GiB) in 46s, read: 12.0 MiB/s, write: 12.0 MiB/s
111: 2022-06-07 18:11:28 INFO:  24% (784.0 MiB of 3.2 GiB) in 49s, read: 14.7 MiB/s, write: 14.7 MiB/s
111: 2022-06-07 18:11:31 INFO:  26% (852.0 MiB of 3.2 GiB) in 52s, read: 22.7 MiB/s, write: 22.7 MiB/s
111: 2022-06-07 18:11:35 INFO:  27% (900.0 MiB of 3.2 GiB) in 56s, read: 12.0 MiB/s, write: 12.0 MiB/s
111: 2022-06-07 18:11:38 INFO:  29% (960.0 MiB of 3.2 GiB) in 59s, read: 20.0 MiB/s, write: 20.0 MiB/s
111: 2022-06-07 18:11:41 INFO:  30% (988.0 MiB of 3.2 GiB) in 1m 2s, read: 9.3 MiB/s, write: 9.3 MiB/s
111: 2022-06-07 18:11:44 INFO:  31% (1016.0 MiB of 3.2 GiB) in 1m 5s, read: 9.3 MiB/s, write: 9.3 MiB/s
111: 2022-06-07 18:11:47 INFO:  32% (1.0 GiB of 3.2 GiB) in 1m 8s, read: 10.7 MiB/s, write: 10.7 MiB/s
111: 2022-06-07 18:11:50 INFO:  33% (1.1 GiB of 3.2 GiB) in 1m 11s, read: 10.7 MiB/s, write: 10.7 MiB/s
111: 2022-06-07 18:11:53 INFO:  34% (1.1 GiB of 3.2 GiB) in 1m 14s, read: 14.7 MiB/s, write: 14.7 MiB/s
111: 2022-06-07 18:11:56 INFO:  37% (1.2 GiB of 3.2 GiB) in 1m 17s, read: 33.3 MiB/s, write: 33.3 MiB/s
111: 2022-06-07 18:11:59 INFO:  38% (1.2 GiB of 3.2 GiB) in 1m 20s, read: 12.0 MiB/s, write: 12.0 MiB/s
111: 2022-06-07 18:12:02 INFO:  39% (1.3 GiB of 3.2 GiB) in 1m 23s, read: 8.0 MiB/s, write: 8.0 MiB/s
111: 2022-06-07 18:12:05 INFO:  41% (1.3 GiB of 3.2 GiB) in 1m 26s, read: 21.3 MiB/s, write: 21.3 MiB/s
111: 2022-06-07 18:12:08 INFO:  42% (1.4 GiB of 3.2 GiB) in 1m 29s, read: 14.7 MiB/s, write: 13.3 MiB/s
111: 2022-06-07 18:12:11 INFO:  44% (1.4 GiB of 3.2 GiB) in 1m 32s, read: 18.7 MiB/s, write: 18.7 MiB/s
111: 2022-06-07 18:12:14 INFO:  46% (1.5 GiB of 3.2 GiB) in 1m 35s, read: 26.7 MiB/s, write: 26.7 MiB/s
111: 2022-06-07 18:12:17 INFO:  48% (1.5 GiB of 3.2 GiB) in 1m 38s, read: 18.7 MiB/s, write: 18.7 MiB/s
111: 2022-06-07 18:12:20 INFO:  49% (1.6 GiB of 3.2 GiB) in 1m 41s, read: 14.7 MiB/s, write: 14.7 MiB/s
111: 2022-06-07 18:12:23 INFO:  52% (1.7 GiB of 3.2 GiB) in 1m 44s, read: 25.3 MiB/s, write: 25.3 MiB/s
111: 2022-06-07 18:12:34 INFO:  52% (1.7 GiB of 3.2 GiB) in 1m 55s, read: 1.1 MiB/s, write: 1.1 MiB/s
111: 2022-06-07 18:12:34 ERROR: backup write data failed: command error: protocol canceled
111: 2022-06-07 18:12:34 INFO: aborting backup job
111: 2022-06-07 18:12:34 INFO: resuming VM again
111: 2022-06-07 18:12:34 ERROR: Backup of VM 111 failed - backup write data failed: command error: protocol canceled

OPNsense log: 

<13>1 2022-06-07T15:57:03+00:00 OPNsense.localdomain /update_tables.py 34957 - [meta sequenceId="1"] resolving 1 hostnames (2 addresses) for ALIASXXX took 0.17 seconds
<13>1 2022-06-07T16:02:05+00:00 OPNsense.localdomain /update_tables.py 30397 - [meta sequenceId="1"] resolving 1 hostnames (2 addresses) for ALIASXXX took 0.19 seconds
<13>1 2022-06-07T16:08:03+00:00 OPNsense.localdomain /update_tables.py 48917 - [meta sequenceId="1"] resolving 1 hostnames (2 addresses) for ALIASXXX took 0.19 seconds
<11>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="1"] /usr/local/etc/rc.newwanip: IPv4 renewal is starting on 'pppoe0'
<11>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="2"] /usr/local/etc/rc.newwanip: On (IP address: XXX.XXX.XXX.XXX) (interface: WAN[opt1]) (real interface: pppoe0).
<13>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="3"] plugins_configure hosts ()
<13>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="4"] plugins_configure hosts (execute task : dnsmasq_hosts_generate())
<13>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="5"] plugins_configure hosts (execute task : unbound_hosts_generate())
<11>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 42608 - [meta sequenceId="6"] /usr/local/etc/rc.newwanipv6: IPv6 renewal is starting on 'pppoe0'
<11>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 42608 - [meta sequenceId="7"] /usr/local/etc/rc.newwanipv6: On (IP address: ) (interface: WAN[opt1]) (real interface: pppoe0).
<11>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 42608 - [meta sequenceId="8"] /usr/local/etc/rc.newwanipv6: Failed to detect IP for WAN[opt1]
<13>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain dhcp6c 52607 - [meta sequenceId="9"] RTSOLD script - Sending SIGHUP to dhcp6c
<11>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="10"] /usr/local/etc/rc.newwanip: ROUTING: entering configure using 'opt1'
<11>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="11"] /usr/local/etc/rc.newwanip: ROUTING: IPv4 default gateway set to opt1
<11>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="12"] /usr/local/etc/rc.newwanip: ROUTING: setting IPv4 default route toXXX.XXX.XXX.XXX
<11>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="13"] /usr/local/etc/rc.newwanip: ROUTING: keeping current default gateway 'XXX.XXX.XXX.XXX'
<11>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="14"] /usr/local/etc/rc.newwanip: ROUTING: IPv6 default gateway set to opt1
<11>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="15"] /usr/local/etc/rc.newwanip: ROUTING: setting IPv6 default route to fe80::2a8a:1cff:feef:c243
<11>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="16"] /usr/local/etc/rc.newwanip: ROUTING: keeping current default gateway 'fe80::2a8a:1cff:feef:c243%pppoe0'
<13>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="17"] plugins_configure monitor ()
<13>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="18"] plugins_configure monitor (execute task : dpinger_configure_do())
<11>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="19"] /usr/local/etc/rc.newwanip: The WAN_DHCP6 monitor address is empty, skipping.
<11>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="20"] /usr/local/etc/rc.newwanip: The WAN_PPPOE monitor address is empty, skipping.
<13>1 2022-06-07T16:12:29+00:00 OPNsense.localdomain dhcp6c 62501 - [meta sequenceId="21"] RTSOLD script - Sending SIGHUP to dhcp6c
<13>1 2022-06-07T16:12:31+00:00 OPNsense.localdomain dhcp6c 75443 - [meta sequenceId="22"] dhcp6c REQUEST on pppoe0 - running newipv6
<11>1 2022-06-07T16:12:31+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="23"] /usr/local/etc/rc.newwanipv6: IPv6 renewal is starting on 'pppoe0'
<11>1 2022-06-07T16:12:31+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="24"] /usr/local/etc/rc.newwanipv6: On (IP address: XXXX:XXXX:XXXX:1cff:20c:6cff:fe0a:1319) (interface: WAN[opt1]) (real interface: pppoe0).
<13>1 2022-06-07T16:12:31+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="25"] plugins_configure hosts ()
<13>1 2022-06-07T16:12:31+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="26"] plugins_configure hosts (execute task : dnsmasq_hosts_generate())
<13>1 2022-06-07T16:12:31+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="27"] plugins_configure hosts (execute task : unbound_hosts_generate())
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="28"] /usr/local/etc/rc.newwanipv6: ROUTING: entering configure using 'opt1'
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="29"] /usr/local/etc/rc.newwanipv6: ROUTING: IPv4 default gateway set to opt1
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="30"] /usr/local/etc/rc.newwanipv6: ROUTING: setting IPv4 default route toXXX.XXX.XXX.XXX
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="31"] /usr/local/etc/rc.newwanipv6: ROUTING: keeping current default gateway 'XXX.XXX.XXX.XXX'
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="32"] /usr/local/etc/rc.newwanipv6: ROUTING: IPv6 default gateway set to opt1
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="33"] /usr/local/etc/rc.newwanipv6: ROUTING: setting IPv6 default route to fe80::2a8a:1cff:feef:c243
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="34"] /usr/local/etc/rc.newwanipv6: ROUTING: keeping current default gateway 'fe80::2a8a:1cff:feef:c243%pppoe0'
<13>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="35"] plugins_configure monitor ()
<13>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="36"] plugins_configure monitor (execute task : dpinger_configure_do())
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="37"] /usr/local/etc/rc.newwanipv6: The WAN_DHCP6 monitor address is empty, skipping.
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="38"] /usr/local/etc/rc.newwanipv6: The WAN_PPPOE monitor address is empty, skipping.
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="39"] /usr/local/etc/rc.newwanip: IP address change detected, killing states of old ip 79.252.211.220
<13>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="40"] plugins_configure vpn (,opt1)
<13>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="41"] plugins_configure vpn (execute task : ipsec_configure_do(,opt1))
<13>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="42"] plugins_configure vpn (execute task : openvpn_configure_do(,opt1))
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="43"] /usr/local/etc/rc.newwanip: Resyncing OpenVPN instances for interface WAN.
<13>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="44"] plugins_configure newwanip (,opt1)
<13>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="45"] plugins_configure newwanip (execute task : dyndns_configure_do(,opt1))
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="46"] /usr/local/etc/rc.newwanip: Dynamic DNS: updatedns() starting
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="47"] /usr/local/etc/rc.newwanip: Dynamic DNS (XXX.de): XXX.XXX.XXX.XXX extracted
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="48"] /usr/local/etc/rc.newwanip: Dynamic DNS (XXX.de): running dyndns_failover_interface for opt1. found pppoe0
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="49"] /usr/local/etc/rc.newwanip: Dynamic DNS (XXX.de via STRATO): _update() starting.
<13>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain dhcp6c 7163 - [meta sequenceId="50"] dhcp6c RELEASE on pppoe0 - running dns reload
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="51"] /usr/local/etc/rc.newwanip: Dynamic DNS (XXX.de): _checkStatus() starting.
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="52"] /usr/local/etc/rc.newwanip: Dynamic DNS (XXX.de): Current Service: strato
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="53"] /usr/local/etc/rc.newwanip: Dynamic DNS (XXX.de): XXX.XXX.XXX.XXX extracted
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="54"] /usr/local/etc/rc.newwanip: Dynamic DNS: updating cache file /var/cache/dyndns_opt1_XXX.de_0.cache: XXX.XXX.XXX.XXX
<11>1 2022-06-07T16:12:32+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="55"] /usr/local/etc/rc.newwanip: Dynamic DNS: (Success) IP address updated successfully (XXX.XXX.XXX.XXX)
<11>1 2022-06-07T16:12:33+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="56"] /usr/local/etc/rc.newwanip: Dynamic DNS: updatedns() starting
<11>1 2022-06-07T16:12:33+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="57"] /usr/local/etc/rc.newwanip: Dynamic DNS (XXX.de): XXXX:XXXX:XXXX:1cff:20c:6cff:fe0a:1319 extracted
<11>1 2022-06-07T16:12:33+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="58"] /usr/local/etc/rc.newwanip: Dynamic DNS (XXX.de): running dyndns_failover_interface for opt1. found pppoe0
<11>1 2022-06-07T16:12:33+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="59"] /usr/local/etc/rc.newwanip: Dynamic DNS (XXX.de): XXXX:XXXX:XXXX:1cff:20c:6cff:fe0a:1319 extracted
<11>1 2022-06-07T16:12:33+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="60"] /usr/local/etc/rc.newwanip: Dynamic DNS (XXX.de): Current WAN IP: XXXX:XXXX:XXXX:1cff:20c:6cff:fe0a:1319 Cached IPv6: XXXX:XXXX:XXXX:1942:20c:6cff:fe0a:1319 
<11>1 2022-06-07T16:12:33+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="61"] /usr/local/etc/rc.newwanip: Dynamic DNS (XXX.de): Dynamic DNS: cacheIP != wan_ip.  Updating. Cached IP: XXXX:XXXX:XXXX:1942:20c:6cff:fe0a:1319 WAN IP: XXXX:XXXX:XXXX:1cff:20c:6cff:fe0a:1319 
<11>1 2022-06-07T16:12:33+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="62"] /usr/local/etc/rc.newwanip: Dynamic DNS (XXX.de via STRATO (v6)): _update() starting.
<11>1 2022-06-07T16:12:33+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="63"] /usr/local/etc/rc.newwanip: Dynamic DNS (XXX.de): _checkStatus() starting.
<11>1 2022-06-07T16:12:33+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="64"] /usr/local/etc/rc.newwanip: Dynamic DNS (XXX.de): Current Service: strato-v6
<11>1 2022-06-07T16:12:33+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="65"] /usr/local/etc/rc.newwanip: Dynamic DNS (XXX.de): XXXX:XXXX:XXXX:1cff:20c:6cff:fe0a:1319 extracted
<11>1 2022-06-07T16:12:33+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="66"] /usr/local/etc/rc.newwanip: Dynamic DNS: updating cache file /var/cache/dyndns_opt1_XXX.de_1_v6.cache: XXXX:XXXX:XXXX:1cff:20c:6cff:fe0a:1319
<11>1 2022-06-07T16:12:33+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="67"] /usr/local/etc/rc.newwanip: Dynamic DNS: (Success) IP address updated successfully (XXXX:XXXX:XXXX:1cff:20c:6cff:fe0a:1319)
<13>1 2022-06-07T16:12:34+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="68"] plugins_configure vpn (,opt1)
<13>1 2022-06-07T16:12:34+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="69"] plugins_configure vpn (execute task : ipsec_configure_do(,opt1))
<13>1 2022-06-07T16:12:34+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="70"] plugins_configure vpn (execute task : openvpn_configure_do(,opt1))
<11>1 2022-06-07T16:12:34+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="71"] /usr/local/etc/rc.newwanipv6: Resyncing OpenVPN instances for interface WAN.
<13>1 2022-06-07T16:12:34+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="72"] plugins_configure newwanip (,opt1)
<13>1 2022-06-07T16:12:34+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="73"] plugins_configure newwanip (execute task : dyndns_configure_do(,opt1))
<11>1 2022-06-07T16:12:34+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="74"] /usr/local/etc/rc.newwanipv6: Dynamic DNS: updatedns() starting
<11>1 2022-06-07T16:12:34+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="75"] /usr/local/etc/rc.newwanipv6: Dynamic DNS (XXX.de): XXX.XXX.XXX.XXX extracted
<11>1 2022-06-07T16:12:34+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="76"] /usr/local/etc/rc.newwanipv6: Dynamic DNS (XXX.de): running dyndns_failover_interface for opt1. found pppoe0
<11>1 2022-06-07T16:12:34+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="77"] /usr/local/etc/rc.newwanipv6: Dynamic DNS (XXX.de via STRATO): _update() starting.
<13>1 2022-06-07T16:12:34+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="78"] plugins_configure newwanip (execute task : ntpd_configure_do())
<11>1 2022-06-07T16:12:34+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="79"] /usr/local/etc/rc.newwanipv6: Dynamic DNS (XXX.de): _checkStatus() starting.
<11>1 2022-06-07T16:12:34+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="80"] /usr/local/etc/rc.newwanipv6: Dynamic DNS (XXX.de): Current Service: strato
<13>1 2022-06-07T16:12:34+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="81"] plugins_configure newwanip (execute task : opendns_configure_do())
<13>1 2022-06-07T16:12:34+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="82"] plugins_configure newwanip (execute task : openssh_configure_do(,opt1))
<13>1 2022-06-07T16:12:34+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="83"] plugins_configure newwanip (execute task : unbound_configure_do(,opt1))
<11>1 2022-06-07T16:12:35+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="84"] /usr/local/etc/rc.newwanipv6: Dynamic DNS (XXX.de): XXX.XXX.XXX.XXX extracted
<11>1 2022-06-07T16:12:35+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="85"] /usr/local/etc/rc.newwanipv6: Dynamic DNS: updating cache file /var/cache/dyndns_opt1_XXX.de_0.cache: XXX.XXX.XXX.XXX
<11>1 2022-06-07T16:12:35+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="86"] /usr/local/etc/rc.newwanipv6: Dynamic DNS: (Success) No change in IP address
<11>1 2022-06-07T16:12:36+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="87"] /usr/local/etc/rc.newwanipv6: Dynamic DNS: updatedns() starting
<11>1 2022-06-07T16:12:36+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="88"] /usr/local/etc/rc.newwanipv6: Dynamic DNS (XXX.de): XXXX:XXXX:XXXX:1cff:20c:6cff:fe0a:1319 extracted
<11>1 2022-06-07T16:12:36+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="89"] /usr/local/etc/rc.newwanipv6: Dynamic DNS (XXX.de): running dyndns_failover_interface for opt1. found pppoe0
<11>1 2022-06-07T16:12:36+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="90"] /usr/local/etc/rc.newwanipv6: Dynamic DNS (XXX.de): XXXX:XXXX:XXXX:1cff:20c:6cff:fe0a:1319 extracted
<11>1 2022-06-07T16:12:36+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="91"] /usr/local/etc/rc.newwanipv6: Dynamic DNS (XXX.de): Current WAN IP: XXXX:XXXX:XXXX:1cff:20c:6cff:fe0a:1319 Cached IPv6: XXXX:XXXX:XXXX:1cff:20c:6cff:fe0a:1319 
<11>1 2022-06-07T16:12:36+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="92"] /usr/local/etc/rc.newwanipv6: Dynamic DNS (XXX.de): No change in my IP address and/or 25 days has not passed. Not updating dynamic DNS entry.
<13>1 2022-06-07T16:12:37+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="93"] plugins_configure newwanip (execute task : ntpd_configure_do())
<13>1 2022-06-07T16:12:38+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="94"] plugins_configure newwanip (execute task : vxlan_configure_interface())
<13>1 2022-06-07T16:12:38+00:00 OPNsense.localdomain opnsense 33309 - [meta sequenceId="95"] plugins_configure newwanip (execute task : webgui_configure_do(,opt1))
<13>1 2022-06-07T16:12:41+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="96"] plugins_configure newwanip (execute task : opendns_configure_do())
<13>1 2022-06-07T16:12:41+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="97"] plugins_configure newwanip (execute task : openssh_configure_do(,opt1))
<13>1 2022-06-07T16:12:41+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="98"] plugins_configure newwanip (execute task : unbound_configure_do(,opt1))
<13>1 2022-06-07T16:12:44+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="99"] plugins_configure newwanip (execute task : vxlan_configure_interface())
<13>1 2022-06-07T16:12:44+00:00 OPNsense.localdomain opnsense 79956 - [meta sequenceId="100"] plugins_configure newwanip (execute task : webgui_configure_do(,opt1))
<13>1 2022-06-07T16:13:03+00:00 OPNsense.localdomain /update_tables.py 3898 - [meta sequenceId="101"] remove old alias __automatic_fa196dd0_0
<13>1 2022-06-07T16:13:03+00:00 OPNsense.localdomain /update_tables.py 3898 - [meta sequenceId="102"] remove old alias __automatic_5a71eb0a_0
<13>1 2022-06-07T16:14:03+00:00 OPNsense.localdomain /update_tables.py 96695 - [meta sequenceId="103"] resolving 1 hostnames (2 addresses) for ALIASXXX took 0.21 seconds

Additional context

Potatoes are amazing

Environment

OPNsense 22.1.8_1 (amd64, OpenSSL). KVM Guest via Proxmox (On a separate node. My backups are failing on a different one.)

fichtner commented 8 months ago

Closing stale issue