21.1.7_1 Upgrade - Fatal error: Uncaught Error:

[tofflock]

Hi

I've just carried out the upgrade from 21.1.6 to 21.1.7_1 on one of my firewalls.

The upgrade log recorded an error which I saw flash past.  I did read up a bit about Phalcon before I started the upgrade.  However, I know nothing about it and hence this error means little to me - but I get the gist of "Fatal error".

The error message, with a bit of context is:

[28/31] Extracting os-nginx-1.23: .......... done
Stopping configd...done
Starting configd.

Fatal error: Uncaught Error: Class 'Phalcon\Validation\Validator' not found in /usr/local/opnsense/mvc/app/models/OPNsense/Base/Validators/CallbackValidator.php:42
Stack trace:
#0 [internal function]: unknown()
#1 [internal function]: Phalcon\Loader->autoLoad('OPNsense\\Base\\V...')
#2 [internal function]: spl_autoload_call('OPNsense\\Base\\V...')
#3 /usr/local/opnsense/mvc/script/run_migrations.php(50): ReflectionClass->__construct('OPNsense\\Base\\V...')
#4 {main}
  thrown in /usr/local/opnsense/mvc/app/models/OPNsense/Base/Validators/CallbackValidator.php on line 42
Keep version OPNsense\Nginx\Nginx (1.20.1)
Reloading plugin configuration
Configuring system logging...done.
Reloading template OPNsense/Nginx: OK
[29/31] Upgrading os-dyndns from 1.24 to 1.24_2...
[29/31] Extracting os-dyndns-1.24_2: .......... done

I didn't get a rebooting notification and initially I thought the system hadn't rebooted.  However, I did get logged out of the console and the system uptime indicates it did a reboot around the time of the upgrade.

If anyone has any ideas of what went wrong  or why, I'd be glad to hear it.  Is it possible that some part of the upgrade process didn't run, or didn't run correctly?  Do I need to do anything to correct this malfunction?

Thanks in advance

PeterF


P.S    In case I've inadvertently omitted some useful information the full log (of the upgrade) is below...

Code Select Expand

***GOT REQUEST TO UPDATE***
Updating OPNsense repository catalogue...
OPNsense repository is up to date.
All repositories are up to date.
Updating OPNsense repository catalogue...
OPNsense repository is up to date.
All repositories are up to date.
Checking for upgrades (60 candidates): .......... done
Processing candidates (60 candidates): ..... done
The following 30 package(s) will be affected (of 0 checked):

New packages to be INSTALLED:
oniguruma: 6.9.7.1
php73-mbstring: 7.3.28
php73-pecl-psr: 1.1.0
php73-phalcon4: 4.1.2

Installed packages to be UPGRADED:
acme.sh: 2.8.9 -> 2.9.0
curl: 7.76.1 -> 7.77.0
expat: 2.3.0 -> 2.4.1
glib: 2.66.7_1,1 -> 2.66.8,2
isc-dhcp44-relay: 4.4.2_1 -> 4.4.2P1
isc-dhcp44-server: 4.4.2_1 -> 4.4.2P1_1
nspr: 4.30 -> 4.31
nss: 3.65 -> 3.66
openldap-sasl-client: 2.4.58 -> 2.4.59
opnsense: 21.1.6 -> 21.1.7_1
opnsense-lang: 20.1.4 -> 21.1.7
opnsense-update: 21.1.6 -> 21.1.7
os-dyndns: 1.24 -> 1.24_2
os-nginx: 1.22_1 -> 1.23
os-wireguard: 1.6 -> 1.7
pcre2: 10.36 -> 10.37
py37-certifi: 2020.12.5 -> 2021.5.30
py37-chardet: 3.0.4_3,1 -> 4.0.0,1
py37-setuptools: 44.0.0_1 -> 57.0.0
py37-ujson: 3.0.0 -> 4.0.2
py37-yaml: 5.3.1_1 -> 5.4.1
squid: 4.14 -> 4.15
strongswan: 5.9.2_1 -> 5.9.2_2

Installed packages to be REINSTALLED:
os-theme-cicada-1.28 (ABI changed: 'freebsd:12:x86:64' -> 'freebsd:*:*')
os-theme-rebellion-1.8.7 (ABI changed: 'freebsd:12:x86:64' -> 'freebsd:*:*')
os-theme-vicuna-1.4 (ABI changed: 'freebsd:12:x86:64' -> 'freebsd:*:*')

Number of packages to be installed: 4
Number of packages to be upgraded: 23
Number of packages to be reinstalled: 3

