I will keep trying other options before reinstalling it. All other tasks work, with the same configuration. But this “MZ” started to fail in the last month.
syslog
Jul 8 11:38:10 openvas kernel: [ 3341.769134] redis-server invoked oom-killer: gfp_mask=0x100cca(GFP_HIGHUSER_MOVABLE), order=0, oom_score_adj=0
Jul 8 11:38:10 openvas kernel: [ 3341.769141] CPU: 0 PID: 847 Comm: redis-server Not tainted 5.13.0-1031-azure #37~20.04.1-Ubuntu
Jul 8 11:38:10 openvas kernel: [ 3341.769143] Hardware name: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v1.0 11/26/2012
Jul 8 11:38:10 openvas kernel: [ 3341.769145] Call Trace:
Jul 8 11:38:10 openvas kernel: [ 3341.769146] <TASK>
Jul 8 11:38:10 openvas kernel: [ 3341.769150] dump_stack+0x6b/0x86
Jul 8 11:38:10 openvas kernel: [ 3341.769155] dump_header+0x4f/0x1f6
Jul 8 11:38:10 openvas kernel: [ 3341.769158] oom_kill_process.cold+0xb/0x10
Jul 8 11:38:10 openvas kernel: [ 3341.769160] out_of_memory+0x1cf/0x520
Jul 8 11:38:10 openvas kernel: [ 3341.769163] __alloc_pages_slowpath.constprop.0+0xc86/0xd50
Jul 8 11:38:10 openvas kernel: [ 3341.769167] __alloc_pages+0x2ce/0x320
Jul 8 11:38:10 openvas kernel: [ 3341.769169] alloc_pages+0x87/0xe0
Jul 8 11:38:10 openvas kernel: [ 3341.769173] __page_cache_alloc+0x89/0xb0
Jul 8 11:38:10 openvas kernel: [ 3341.769176] pagecache_get_page+0x218/0x4d0
Jul 8 11:38:10 openvas kernel: [ 3341.769178] filemap_fault+0x5b2/0x9a0
Jul 8 11:38:10 openvas kernel: [ 3341.769181] ? next_uptodate_page+0x160/0x270
Jul 8 11:38:10 openvas kernel: [ 3341.769183] ext4_filemap_fault+0x32/0x50
Jul 8 11:38:10 openvas kernel: [ 3341.769187] __do_fault+0x3c/0x130
Jul 8 11:38:10 openvas kernel: [ 3341.769190] do_fault+0x1d6/0x500
Jul 8 11:38:10 openvas kernel: [ 3341.769192] __handle_mm_fault+0x5d1/0x960
Jul 8 11:38:10 openvas kernel: [ 3341.769195] handle_mm_fault+0xcd/0x2a0
Jul 8 11:38:10 openvas kernel: [ 3341.769197] do_user_addr_fault+0x1ae/0x640
Jul 8 11:38:10 openvas kernel: [ 3341.769201] exc_page_fault+0x77/0x160
Jul 8 11:38:10 openvas kernel: [ 3341.769204] ? asm_exc_page_fault+0x8/0x30
Jul 8 11:38:10 openvas kernel: [ 3341.769207] asm_exc_page_fault+0x1e/0x30
Jul 8 11:38:10 openvas kernel: [ 3341.769209] RIP: 0033:0x7f76bf038bea
Jul 8 11:38:10 openvas kernel: [ 3341.769213] Code: f3 0f 1e fa 66 0f ef c0 66 0f ef c9 66 0f ef d2 66 0f ef db 48 89 f8 48 89 f9 48 81 e1 ff 0f 00 00 48 81 f9 cf 0f 00 00 77 66 <f3> 0f 6f 20 66 0f 74 e0 6
6 0f d7 d4 85 d2 74 04 0f bc c2 c3 48 83
Jul 8 11:38:10 openvas kernel: [ 3341.769214] RSP: 002b:00007ffd49a541e8 EFLAGS: 00010297
Jul 8 11:38:10 openvas kernel: [ 3341.769216] RAX: 00007f76bf3ac329 RBX: 0000000000000009 RCX: 0000000000000329
Jul 8 11:38:10 openvas kernel: [ 3341.769218] RDX: 0000000000000000 RSI: 00007f76bf3abbd7 RDI: 00007f76bf3ac329
Jul 8 11:38:10 openvas kernel: [ 3341.769219] RBP: 0000000000000005 R08: 0000000000008010 R09: 0000000000000000
Jul 8 11:38:10 openvas kernel: [ 3341.769220] R10: 0000000000000002 R11: 0000000000000005 R12: 00007f76bf3ac329
Jul 8 11:38:10 openvas kernel: [ 3341.769221] R13: 00007f76bf3bcad0 R14: 00007f76bf3bcb48 R15: 00007f76bf3bcb48
Jul 8 11:38:10 openvas kernel: [ 3341.769223] </TASK>
Jul 8 11:38:10 openvas kernel: [ 3341.769224] Mem-Info:
Jul 8 11:38:10 openvas kernel: [ 3341.769225] active_anon:181197 inactive_anon:3740878 isolated_anon:0
Jul 8 11:38:10 openvas kernel: [ 3341.769225] active_file:117 inactive_file:76 isolated_file:0
Jul 8 11:38:10 openvas kernel: [ 3341.769225] unevictable:4641 dirty:0 writeback:0
Jul 8 11:38:10 openvas kernel: [ 3341.769225] slab_reclaimable:15198 slab_unreclaimable:27308
Jul 8 11:38:10 openvas kernel: [ 3341.769225] mapped:41847 shmem:31231 pagetables:17343 bounce:0
Jul 8 11:38:10 openvas kernel: [ 3341.769225] free:19361 free_pcp:0 free_cma:0
Jul 8 11:38:10 openvas kernel: [ 3341.769229] Node 0 active_anon:724788kB inactive_anon:14963512kB active_file:468kB inactive_file:304kB unevictable:18564kB isolated(anon):0kB isolated(file):0kB mapped:167
388kB dirty:0kB writeback:0kB shmem:124924kB shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 0kB writeback_tmp:0kB kernel_stack:13728kB pagetables:69372kB all_unreclaimable? no
Jul 8 11:38:10 openvas kernel: [ 3341.769233] Node 0 DMA free:13312kB min:12kB low:24kB high:36kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB writepending:0kB present:15996kB managed:15360kB mlocked:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
Jul 8 11:38:10 openvas kernel: [ 3341.769237] lowmem_reserve[]: 0 3847 15876 15876 15876
Jul 8 11:38:10 openvas kernel: [ 3341.769240] Node 0 DMA32 free:51924kB min:3904kB low:7840kB high:11776kB reserved_highatomic:0KB active_anon:12732kB inactive_anon:3893896kB active_file:408kB inactive_file:0kB unevictable:0kB writepending:0kB present:4046236kB managed:3980688kB mlocked:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
Jul 8 11:38:10 openvas kernel: [ 3341.769244] lowmem_reserve[]: 0 0 12028 12028 12028
Jul 8 11:38:10 openvas kernel: [ 3341.769246] Node 0 Normal free:12208kB min:12208kB low:24524kB high:36840kB reserved_highatomic:0KB active_anon:712056kB inactive_anon:11069616kB active_file:432kB inactive_file:0kB unevictable:18564kB writepending:0kB present:12713984kB managed:12324612kB mlocked:18564kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
Jul 8 11:38:10 openvas kernel: [ 3341.769250] lowmem_reserve[]: 0 0 0 0 0
Jul 8 11:38:10 openvas kernel: [ 3341.769253] Node 0 DMA: 0*4kB 0*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 1*1024kB (U) 2*2048kB (UM) 2*4096kB (M) = 13312kB
Jul 8 11:38:10 openvas kernel: [ 3341.769263] Node 0 DMA32: 828*4kB (UME) 792*8kB (UME) 430*16kB (UME) 195*32kB (UME) 98*64kB (UME) 48*128kB (UE) 33*256kB (UME) 11*512kB (UE) 3*1024kB (UE) 0*2048kB 0*4096kB = 52336kB
Jul 8 11:38:10 openvas kernel: [ 3341.769275] Node 0 Normal: 1069*4kB (UME) 323*8kB (UME) 212*16kB (UME) 127*32kB (UME) 4*64kB (UME) 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 14572kB
Jul 8 11:38:10 openvas kernel: [ 3341.769285] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0 hugepages_size=2048kB
Jul 8 11:38:10 openvas kernel: [ 3341.769286] 38677 total pagecache pages
Jul 8 11:38:10 openvas kernel: [ 3341.769287] 5294 pages in swap cache
Jul 8 11:38:10 openvas kernel: [ 3341.769288] Swap cache stats: add 1270884, delete 1265590, find 1373939/1377857
Jul 8 11:38:10 openvas kernel: [ 3341.769289] Free swap = 0kB
Jul 8 11:38:10 openvas kernel: [ 3341.769289] Total swap = 5024760kB
Jul 8 11:38:10 openvas kernel: [ 3341.769290] 4194054 pages RAM
Jul 8 11:38:10 openvas kernel: [ 3341.769291] 0 pages HighMem/MovableOnly
Jul 8 11:38:10 openvas kernel: [ 3341.769291] 113889 pages reserved
Jul 8 11:38:10 openvas kernel: [ 3341.769292] 0 pages hwpoisoned
Jul 8 11:38:10 openvas kernel: [ 3341.769551] oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/system.slice/system-redis\x2dserver.slice/redis-server@openvas.service,task=redis-server,pid=846,uid=114
Jul 8 11:38:10 openvas kernel: [ 3341.769571] Out of memory: Killed process 846 (redis-server) total-vm:27367444kB, anon-rss:14808560kB, file-rss:516kB, shmem-rss:0kB, UID:114 pgtables:46140kB oom_score_adj:0
Jul 8 11:38:10 openvas kernel: [ 3342.506177] oom_reaper: reaped process 846 (redis-server), now anon-rss:0kB, file-rss:0kB, shmem-rss:0kB
Jul 8 11:38:10 openvas ospd-openvas[156641]: double free or corruption (fasttop)
Jul 8 11:38:11 openvas systemd[1]: redis-server@openvas.service: Main process exited, code=killed, status=9/KILL
Jul 8 11:38:11 openvas systemd[1]: redis-server@openvas.service: Failed with result 'signal'.
Jul 8 11:38:11 openvas systemd[1]: redis-server@openvas.service: Scheduled restart job, restart counter is at 1.
Jul 8 11:38:11 openvas systemd[1]: Stopped Advanced key-value store (openvas).
Jul 8 11:38:11 openvas systemd[1]: Starting Advanced key-value store (openvas)...
Jul 8 11:38:11 openvas redis-server[158107]: 158107:C 08 Jul 2022 11:38:11.823 # oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo
Jul 8 11:38:11 openvas redis-server[158107]: 158107:C 08 Jul 2022 11:38:11.823 # Redis version=5.0.7, bits=64, commit=00000000, modified=0, pid=158107, just started
Jul 8 11:38:11 openvas redis-server[158107]: 158107:C 08 Jul 2022 11:38:11.823 # Configuration loaded
Jul 8 11:38:11 openvas redis-server: oO0OoO0OoO0Oo Redis is starting oO0OoO0OoO0Oo
Jul 8 11:38:11 openvas redis-server: Redis version=5.0.7, bits=64, commit=00000000, modified=0, pid=158107, just started
Jul 8 11:38:11 openvas systemd[1]: redis-server@openvas.service: Can't open PID file /run/redis-openvas/redis-server.pid (yet?) after start: Operation not permitted
Jul 8 11:38:11 openvas redis-server: Configuration loaded
Jul 8 11:38:11 openvas redis[158115]: _._ #012 _.-``__ ''-._ #012 _.-`` `. `_. ''-._ Redis 5.0.7 (00000000/0) 64 bit#012 .-`` .-```. ```\/ _.,_ ''-._ #012 ( ' , .-` | `, ) Running in standalone mode#012 |`-._`-...-` __...-.``-._|'` _.-'| Port: 0#012 | `-._ `._ / _.-' | PID: 158115#012 `-._ `-._ `-./ _.-' _.-' #012 |`-._`-._ `-.__.-' _.-'_.-'| #012 | `-._`-._ _.-'_.-' | http://redis.io #012 `-._ `-._`-.__.-'_.-' _.-' #012 |`-._`-._ `-.__.-' _.-'_.-'| #012 | `-._`-._ _.-'_.-' | #012 `-._ `-._`-.__.-'_.-' _.-' #012 `-._ `-.__.-' _.-' #012 `-._ _.-' #012 `-.__.-' #012
Jul 8 11:38:11 openvas redis[158115]: Server initialized
Jul 8 11:38:11 openvas redis[158115]: The server is now ready to accept connections at /run/redis-openvas/redis.sock
ospd-openvas.log
OSPD[864] 2022-07-08 10:49:10,782: INFO: (ospd.ospd) Starting scan ba7a1f5c-4130-4f69-905b-2f66859078e1.
OSPD[864] 2022-07-08 11:38:10,966: ERROR: (ospd.ospd) ba7a1f5c-4130-4f69-905b-2f66859078e1: Exception Error while reading from socket: (104, 'Connection reset by peer') while scanning
Traceback (most recent call last):
File "/usr/local/lib/python3.8/dist-packages/redis/connection.py", line 198, in _read_from_socket
data = recv(self._sock, socket_read_size)
File "/usr/local/lib/python3.8/dist-packages/redis/_compat.py", line 72, in recv
return sock.recv(*args, **kwargs)
ConnectionResetError: [Errno 104] Connection reset by peer
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File "/usr/local/lib/python3.8/dist-packages/ospd/ospd.py", line 522, in start_scan
self.exec_scan(scan_id)
File "/usr/local/lib/python3.8/dist-packages/ospd_openvas/daemon.py", line 1321, in exec_scan
target_is_finished = kbdb.target_is_finished(scan_id)
File "/usr/local/lib/python3.8/dist-packages/ospd_openvas/db.py", line 543, in target_is_finished
status = self._get_single_item(f'internal/{scan_id}')
File "/usr/local/lib/python3.8/dist-packages/ospd_openvas/db.py", line 437, in _get_single_item
return OpenvasDB.get_single_item(self.ctx, name)
File "/usr/local/lib/python3.8/dist-packages/ospd_openvas/db.py", line 261, in get_single_item
return ctx.lindex(name, index)
File "/usr/local/lib/python3.8/dist-packages/redis/client.py", line 1939, in lindex
return self.execute_command('LINDEX', name, index)
File "/usr/local/lib/python3.8/dist-packages/redis/client.py", line 901, in execute_command
return self.parse_response(conn, command_name, **options)
File "/usr/local/lib/python3.8/dist-packages/redis/client.py", line 915, in parse_response
response = connection.read_response()
File "/usr/local/lib/python3.8/dist-packages/redis/connection.py", line 739, in read_response
response = self._parser.read_response()
File "/usr/local/lib/python3.8/dist-packages/redis/connection.py", line 324, in read_response
raw = self._buffer.readline()
File "/usr/local/lib/python3.8/dist-packages/redis/connection.py", line 256, in readline
self._read_from_socket()
File "/usr/local/lib/python3.8/dist-packages/redis/connection.py", line 222, in _read_from_socket
raise ConnectionError("Error while reading from socket: %s" %
redis.exceptions.ConnectionError: Error while reading from socket: (104, 'Connection reset by peer')
OSPD[864] 2022-07-08 11:38:11,162: INFO: (ospd.ospd) ba7a1f5c-4130-4f69-905b-2f66859078e1: Host scan got interrupted. Progress: 12, Status: RUNNING
OSPD[864] 2022-07-08 11:38:11,162: INFO: (ospd.ospd) ba7a1f5c-4130-4f69-905b-2f66859078e1: Scan interrupted.
OSPD[864] 2022-07-08 11:38:16,071: INFO: (ospd.ospd) ba7a1f5c-4130-4f69-905b-2f66859078e1: Scan process is dead and its progress is 12
OSPD[864] 2022-07-08 11:38:16,075: INFO: (ospd.ospd) ba7a1f5c-4130-4f69-905b-2f66859078e1: Scan interrupted.
OSPD[864] 2022-07-08 11:38:16,082: INFO: (ospd.ospd) ba7a1f5c-4130-4f69-905b-2f66859078e1: Scan process is dead and its progress is 12
OSPD[864] 2022-07-08 11:38:16,083: INFO: (ospd.ospd) ba7a1f5c-4130-4f69-905b-2f66859078e1: Scan interrupted.
OSPD[864] 2022-07-08 11:38:16,200: INFO: (ospd.ospd) ba7a1f5c-4130-4f69-905b-2f66859078e1: Scan process is dead and its progress is 12
OSPD[864] 2022-07-08 11:38:16,200: INFO: (ospd.ospd) ba7a1f5c-4130-4f69-905b-2f66859078e1: Scan interrupted.
OSPD[864] 2022-07-08 11:38:16,330: INFO: (ospd.ospd) ba7a1f5c-4130-4f69-905b-2f66859078e1: Scan process is dead and its progress is 12
OSPD[864] 2022-07-08 11:38:16,331: INFO: (ospd.ospd) ba7a1f5c-4130-4f69-905b-2f66859078e1: Scan interrupted.