[Openvas-discuss] OpenVAS "internal error", scans not completing

Remy van Elst relst at relst.nl
Mon Dec 16 07:45:39 CET 2013


Hello,

My OpenVAS setup fails to complete scans. The setup consists out of 1 
master node and 2 scanner nodes. The two scanner nodes are running on 
external VPS servers. Once a scan is started, it works for a while, I 
can see related processes on the scanner node and the it stops and in 
the web interface shows as internal error.

Sometimes it just scans two targets, sometimes more, most of the time it 
fails before the first target is scanned.

Included are the logs for a sample scan which fails. The scan is of my 
own domain, https://raymii.org. The IP adresses configured in Greenbone 
are: 64.188.45.131, 69.197.183.203, 84.200.77.168, 151.236.14.35, 
192.71.245.64, 198.211.118.121, 205.185.117.74, 205.185.124.11.


Version information from the master node

     Host OS: Ubuntu 12.04.4 64 bit

     openvasad --version
     OpenVAS Administrator 1.2.1

     openvasmd --version
     OpenVAS Manager 3.0.6
     Manager DB revision 56

     openvassd --version
     OpenVAS Scanner 3.3.1

Version information from the scanning node:

     Host OS: Ubuntu 12.04.4 64 bit

     openvasad --version
     OpenVAS Administrator 1.2.1

     openvasmd --version
     OpenVAS Manager 3.0.6
     Manager DB revision 56

     openvassd --version
     OpenVAS Scanner 3.3.1

This is from the scanner node's log:

     tail -f /var/log/openvas/openvassd.dump -f 
/var/log/openvas/openvassd.messages -f /var/log/openvas/openvasad.log
     ==> /var/log/openvas/openvassd.dump <==

     ==> /var/log/openvas/openvassd.messages <==
     [Sun Dec 15 10:03:18 2013][5628] openvassd 3.3.1 started
     [Sun Dec 15 19:27:21 2013][28713] user om starts a new scan. 
Target(s) : 64.188.45.131, 69.197.183.203, 84.200.77.168, 151.236.14.35, 
192.71.245.64, 198.211.118.121, 205.185.117.74, 205.185.124.11, with 
max_hosts = 1 and max_checks = 1
     [Sun Dec 15 19:27:21 2013][28713] user om : testing 64.188.45.131 
(::ffff:64.188.45.131) [28740]
     [Sun Dec 15 19:27:22 2013][28740] user om : new KB will be saved as 
/var/lib/openvas/users/om/kbs/64.188.45.131

     ==> /var/log/openvas/openvasad.log <==


This is from the openvas master (where greenbone runs) logging:

     /var/log/openvasmd.log

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250: <= 
<get_tasks_response status="200" 
status_text="OK"><task_count>1</task_count><sort><field>ROWID<order>ascending</order></field></sort><apply_overrides>0</apply_overrides><task 
id="aa23c04b-3fa5-42ac-ba49-4fc067ec4084"><name>48caee8c-7c60-42e6-8058-02b4c086019e</name><comment>Slave 
task created by 
Master</comment><owner><name>admin</name></owner><observers></observers><config 
id="22049be4-03bf-4756-bab1-573aecf09647"><name>48caee8c-7c60-42e6-8058-02b4c086019e</name><trash>0</trash></config><escalator 
id=""><name></name><trash>0</trash></escalator><target 
id="88f40701-23f2-4c10-a84b-2a464f01259b"><name>48caee8c-7c60-42e6-8058-02b4c086019e</name><trash>0</trash></target><slave 
id=""><name></name><trash>0</trash></slave><status>Running</status><progress><host_progress><host>64.188.45.131</host>98</host_progress><host_progress><host>69.197.183.203</host>92</host_progress>23</progress><report_count>1<finished>0</finished></report_count><trend></trend><schedule 
id=""><name></name><next_time>over</next_time><trash>0</trash></schedule><preferences><preference><name>Maximum 
concurrently executed NVTs per 
host</name><scanner_name>max_checks</scanner_name><value>1</value></preference><preference><name>Maximum 
concurrently scanned 
hosts</name><scanner_name>max_hosts</scanner_name><value>1</value></preference></preferences></task></get_tasks_response>

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    asking for 1048576

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    Error: Error on 
line 1 char 1: Document was empty or contained only whitespace

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    End error: Error 
on line 1 char 1: Document was empty or contained only whitespace

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    asking for 1048576

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    Error: Error on 
line 1 char 1: Document was empty or contained only whitespace

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    End error: Error 
on line 1 char 1: Document was empty or contained only whitespace

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    asking for 1048576

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    Error: Error on 
line 1 char 1: Document was empty or contained only whitespace

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    End error: Error 
on line 1 char 1: Document was empty or contained only whitespace

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    asking for 1048576

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    Error: Error on 
line 1 char 1: Document was empty or contained only whitespace

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    End error: Error 
on line 1 char 1: Document was empty or contained only whitespace

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    asking for 1048576

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    Error: Error on 
line 1 char 1: Document was empty or contained only whitespace

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    End error: Error 
on line 1 char 1: Document was empty or contained only whitespace

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    asking for 1048576

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    Error: Error on 
line 1 char 1: Document was empty or contained only whitespace

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    End error: Error 
on line 1 char 1: Document was empty or contained only whitespace

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    asking for 1048576

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    Error: Error on 
line 1 char 1: Document was empty or contained only whitespace

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    End error: Error 
on line 1 char 1: Document was empty or contained only whitespace

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    asking for 1048576

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    Error: Error on 
line 1 char 1: Document was empty or contained only whitespace

     lib   xml:MESSAGE:2013-12-15 22h59.08 UTC:9250:    End error: Error 
on line 1 char 1: Document was empty or contained only whitespace

     event task:MESSAGE:2013-12-15 22h59.08 UTC:9250: Status of task 
test1 (529b1a8b-3d91-4c8e-a8b2-5ba4f4d56d1f) has changed to Internal Error


     /var/log/openvassd.messages
     [Mon Dec 16 05:39:33 2013][8956] Caught HUP signal - reconfiguring 
openvassd



This is an example scan which fails, nowdays every scan fails to 
complete. The setup has worked for a few months without issues, then all 
of a sudden scans start failing.


-------------- next part --------------
A non-text attachment was scrubbed...
Name: smime.p7s
Type: application/pkcs7-signature
Size: 3722 bytes
Desc: S/MIME-cryptografische ondertekening
URL: <http://lists.wald.intevation.org/pipermail/openvas-discuss/attachments/20131216/bb3c3e56/attachment.p7s>


More information about the Openvas-discuss mailing list