Quick and dirty steps to re-create the feed validation key:
cd /tmp
wget https://www.greenbone.net/GBCommunitySigningKey.asc
echo "8AE4BE429B60A59B311C2E739823FAA60ED1E580:6:" > /tmp/ownertrust.txt
export GNUPGHOME=/tmp/openvas-gnupg
mkdir -p $GNUPGHOME
gpg --import /tmp/GBCommunitySigningKey.asc
gpg --import-ownertrust < /tmp/ownertrust.txt
export OPENVAS_GNUPG_HOME=/etc/openvas/gnupg
sudo mkdir -p $OPENVAS_GNUPG_HOME
sudo cp -r /tmp/openvas-gnupg/* $OPENVAS_GNUPG_HOME/
sudo chown -R _gvm:_gvm $OPENVAS_GNUPG_HOME
Output:
--2023-02-20 14:45:29-- https://www.greenbone.net/GBCommunitySigningKey.asc
Resolving www.greenbone.net (www.greenbone.net)... 84.39.109.181, 2a01:b1c0:21c:0:20ca:ff:fef0:d01
Connecting to www.greenbone.net (www.greenbone.net)|84.39.109.181|:443... connected.
HTTP request sent, awaiting response... 200 OK
Length: 1652 (1.6K) [application/octet-stream]
Saving to: ‘GBCommunitySigningKey.asc’
GBCommunitySigningKey.asc 100%[==================================================================================>] 1.61K --.-KB/s in 0s
2023-02-20 14:45:29 (9.99 MB/s) - ‘GBCommunitySigningKey.asc’ saved [1652/1652]
gpg: WARNING: unsafe permissions on homedir '/tmp/openvas-gnupg'
gpg: keybox '/tmp/openvas-gnupg/pubring.kbx' created
gpg: /tmp/openvas-gnupg/trustdb.gpg: trustdb created
gpg: key 9823FAA60ED1E580: public key "Greenbone Community Feed integrity key" imported
gpg: Total number processed: 1
gpg: imported: 1
gpg: WARNING: unsafe permissions on homedir '/tmp/openvas-gnupg'
gpg: inserting ownertrust of 6
Folder contents after running:
┌──(root㉿kali-raspberry-pi)-[/tmp]
└─# ls -aml /etc/openvas/gnupg/
total 24
drwxr-xr-x 3 _gvm _gvm 4096 Feb 20 14:45 .
drwxr-xr-x 3 root root 4096 Feb 20 13:52 ..
drwx------ 2 _gvm _gvm 4096 Feb 20 14:45 private-keys-v1.d
-rw-r--r-- 1 _gvm _gvm 1343 Feb 20 14:45 pubring.kbx
-rw------- 1 _gvm _gvm 32 Feb 20 14:45 pubring.kbx~
srwx------ 1 _gvm _gvm 0 Feb 20 14:45 S.gpg-agent
srwx------ 1 _gvm _gvm 0 Feb 20 14:45 S.gpg-agent.browser
srwx------ 1 _gvm _gvm 0 Feb 20 14:45 S.gpg-agent.extra
srwx------ 1 _gvm _gvm 0 Feb 20 14:45 S.gpg-agent.ssh
-rw------- 1 _gvm _gvm 1240 Feb 20 14:45 trustdb.gpg
Updated output from ospd-openvas:
OSPD[1301] 2023-02-20 14:49:44,099: INFO: (ospd_openvas.daemon) Loading VTs. Scans will be [requested|queued] until VTs are loaded. This may take a few minutes, please wait...
OSPD[1301] 2023-02-20 14:49:44,178: DEBUG: (ospd_openvas.gpg_sha_verifier) Using /etc/openvas/gnupg as GnuPG home.
OSPD[1301] 2023-02-20 14:49:44,181: DEBUG: (gnupg) 1335: gpg --status-fd 2 --no-tty --no-verbose --fixed-list-mode --batch --with-colons --homedir /etc/openvas/gnupg --version
OSPD[1301] 2023-02-20 14:49:44,184: DEBUG: (gnupg) stderr reader: <Thread(Thread-5 (_read_response), initial daemon)>
OSPD[1301] 2023-02-20 14:49:44,185: DEBUG: (gnupg) stdout reader: <Thread(Thread-6 (_read_data), initial daemon)>
OSPD[1301] 2023-02-20 14:49:44,188: DEBUG: (gnupg) gpg: WARNING: unsafe permissions on homedir '/etc/openvas/gnupg'
OSPD[1301] 2023-02-20 14:49:44,189: DEBUG: (gnupg) chunk: b'gpg (GnuPG) 2.2.40\nlibgcrypt 1.10.1\nCopyright (C) 2022 g10 Code GmbH\nLicense GNU GPL-3.0-or-later <https://gnu.org/licenses/gpl.html>\nThis is free software: you are free to change and redistribute it.\nThere is NO WARRANTY, to the extent permitted by law.\n\n'
OSPD[1301] 2023-02-20 14:49:44,442: DEBUG: (gnupg) verify_file: <_io.BufferedReader name='/var/lib/notus/advisories/sha256sums.asc'>, '/var/lib/notus/advisories/sha256sums'
OSPD[1301] 2023-02-20 14:49:44,442: DEBUG: (gnupg) Handling detached verification
OSPD[1301] 2023-02-20 14:49:44,444: DEBUG: (gnupg) Wrote to temp file: b'-----BEGIN PGP SIGNATURE-----\n\niQIzBAABCgAdFiEEiuS+QptgpZsxHC5zmCP6pg7R5YAFAmPu/FsACgkQmCP6pg7R\n5YCdbw//SOhRR9mGLseG1aOfjc819xBF05IVXZkSYGuy3DWsK76+nOTtx4SzKhuS\nApr+Q5domPDF+EyZQhoWmWq4/BWQ4iJMc+j5w6Ia7Ifl/LhhQx9sLdR8B3OdaObb\nxplXaOg4TOUE9elbqlukKh5KvwbQE2EI/rP5qPHLK0ofDwM6O4UNzDW0oZUn5hMO\ngXjn9TlMq727H5sPz/5ZyitlF2pxRuIGQwD1IVbnrrsUPGuo8TR1GL4FBSoW8blV\n7eIk/NCCH89WAGCf32et0uL7eGpqLd6NNKW2JWlS88icvfgIpUcNZMuZuddEybSw\nGDH4EdnXo+xUONgCv6ao+bn3ZtGmhQ4dp2lySRTBgk0eEqZuiH+qyTrwtU6fC7NO\nmL7zTDRR/mt6DucKwfDhs9GXwSrKi6260n1hrw3tlJOHTcZKZemZ67dOZ6OM05Fy\nxoZ45Wr5iOI8ORTE0ooHFS1wx/PJKF+7XtpGlo5Vret9kDYiwg3ABjnkdJGI9ji0\nY+BfSBh5FdnV2eQvx5sIn+Lfte4XwMXuw2gjkE1zkn0opIIxxoBLpoiVVJERnhyg\nv/tOMS/b5e6p+g5yBypwVhNdhbksDBR1vKpHR+5i4pyiE0vHg5CcQa0O8WeCzizk\n592mJow2kZAUPjOgr54R49abC6soT045AuFS8yp3Dz5RddepPNY=\n=q8u+\n-----END PGP SIGNATURE-----\n'
OSPD[1301] 2023-02-20 14:49:44,446: DEBUG: (gnupg) 1349: gpg --status-fd 2 --no-tty --no-verbose --fixed-list-mode --batch --with-colons --homedir /etc/openvas/gnupg --verify /tmp/pygpgtronydgn /var/lib/notus/advisories/sha256sums
OSPD[1301] 2023-02-20 14:49:44,446: DEBUG: (gnupg) stderr reader: <Thread(Thread-7 (_read_response), initial daemon)>
OSPD[1301] 2023-02-20 14:49:44,447: DEBUG: (gnupg) stdout reader: <Thread(Thread-8 (_read_data), initial daemon)>
OSPD[1301] 2023-02-20 14:49:44,452: DEBUG: (gnupg) gpg: WARNING: unsafe permissions on homedir '/etc/openvas/gnupg'
OSPD[1301] 2023-02-20 14:49:44,452: DEBUG: (gnupg) [GNUPG:] NEWSIG
OSPD[1301] 2023-02-20 14:49:44,453: DEBUG: (gnupg) message ignored: NEWSIG,
OSPD[1301] 2023-02-20 14:49:44,453: DEBUG: (gnupg) gpg: Signature made Fri 17 Feb 2023 04:02:35 AM UTC
OSPD[1301] 2023-02-20 14:49:44,454: DEBUG: (gnupg) gpg: using RSA key 8AE4BE429B60A59B311C2E739823FAA60ED1E580
OSPD[1301] 2023-02-20 14:49:44,454: DEBUG: (gnupg) [GNUPG:] KEY_CONSIDERED 8AE4BE429B60A59B311C2E739823FAA60ED1E580 0
OSPD[1301] 2023-02-20 14:49:44,454: DEBUG: (gnupg) message ignored: KEY_CONSIDERED, 8AE4BE429B60A59B311C2E739823FAA60ED1E580 0
OSPD[1301] 2023-02-20 14:49:44,455: DEBUG: (gnupg) [GNUPG:] SIG_ID wuE5T9sJvD4lXnWwfGV1khdhdGA 2023-02-17 1676606555
OSPD[1301] 2023-02-20 14:49:44,455: DEBUG: (gnupg) [GNUPG:] KEY_CONSIDERED 8AE4BE429B60A59B311C2E739823FAA60ED1E580 0
OSPD[1301] 2023-02-20 14:49:44,455: DEBUG: (gnupg) message ignored: KEY_CONSIDERED, 8AE4BE429B60A59B311C2E739823FAA60ED1E580 0
OSPD[1301] 2023-02-20 14:49:44,455: DEBUG: (gnupg) [GNUPG:] GOODSIG 9823FAA60ED1E580 Greenbone Community Feed integrity key
OSPD[1301] 2023-02-20 14:49:44,455: DEBUG: (gnupg) gpg: Good signature from "Greenbone Community Feed integrity key" [ultimate]
OSPD[1301] 2023-02-20 14:49:44,456: DEBUG: (gnupg) [GNUPG:] VALIDSIG 8AE4BE429B60A59B311C2E739823FAA60ED1E580 2023-02-17 1676606555 0 4 0 1 10 00 8AE4BE429B60A59B311C2E739823FAA60ED1E580
OSPD[1301] 2023-02-20 14:49:44,456: DEBUG: (gnupg) [GNUPG:] KEY_CONSIDERED 8AE4BE429B60A59B311C2E739823FAA60ED1E580 0
OSPD[1301] 2023-02-20 14:49:44,456: DEBUG: (gnupg) message ignored: KEY_CONSIDERED, 8AE4BE429B60A59B311C2E739823FAA60ED1E580 0
OSPD[1301] 2023-02-20 14:49:44,456: DEBUG: (gnupg) [GNUPG:] TRUST_ULTIMATE 0 pgp
OSPD[1301] 2023-02-20 14:49:45,016: DEBUG: (ospd_openvas.lock) Removed lock from file /run/ospd/feed-update.lock.
OSPD[1301] 2023-02-20 14:49:45,053: DEBUG: (ospd.main) Performing exit clean up
OSPD[1301] 2023-02-20 14:49:45,053: DEBUG: (ospd.ospd) All scans stopped and daemon clean and ready to exit
OSPD[1301] 2023-02-20 14:49:45,053: INFO: (ospd.main) Shutting-down server ...
OSPD[1301] 2023-02-20 14:49:45,058: DEBUG: (ospd.main) Finishing daemon process
It cycles through this process over and over.
journalctl -u ospd-openvas shows:
Feb 20 14:53:13 kali-raspberry-pi systemd[1]: Starting ospd-openvas.service - OSPd Wrapper for the OpenVAS Scanner (ospd-openvas)...
Feb 20 14:53:14 kali-raspberry-pi systemd[1]: Started ospd-openvas.service - OSPd Wrapper for the OpenVAS Scanner (ospd-openvas).
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: Traceback (most recent call last):
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/redis/client.py", line 1950, in _execute_transaction
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: response = self.parse_response(connection, "_")
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/redis/client.py", line 2018, in parse_response
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: result = Redis.parse_response(self, connection, command_name, **options)
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/redis/client.py", line 1254, in parse_response
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: response = connection.read_response()
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: ^^^^^^^^^^^^^^^^^^^^^^^^^^
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/redis/connection.py", line 839, in read_response
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: raise response
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: redis.exceptions.ExecAbortError: Transaction discarded because of previous errors.
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: During handling of the above exception, another exception occurred:
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: Traceback (most recent call last):
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/bin/ospd-openvas", line 8, in <module>
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: sys.exit(main())
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: ^^^^^^
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/ospd_openvas/daemon.py", line 1268, in main
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: daemon_main('OSPD - openvas', OSPDopenvas, NotusParser())
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/ospd/main.py", line 164, in main
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: daemon.init(server)
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/ospd_openvas/daemon.py", line 549, in init
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: self.update_vts()
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/ospd_openvas/daemon.py", line 674, in update_vts
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: self.notus.reload_cache()
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/ospd_openvas/notus.py", line 156, in reload_cache
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: self.cache.store_advisory(advisory["oid"], res)
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/ospd_openvas/notus.py", line 86, in store_advisory
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: return OpenvasDB.set_single_item(
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: ^^^^^^^^^^^^^^^^^^^^^^^^^^
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/ospd_openvas/db.py", line 345, in set_single_item
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: pipe.execute()
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/redis/client.py", line 2078, in execute
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: return conn.retry.call_with_retry(
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/redis/retry.py", line 46, in call_with_retry
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: return do()
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: ^^^^
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/redis/client.py", line 2079, in <lambda>
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: lambda: execute(conn, stack, raise_on_error),
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/redis/client.py", line 1953, in _execute_transaction
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: raise errors[0][1]
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/redis/client.py", line 1943, in _execute_transaction
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: self.parse_response(connection, "_")
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/redis/client.py", line 2018, in parse_response
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: result = Redis.parse_response(self, connection, command_name, **options)
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/redis/client.py", line 1254, in parse_response
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: response = connection.read_response()
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: ^^^^^^^^^^^^^^^^^^^^^^^^^^
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/redis/connection.py", line 839, in read_response
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: raise response
Feb 20 14:53:25 kali-raspberry-pi ospd-openvas[1494]: redis.exceptions.ResponseError: Command # 1 (DEL internal/notus/advisories/1.3.6.1.4.1.25623.1.1.4.2012.0033.1>
Feb 20 14:53:26 kali-raspberry-pi ospd-openvas[1494]: Exception ignored in atexit callback: <function exit_cleanup at 0xb37a9528>
Feb 20 14:53:26 kali-raspberry-pi ospd-openvas[1494]: Traceback (most recent call last):
Feb 20 14:53:26 kali-raspberry-pi ospd-openvas[1494]: File "/usr/lib/python3/dist-packages/ospd/main.py", line 86, in exit_cleanup
Feb 20 14:53:26 kali-raspberry-pi ospd-openvas[1494]: sys.exit()
Feb 20 14:53:26 kali-raspberry-pi ospd-openvas[1494]: SystemExit:
Feb 20 14:53:26 kali-raspberry-pi systemd[1]: ospd-openvas.service: Main process exited, code=exited, status=1/FAILURE
Feb 20 14:53:26 kali-raspberry-pi systemd[1]: ospd-openvas.service: Failed with result 'exit-code'.
It looked like a reboot was going to solve this. Rebooted, ran gvm-start and it looked like it was syncing for a bit:
913:M 20 Feb 2023 15:32:44.526 - DB 0: 1 keys (0 volatile) in 4 slots HT.
913:M 20 Feb 2023 15:32:44.526 - DB 1: 8522 keys (0 volatile) in 16384 slots HT.
913:M 20 Feb 2023 15:32:44.526 - DB 2: 11381 keys (0 volatile) in 16384 slots HT.
913:M 20 Feb 2023 15:32:44.527 . 2 clients connected (0 replicas), 33649616 bytes in use
913:M 20 Feb 2023 15:32:49.552 - DB 0: 1 keys (0 volatile) in 4 slots HT.
913:M 20 Feb 2023 15:32:49.553 - DB 1: 8522 keys (0 volatile) in 16384 slots HT.
913:M 20 Feb 2023 15:32:49.553 - DB 2: 17474 keys (0 volatile) in 49152 slots HT.
913:M 20 Feb 2023 15:32:49.554 . 2 clients connected (0 replicas), 46681208 bytes in use
913:M 20 Feb 2023 15:32:54.583 - DB 0: 1 keys (0 volatile) in 4 slots HT.
913:M 20 Feb 2023 15:32:54.583 - DB 1: 8522 keys (0 volatile) in 16384 slots HT.
913:M 20 Feb 2023 15:32:54.583 - DB 2: 23667 keys (0 volatile) in 32768 slots HT.
913:M 20 Feb 2023 15:32:54.583 . 2 clients connected (0 replicas), 59750192 bytes in use
913:M 20 Feb 2023 15:32:59.605 - DB 0: 1 keys (0 volatile) in 4 slots HT.
913:M 20 Feb 2023 15:32:59.605 - DB 1: 8522 keys (0 volatile) in 16384 slots HT.
913:M 20 Feb 2023 15:32:59.605 - DB 2: 32037 keys (0 volatile) in 32768 slots HT.
But eventually, I’m in the same spot. ospd-openvas crashes over and over:
Feb 20 15:26:22 kali-raspberry-pi ospd-openvas[1323]: redis.exceptions.ResponseError: Command # 1 (DEL internal/notus/advisories/1.3.6.1.4.1.25623.1.1.4.2012.0033.1) of pipeline caused error: MISCONF Redis is configured to save RDB snapshots, but it's currently unable to persist to disk. Commands that may modify the data set are disabled, because this instance is configured to report errors during writes if RDB snapshotting fails (stop-writes-on-bgsave-error option). Please check the Redis logs for details about the RDB error.
I had enabled verbose logging for redis, but it didn’t provide any valuable information regarding what was happening. So I enabled debug logging for redis and rebooted again. No love unfortunately.
ospd-openvas.log
OSPD[926] 2023-02-20 15:32:38,098: DEBUG: (gnupg) [GNUPG:] VALIDSIG 8AE4BE429B60A59B311C2E739823FAA60ED1E580 2023-02-20 1676865761 0 4 0 1 10 00 8AE4BE429B60A59B311C2E739823FAA60ED1E580
OSPD[926] 2023-02-20 15:32:38,099: DEBUG: (gnupg) [GNUPG:] KEY_CONSIDERED 8AE4BE429B60A59B311C2E739823FAA60ED1E580 0
OSPD[926] 2023-02-20 15:32:38,099: DEBUG: (gnupg) message ignored: KEY_CONSIDERED, 8AE4BE429B60A59B311C2E739823FAA60ED1E580 0
OSPD[926] 2023-02-20 15:32:38,100: DEBUG: (gnupg) [GNUPG:] TRUST_ULTIMATE 0 pgp
OSPD[926] 2023-02-20 15:33:00,013: DEBUG: (ospd_openvas.openvas) Loading VTs into Redis DB...
OSPD[926] 2023-02-20 15:33:22,828: DEBUG: (paho.mqtt.client) Sending PINGREQ
OSPD[926] 2023-02-20 15:33:22,829: DEBUG: (paho.mqtt.client) Received PINGRESP
OSPD[926] 2023-02-20 15:34:22,896: DEBUG: (paho.mqtt.client) Sending PINGREQ
OSPD[926] 2023-02-20 15:34:22,897: DEBUG: (paho.mqtt.client) Received PINGRESP
OSPD[926] 2023-02-20 15:35:22,966: DEBUG: (paho.mqtt.client) Sending PINGREQ
OSPD[926] 2023-02-20 15:35:22,967: DEBUG: (paho.mqtt.client) Received PINGRESP
OSPD[926] 2023-02-20 15:36:23,034: DEBUG: (paho.mqtt.client) Sending PINGREQ
OSPD[926] 2023-02-20 15:36:23,035: DEBUG: (paho.mqtt.client) Received PINGRESP
OSPD[926] 2023-02-20 15:36:35,048: DEBUG: (ospd_openvas.openvas) Finished loading VTs into Redis DB
OSPD[926] 2023-02-20 15:36:35,049: INFO: (ospd_openvas.daemon) VTs were up to date. Feed version is 0.
OSPD[926] 2023-02-20 15:36:35,050: DEBUG: (ospd_openvas.daemon) Calculating vts integrity check hash...
OSPD[926] 2023-02-20 15:36:55,561: DEBUG: (ospd_openvas.lock) Removed lock from file /run/ospd/feed-update.lock.
OSPD[926] 2023-02-20 15:37:05,563: DEBUG: (ospd_openvas.daemon) Current feed version: 0
OSPD[926] 2023-02-20 15:37:05,564: DEBUG: (ospd_openvas.daemon) Plugin feed version: 202302201018
OSPD[926] 2023-02-20 15:37:05,565: DEBUG: (ospd_openvas.lock) Created lock file /run/ospd/feed-update.lock.
OSPD[926] 2023-02-20 15:37:05,565: INFO: (ospd_openvas.daemon) Loading VTs. Scans will be [requested|queued] until VTs are loaded. This may take a few minutes, please wait...
OSPD[926] 2023-02-20 15:37:06,372: DEBUG: (ospd_openvas.lock) Removed lock from file /run/ospd/feed-update.lock.
OSPD[926] 2023-02-20 15:37:12,326: DEBUG: (ospd.main) Performing exit clean up
OSPD[926] 2023-02-20 15:37:12,326: DEBUG: (ospd.ospd) All scans stopped and daemon clean and ready to exit
OSPD[926] 2023-02-20 15:37:12,327: INFO: (ospd.main) Shutting-down server ...
OSPD[926] 2023-02-20 15:37:12,584: DEBUG: (ospd.main) Finishing daemon process
journalctl
Feb 20 15:32:19 kali-raspberry-pi systemd[1]: Starting ospd-openvas.service - OSPd Wrapper for the OpenVAS Scanner (ospd-openvas)...
Feb 20 15:32:22 kali-raspberry-pi systemd[1]: Started ospd-openvas.service - OSPd Wrapper for the OpenVAS Scanner (ospd-openvas).
Feb 20 15:37:06 kali-raspberry-pi ospd-openvas[948]: Traceback (most recent call last):
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/redis/client.py", line 1950, in _execute_transaction
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: response = self.parse_response(connection, "_")
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/redis/client.py", line 2018, in parse_response
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: result = Redis.parse_response(self, connection, command_name, **options)
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/redis/client.py", line 1254, in parse_response
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: response = connection.read_response()
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: ^^^^^^^^^^^^^^^^^^^^^^^^^^
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/redis/connection.py", line 839, in read_response
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: raise response
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: redis.exceptions.ExecAbortError: Transaction discarded because of previous errors.
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: During handling of the above exception, another exception occurred:
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: Traceback (most recent call last):
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: File "/usr/bin/ospd-openvas", line 8, in <module>
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: sys.exit(main())
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: ^^^^^^
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/ospd_openvas/daemon.py", line 1268, in main
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: daemon_main('OSPD - openvas', OSPDopenvas, NotusParser())
Feb 20 15:37:11 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/ospd/main.py", line 165, in main
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: daemon.run()
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/ospd/ospd.py", line 1115, in run
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: self.scheduler()
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/ospd_openvas/daemon.py", line 725, in scheduler
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: self.check_feed()
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/ospd_openvas/daemon.py", line 707, in check_feed
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: self.update_vts()
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/ospd_openvas/daemon.py", line 674, in update_vts
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: self.notus.reload_cache()
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/ospd_openvas/notus.py", line 156, in reload_cache
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: self.cache.store_advisory(advisory["oid"], res)
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/ospd_openvas/notus.py", line 86, in store_advisory
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: return OpenvasDB.set_single_item(
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: ^^^^^^^^^^^^^^^^^^^^^^^^^^
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/ospd_openvas/db.py", line 345, in set_single_item
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: pipe.execute()
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/redis/client.py", line 2078, in execute
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: return conn.retry.call_with_retry(
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/redis/retry.py", line 46, in call_with_retry
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: return do()
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: ^^^^
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/redis/client.py", line 2079, in <lambda>
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: lambda: execute(conn, stack, raise_on_error),
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/redis/client.py", line 1953, in _execute_transaction
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: raise errors[0][1]
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/redis/client.py", line 1943, in _execute_transaction
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: self.parse_response(connection, "_")
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/redis/client.py", line 2018, in parse_response
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: result = Redis.parse_response(self, connection, command_name, **options)
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/redis/client.py", line 1254, in parse_response
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: response = connection.read_response()
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: ^^^^^^^^^^^^^^^^^^^^^^^^^^
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/redis/connection.py", line 839, in read_response
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: raise response
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: redis.exceptions.ResponseError: Command # 1 (DEL internal/notus/advisories/1.3.6.1.4.1.25623.1.1.4.2012.0033.1) of pipeline caused error: MISCONF Redis is configured to save RDB snapshots, but it's currently unable to persist to disk. Commands that may modify the data set are disabled, because this instance is configured to report errors during writes if RDB snapshotting fails (stop-writes-on-bgsave-error option). Please check the Redis logs for details about the RDB error.
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: Exception ignored in atexit callback: <function exit_cleanup at 0xb3839528>
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: Traceback (most recent call last):
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: File "/usr/lib/python3/dist-packages/ospd/main.py", line 86, in exit_cleanup
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: sys.exit()
Feb 20 15:37:12 kali-raspberry-pi ospd-openvas[948]: SystemExit:
Feb 20 15:37:13 kali-raspberry-pi systemd[1]: ospd-openvas.service: Main process exited, code=exited, status=1/FAILURE
Feb 20 15:37:13 kali-raspberry-pi systemd[1]: ospd-openvas.service: Failed with result 'exit-code'.
Feb 20 15:37:13 kali-raspberry-pi systemd[1]: ospd-openvas.service: Consumed 2min 27.559s CPU time.
redis
913:M 20 Feb 2023 15:36:58.458 - DB 0: 1 keys (0 volatile) in 4 slots HT.
913:M 20 Feb 2023 15:36:58.458 - DB 1: 11758 keys (0 volatile) in 16384 slots HT.
913:M 20 Feb 2023 15:36:58.458 - DB 2: 32937 keys (0 volatile) in 65536 slots HT.
913:M 20 Feb 2023 15:36:58.459 . 2 clients connected (0 replicas), 82889264 bytes in use
913:M 20 Feb 2023 15:37:01.073 * 10000 changes in 60 seconds. Saving...
913:M 20 Feb 2023 15:37:01.079 * Background saving started by pid 1185
1185:C 20 Feb 2023 15:37:01.084 - Fork CoW for RDB: current 0 MB, peak 0 MB, average 0 MB
913:M 20 Feb 2023 15:37:01.281 # Background saving terminated by signal 31
913:M 20 Feb 2023 15:37:03.498 - DB 0: 1 keys (0 volatile) in 4 slots HT.
913:M 20 Feb 2023 15:37:03.498 - DB 1: 11758 keys (0 volatile) in 16384 slots HT.
913:M 20 Feb 2023 15:37:03.499 - DB 2: 32937 keys (0 volatile) in 65536 slots HT.
913:M 20 Feb 2023 15:37:03.499 . 2 clients connected (0 replicas), 82888240 bytes in use
913:M 20 Feb 2023 15:37:07.017 * 10000 changes in 60 seconds. Saving...
913:M 20 Feb 2023 15:37:07.027 * Background saving started by pid 1188
1188:C 20 Feb 2023 15:37:07.037 - Fork CoW for RDB: current 0 MB, peak 0 MB, average 0 MB
913:M 20 Feb 2023 15:37:07.229 # Background saving terminated by signal 31
913:M 20 Feb 2023 15:37:08.542 - DB 0: 1 keys (0 volatile) in 4 slots HT.
913:M 20 Feb 2023 15:37:08.542 - DB 1: 11758 keys (0 volatile) in 16384 slots HT.
913:M 20 Feb 2023 15:37:08.543 - DB 2: 32937 keys (0 volatile) in 65536 slots HT.
913:M 20 Feb 2023 15:37:08.543 . 2 clients connected (0 replicas), 82908712 bytes in use
913:M 20 Feb 2023 15:37:13.019 - Client closed connection id=8 addr=/var/run/redis-openvas/redis-server.sock:0 laddr=/var/run/redis-openvas/redis-server.sock:0 fd=8 name= age=280 idle=7 flags=U db=1 sub=0 psub=0 ssub=0 multi=-1 qbuf=0 qbuf-free=20474 argv-mem=0 multi-mem=0 rbs=1024 rbp=0 obl=0 oll=0 omem=0 tot-mem=22144 events=r cmd=lindex user=default redir=-1 resp=2
913:M 20 Feb 2023 15:37:13.020 - Client closed connection id=9 addr=/var/run/redis-openvas/redis-server.sock:0 laddr=/var/run/redis-openvas/redis-server.sock:0 fd=7 name= age=274 idle=6 flags=U db=2 sub=0 psub=0 ssub=0 multi=-1 qbuf=0 qbuf-free=20474 argv-mem=0 multi-mem=0 rbs=1024 rbp=0 obl=0 oll=0 omem=0 tot-mem=22144 events=r cmd=exec user=default redir=-1 resp=2
913:M 20 Feb 2023 15:37:13.069 * 10000 changes in 60 seconds. Saving...
913:M 20 Feb 2023 15:37:13.074 * Background saving started by pid 1194
1194:C 20 Feb 2023 15:37:13.079 - Fork CoW for RDB: current 0 MB, peak 0 MB, average 0 MB
913:M 20 Feb 2023 15:37:13.276 # Background saving terminated by signal 31
913:M 20 Feb 2023 15:37:13.578 - DB 0: 1 keys (0 volatile) in 4 slots HT.
913:M 20 Feb 2023 15:37:13.578 - DB 1: 11758 keys (0 volatile) in 16384 slots HT.
913:M 20 Feb 2023 15:37:13.578 - DB 2: 32937 keys (0 volatile) in 65536 slots HT.
913:M 20 Feb 2023 15:37:13.578 . 0 clients connected (0 replicas), 82884368 bytes in use
913:M 20 Feb 2023 15:37:18.611 - DB 0: 1 keys (0 volatile) in 4 slots HT.
913:M 20 Feb 2023 15:37:18.611 - DB 1: 11758 keys (0 volatile) in 16384 slots HT.
913:M 20 Feb 2023 15:37:18.611 - DB 2: 32937 keys (0 volatile) in 65536 slots HT.
913:M 20 Feb 2023 15:37:18.612 . 0 clients connected (0 replicas), 82884368 bytes in use
913:M 20 Feb 2023 15:37:19.014 * 10000 changes in 60 seconds. Saving...
913:M 20 Feb 2023 15:37:19.022 * Background saving started by pid 1199
1199:C 20 Feb 2023 15:37:19.030 - Fork CoW for RDB: current 0 MB, peak 0 MB, average 0 MB
913:M 20 Feb 2023 15:37:19.224 # Background saving terminated by signal 31
From looking at the redis error, it looks like it can be caused by a lack of memory. 2gb physical w/8gb swap on this device. It’s not eating into swap, but I tried “sysctl vm.overcommit_memory=1” anyway (a suggested fix for this on several redis threads). No luck.
I’m out of ideas, short of manually compiling/installing. Any other ideas/suggestions?
Thanks,
Rob