Error opnsense/usr/local/etc/rc.newwanip every 2 minutes

[bunchofreeds]

Hi,

Firstly I've just noticed this now in the System>Logs>General and not sure if this is new to 22.7.
I am seeing this error every two and a half minutes.
It's not causing any noticeable issue to connectivity.
I'm on OPNsense 22.7_4-amd64 running as a Proxmox VM.
I've hidden my real WAN IP address.

Anyone else seeing this or know how to resolve it?

2022-08-01T09:07:33   Notice   opnsense   plugins_configure hosts (execute task : unbound_hosts_generate())   
2022-08-01T09:07:33   Notice   opnsense   plugins_configure hosts (execute task : dnsmasq_hosts_generate())   
2022-08-01T09:07:33   Notice   opnsense   plugins_configure hosts ()   
2022-08-01T09:07:33   Error   opnsense   /usr/local/etc/rc.newwanip: On (IP address: 100.100.100.100) (interface: WAN[wan]) (real interface: vtnet1).   
2022-08-01T09:07:33   Error   opnsense   /usr/local/etc/rc.newwanip: IPv4 renewal is starting on 'vtnet1'   
2022-08-01T09:07:33   Notice   dhclient   Creating resolv.conf   
2022-08-01T09:05:04   Notice   opnsense   plugins_configure hosts (execute task : unbound_hosts_generate())   
2022-08-01T09:05:04   Notice   opnsense   plugins_configure hosts (execute task : dnsmasq_hosts_generate())   
2022-08-01T09:05:04   Notice   opnsense   plugins_configure hosts ()   
2022-08-01T09:05:04   Error   opnsense   /usr/local/etc/rc.newwanip: On (IP address: 100.100.100.100) (interface: WAN[wan]) (real interface: vtnet1).   
2022-08-01T09:05:04   Error   opnsense   /usr/local/etc/rc.newwanip: IPv4 renewal is starting on 'vtnet1'   
2022-08-01T09:05:03   Notice   dhclient   Creating resolv.conf   
2022-08-01T09:02:33   Notice   opnsense   plugins_configure hosts (execute task : unbound_hosts_generate())   
2022-08-01T09:02:33   Notice   opnsense   plugins_configure hosts (execute task : dnsmasq_hosts_generate())   
2022-08-01T09:02:33   Notice   opnsense   plugins_configure hosts ()   
2022-08-01T09:02:33   Error   opnsense   /usr/local/etc/rc.newwanip: On (IP address: 100.100.100.100) (interface: WAN[wan]) (real interface: vtnet1).   
2022-08-01T09:02:33   Error   opnsense   /usr/local/etc/rc.newwanip: IPv4 renewal is starting on 'vtnet1'   
2022-08-01T09:02:33   Notice   dhclient   Creating resolv.conf

[bunchofreeds]

My ISP uses DHCP
Is it possible this is the lease time and showing the renewal cycle?

Also I have been using the DynDNS plugin as well as 'tried' to use os-ddclient.
I'm just using the Legacy DynDNS for now.

[franco]

You can check lease time via i.e.

# cat /var/db/dhclient.leases.igb1


Cheers,
Franco

[bunchofreeds]

Thanks @franco

Does seem to be related to a regular renewal happening on my WAN DHCP.

Lease is set to 300 (5 Minutes)
  option dhcp-lease-time 300;

But the renewal and error happens every 2 and a half mins

I have had the same WAN IP for many Months

Is this supposed to show as an error in my System General Log?

[Scuro]

But the renewal and error happens every 2 and a half mins

That is standard behavior for DHCP. They always request renewal at half of lease.

[bunchofreeds]

Thanks @scuro

So looks to be a correct process.

Anyone know if this should be showing as an error? Is this correctly reporting in the logs?

Thanks

[Scuro]

You can check lease time via i.e.

# cat /var/db/dhclient.leases.igb1

I didn't have that file but I did have dhclient.leases.igc0.
Am I supposed to have 13 leases in that file all of them with the same interface and address?

I have one of these for every hour

Code Select Expand

lease {
  interface "igc0";
  fixed-address 67.###.##.35;
  option subnet-mask 255.255.252.0;
  option routers 67.###.##.1;
  option domain-name-servers 75.75.75.75,75.75.76.76;
  option host-name "scuros-fw";
  option domain-name "##.##.comcast.net.";
  option broadcast-address 255.255.255.255;
  option dhcp-lease-time 3600;
  option dhcp-message-type 5;
  option dhcp-server-identifier 96.###.###.143;
  option dhcp-renewal-time 657;
  option dhcp-rebinding-time 2700;
  renew 1 2022/8/1 19:55:10;
  rebind 1 2022/8/1 20:03:21;
  expire 1 2022/8/1 20:44:13;
}

[bunchofreeds]

@Scuro do you have DHCP for your WAN and it's on interface igc0?

My WAN interface is vtnet1 so I used # cat /var/db/dhclient.leases.vtnet1
I'm assuming this was the correct thing to do :)

Are you able to see if you have an error in your OPNsense>System>General logs every 30 mins similar to this:

2022-08-01T09:07:33   Error   opnsense   /usr/local/etc/rc.newwanip: On (IP address: 100.100.100.100) (interface: WAN[wan]) (real interface: vtnet1).   
2022-08-01T09:07:33   Error   opnsense   /usr/local/etc/rc.newwanip: IPv4 renewal is starting on 'vtnet1'


Thanks

[Scuro]

@Scuro do you have DHCP for your WAN and it's on interface igc0?

Yes and I had made a similar thread. https://forum.opnsense.org/index.php?topic=29571.0

The error varies between 10 minutes to an hour.

[franco]

A probable solution was posted here not too long ago:

https://forum.opnsense.org/index.php?topic=29556.msg143071#msg143071


Cheers,
Franco

[bunchofreeds]

That solved it!

Thanks Franco!

[franco]

No problem, thanks for your structured report!


Cheers,
Franco