Default ipv4 route drop when WAN dhcp renew

Started by skool, February 06, 2023, 02:02:42 PM

Previous topic - Next topic
Quote from: franco on February 21, 2023, 09:11:37 AM
Ok, so here is a temporary workaround and a debug change to see more of what is going on inside dhclient-script:

https://github.com/opnsense/core/commit/26d26e2054

# opnsense-patch 26d26e2054

Sorry no log, but it seems to have solved the problem on my side.

February 23, 2023, 08:54:55 PM #31 Last Edit: February 23, 2023, 09:00:36 PM by Bytechanger
sudo opnsense-patch 26d26e2054

For me works better, but disconnect furthermore for short time!
No IP change but broke connection

2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (execute task : dpinger_configure_do(,WAN_GLASFASER_DHCP))
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (,WAN_GLASFASER_DHCP)
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (execute task : dpinger_configure_do(,WAN_GLASFASER_DHCP6))
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (,WAN_GLASFASER_DHCP6)
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: keeping current default gateway 'fe80::ff:XXXX:202%vtnet0'
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: setting IPv6 default route to fe80::ff:XXXX:202
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: IPv6 default gateway set to opt4
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: setting IPv4 default route to 100.111.0.1
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: IPv4 default gateway set to opt4
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: entering configure using 'opt4'
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: No IP change detected for wan[opt4]
2023-02-23T20:43:49 Notice dhclient Creating resolv.conf
2023-02-23T20:43:49 Notice dhclient New Routers (vtnet0): 100.111.0.1
2023-02-23T20:43:49 Notice dhclient New Broadcast Address (vtnet0): 100.111.255.255
2023-02-23T20:43:49 Notice dhclient New Subnet Mask (vtnet0): 255.255.0.0
2023-02-23T20:43:49 Notice dhclient New IP Address (vtnet0): 100.111.1XX.83
2023-02-23T20:43:49 Notice dhclient DEBUG calling add_new_address/add_new_routes
2023-02-23T20:43:49 Notice dhclient DEBUG alias_ip_address:
2023-02-23T20:43:49 Notice dhclient DEBUG new_ip_address: 100.111.1XX.83
2023-02-23T20:43:49 Notice dhclient DEBUG old_ip_address: 100.111.1XX.83
2023-02-23T20:43:49 Notice dhclient DEBUG entering with BOUND
2023-02-23T20:43:00 Notice send_telemetry.py telemetry data collected 1 records in 0.04 seconds @2023-02-23 19:42:43.193093
2023-02-23T20:11:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-23 19:10:53.646493
2023-02-23T19:42:30 Notice dhclient Creating resolv.conf
2023-02-23T19:42:30 Notice dhclient DEBUG alias_ip_address:
2023-02-23T19:42:30 Notice dhclient DEBUG new_ip_address: 100.111.1XX.83
2023-02-23T19:42:30 Notice dhclient DEBUG old_ip_address: 100.111.1XX.83
2023-02-23T19:42:30 Notice dhclient DEBUG entering with RENEW
2023-02-23T18:47:11 Notice dhclient Creating resolv.conf
2023-02-23T18:47:11 Notice dhclient DEBUG alias_ip_address:
2023-02-23T18:47:11 Notice dhclient DEBUG new_ip_address: 100.111.1XX.83
2023-02-23T18:47:11 Notice dhclient DEBUG old_ip_address: 100.111.1XX.83
2023-02-23T18:47:11 Notice dhclient DEBUG entering with RENEW
2023-02-23T18:16:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-23 17:15:07.410136
2023-02-23T17:53:54 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (execute task : dpinger_configure_do(,WAN_GLASFASER_DHCP))
2023-02-23T17:53:54 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (,WAN_GLASFASER_DHCP)
2023-02-23T17:53:54 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (execute task : dpinger_configure_do(,WAN_GLASFASER_DHCP6))
2023-02-23T17:53:54 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (,WAN_GLASFASER_DHCP6)
2023-02-23T17:53:54 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: keeping current default gateway 'fe80::ff:XXXX:202%vtnet0'
2023-02-23T17:53:54 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: setting IPv6 default route to fe80::ff:XXXX:202
2023-02-23T17:53:54 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: IPv6 default gateway set to opt4
2023-02-23T17:53:54 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: setting IPv4 default route to 100.111.0.1
2023-02-23T17:53:54 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: IPv4 default gateway set to opt4
2023-02-23T17:53:54 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: entering configure using 'opt4'
2023-02-23T17:53:54 Notice opnsense /usr/local/etc/rc.newwanip: No IP change detected for wan[opt4]
2023-02-23T17:53:54 Notice dhclient Creating resolv.conf
2023-02-23T17:53:54 Notice dhclient New Routers (vtnet0): 100.111.0.1
2023-02-23T17:53:54 Notice dhclient New Broadcast Address (vtnet0): 100.111.255.255
2023-02-23T17:53:54 Notice dhclient New Subnet Mask (vtnet0): 255.255.0.0
2023-02-23T17:53:54 Notice dhclient New IP Address (vtnet0): 100.111.1XX.83
2023-02-23T17:53:54 Notice dhclient DEBUG calling add_new_address/add_new_routes
2023-02-23T17:53:54 Notice dhclient DEBUG alias_ip_address:
2023-02-23T17:53:54 Notice dhclient DEBUG new_ip_address: 100.111.1XX.83
2023-02-23T17:53:54 Notice dhclient DEBUG old_ip_address:
2023-02-23T17:53:54 Notice dhclient DEBUG entering with BOUND
2023-02-23T16:52:23 Notice dhclient Creating resolv.conf
2023-02-23T16:52:23 Notice dhclient DEBUG alias_ip_address:
2023-02-23T16:52:23 Notice dhclient DEBUG new_ip_address: 100.111.1XX.83
2023-02-23T16:52:23 Notice dhclient DEBUG old_ip_address: 100.111.1XX.83
2023-02-23T16:52:23 Notice dhclient DEBUG entering with RENEW
2023-02-23T15:57:05 Notice dhclient Creating resolv.conf
2023-02-23T15:57:05 Notice dhclient DEBUG alias_ip_address:
2023-02-23T15:57:05 Notice dhclient DEBUG new_ip_address: 100.111.1XX.83
2023-02-23T15:57:05 Notice dhclient DEBUG old_ip_address: 100.111.1XX.83
2023-02-23T15:57:05 Notice dhclient DEBUG entering with RENEW
2023-02-23T15:47:56 Notice configctl event @ 1677163676.34 exec: system event config_changed
2023-02-23T15:47:56 Notice configctl event @ 1677163676.34 msg: Feb 23 15:47:56 OPNsense.localdomain config[4124]: [2023-02-23T15:47:56+01:00][INFO] config-event: new_config /conf/backup/config-1677163676.319.xml
2023-02-23T15:47:56 Notice configctl event @ 1677163676.34 msg: Feb 23 15:47:55 OPNsense.localdomain config[4124]: [2023-02-23T15:47:55+01:00][INFO] config-event: new_config /conf/backup/config-1677163675.8195.xml
2023-02-23T15:47:55 Notice configctl event @ 1677163674.68 exec: system event config_changed
2023-02-23T15:47:55 Notice configctl event @ 1677163674.68 msg: Feb 23 15:47:54 OPNsense.localdomain config[4124]: [2023-02-23T15:47:54+01:00][INFO] config-event: new_config /conf/backup/config-1677163674.6631.xml
2023-02-23T15:47:43 Notice configctl event @ 1677163662.49 exec: system event config_changed
2023-02-23T15:47:43 Notice configctl event @ 1677163662.49 msg: Feb 23 15:47:42 OPNsense.localdomain config[5005]: [2023-02-23T15:47:42+01:00][INFO] config-event: new_config /conf/backup/config-1677163662.4784.xml
2023-02-23T15:47:42 Notice configctl event @ 1677163661.77 exec: system event config_changed
2023-02-23T15:47:42 Notice configctl event @ 1677163661.77 msg: Feb 23 15:47:41 OPNsense.localdomain config[5005]: [2023-02-23T15:47:41+01:00][INFO] config-event: new_config /conf/backup/config-1677163661.753.xml
2023-02-23T15:47:41 Notice configctl event @ 1677163660.51 exec: system event config_changed
2023-02-23T15:47:41 Notice configctl event @ 1677163660.51 msg: Feb 23 15:47:40 OPNsense.localdomain config[5005]: [2023-02-23T15:47:40+01:00][INFO] config-event: new_config /conf/backup/config-1677163660.4891.xml
2023-02-23T15:47:34 Notice configctl event @ 1677163654.44 exec: system event config_changed
2023-02-23T15:47:34 Notice configctl event @ 1677163654.44 msg: Feb 23 15:47:34 OPNsense.localdomain config[32786]: [2023-02-23T15:47:34+01:00][INFO] config-event: new_config /conf/backup/config-1677163654.4282.xml
2023-02-23T15:47:32 Notice configctl event @ 1677163652.39 exec: system event config_changed
2023-02-23T15:47:32 Notice configctl event @ 1677163652.39 msg: Feb 23 15:47:32 OPNsense.localdomain config[32786]: [2023-02-23T15:47:32+01:00][INFO] config-event: new_config /conf/backup/config-1677163652.3764.xml
2023-02-23T15:47:31 Notice configctl event @ 1677163651.18 exec: system event config_changed
2023-02-23T15:47:31 Notice configctl event @ 1677163651.18 msg: Feb 23 15:47:31 OPNsense.localdomain config[32786]: [2023-02-23T15:47:31+01:00][INFO] config-event: new_config /conf/backup/config-1677163651.1677.xml
2023-02-23T15:47:23 Notice configctl event @ 1677163642.82 exec: system event config_changed
2023-02-23T15:47:23 Notice configctl event @ 1677163642.82 msg: Feb 23 15:47:22 OPNsense.localdomain config[62690]: [2023-02-23T15:47:22+01:00][INFO] config-event: new_config /conf/backup/config-1677163642.7993.xml
2023-02-23T15:47:22 Notice configctl event @ 1677163642.20 exec: system event config_changed
2023-02-23T15:47:22 Notice configctl event @ 1677163642.20 msg: Feb 23 15:47:22 OPNsense.localdomain config[62690]: [2023-02-23T15:47:22+01:00][INFO] config-event: new_config /conf/backup/config-1677163642.1811.xml
2023-02-23T15:47:20 Notice configctl event @ 1677163639.72 exec: system event config_changed
2023-02-23T15:47:20 Notice configctl event @ 1677163639.72 msg: Feb 23 15:47:19 OPNsense.localdomain config[62690]: [2023-02-23T15:47:19+01:00][INFO] config-event: new_config /conf/backup/config-1677163639.6977.xml
2023-02-23T15:47:12 Notice configctl event @ 1677163632.23 exec: system event config_changed
2023-02-23T15:47:12 Notice configctl event @ 1677163632.23 msg: Feb 23 15:47:12 OPNsense.localdomain config[62690]: [2023-02-23T15:47:12+01:00][INFO] config-event: new_config /conf/backup/config-1677163632.2123.xml
2023-02-23T15:47:10 Notice configctl event @ 1677163630.36 exec: system event config_changed
2023-02-23T15:47:10 Notice configctl event @ 1677163630.36 msg: Feb 23 15:47:10 OPNsense.localdomain config[62690]: [2023-02-23T15:47:10+01:00][INFO] config-event: new_config /conf/backup/config-1677163630.3482.xml
2023-02-23T15:47:09 Notice configctl event @ 1677163629.13 exec: system event config_changed
2023-02-23T15:47:09 Notice configctl event @ 1677163629.13 msg: Feb 23 15:47:09 OPNsense.localdomain config[5005]: [2023-02-23T15:47:09+01:00][INFO] config-event: new_config /conf/backup/config-1677163629.1132.xml
2023-02-23T15:04:23 Notice dhclient Creating resolv.conf
2023-02-23T15:04:23 Notice dhclient DEBUG alias_ip_address:
2023-02-23T15:04:23 Notice dhclient DEBUG new_ip_address: 100.111.1XX.83
2023-02-23T15:04:23 Notice dhclient DEBUG old_ip_address: 100.111.1XX.83
2023-02-23T15:04:23 Notice dhclient DEBUG entering with RENEW
2023-02-23T14:11:38 Notice dhclient Creating resolv.conf
2023-02-23T14:11:38 Notice dhclient DEBUG alias_ip_address:
2023-02-23T14:11:38 Notice dhclient DEBUG new_ip_address: 100.111.1XX.83
2023-02-23T14:11:38 Notice dhclient DEBUG old_ip_address: 100.111.1XX.83
2023-02-23T14:11:38 Notice dhclient DEBUG entering with RENEW
2023-02-23T13:17:32 Notice dhclient Creating resolv.conf
2023-02-23T13:17:32 Notice dhclient DEBUG alias_ip_address:
2023-02-23T13:17:32 Notice dhclient DEBUG new_ip_address: 100.111.1XX.83
2023-02-23T13:17:32 Notice dhclient DEBUG old_ip_address: 100.111.1XX.83
2023-02-23T13:17:32 Notice dhclient DEBUG entering with RENEW
2023-02-23T12:22:07 Notice dhclient Creating resolv.conf
2023-02-23T12:22:07 Notice dhclient DEBUG alias_ip_address:
2023-02-23T12:22:07 Notice dhclient DEBUG new_ip_address: 100.111.1XX.83
2023-02-23T12:22:07 Notice dhclient DEBUG old_ip_address: 100.111.1XX.83
2023-02-23T12:22:07 Notice dhclient DEBUG entering with RENEW
2023-02-23T11:26:25 Notice dhclient Creating resolv.conf
2023-02-23T11:26:25 Notice dhclient DEBUG alias_ip_address:
2023-02-23T11:26:25 Notice dhclient DEBUG new_ip_address: 100.111.1XX.83
2023-02-23T11:26:25 Notice dhclient DEBUG old_ip_address: 100.111.1XX.83
2023-02-23T11:26:25 Notice dhclient DEBUG entering with RENEW
2023-02-23T10:33:49 Notice dhclient Creating resolv.conf
2023-02-23T10:33:49 Notice dhclient DEBUG alias_ip_address:
2023-02-23T10:33:49 Notice dhclient DEBUG new_ip_address: 100.111.1XX.83
2023-02-23T10:33:49 Notice dhclient DEBUG old_ip_address: 100.111.1XX.83
2023-02-23T10:33:49 Notice dhclient DEBUG entering with RENEW
2023-02-23T09:39:29 Notice dhclient Creating resolv.conf
2023-02-23T09:39:29 Notice dhclient DEBUG alias_ip_address:
2023-02-23T09:39:29 Notice dhclient DEBUG new_ip_address: 100.111.1XX.83
2023-02-23T09:39:29 Notice dhclient DEBUG old_ip_address: 100.111.1XX.83
2023-02-23T09:39:29 Notice dhclient DEBUG entering with RENEW
2023-02-23T08:47:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-23 07:46:01.961127
2023-02-23T08:46:50 Notice dhclient Creating resolv.conf
2023-02-23T08:46:50 Notice dhclient DEBUG alias_ip_address:
2023-02-23T08:46:50 Notice dhclient DEBUG new_ip_address: 100.111.1XX.83
2023-02-23T08:46:50 Notice dhclient DEBUG old_ip_address: 100.111.1XX.83
2023-02-23T08:46:50 Notice dhclient DEBUG entering with RENEW
2023-02-23T08:46:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-23 07:45:02.073074
2023-02-23T07:53:53 Notice dhclient Creating resolv.conf
2023-02-23T07:53:53 Notice dhclient DEBUG alias_ip_address:
2023-02-23T07:53:53 Notice dhclient DEBUG new_ip_address: 100.111.1XX.83
2023-02-23T07:53:53 Notice dhclient DEBUG old_ip_address: 100.111.1XX.83
2023-02-23T07:53:53 Notice dhclient DEBUG entering with RENEW
2023-02-23T07:39:44 Notice configctl event @ 1677134383.71 exec: system event config_changed
2023-02-23T07:39:44 Notice configctl event @ 1677134383.71 msg: Feb 23 07:39:43 OPNsense.localdomain config[70352]: [2023-02-23T07:39:43+01:00][INFO] config-event: new_config /conf/backup/config-1677134383.686.xml
2023-02-23T07:36:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-23 06:35:35.251138
2023-02-23T07:21:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-23 06:20:02.438284
2023-02-23T07:02:28 Notice opnsense /usr/local/etc/rc.newwanipv6: No IP change detected for wan[opt4]
2023-02-23T07:02:28 Notice dhcp6c dhcp6c REQUEST on vtnet0 - running newipv6
2023-02-23T07:02:26 Notice dhcp6c RTSOLD script - Sending SIGHUP to dhcp6c