The process will require 17 MiB more space.
27 MiB to be downloaded.
[1/30] Fetching strongswan-5.9.2_2.txz: .......... done
[2/30] Fetching squid-4.15.txz: .......... done
[3/30] Fetching py37-yaml-5.4.1.txz: .......... done
[4/30] Fetching py37-ujson-4.0.2.txz: ...... done
[5/30] Fetching py37-setuptools-57.0.0.txz: .......... done
[6/30] Fetching py37-chardet-4.0.0,1.txz: .......... done
[7/30] Fetching py37-certifi-2021.5.30.txz: .......... done
[8/30] Fetching pcre2-10.37.txz: .......... done
[9/30] Fetching os-wireguard-1.7.txz: .. done
[10/30] Fetching os-theme-vicuna-1.4.txz: .......... done
[11/30] Fetching os-theme-rebellion-1.8.7.txz: .......... done
[12/30] Fetching os-theme-cicada-1.28.txz: .......... done
[13/30] Fetching os-nginx-1.23.txz: .......... done
[14/30] Fetching os-dyndns-1.24_2.txz: .... done
[15/30] Fetching opnsense-update-21.1.7.txz: ........ done
[16/30] Fetching opnsense-lang-21.1.7.txz: .......... done
[17/30] Fetching opnsense-21.1.7_1.txz: .......... done
[18/30] Fetching openldap-sasl-client-2.4.59.txz: .......... done
[19/30] Fetching nss-3.66.txz: .......... done
[20/30] Fetching nspr-4.31.txz: .......... done
[21/30] Fetching isc-dhcp44-server-4.4.2P1_1.txz: .......... done
[22/30] Fetching isc-dhcp44-relay-4.4.2P1.txz: .......... done
[23/30] Fetching glib-2.66.8,2.txz: .......... done
[24/30] Fetching expat-2.4.1.txz: .......... done
[25/30] Fetching curl-7.77.0.txz: .......... done
[26/30] Fetching acme.sh-2.9.0.txz: .......... done
[27/30] Fetching php73-phalcon4-4.1.2.txz: .......... done
[28/30] Fetching php73-pecl-psr-1.1.0.txz: .. done
[29/30] Fetching php73-mbstring-7.3.28.txz: .......... done
[30/30] Fetching oniguruma-6.9.7.1.txz: .......... done
Checking integrity... done (1 conflicting)
  - php73-phalcon4-4.1.2 conflicts with php73-phalcon-3.4.5 on /usr/local/etc/php/ext-30-phalcon.ini
Checking integrity... done (0 conflicting)
Conflicts with the existing packages have been found.
One more solver iteration is needed to resolve them.
The following 31 package(s) will be affected (of 0 checked):

Installed packages to be REMOVED:
php73-phalcon: 3.4.5

New packages to be INSTALLED:
oniguruma: 6.9.7.1
php73-mbstring: 7.3.28
php73-pecl-psr: 1.1.0
php73-phalcon4: 4.1.2

Installed packages to be UPGRADED:
acme.sh: 2.8.9 -> 2.9.0
curl: 7.76.1 -> 7.77.0
expat: 2.3.0 -> 2.4.1
glib: 2.66.7_1,1 -> 2.66.8,2
isc-dhcp44-relay: 4.4.2_1 -> 4.4.2P1
isc-dhcp44-server: 4.4.2_1 -> 4.4.2P1_1
nspr: 4.30 -> 4.31
nss: 3.65 -> 3.66
openldap-sasl-client: 2.4.58 -> 2.4.59
opnsense: 21.1.6 -> 21.1.7_1
opnsense-lang: 20.1.4 -> 21.1.7
opnsense-update: 21.1.6 -> 21.1.7
os-dyndns: 1.24 -> 1.24_2
os-nginx: 1.22_1 -> 1.23
os-wireguard: 1.6 -> 1.7
pcre2: 10.36 -> 10.37
py37-certifi: 2020.12.5 -> 2021.5.30
py37-chardet: 3.0.4_3,1 -> 4.0.0,1
py37-setuptools: 44.0.0_1 -> 57.0.0
py37-ujson: 3.0.0 -> 4.0.2
py37-yaml: 5.3.1_1 -> 5.4.1
squid: 4.14 -> 4.15
strongswan: 5.9.2_1 -> 5.9.2_2

Installed packages to be REINSTALLED:
os-theme-cicada-1.28 (ABI changed: 'freebsd:12:x86:64' -> 'freebsd:*:*')
os-theme-rebellion-1.8.7 (ABI changed: 'freebsd:12:x86:64' -> 'freebsd:*:*')
os-theme-vicuna-1.4 (ABI changed: 'freebsd:12:x86:64' -> 'freebsd:*:*')

Number of packages to be removed: 1
Number of packages to be installed: 4
Number of packages to be upgraded: 23
Number of packages to be reinstalled: 3

