Unknown Command Error Received During Openvas Scan

Hi,

When I start an openvas scan, I get the following errors on some targets (not all of them) during the scan process.

/var/log/gvm/openvas.log:

lib  nasl:MESSAGE:2022-03-01 21h05.59 utc:3167511: Unknown command 'public-key-algorithm-name' passed to cert_query
lib  nasl:MESSAGE:2022-03-01 21h06.01 utc:3167550: Unknown command 'public-key-algorithm-name' passed to cert_query
lib  nasl:MESSAGE:2022-03-01 21h06.07 utc:3168088: Unknown command 'public-key-algorithm-name' passed to cert_query
lib  nasl:MESSAGE:2022-03-01 21h06.19 utc:3170749: Unknown command 'public-key-algorithm-name' passed to cert_query

I ran the following commands to sync the feeds:

  • sudo -u gvm greenbone-nvt-sync
  • sudo -u gvm greenbone-feed-sync --type SCAP
  • sudo -u gvm greenbone-feed-sync --type CERT
  • sudo -u gvm greenbone-feed-sync --type GVMD_DATA

Thinking maybe this can solve the problem. But nothing changed.


I set the openvas log level to 127 > 128 and looked through the logs. It seems that problem occurs during redis scan.

lib  nasl:MESSAGE:2022-03-14 12h35.17 utc:2433977: Unknown command 'public-key-algorithm-name' passed to cert_query
libgvm util:  DEBUG:2022-03-14 12h35.17 utc:2433977: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/2
libgvm util:  DEBUG:2022-03-14 12h35.17 utc:2433979: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h35.17 utc:2433980: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h35.17 utc:2433981: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h35.17 utc:2433982: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
---