Now installed:
sudo opnsense-revert opnsense && sudo opnsense-patch 90f1d1d766

February 24, 2023, 06:17:52 AM #32 Last Edit: February 24, 2023, 06:21:20 AM by Bytechanger
Now it´s getting worst....

Since tonight, no connection....
( at about 1:26 I got an email, but in log I thing at 02:02)


2023-02-24T06:04:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-24 05:03:22.430870
2023-02-24T05:58:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-24 04:57:15.395869
2023-02-24T05:52:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-24 04:51:08.511828
2023-02-24T05:49:51 Notice dhclient Creating resolv.conf
2023-02-24T05:46:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-24 04:45:01.742789
2023-02-24T05:39:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-24 04:38:55.031610
2023-02-24T05:33:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-24 04:32:48.183564
2023-02-24T05:27:00 Notice send_telemetry.py telemetry data collected 1 records in 0.17 seconds @2023-02-24 04:26:41.404054
2023-02-24T05:21:00 Notice send_telemetry.py telemetry data collected 1 records in 0.11 seconds @2023-02-24 04:20:32.067803
2023-02-24T05:15:00 Notice send_telemetry.py telemetry data collected 1 records in 0.12 seconds @2023-02-24 04:14:22.809344
2023-02-24T05:09:58 Error send_heartbeat.py connection error sending heartbeat to https://opnsense.emergingthreats.net/api/v1/telemetry
2023-02-24T05:09:00 Notice send_telemetry.py telemetry data collected 1 records in 0.13 seconds @2023-02-24 04:08:13.662099
2023-02-24T05:03:00 Notice send_telemetry.py telemetry data collected 1 records in 0.15 seconds @2023-02-24 04:02:04.424705
2023-02-24T04:56:00 Notice send_telemetry.py telemetry data collected 1 records in 0.15 seconds @2023-02-24 03:55:54.994827
2023-02-24T04:55:24 Notice dhclient Creating resolv.conf
2023-02-24T04:50:00 Notice send_telemetry.py telemetry data collected 1 records in 0.12 seconds @2023-02-24 03:49:45.796397
2023-02-24T04:44:00 Notice send_telemetry.py telemetry data collected 1 records in 0.11 seconds @2023-02-24 03:43:36.488802
2023-02-24T04:38:00 Notice send_telemetry.py telemetry data collected 1 records in 0.14 seconds @2023-02-24 03:37:27.250530
2023-02-24T04:32:00 Notice send_telemetry.py telemetry data collected 1 records in 0.13 seconds @2023-02-24 03:31:18.013304
2023-02-24T04:26:00 Notice send_telemetry.py telemetry data collected 1 records in 0.10 seconds @2023-02-24 03:25:08.589171
2023-02-24T04:19:00 Notice send_telemetry.py telemetry data collected 1 records in 0.10 seconds @2023-02-24 03:18:58.714955
2023-02-24T04:13:00 Notice send_telemetry.py telemetry data collected 1 records in 0.10 seconds @2023-02-24 03:12:49.419820
2023-02-24T04:07:00 Notice send_telemetry.py telemetry data collected 1 records in 0.15 seconds @2023-02-24 03:06:40.106696
2023-02-24T04:02:26 Notice dhclient Creating resolv.conf
2023-02-24T04:01:00 Notice send_telemetry.py telemetry data collected 1 records in 0.10 seconds @2023-02-24 03:00:30.674823
2023-02-24T04:00:05 Error send_heartbeat.py connection error sending heartbeat to https://opnsense.emergingthreats.net/api/v1/telemetry
2023-02-24T03:55:00 Notice send_telemetry.py telemetry data collected 1 records in 0.10 seconds @2023-02-24 02:54:21.375515
2023-02-24T03:49:00 Notice send_telemetry.py telemetry data collected 1 records in 0.11 seconds @2023-02-24 02:48:12.011573
2023-02-24T03:43:00 Notice send_telemetry.py telemetry data collected 1 records in 0.11 seconds @2023-02-24 02:42:02.968904
2023-02-24T03:36:00 Notice send_telemetry.py telemetry data collected 1 records in 0.12 seconds @2023-02-24 02:35:53.429234
2023-02-24T03:30:00 Notice send_telemetry.py telemetry data collected 1 records in 0.10 seconds @2023-02-24 02:29:44.322231
2023-02-24T03:24:00 Notice send_telemetry.py telemetry data collected 1 records in 0.10 seconds @2023-02-24 02:23:34.976761
2023-02-24T03:18:00 Notice send_telemetry.py telemetry data collected 1 records in 0.12 seconds @2023-02-24 02:17:25.647374
2023-02-24T03:12:00 Notice send_telemetry.py telemetry data collected 1 records in 0.11 seconds @2023-02-24 02:11:16.212929
2023-02-24T03:08:56 Notice dhclient Creating resolv.conf
2023-02-24T03:07:06 Error send_heartbeat.py connection error sending heartbeat to https://opnsense.emergingthreats.net/api/v1/telemetry
2023-02-24T03:06:00 Notice send_telemetry.py telemetry data collected 1 records in 0.12 seconds @2023-02-24 02:05:06.705410
[...]
2023-02-24T02:22:00 Notice send_telemetry.py telemetry data collected 1 records in 0.10 seconds @2023-02-24 01:22:00.314843
2023-02-24T02:16:00 Notice send_telemetry.py telemetry data collected 1 records in 0.16 seconds @2023-02-24 01:15:50.910864
2023-02-24T02:15:22 Notice dhclient Creating resolv.conf
2023-02-24T02:10:00 Notice send_telemetry.py telemetry data collected 1 records in 0.15 seconds @2023-02-24 01:09:41.530790
2023-02-24T02:05:35 Error send_heartbeat.py connection error sending heartbeat to https://opnsense.emergingthreats.net/api/v1/telemetry
2023-02-24T02:04:00 Notice send_telemetry.py telemetry data collected 1 records in 0.11 seconds @2023-02-24 01:03:31.953147
2023-02-24T02:02:02 Error rule-updater.py download failed for https://opnsense.emergingthreats.net/api/v1/ruleset/version (HTTPSConnectionPool(host='opnsense.emergingthreats.net', port=443): Max retries exceeded with url: /api/v1/ruleset/version (Caused by NewConnectionError('<urllib3.connection.HTTPSConnection object at 0x80277b340>: Failed to establish a new connection: [Errno 51] Network is unreachable')))
[...]
2023-02-24T02:02:00 Error rule-updater.py download failed for https://sslbl.abuse.ch/blacklist/sslblacklist_tls_cert.rules (HTTPSConnectionPool(host='sslbl.abuse.ch', port=443): Max retries exceeded with url: /blacklist/sslblacklist_tls_cert.rules (Caused by NewConnectionError('<urllib3.connection.HTTPSConnection object at 0x8027528b0>: Failed to establish a new connection: [Errno 51] Network is unreachable')))
2023-02-24T01:58:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-24 00:57:22.808024
2023-02-24T01:52:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-24 00:51:15.972633
2023-02-24T01:46:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-24 00:45:09.107259
2023-02-24T01:40:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-24 00:39:02.232873
2023-02-24T01:36:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-24 00:35:10.695577
2023-02-24T01:33:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-24 00:32:55.359621
2023-02-24T01:27:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-24 00:26:48.595669
2023-02-24T01:22:51 Notice dhclient Creating resolv.conf
2023-02-24T01:22:51 Notice dhclient New Routers (vtnet0): 100.111.0.1
2023-02-24T01:22:51 Notice dhclient New Broadcast Address (vtnet0): 100.111.255.255
2023-02-24T01:22:51 Notice dhclient New Subnet Mask (vtnet0): 255.255.0.0
2023-02-24T01:22:51 Notice dhclient New IP Address (vtnet0): 100.111.1XX.83
2023-02-24T01:21:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-24 00:20:41.495745
2023-02-24T00:20:35 Notice dhclient Creating resolv.conf
2023-02-23T23:27:54 Notice dhclient Creating resolv.conf
2023-02-23T22:34:32 Notice dhclient Creating resolv.conf
2023-02-23T21:40:27 Notice dhclient Creating resolv.conf
2023-02-23T20:58:00 Notice sudo Andreas : TTY=pts/0 ; PWD=/home/Andreas ; USER=root ; COMMAND=/usr/local/sbin/opnsense-patch 90f1d1d766
2023-02-23T20:58:00 Notice syslog-ng Configuration reload finished;
2023-02-23T20:58:00 Notice syslog-ng Configuration reload request received, reloading configuration;
2023-02-23T20:57:59 Notice opnsense (system local trust) skip intermediate certificate /C=US/O=Internet Security Research Group/CN=ISRG Root X1 from R3 (ACME Client)
2023-02-23T20:57:59 Notice opnsense (system local trust) skip intermediate certificate /C=US/O=Let's Encrypt/CN=R3 from R3 (ACME Client)
2023-02-23T20:57:52 Notice sudo Andreas : TTY=pts/0 ; PWD=/home/Andreas ; USER=root ; COMMAND=/usr/local/sbin/opnsense-revert opnsense
2023-02-23T20:57:41 Notice syslog-ng Configuration reload finished;
2023-02-23T20:57:41 Notice syslog-ng Configuration reload request received, reloading configuration;
2023-02-23T20:57:40 Notice opnsense (system local trust) skip intermediate certificate /C=US/O=Internet Security Research Group/CN=ISRG Root X1 from R3 (ACME Client)
2023-02-23T20:57:40 Notice opnsense (system local trust) skip intermediate certificate /C=US/O=Let's Encrypt/CN=R3 from R3 (ACME Client)
2023-02-23T20:57:32 Notice sudo Andreas : TTY=pts/0 ; PWD=/home/Andreas ; USER=root ; COMMAND=/usr/local/sbin/opnsense-revert opnsense
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (execute task : dpinger_configure_do(,WAN_GLASFASER_DHCP))
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (,WAN_GLASFASER_DHCP)
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (execute task : dpinger_configure_do(,WAN_GLASFASER_DHCP6))
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (,WAN_GLASFASER_DHCP6)
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: keeping current default gateway 'fe80::ff:XXXX:202%vtnet0'
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: setting IPv6 default route to fe80::ff:XXXX:202
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: IPv6 default gateway set to opt4
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: setting IPv4 default route to 100.111.0.1
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: IPv4 default gateway set to opt4
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: entering configure using 'opt4'
2023-02-23T20:43:49 Notice opnsense /usr/local/etc/rc.newwanip: No IP change detected for wan[opt4]
2023-02-23T20:43:49 Notice dhclient Creating resolv.conf
2023-02-23T20:43:49 Notice dhclient New Routers (vtnet0): 100.111.0.1
2023-02-23T20:43:49 Notice dhclient New Broadcast Address (vtnet0): 100.111.255.255
2023-02-23T20:43:49 Notice dhclient New Subnet Mask (vtnet0): 255.255.0.0
2023-02-23T20:43:49 Notice dhclient New IP Address (vtnet0): 100.111.1XX.83
2023-02-23T20:43:49 Notice dhclient DEBUG calling add_new_address/add_new_routes
2023-02-23T20:43:49 Notice dhclient DEBUG alias_ip_address:
2023-02-23T20:43:49 Notice dhclient DEBUG new_ip_address: 100.111.1XX.83
2023-02-23T20:43:49 Notice dhclient DEBUG old_ip_address: 100.111.1XX.83
2023-02-23T20:43:49 Notice dhclient DEBUG entering with BOUND
2023-02-23T20:43:00 Notice send_telemetry.py telemetry data collected 1 records in 0.04 seconds @2023-02-23 19:42:43.193093
2023-02-23T20:11:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-23 19:10:53.646493