The process will require 10 MiB more space.
[1/31] Upgrading py37-setuptools from 44.0.0_1 to 57.0.0...
[1/31] Extracting py37-setuptools-57.0.0: .......... done
[2/31] Upgrading openldap-sasl-client from 2.4.58 to 2.4.59...
[2/31] Extracting openldap-sasl-client-2.4.59: .......... done
[3/31] Upgrading strongswan from 5.9.2_1 to 5.9.2_2...
[3/31] Extracting strongswan-5.9.2_2: .......... done
[4/31] Upgrading squid from 4.14 to 4.15...
===> Creating groups.
Using existing group 'squid'.
===> Creating users
Using existing user 'squid'.
===> Creating homedir(s)
===> Pre-installation configuration for squid-4.15
[4/31] Extracting squid-4.15: .......... done
[5/31] Upgrading py37-ujson from 3.0.0 to 4.0.2...
[5/31] Extracting py37-ujson-4.0.2: ......... done
[6/31] Upgrading opnsense-update from 21.1.6 to 21.1.7...
[6/31] Extracting opnsense-update-21.1.7: .......... done
[7/31] Upgrading opnsense-lang from 20.1.4 to 21.1.7...
[7/31] Extracting opnsense-lang-21.1.7: .......... done
[8/31] Upgrading isc-dhcp44-server from 4.4.2_1 to 4.4.2P1_1...
===> Creating groups.
Using existing group 'dhcpd'.
===> Creating users
Using existing user 'dhcpd'.
[8/31] Extracting isc-dhcp44-server-4.4.2P1_1: .......... done
[9/31] Upgrading isc-dhcp44-relay from 4.4.2_1 to 4.4.2P1...
[9/31] Extracting isc-dhcp44-relay-4.4.2P1: ....... done
[10/31] Deinstalling php73-phalcon-3.4.5...
[10/31] Deleting files for php73-phalcon-3.4.5: ........ done
[11/31] Upgrading nspr from 4.30 to 4.31...
[11/31] Extracting nspr-4.31: .......... done
[12/31] Upgrading curl from 7.76.1 to 7.77.0...
[12/31] Extracting curl-7.77.0: .......... done
[13/31] Installing oniguruma-6.9.7.1...
[13/31] Extracting oniguruma-6.9.7.1: .......... done
[14/31] Installing php73-pecl-psr-1.1.0...
[14/31] Extracting php73-pecl-psr-1.1.0: .......... done
[15/31] Installing php73-mbstring-7.3.28...
[15/31] Extracting php73-mbstring-7.3.28: .......... done
[16/31] Installing php73-phalcon4-4.1.2...
[16/31] Extracting php73-phalcon4-4.1.2: ........ done
[17/31] Upgrading py37-certifi from 2020.12.5 to 2021.5.30...
[17/31] Extracting py37-certifi-2021.5.30: .......... done
[18/31] Upgrading pcre2 from 10.36 to 10.37...
[18/31] Extracting pcre2-10.37: .......... done
[19/31] Upgrading py37-yaml from 5.3.1_1 to 5.4.1...
[19/31] Extracting py37-yaml-5.4.1: .......... done
[20/31] Upgrading py37-chardet from 3.0.4_3,1 to 4.0.0,1...
[20/31] Extracting py37-chardet-4.0.0,1: .......... done
[21/31] Upgrading nss from 3.65 to 3.66...
[21/31] Extracting nss-3.66: .......... done
[22/31] Upgrading glib from 2.66.7_1,1 to 2.66.8,2...
[22/31] Extracting glib-2.66.8,2: .......... done
No schema files found: doing nothing.
[23/31] Upgrading expat from 2.3.0 to 2.4.1...
[23/31] Extracting expat-2.4.1: .......... done
[24/31] Upgrading os-wireguard from 1.6 to 1.7...
[24/31] Extracting os-wireguard-1.7: .......... done
Stopping configd...done
Starting configd.
Keep version OPNsense\Wireguard\General (0.0.1)
Keep version OPNsense\Wireguard\Server (0.0.2)
Keep version OPNsense\Wireguard\Client (0.0.6)
Reloading plugin configuration
Configuring system logging...done.
Reloading template OPNsense/Wireguard: OK
[25/31] Reinstalling os-theme-vicuna-1.4...
[25/31] Extracting os-theme-vicuna-1.4: .......... done
[26/31] Reinstalling os-theme-rebellion-1.8.7...
[26/31] Extracting os-theme-rebellion-1.8.7: .......... done
[27/31] Reinstalling os-theme-cicada-1.28...
[27/31] Extracting os-theme-cicada-1.28: .......... done
[28/31] Upgrading os-nginx from 1.22_1 to 1.23...
[28/31] Extracting os-nginx-1.23: .......... done
Stopping configd...done
Starting configd.

Fatal error: Uncaught Error: Class 'Phalcon\Validation\Validator' not found in /usr/local/opnsense/mvc/app/models/OPNsense/Base/Validators/CallbackValidator.php:42
Stack trace:
#0 [internal function]: unknown()
#1 [internal function]: Phalcon\Loader->autoLoad('OPNsense\\Base\\V...')
#2 [internal function]: spl_autoload_call('OPNsense\\Base\\V...')
#3 /usr/local/opnsense/mvc/script/run_migrations.php(50): ReflectionClass->__construct('OPNsense\\Base\\V...')
#4 {main}
  thrown in /usr/local/opnsense/mvc/app/models/OPNsense/Base/Validators/CallbackValidator.php on line 42