libgvm util:  DEBUG:2022-03-14 12h36.36 utc:2437997: Key 'HostDetails' already contained value 'EXIT_CODE'
sd   main:  DEBUG:2022-03-14 12h36.36 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.36 utc:2437998: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.36 utc:2437999: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.36 utc:2438000: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.36 utc:2438001: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.36 utc:2438002: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.36 utc:2438003: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.36 utc:2438004: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
sd   main:  DEBUG:2022-03-14 12h36.36 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.36 utc:2438005: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.36 utc:2438005: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/2
sd   main:  DEBUG:2022-03-14 12h36.36 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.36 utc:2438006: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.36 utc:2438007: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.36 utc:2438008: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.36 utc:2438009: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.36 utc:2438010: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.37 utc:2438011: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.37 utc:2438012: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
sd   main:  DEBUG:2022-03-14 12h36.37 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.37 utc:2438013: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.37 utc:2438013: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/2
sd   main:  DEBUG:2022-03-14 12h36.37 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.37 utc:2438014: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.37 utc:2438014: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/2
sd   main:  DEBUG:2022-03-14 12h36.37 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.37 utc:2438015: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
sd   main:  DEBUG:2022-03-14 12h36.38 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438016: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
sd   main:  DEBUG:2022-03-14 12h36.38 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438017: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438018: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438019: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438019: Key 'HostDetails' already contained value 'EXIT_CODE'
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438020: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438021: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438022: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438023: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
sd   main:  DEBUG:2022-03-14 12h36.38 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438024: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438024: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/2
sd   main:  DEBUG:2022-03-14 12h36.38 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438025: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438025: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/2
sd   main:  DEBUG:2022-03-14 12h36.38 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438026: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438026: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/2
sd   main:  DEBUG:2022-03-14 12h36.38 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438027: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438027: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/2
sd   main:  DEBUG:2022-03-14 12h36.38 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438028: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438029: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438030: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438030: Key 'HostDetails' already contained value 'EXIT_CODE'
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438031: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438032: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438033: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438034: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
sd   main:  DEBUG:2022-03-14 12h36.38 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438035: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438035: Key 'HostDetails' already contained value 'EXIT_CODE'
sd   main:  DEBUG:2022-03-14 12h36.38 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
sd   main:  DEBUG:2022-03-14 12h36.38 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438037: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
lib  nasl:MESSAGE:2022-03-14 12h36.38 utc:2438037: Unknown command 'public-key-algorithm-name' passed to cert_query
lib  nasl:MESSAGE:2022-03-14 12h36.38 utc:2438037: Unknown command 'public-key-algorithm-name' passed to cert_query
lib  nasl:MESSAGE:2022-03-14 12h36.38 utc:2438037: Unknown command 'public-key-algorithm-name' passed to cert_query
lib  nasl:MESSAGE:2022-03-14 12h36.38 utc:2438037: Unknown command 'public-key-algorithm-name' passed to cert_query
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438037: Key 'HostDetails' already contained value 'EXIT_CODE'
sd   main:  DEBUG:2022-03-14 12h36.38 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.38 utc:2438042: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
lib  nasl:MESSAGE:2022-03-14 12h36.38 utc:2438042: Unknown command 'public-key-algorithm-name' passed to cert_query
lib  nasl:MESSAGE:2022-03-14 12h36.38 utc:2438042: Unknown command 'public-key-algorithm-name' passed to cert_query
lib  nasl:MESSAGE:2022-03-14 12h36.38 utc:2438042: Unknown command 'public-key-algorithm-name' passed to cert_query
lib  nasl:MESSAGE:2022-03-14 12h36.39 utc:2438042: Unknown command 'public-key-algorithm-name' passed to cert_query
libgvm util:  DEBUG:2022-03-14 12h36.39 utc:2438042: Key 'HostDetails' already contained value 'EXIT_CODE'
sd   main:  DEBUG:2022-03-14 12h36.39 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.39 utc:2438045: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
lib  nasl:MESSAGE:2022-03-14 12h36.39 utc:2438045: Unknown command 'public-key-algorithm-name' passed to cert_query
lib  nasl:MESSAGE:2022-03-14 12h36.39 utc:2438045: Unknown command 'public-key-algorithm-name' passed to cert_query
lib  nasl:MESSAGE:2022-03-14 12h36.39 utc:2438045: Unknown command 'public-key-algorithm-name' passed to cert_query
lib  nasl:MESSAGE:2022-03-14 12h36.39 utc:2438045: Unknown command 'public-key-algorithm-name' passed to cert_query
libgvm util:  DEBUG:2022-03-14 12h36.39 utc:2438045: Key 'HostDetails' already contained value 'EXIT_CODE'
sd   main:  DEBUG:2022-03-14 12h36.39 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.39 utc:2438046: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
lib  misc:  DEBUG:2022-03-14 12h36.39 utc:2438047: [2438047] open_stream_connection: TCP:443 transport:1 timeout:10  priority: ''
lib  misc:  DEBUG:2022-03-14 12h36.39 utc:2438047: [2438047] connect : Operation now in progress
libgvm util:  DEBUG:2022-03-14 12h36.39 utc:2438047: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
lib  misc:  DEBUG:2022-03-14 12h36.39 utc:2438047: close_stream_connection TCP:443 (fd=1000000)
lib  misc:  DEBUG:2022-03-14 12h36.39 utc:2438047: [2438047] release_connection_fd: fd > 0 fd=20
sd   main:  DEBUG:2022-03-14 12h36.39 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.39 utc:2438048: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
sd   main:  DEBUG:2022-03-14 12h36.39 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.39 utc:2438049: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.39 utc:2438050: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
libgvm util:  DEBUG:2022-03-14 12h36.39 utc:2438051: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
lib  misc:  DEBUG:2022-03-14 12h36.39 utc:2438051: [2438051] open_stream_connection: TCP:443 transport:1 timeout:10  priority: ''
lib  misc:  DEBUG:2022-03-14 12h36.39 utc:2438051: [2438051] connect : Operation now in progress
lib  misc:  DEBUG:2022-03-14 12h36.39 utc:2438051: [2438051] open_stream_connection: TCP:443 transport:1 timeout:10  priority: ''
lib  misc:  DEBUG:2022-03-14 12h36.39 utc:2438051: [2438051] connect : Operation now in progress
lib  misc:  DEBUG:2022-03-14 12h36.39 utc:2438051: [2438051] open_stream_connection: TCP:443 transport:1 timeout:10  priority: ''
lib  misc:  DEBUG:2022-03-14 12h36.39 utc:2438051: [2438051] connect : Operation now in progress
lib  misc:  DEBUG:2022-03-14 12h36.39 utc:2438051: [2438051] open_stream_connection: TCP:443 transport:1 timeout:10  priority: ''
lib  misc:  DEBUG:2022-03-14 12h36.39 utc:2438051: [2438051] connect : Operation now in progress
lib  misc:  DEBUG:2022-03-14 12h36.39 utc:2437985: close_stream_connection TCP:443 (fd=1000000)
lib  misc:  DEBUG:2022-03-14 12h36.39 utc:2437985: [2437985] release_connection_fd: fd > 0 fd=20
sd   main:  DEBUG:2022-03-14 12h36.39 utc:2433723: pluginlaunch_wait_for_free_process. Number of running processes >= maximum running processes (4 >= 4). Waiting for free slot for processes.
libgvm util:  DEBUG:2022-03-14 12h36.39 utc:2438052: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
lib  misc:  DEBUG:2022-03-14 12h36.39 utc:2438053: [2438053] open_stream_connection: TCP:443 transport:1 timeout:10  priority: ''
lib  misc:  DEBUG:2022-03-14 12h36.39 utc:2438053: [2438053] connect : Operation now in progress
libgvm util:  DEBUG:2022-03-14 12h36.39 utc:2438053: get_redis_ctx: connected to redis:///run/redis-openvas/redis.sock/3
lib  misc:  DEBUG:2022-03-14 12h36.39 utc:2438053: close_stream_connection TCP:443 (fd=1000000)

GVM versions

gsad: Greenbone Security Assistant 21.4.3
gvmd: Greenbone Vulnerability Manager 21.4.4
openvas-scanner: OpenVAS 21.4.3
gvm-libs: gvm-libs 21.4.3

Environment

Operating system: Debian GNU/Linux 11 (bullseye)
Kernel: Linux <hostname> 5.10.0-11-amd64 #1 SMP Debian 5.10.92-1 (2022-01-18) x86_64 GNU/Linux
Installation method / source: Source / https://greenbone.github.io/docs/gvm-21.04/

Please update all of your GVM components (not only gvmd) to the latest version announced in the topic below. The newest releases are including the Change: extend cert_query() nasl function to get the public key algorithm by jjnicola · Pull Request #995 · greenbone/openvas-scanner · GitHub extension required for newer VTs in the feed.

2 Likes