Reboot, now working.

I'm curious how long it works

Greet

Good? Morning!

Quote from: franco on February 23, 2023, 08:05:58 AM
I think the fix still applies, feel free to try it now:
# opnsense-revert opnsense && opnsense-patch 90f1d1d766

So I tested this patch, but it does not work for me. Default route was gone again this morning. Here is the log (with the patch applied):
2023-02-24T06:20:32 Notice dhclient Creating resolv.conf
2023-02-24T06:20:32 Notice dhclient New Routers (vtnet2): 81.xxx.xx.1
2023-02-24T06:20:32 Notice dhclient New Broadcast Address (vtnet2): 81.xxx.xx.255
2023-02-24T06:20:32 Notice dhclient New Subnet Mask (vtnet2): 255.255.255.0
2023-02-24T06:20:32 Notice dhclient New IP Address (vtnet2): 81.xxx.xx.x29
2023-02-24T06:19:39 Notice dhclient Creating resolv.conf
2023-02-24T06:19:39 Notice dhclient New Routers (vtnet2): 192.168.100.1
2023-02-24T06:19:39 Notice dhclient New Broadcast Address (vtnet2): 192.168.100.255
2023-02-24T06:19:39 Notice dhclient New Subnet Mask (vtnet2): 255.255.255.0
2023-02-24T06:19:39 Notice dhclient New IP Address (vtnet2): 192.168.100.10
2023-02-24T05:24:03 Error dhclient send_packet: No route to host
2023-02-24T05:17:44 Error dhclient send_packet: No route to host
2023-02-24T05:11:04 Error dhclient send_packet: No route to host