Keep version OPNsense\Nginx\Nginx (1.20.1)
Reloading plugin configuration
Configuring system logging...done.
Reloading template OPNsense/Nginx: OK
[29/31] Upgrading os-dyndns from 1.24 to 1.24_2...
[29/31] Extracting os-dyndns-1.24_2: .......... done
Stopping configd...done
Starting configd.
Reloading plugin configuration
Configuring system logging...done.
[30/31] Upgrading opnsense from 21.1.6 to 21.1.7_1...
[30/31] Extracting opnsense-21.1.7_1: .......... done
Stopping configd...done
Resetting root shell
Updating /etc/shells
Unhooking from /etc/rc
Unhooking from /etc/rc.shutdown
Updating /etc/shells
Registering root shell
Hooking into /etc/rc
Hooking into /etc/rc.shutdown
Starting configd.
>>> Invoking update script 'refresh'
Keep version OPNsense\Monit\Monit (1.0.9)
Keep version OPNsense\Firewall\Alias (1.0.0)
Keep version OPNsense\Firewall\Category (1.0.0)
Keep version OPNsense\OpenVPN\Export (0.0.1)
Keep version OPNsense\CaptivePortal\CaptivePortal (1.0.0)
Keep version OPNsense\Core\Firmware (1.0.0)
Keep version OPNsense\Interfaces\Loopback (1.0.0)
Keep version OPNsense\Interfaces\VxLan (1.0.1)
Keep version OPNsense\Unboundplus\Dnsbl (0.0.1)
Keep version OPNsense\Unboundplus\Miscellaneous (0.0.2)
Keep version OPNsense\Cron\Cron (1.0.2)
Keep version OPNsense\IPsec\IPsec (1.0.0)
Keep version OPNsense\Backup\NextcloudSettings (1.0.0)
Keep version OPNsense\TrafficShaper\TrafficShaper (1.0.3)
Keep version OPNsense\Syslog\Syslog (1.0.0)

[franco]

Hi,

Error naturally appears during upgrade when php73-phalcon4 package was already installed but opnsense package is not updated yet.

[16/31] Extracting php73-phalcon4-4.1.2: ........ done
[...]
Fatal error: Uncaught Error: Class 'Phalcon\Validation\Validator' not found
[...]
[30/31] Extracting opnsense-21.1.7_1: .......... done

Afterwards the code producing the error is no longer there.


Cheers,
Franco

[tofflock]

Hi

Thanks for your reply.  Whilst I understand your logic in saying that the error is benign, I can still see some unexplained problems with the system.  These symptons persist after a reboot.

1  Unexplained shells running, started at boot.  If I open a shell (text console, option 8 ) and see who is logged in, I can see another 8 shells running:

Code Select Expand

Last login: Fri Jun 18 10:26:30 2021 from 192.168.110.70
----------------------------------------------
|      Hello, this is OPNsense 21.1          |         @@@@@@@@@@@@@@@
|                                            |        @@@@         @@@@
| Website:      https://opnsense.org/        |         @@@\\\   ///@@@
| Handbook:     https://docs.opnsense.org/   |       ))))))))   ((((((((
| Forums:       https://forum.opnsense.org/  |         @@@///   \\\@@@
| Code:         https://github.com/opnsense  |        @@@@         @@@@
| Twitter:      https://twitter.com/opnsense |         @@@@@@@@@@@@@@@
----------------------------------------------

*** xxxxx.xxxxxxx.xxx: OPNsense 21.1.7_1 (amd64/OpenSSL) ***

LAN (igb2)      -> v4: 192.168.110.100/24
WAN (pppoe0)    -> v4/PPPoE: 84.68.8.135/32
WiFi (igb3)     -> v4: 10.10.10.10/24

HTTPS: SHA256 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
               00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
SSH:   SHA256 0000000000000000000000000000/00000000000000 (ECDSA)
SSH:   SHA256 0000000000000000000000000000000000000000000 (ED25519)
SSH:   SHA256 0000000000000000000000000000000000000000000 (RSA)

  0) Logout                              7) Ping host
  1) Assign interfaces                   8) Shell
  2) Set interface IP address            9) pfTop
  3) Reset the root password            10) Firewall log
  4) Reset to factory defaults          11) Reload all services
  5) Power off system                   12) Update from console
  6) Reboot system                      13) Restore a backup

Enter an option: 8

root@xxxxx:~ # w
11:12AM  up 53 mins, 9 users, load averages: 0.54, 0.58, 0.50
USER       TTY      FROM                  LOGIN@  IDLE WHAT
root       v5       -                    10:20AM    51 /bin/sh /usr/local/sbin/opnsense-shell
root       v3       -                    10:20AM    51 /bin/sh /usr/local/sbin/opnsense-shell
root       v7       -                    10:20AM    51 /bin/sh /usr/local/sbin/opnsense-shell
root       v6       -                    10:20AM    51 /bin/sh /usr/local/sbin/opnsense-shell
root       v1       -                    10:20AM    51 /bin/sh /usr/local/sbin/opnsense-shell
root       v4       -                    10:20AM    51 /bin/sh /usr/local/sbin/opnsense-shell
root       v2       -                    10:20AM    51 /bin/sh /usr/local/sbin/opnsense-shell
root       v0       -                    10:20AM    51 /bin/sh /usr/local/sbin/opnsense-shell
root       pts/0    picasso.xxx.xxxxxxx  11:12AM     - w
root@stone:~ #

These shells are started at boot time.

2  I also have no real-time traffic graphs in the web console.  If I go to

Code Select Expand

REPORTING -> TRAFFIC I get a screen with the tabs, but nothing else displayed.  There are no axes.  The data select button at the top right says "Nothing Selected" and the drop down button does not work.

Looking for other symptons...

The system log file doesn't seem to display anything untoward after the most recent reboot (see below for a sanitised extract).