If interesting, you can find the leases in the attached file. Interestingly, this time my modem first provided a local address and a minute later a correct (external) one.

@patman From the log I can see the new "force" doesn't go through, likely because the backend wasn't restarted (no reboot). small oversight on my end in instructions provided. Run this:

# service configd restart

Next time it should fire properly.


Cheers,
Franco

PS: It's really annoying routers give out 192.168.x.x addresses temporarily as it can mess up the system state.

February 24, 2023, 12:55:48 PM #36 Last Edit: February 24, 2023, 12:58:40 PM by Bytechanger
I did the reboot, but today at 11:38 WAN is shorten down! No ipv4 change!


2023-02-24T11:40:22 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (execute task : webgui_configure_do(,opt4))
2023-02-24T11:40:22 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (execute task : vxlan_configure_do())
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (execute task : unbound_configure_do(,opt4))
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (execute task : openssh_configure_do(,opt4))
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (execute task : opendns_configure_do())
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (execute task : ntpd_configure_do())
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (execute task : dyndns_configure_do(,opt4))
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (execute task : dnsmasq_configure_do())
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (,opt4)
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: Resyncing OpenVPN instances for interface wan.
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure vpn (execute task : openvpn_configure_do(,opt4))
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure vpn (execute task : ipsec_configure_do(,opt4))
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure vpn (,opt4)
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (execute task : dpinger_configure_do(,WAN_GLASFASER_DHCP))
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (,WAN_GLASFASER_DHCP)
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (execute task : dpinger_configure_do(,WAN_GLASFASER_DHCP6))
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (,WAN_GLASFASER_DHCP6)
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: keeping current default gateway 'fe80::ff:fe02:202%vtnet0'
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: setting IPv6 default route to fe80::ff:fe02:202
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: IPv6 default gateway set to opt4
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: setting IPv4 default route to 100.111.0.1
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: IPv4 default gateway set to opt4
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: entering configure using 'opt4'
2023-02-24T11:40:21 Notice opnsense /usr/local/etc/rc.newwanip: IP renewal starting (new: 100.111.1XX.83, old: 100.111.133.83, interface: wan[opt4], device: vtnet0, force: yes)
2023-02-24T11:40:21 Notice dhclient Creating resolv.conf
2023-02-24T11:40:21 Notice dhclient New Routers (vtnet0): 100.111.0.1
2023-02-24T11:40:21 Notice dhclient New Broadcast Address (vtnet0): 100.111.255.255
2023-02-24T11:40:21 Notice dhclient New Subnet Mask (vtnet0): 255.255.0.0
2023-02-24T11:40:21 Notice dhclient New IP Address (vtnet0): 100.111.1XX.83
2023-02-24T11:39:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-24 10:38:50.137754
2023-02-24T10:52:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-24 09:51:42.397138
2023-02-24T10:38:34 Notice dhclient Creating resolv.conf
2023-02-24T09:46:02 Notice dhclient Creating resolv.conf


so it is better (inet comes back), but the short interruption is not good!

The patch 90f1d1d766 doesn't work for me too.

Will try your command # service configd restart

14:28 again, ipv4 is gone for a while (ipv6 stays alive)


2023-02-24T14:30:35 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (execute task : webgui_configure_do(,opt4))
2023-02-24T14:30:35 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (execute task : vxlan_configure_do())
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (execute task : unbound_configure_do(,opt4))
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (execute task : openssh_configure_do(,opt4))
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (execute task : opendns_configure_do())
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (execute task : ntpd_configure_do())
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (execute task : dyndns_configure_do(,opt4))
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (execute task : dnsmasq_configure_do())
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure newwanip (,opt4)
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: Resyncing OpenVPN instances for interface wan.
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure vpn (execute task : openvpn_configure_do(,opt4))
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure vpn (execute task : ipsec_configure_do(,opt4))
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure vpn (,opt4)
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (execute task : dpinger_configure_do(,WAN_GLASFASER_DHCP))
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (,WAN_GLASFASER_DHCP)
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (execute task : dpinger_configure_do(,WAN_GLASFASER_DHCP6))
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: plugins_configure monitor (,WAN_GLASFASER_DHCP6)
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: keeping current default gateway 'fe80::ff:feXX:202%vtnet0'
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: setting IPv6 default route to fe80::ff:feXX:202
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: IPv6 default gateway set to opt4
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: setting IPv4 default route to 100.111.0.1
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: IPv4 default gateway set to opt4
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: ROUTING: entering configure using 'opt4'
2023-02-24T14:30:34 Notice opnsense /usr/local/etc/rc.newwanip: IP renewal starting (new: 100.111.1XX.83, old: 100.111.1XX.83, interface: wan[opt4], device: vtnet0, force: yes)
2023-02-24T14:30:34 Notice dhclient Creating resolv.conf
2023-02-24T14:30:34 Notice dhclient New Routers (vtnet0): 100.111.0.1
2023-02-24T14:30:34 Notice dhclient New Broadcast Address (vtnet0): 100.111.255.255
2023-02-24T14:30:34 Notice dhclient New Subnet Mask (vtnet0): 255.255.0.0
2023-02-24T14:30:34 Notice dhclient New IP Address (vtnet0): 100.111.1XX.83
2023-02-24T14:29:00 Notice send_telemetry.py telemetry data collected 1 records in 0.03 seconds @2023-02-24 13:28:28.024076
2023-02-24T13:28:18 Notice dhclient Creating resolv.conf
2023-02-24T12:34:33 Notice dhclient Creating resolv.conf