Any ideas what starts these shells and why?  Is it time for a clean install?  (I last did a clean install 3 months ago - I don't want to make a habit of it!)

Thanks in advance...

PeterF



System Log - after reboot

Code Select Expand

Jun 18 10:18:50 myopn.mydomain.com syslog-ng[6873]: syslog-ng shutting down; version='3.32.1'
Jun 18 10:20:04 myopn.mydomain.com syslog-ng[22255]: syslog-ng starting up; version='3.32.1'
Jun 18 10:20:04 myopn.mydomain.com opnsense[87833]: plugins_configure loopback_prepare (1)
Jun 18 10:20:04 myopn.mydomain.com opnsense[87833]: plugins_configure loopback_prepare (execute task : loopback_configure_interface(1))
Jun 18 10:20:04 myopn.mydomain.com opnsense[87833]: plugins_configure openvpn_prepare (1)
Jun 18 10:20:04 myopn.mydomain.com opnsense[87833]: plugins_configure openvpn_prepare (execute task : openvpn_prepare(1))
Jun 18 10:20:05 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: The command '/usr/sbin/ngctl msg 'igb1': setautosrc 1' returned exit code '71', the output was 'ngctl: send msg: No such file or directory'
Jun 18 10:20:06 myopn.mydomain.com opnsense[87833]: plugins_configure ipsec_prepare (1)
Jun 18 10:20:06 myopn.mydomain.com opnsense[87833]: plugins_configure ipsec_prepare (execute task : ipsec_configure_vti(1))
Jun 18 10:20:06 myopn.mydomain.com opnsense[87833]: plugins_configure vxlan_prepare (1)
Jun 18 10:20:06 myopn.mydomain.com opnsense[87833]: plugins_configure vxlan_prepare (execute task : vxlan_configure_interface(1))
Jun 18 10:20:12 myopn.mydomain.com opnsense[30434]: /usr/local/etc/rc.newwanip: IP renewal deferred during boot on 'pppoe0'
Jun 18 10:20:15 myopn.mydomain.com opnsense[87833]: plugins_configure earlybootup (1)
Jun 18 10:20:15 myopn.mydomain.com opnsense[87833]: plugins_configure earlybootup (execute task : openssh_configure_do(1))
Jun 18 10:20:15 myopn.mydomain.com opnsense[87833]: plugins_configure earlybootup (execute task : webgui_configure_do(1))
Jun 18 10:20:16 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: ROUTING: entering configure using defaults
Jun 18 10:20:16 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: ROUTING: IPv4 default gateway set to wan
Jun 18 10:20:16 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: ROUTING: setting IPv4 default route to 212.1.1.10
Jun 18 10:20:16 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: ROUTING: keeping current default gateway '212.1.1.10'
Jun 18 10:20:16 myopn.mydomain.com opnsense[87833]: plugins_configure hosts ()
Jun 18 10:20:16 myopn.mydomain.com opnsense[87833]: plugins_configure hosts (execute task : dnsmasq_hosts_generate())
Jun 18 10:20:16 myopn.mydomain.com opnsense[87833]: plugins_configure hosts (execute task : unbound_hosts_generate())
Jun 18 10:20:16 myopn.mydomain.com opnsense[87833]: plugins_configure dhcp (1)
Jun 18 10:20:16 myopn.mydomain.com opnsense[87833]: plugins_configure dhcp (execute task : dhcpd_dhcp_configure(1))
Jun 18 10:20:16 myopn.mydomain.com dhcpleases[90497]: Could not deliver signal HUP to process because its pidfile does not exist, No such file or directory.
Jun 18 10:20:16 myopn.mydomain.com dhcpleases[90497]: Could not deliver signal HUP to process because its pidfile does not exist, No such file or directory.
Jun 18 10:20:16 myopn.mydomain.com dhcpleases[90497]: Could not deliver signal HUP to process because its pidfile does not exist, No such file or directory.
Jun 18 10:20:16 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: Warning! dhcpd_radvd_configure(auto) found no suitable IPv6 address on igb2
Jun 18 10:20:16 myopn.mydomain.com opnsense[87833]: plugins_configure dhcrelay (1)
Jun 18 10:20:16 myopn.mydomain.com opnsense[87833]: plugins_configure dhcrelay (execute task : dhcpd_dhcrelay_configure(1))
Jun 18 10:20:16 myopn.mydomain.com opnsense[87833]: plugins_configure dns (1)
Jun 18 10:20:16 myopn.mydomain.com opnsense[87833]: plugins_configure dns (execute task : dnsmasq_configure_do(1))
Jun 18 10:20:17 myopn.mydomain.com opnsense[87833]: plugins_configure dns (execute task : unbound_configure_do(1))
Jun 18 10:20:17 myopn.mydomain.com opnsense[87833]: plugins_configure monitor (1)
Jun 18 10:20:17 myopn.mydomain.com opnsense[87833]: plugins_configure monitor (execute task : dpinger_configure_do(1))
Jun 18 10:20:17 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: The WAN_PPPOE monitor address is empty, skipping.
Jun 18 10:20:24 myopn.mydomain.com opnsense[87833]: plugins_configure vpn (1)
Jun 18 10:20:24 myopn.mydomain.com opnsense[87833]: plugins_configure vpn (execute task : ipsec_configure_do(1))
Jun 18 10:20:25 myopn.mydomain.com opnsense[87833]: plugins_configure vpn (execute task : openvpn_configure_do(1))
Jun 18 10:20:25 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: Resyncing OpenVPN instances.
Jun 18 10:20:25 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: OpenVPN server 2 instance started on PID 8408.
Jun 18 10:20:25 myopn.mydomain.com opnsense[87833]: plugins_configure bootup (1)
Jun 18 10:20:25 myopn.mydomain.com opnsense[87833]: plugins_configure bootup (execute task : dyndns_configure_do(1))
Jun 18 10:20:25 myopn.mydomain.com opnsense[62974]: /usr/local/etc/rc.newwanip: IP renewal deferred during boot on 'ovpns2'
Jun 18 10:20:26 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: Dynamic DNS: updating cache file /var/cache/dyndns_wan_myopn.duckdns.org_0.cache: 84.1.1.1
Jun 18 10:20:26 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: Dynamic DNS: (Success) IP Address Updated Successfully!
Jun 18 10:20:28 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: Dynamic DNS: updatedns() starting
Jun 18 10:20:28 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: Dynamic DNS (myopn.mydomain.com): 84.1.1.1 extracted
Jun 18 10:20:28 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: Dynamic DNS (myopn.mydomain.com): running dyndns_failover_interface for wan. found pppoe0
Jun 18 10:20:28 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: Dynamic DNS (myopn.mydomain.com via Custom): _update() starting.
Jun 18 10:20:29 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: Dynamic DNS (myopn.mydomain.com): _checkStatus() starting.
Jun 18 10:20:29 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: Dynamic DNS (myopn.mydomain.com): Current Service: custom
Jun 18 10:20:29 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: Dynamic DNS (myopn.mydomain.com): 84.1.1.1 extracted
Jun 18 10:20:29 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: Dynamic DNS: updating cache file /var/cache/dyndns_wan_myopn.mydomain.com_2.cache: 84.1.1.1
Jun 18 10:20:29 myopn.mydomain.com opnsense[87833]: /usr/local/etc/rc.bootup: Dynamic DNS: (Success) IP Address Updated Successfully!
Jun 18 10:20:30 myopn.mydomain.com opnsense[87833]: plugins_configure bootup (execute task : ntpd_configure_defer(1))
Jun 18 10:20:30 myopn.mydomain.com opnsense[87833]: plugins_configure bootup (execute task : opendns_configure_do(1))
Jun 18 10:20:30 myopn.mydomain.com opnsense[87833]: plugins_configure bootup (execute task : unbound_configure_do(1))
Jun 18 10:20:32 myopn.mydomain.com syslog-ng[22255]: syslog-ng shutting down; version='3.32.1'
Jun 18 10:20:32 myopn.mydomain.com syslog-ng[56285]: syslog-ng starting up; version='3.32.1'
Jun 18 10:20:32 myopn.mydomain.com opnsense[74970]: /usr/local/etc/rc.newwanip: IPv4 renewal is starting on 'ovpns2'
Jun 18 10:20:32 myopn.mydomain.com opnsense[74970]: /usr/local/etc/rc.newwanip: Interface '' is disabled or empty, nothing to do.
Jun 18 10:20:33 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: IPv4 renewal is starting on 'pppoe0'
Jun 18 10:20:33 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: On (IP address: 84.1.1.1) (interface: WAN[wan]) (real interface: pppoe0).
Jun 18 10:20:33 myopn.mydomain.com opnsense[50433]: plugins_configure hosts ()
Jun 18 10:20:33 myopn.mydomain.com opnsense[50433]: plugins_configure hosts (execute task : dnsmasq_hosts_generate())
Jun 18 10:20:33 myopn.mydomain.com opnsense[50433]: plugins_configure hosts (execute task : unbound_hosts_generate())
Jun 18 10:20:33 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: ROUTING: entering configure using 'wan'
Jun 18 10:20:33 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: ROUTING: IPv4 default gateway set to wan
Jun 18 10:20:33 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: ROUTING: setting IPv4 default route to 212.1.1.10
Jun 18 10:20:33 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: ROUTING: keeping current default gateway '212.1.1.10'
Jun 18 10:20:33 myopn.mydomain.com opnsense[50433]: plugins_configure monitor ()
Jun 18 10:20:33 myopn.mydomain.com opnsense[50433]: plugins_configure monitor (execute task : dpinger_configure_do())
Jun 18 10:20:33 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: The WAN_PPPOE monitor address is empty, skipping.
Jun 18 10:20:35 myopn.mydomain.com opnsense[50433]: plugins_configure vpn (,wan)
Jun 18 10:20:35 myopn.mydomain.com opnsense[50433]: plugins_configure vpn (execute task : ipsec_configure_do(,wan))
Jun 18 10:20:35 myopn.mydomain.com opnsense[50433]: plugins_configure vpn (execute task : openvpn_configure_do(,wan))
Jun 18 10:20:35 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: Resyncing OpenVPN instances for interface WAN.
Jun 18 10:20:38 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: OpenVPN server 2 instance started on PID 65729.
Jun 18 10:20:38 myopn.mydomain.com opnsense[64320]: /usr/local/etc/rc.newwanip: IPv4 renewal is starting on 'ovpns2'
Jun 18 10:20:38 myopn.mydomain.com opnsense[64320]: /usr/local/etc/rc.newwanip: Interface '' is disabled or empty, nothing to do.
Jun 18 10:20:40 myopn.mydomain.com opnsense[50433]: plugins_configure newwanip (,wan)
Jun 18 10:20:40 myopn.mydomain.com opnsense[50433]: plugins_configure newwanip (execute task : dyndns_configure_do(,wan))
Jun 18 10:20:41 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: Dynamic DNS: updating cache file /var/cache/dyndns_wan_myopn.duckdns.org_0.cache: 84.1.1.1
Jun 18 10:20:41 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: Dynamic DNS: (Success) IP Address Updated Successfully!
Jun 18 10:20:43 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: Dynamic DNS: updatedns() starting
Jun 18 10:20:43 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: Dynamic DNS (myopn.mydomain.com): 84.1.1.1 extracted
Jun 18 10:20:43 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: Dynamic DNS (myopn.mydomain.com): running dyndns_failover_interface for wan. found pppoe0
Jun 18 10:20:43 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: Dynamic DNS (myopn.mydomain.com via Custom): _update() starting.
Jun 18 10:20:44 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: Dynamic DNS (myopn.mydomain.com): _checkStatus() starting.
Jun 18 10:20:44 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: Dynamic DNS (myopn.mydomain.com): Current Service: custom
Jun 18 10:20:44 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: Dynamic DNS (myopn.mydomain.com): 84.1.1.1 extracted
Jun 18 10:20:44 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: Dynamic DNS: updating cache file /var/cache/dyndns_wan_myopn.mydomain.com_2.cache: 84.1.1.1
Jun 18 10:20:44 myopn.mydomain.com opnsense[50433]: /usr/local/etc/rc.newwanip: Dynamic DNS: (Success) IP Address Updated Successfully!
Jun 18 10:20:45 myopn.mydomain.com opnsense[50433]: plugins_configure newwanip (execute task : ntpd_configure_defer())
Jun 18 10:20:45 myopn.mydomain.com opnsense[50433]: plugins_configure newwanip (execute task : opendns_configure_do())
Jun 18 10:20:45 myopn.mydomain.com opnsense[50433]: plugins_configure newwanip (execute task : openssh_configure_do(,wan))
Jun 18 10:20:45 myopn.mydomain.com opnsense[50433]: plugins_configure newwanip (execute task : unbound_configure_do(,wan))
Jun 18 10:20:45 myopn.mydomain.com opnsense[50433]: plugins_configure newwanip (execute task : vxlan_configure_interface())
Jun 18 10:20:45 myopn.mydomain.com opnsense[50433]: plugins_configure newwanip (execute task : webgui_configure_do(,wan))
Jun 18 10:20:45 myopn.mydomain.com opnsense[96774]: /usr/local/etc/rc.routing_configure: ROUTING: entering configure using defaults
Jun 18 10:20:45 myopn.mydomain.com opnsense[96774]: /usr/local/etc/rc.routing_configure: ROUTING: IPv4 default gateway set to wan
Jun 18 10:20:45 myopn.mydomain.com opnsense[96774]: /usr/local/etc/rc.routing_configure: ROUTING: setting IPv4 default route to 212.1.1.10
Jun 18 10:20:45 myopn.mydomain.com opnsense[96774]: /usr/local/etc/rc.routing_configure: ROUTING: keeping current default gateway '212.1.1.10'
Jun 18 10:20:45 myopn.mydomain.com opnsense[96774]: plugins_configure monitor (1)
Jun 18 10:20:45 myopn.mydomain.com opnsense[96774]: plugins_configure monitor (execute task : dpinger_configure_do(1))
Jun 18 10:20:45 myopn.mydomain.com opnsense[96774]: /usr/local/etc/rc.routing_configure: The WAN_PPPOE monitor address is empty, skipping.
Jun 18 10:20:52 myopn.mydomain.com syslog-ng[56285]: syslog-ng shutting down; version='3.32.1'
Jun 18 10:20:52 myopn.mydomain.com syslog-ng[76590]: syslog-ng starting up; version='3.32.1'
Jun 18 10:20:52 myopn.mydomain.com opnsense[23999]: /usr/local/etc/rc.routing_configure: ROUTING: entering configure using defaults
Jun 18 10:20:52 myopn.mydomain.com opnsense[23999]: /usr/local/etc/rc.routing_configure: ROUTING: IPv4 default gateway set to wan
Jun 18 10:20:52 myopn.mydomain.com opnsense[23999]: /usr/local/etc/rc.routing_configure: ROUTING: setting IPv4 default route to 212.1.1.10
Jun 18 10:20:52 myopn.mydomain.com opnsense[23999]: /usr/local/etc/rc.routing_configure: ROUTING: keeping current default gateway '212.1.1.10'
Jun 18 10:20:52 myopn.mydomain.com opnsense[23999]: plugins_configure monitor (1)
Jun 18 10:20:52 myopn.mydomain.com opnsense[23999]: plugins_configure monitor (execute task : dpinger_configure_do(1))
Jun 18 10:20:53 myopn.mydomain.com opnsense[23999]: /usr/local/etc/rc.routing_configure: The WAN_PPPOE monitor address is empty, skipping.
Jun 18 10:20:53 myopn.mydomain.com /flowd_aggregate.py[67878]: startup, check database.
Jun 18 10:21:45 myopn.mydomain.com /flowd_aggregate.py[67878]: start watching flowd
Jun 18 10:21:55 myopn.mydomain.com /flowd_aggregate.py[67878]: vacuum src_addr_details_086400.sqlite
Jun 18 10:22:12 myopn.mydomain.com /flowd_aggregate.py[67878]: vacuum src_addr_000300.sqlite
Jun 18 10:22:12 myopn.mydomain.com /flowd_aggregate.py[67878]: vacuum src_addr_003600.sqlite
Jun 18 10:22:12 myopn.mydomain.com /flowd_aggregate.py[67878]: vacuum src_addr_086400.sqlite
Jun 18 10:22:15 myopn.mydomain.com /flowd_aggregate.py[67878]: vacuum dst_port_000300.sqlite
Jun 18 10:22:15 myopn.mydomain.com /flowd_aggregate.py[67878]: vacuum dst_port_003600.sqlite
Jun 18 10:22:15 myopn.mydomain.com /flowd_aggregate.py[67878]: vacuum dst_port_086400.sqlite
Jun 18 10:22:18 myopn.mydomain.com /flowd_aggregate.py[67878]: vacuum interface_000030.sqlite
Jun 18 10:22:18 myopn.mydomain.com /flowd_aggregate.py[67878]: vacuum interface_000300.sqlite
Jun 18 10:22:18 myopn.mydomain.com /flowd_aggregate.py[67878]: vacuum interface_003600.sqlite
Jun 18 10:22:18 myopn.mydomain.com /flowd_aggregate.py[67878]: vacuum interface_086400.sqlite
Jun 18 10:22:19 myopn.mydomain.com /flowd_aggregate.py[67878]: vacuum done


[muchacha_grande]

I had this error during 21.1.6 to 21.1.7 update.

Everything is working fine.

[tofflock]

Something is definitely broken in my web console...

I've attempted to run a health audit (System ->Firmware ->Run an audit ->Health) and all I get is a spinner (in the "Updates" tab, and no output in the screen area below.

However, the health audit is successfully carried out and the text output is generated in "/tmp/pkg_upgrade.progress" which is the output file specified in

/usr/local/opnsense/scripts/firmware/health.sh

The health audit hasn't helped though, because it was unable to find a problem:

Code Select Expand

***GOT REQUEST TO AUDIT HEALTH***
Currently running OPNsense 21.1.7_1 (amd64/OpenSSL) at Fri Jun 18 17:32:55 BST 2021
>>> Check installed kernel version
Version 21.1.7 is correct.
>>> Check for missing or altered kernel files
No problems detected.
>>> Check installed base version
Version 21.1.7 is correct.
>>> Check for missing or altered base files
No problems detected.
>>> Check for missing package dependencies
Checking all packages: .......... done
>>> Check for missing or altered package files
Checking all packages: ....
opnsense-21.1.7_1: missing file /usr/local/www/diag_backup.php
Checking all packages......... done
>>> Check for core packages consistency
Core package "opnsense" has 67 dependencies to check.
Checking packages: ..................................................................... done
***DONE***

I suspect that this is also related to the fact that I get no traffic graphs.  Something definitely got broken during the update, or by the update error.

PeterF

[tofflock]

Hi

Good news to report and I'll keep it short.
It turns out that I can detect NO problem in my web console now.  All the odd artefacts that I reported have been fixed, by restarting my browser (Firefox 89.0.1).

I apologise for not thinking of this before.

Thanks for the help and all the good work that is OPNsense...

PeterF

[franco]

Hi Peter,

This is still a bit odd: opnsense-21.1.7_1: missing file /usr/local/www/diag_backup.php

Might be best to reinstall the opnsense package from the firmware packages tab and the error should disappear.

The browser cache issue might be because of https://github.com/opnsense/core/issues/5061 I am trying to verify that now...

As for your shell processes this is normal if you have the console menu unlocked and you probably have a VGA console?


Cheers,
Franco

[karlson2k]

Possibly related: https://forum.opnsense.org/index.php?topic=23605.0

[franco]

Yes.

[tofflock]

Hi Franco

This is still a bit odd: opnsense-21.1.7_1: missing file /usr/local/www/diag_backup.php

Sorry about that.  I was probably in the process of reinstating the original version when the system checked that directory.  I edit this file so that the default is to backup RRD data (not skip, as supplied). Clearly I was in the process of restoring it (delete mine, rename backup) when the system passed by.  I hadn't spotted it.  Sorry.

As for your shell processes this is normal if you have the console menu unlocked and you probably have a VGA console?

Yes, I do have a VGA console which is working.  For a long while my console didn't work, so I didn't see the shell processes.  I rebuilt the system a few weeks ago (with a vga console), and hadn't noticed the shell processes.

Having more confidence in this update now, I've now updated my remote firewall.  The only "odd" sympton it shows is that if I do a Audit->Health I do not get any output on the web console. (One of my reported omissions when I updated my UK firewall - but that one works as expected now).  The output file in /tmp is generated ok.  However, although my browser was not showing any signs of misbehaving with the web-console of the UK firewall, I though I ought to restart it to see if there was any impact.  I did.  That fixed the lack of screen output for Audit->Health. 

Is this more evidence for Issue 5061, perhaps?

Thanks agian for youur help,

PeterF

[franco]

Hi Peter,

Yes, I think so. Just clear browser cache and it will start working again.


Cheers,
Franco