Quote from: Bytechanger on February 24, 2023, 12:55:48 PM
so it is better (inet comes back), but the short interruption is not good!

If you followed the technical discussion you will see this has always been unavoidable the way the kernel scrubs the default route while ifconfig reconfigures the same address from scratch.

Please don't grasp at straws describing common behaviour as a bug.


Cheers,
Franco

oh very nice.

But it is a misconduct that the route is deleted.
As a non-technical expert, this error description should be allowed.

(In addition, this behavior gets on my family's nerves)... ;-)

Greets

Quote from: franco on February 24, 2023, 08:52:46 AM
@patman From the log I can see the new "force" doesn't go through, likely because the backend wasn't restarted (no reboot). small oversight on my end in instructions provided. Run this:
# service configd restart
Thanks, done, checking over the weekend.

Quote from: franco on February 24, 2023, 08:54:23 AM
PS: It's really annoying routers give out 192.168.x.x addresses temporarily as it can mess up the system state.
Yeah, ask me ... I asked my provider to even set the router into "bridge mode". I wonder how this behavior conforms to bridge according to IEEE 802.1 ...  :-X

Had a chance to test 90f1d1d766 over the weekend. Seems to resolve this issue for me. Thanks!

Lost my Orange France FTTP connection a few weeks ago.
Arrived in France yesterday and rebooted rooter. All good for 24 hours until IPV4 went again.

Did I read correctly that I should do

opnsense-patch 26d26e2054

and then

opnsense-revert opnsense && opnsense-patch 90f1d1d766



OPNsense 24.7.* on Qotom i5-5250U with AAISP FTTP 900/120
OPNsense 24.7.* on Qotom i7-4500U with Orange FR FTTP 1000/400

Team Rebellion Member
One of Marjohns TESTERS :-)

Only second one. I have a new patch but I'm still testing locally. The old one still applies. To be precise:

# opnsense-revert opnsense && opnsense-patch 90f1d1d766 && service configd restart


Cheers,
Franco