[Openvas-discuss] `openvasmd --progress --verbose --rebuild` hangs forever

Arthur calvinh34 at gmail.com
Thu Aug 13 16:12:12 CEST 2015


Thanks for the tip. Here is the end of the output 

$ tail -n50 /tmp/openvasmd.trace
5621  read(6, ":a\320Z\343[\34\314\260ps\257+O'&\243\376Bs9\304\t\37U\200\311\270z\322\215\34\25\261r\231<\373\277\276\367\366\177\331\207\327\305\27\243\232\6gT.\365\274\354,N\335$\324\310\r\4\313\347h\274\342ml\37\3753\233/\20Lr[3\22\315\332I:8\270\10\251)r\370\201\t\325C\227\343c\322\2654\236Z\320\225\25V\257\311\303Yg\32\363-\255\205", 120) = 120
5621  select(7, [6], NULL, NULL, {0, 100000}) = 1 (in [6], left {0, 99999})
5621  read(6, ">`\t,Q\330Ff\307\7\"_\253\35$)\325,Gl\r\353\3431-\252`0\251P\263Mv 4@\16^\3\377\1+\324v\211O8\377|Uom\\\222\317#\311P\10\212\372\325m\275NB\267\237\202\22\7\346\323\36\7\247\377\337\372\367r\305&\204\27\1\232\21Shv~\321@\r.j\333\242\215\21)U\221j6n\326\201\364\371\n\307*\260\2S\30\f\7", 120) = 120
5621  select(7, [6], NULL, NULL, {0, 100000}) = 1 (in [6], left {0, 99999})
5621  read(6, "\244\2\6j\375\351\36\31\3\225?\272\362\246\230\213\315*I\323>\314\267\214'\360\211\306t\326\3514\347\235\24Pc`^G\331\t\361\224T@<\\\343&\1\220\6h\377\235P\305#\314`\262\340?\230\277\23\350\4k\\\243\16\322\261>\313\30uS\375Q\337\234\301\204\375\tk\0174|z\360\203?\361g\337\v\24\201P\304y\303\322F\203\2530l\276\215\215\30\20\20\276\325", 120) = 120
5621  select(7, [6], NULL, NULL, {0, 100000}) = 1 (in [6], left {0, 99999})
5621  read(6, "\232X\352~\fP\7B\4\247\276\v\226\343[\273T\310A\226\333\246\341k\271\371lDnv\6\202\351 at +\2270#\354u\203\351p\36\360\367**\371\7{N2U\326\360\201\v?!\262D\335\207\1v\3471\256\247\336=\300s\10\272\246\224!|\307\367\323\230\344\341F-\265g\223\205\30\370\254\235\34hC7\246\337$\207\371\31p6\363\36\270\315\325\201H\305\301\321\212\f", 120) = 120
5621  select(7, [6], NULL, NULL, {0, 100000}) = 1 (in [6], left {0, 99999})
5621  read(6, "\2\300\247\306\320m5\17\rC\235\266\205\356\367\36\352\232\301\257CwI\212\303\206B9dv\221\220\307\27\375\5\301\20\2669J%\313\210n\272\5dka\277oX\"\23SY\330/\352?\31\7\373\26\335\355\333\32\326\375\n?\273\317\\\303\n\272pA,\256\27\\\272\301;\303\331e\316b\327\337 at r\273\361\223i\271\230uXE\2421\227\253c'\177\311\372\241\300).\306", 120) = 120
5621  select(7, [6], NULL, NULL, {0, 100000}) = 1 (in [6], left {0, 99999})
5621  read(6, "\300?\206\5\255\347\337\244\371_.\366\360\4\315\311D\6\345;\331\341\4\353<\306F\36\2046\367\f\316\214\261\25^\324M\320\352\366\213\223\361\220\207P2m\250\305\363I\24\5\246\307W\td\30\366Q\275\233\25\225h\3ld\2368\t\277\367\265\rz\t\330.\\\305\232\261\t\325\16J\342\254\363\366\372C1\324[G\333\"D[\361\311y_\314*\302\361\224\343\234\0211\270E", 120) = 120
5621  select(7, [6], NULL, NULL, {0, 100000}) = 1 (in [6], left {0, 99999})
5621  read(6, "\0349tS\4H\214\260q\310\262\206\254\25\4\"\313O\333\276\t\v\306\313>\20Y-1\217\301zN\356\2\335\276(h\f\20)\00467\336\261z\203\343\232Fm\226\224A\301W\343r\214FK\341/\3230M\2150\310Ka\324\247\215\303\5\3520A=\270\3V\2310\273\212\307\37\4\210\375\253\0264\355X\330F\377\t\233\363\223\332\261\367\3777\371\274d\373\324\3\355\37U", 120) = 120
5621  select(7, [6], NULL, NULL, {0, 100000}) = 1 (in [6], left {0, 99999})
5621  read(6, "\323#\302{\277\330\253\262\307\24\304\271\2116\366\2&Q\362Pe\341\367\230\313\247[\t^\2214\211\21\256\322\265\315\305lD\205\354\31\227\352N\332\35\360\313\253\237\360\206\n8\21\212\316o5\326o\241\n\7\31\3\225\2\216\360\261r\327\262\25\253\350\263 \266H%\2211\371Y\305\303\250\377\203\7Lz\211\253\264\257M^\375]\265\20Z\265A\355\2\1eH\n\330\243:8n", 120) = 120
5621  select(7, [6], NULL, NULL, {0, 100000}) = 1 (in [6], left {0, 99999})
5621  read(6, "\230\245sq\26\245\3065(\377\36\251A\274\32\23\211c\241wXAO`:\220\345~\21\227\212n\225\2150\303\310\367\247'\320\364\207\362W\332wA\275e\24k\322utS\7]\365e\257\366\260jy\343\311\217\333\321HO\320\272\211\251\207j\225\332\347t=_\263\276\264\362\336\n\232Pb\321\177\2705\336\260\2314\313\227\225\361\226\27\234\225t\35\252\33)\212\353\267%\2456", 120) = 120
5621  select(7, [6], NULL, NULL, {0, 100000}) = 1 (in [6], left {0, 99999})
5621  read(6, "\0061\220\277\31\200\336\331R\t\262\237@\214\365\25\246BcFlF\342\220\322\221\231\276\345\234\211G|1f\235\231\357 \177\253EG\345\340p\340\311,\6&\323\327Q\332\223\360Q/O\333\202ds\213\240\313\254\310\2l\337\307A\321\273\321\261z\315\343\177\7x\5L\305B\307\2\224\212\3669\250\275\256l!\10\343&\305[F\226\235}\364X\33\37\34542\225\23\304V\264", 120) = 120
5621  select(7, [6], NULL, NULL, {0, 100000}) = 1 (in [6], left {0, 99999})
5621  read(6, "\316\363\376\32\216\2\224\342\340\240\306\27\210\332\250\207t<\272\273\273G?\326\243p{8q\205\26\243\364\"D\330\277X5\32\223\216d\266\322\335\247v\177\377\313\0022wZ\30Q\275\252\225a\231\216F\240\322JE\2005\203\217#\221f\313\231\243\353\362s\374\33\365\307H\2540\233P\211\v\244m\276\7\275\22\25/n7\371N\\)\217jh\353$q\216\300h\251*\332\327\267", 120) = 120
5621  select(7, [6], NULL, NULL, {0, 100000}) = 1 (in [6], left {0, 99999})
5621  read(6, "\0051xq\224W\33\272U\220\257\315*\361N\n\255\267Q\363\256\346e\377Y\315MS_\30\364\371\202\202\365\4\276VW4\375\266.\371!+\311\264\262\257\257\362!\231\260h\303\231\23\365\235\273\251K3z\264\361\36\237S\210\3469T\304C\332\226\367\260\301\254\246\257\231\236\267R\230\331\345Z\311v\210\325\365!k\345(_\245\31jw\17W\352a\334*\t7\363'U_k", 120) = 120
5621  select(7, [6], NULL, NULL, {0, 100000}) = 1 (in [6], left {0, 99999})
5621  read(6, "y\377)\377x\177@\212XW&\215!'lp\2375\257\263\347s\200\365]\\\327\220\214~\351\26EU\330%2\237\277\337\312\3730\"Q\33\341\2\233\200\236\322\266~G\314\305dW\31h/k94?\4\36\350L\227f9\261\375)\375\302\346$\2637*\271\262\246>=>+\354\203\222\274i\377\202\356\260\211'2\271m\215\16\226`\232\250\322\25q\211u*>\335\371!", 120) = 120
5621  select(7, [6], NULL, NULL, {0, 100000}) = 1 (in [6], left {0, 99999})
5621  read(6, "\246\2532\353\7\354\241\277Q\256\220\266\373\346h\310\36\374\260q\23\233\243\2219\247\1\201t\277\7\211}\265\310\16UU\314io\333Z=\331\256\4\346\236\307]\305o\3612\217\21\250CPE\17\355\250\312\312}\255\347\240(\310\t'\377\"\260z)M\323 \375\370S-\332dC=\260 \374|\321\331/\23\270U\344X\327\350\242\307\202\32\33O\227 pR\202\\\246@\257\237", 120) = 120
5621  select(7, [6], NULL, NULL, {0, 100000}) = 1 (in [6], left {0, 99999})
5621  read(6, "<#\340^5\210\262\35\333\200\351\310\210\25-ad\351\2\227\341!j\t\322\330)&k\37E?C~km\250#\201N!|J\311\262k\255\212\375\302\215O\367\324\324D\374\375\226\277~\2506Xb\271x\35\222E\4\326\276h\323\354\313O\31D\v\215\253h\306f\341\202s\262{aM\3733q\202>|\24.1\267\5\333'u\rja\227+\\\233r\232\337\347\"s", 120) = 120
5621  select(7, [6], NULL, NULL, {0, 100000}) = 1 (in [6], left {0, 99999})
5621  read(6, "\336\363\22$\232\276\306\316\374\361\351\207\3004q\375}\336\347f\2\225\r\361\327\345\21\242]\244\305E_g\7\1\300\255\21\232\237#k\33\375\242\273J'CT\254x\216\325\334\314`\220\r\2518\0\340\243\33\210\223\30\n\365\23\324\241c?)\307nG \327\264\335r |Ik\352\neI\267\366af\33\274\332\17\234/\341%^G\216=)J\255\22Q\253\25\f\246lT", 120) = 120
5621  select(7, [6], NULL, NULL, {0, 100000}) = 1 (in [6], left {0, 99999})
5621  read(6, "\361\34\257t!\206)'\177\236X\331\316\rS\273wT\217\352ficA+6ZUcs\2255\251\325W\271\177P\3564\247\33\373.\221\17\3049g\v;\355a\3121\336\2(\21+\323\237lK\276\237h\33\223D\304\251\257\316Z\302\263\235\223\4\305[@2\3\17O\3\233%\331/\246\344\244s\25\\Rd\213\300\16\2145\2500\20w1g\352\"\17M \255\214\\\272", 120) = 120
5621  gettimeofday({1439474722, 765500}, NULL) = 0
5621  getrusage(RUSAGE_SELF, {ru_utime={0, 53681}, ru_stime={0, 71575}, ...}) = 0
5621  clock_gettime(CLOCK_PROCESS_CPUTIME_ID, {0, 125282378}) = 0
5621  gettimeofday({1439474722, 765699}, NULL) = 0
5621  getrusage(RUSAGE_SELF, {ru_utime={0, 53728}, ru_stime={0, 71638}, ...}) = 0
5621  clock_gettime(CLOCK_PROCESS_CPUTIME_ID, {0, 125390231}) = 0
5621  open("/etc/pkcs11/pkcs11.conf", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
5621  open("/home/ubuntu/.config/pkcs11/pkcs11.conf", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
5621  openat(AT_FDCWD, "/home/ubuntu/.config/pkcs11/modules", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
5621  openat(AT_FDCWD, "/etc/pkcs11/modules", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
5621  openat(AT_FDCWD, "/usr/share/p11-kit/modules", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
5621  open("/etc/gnutls/pkcs11.conf", O_RDONLY) = -1 ENOENT (No such file or directory)
5621  openat(AT_FDCWD, "/var/lib/openvas/openvasmd/trusted_certs", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
5621  connect(5, {sa_family=AF_INET, sin_port=htons(9391), sin_addr=inet_addr("127.0.0.1")}, 16) = 0
5621  rt_sigaction(SIGPIPE, {SIG_IGN, [], SA_RESTORER, 0x7fea44657340}, {SIG_DFL, [], 0}, 8) = 0
5621  writev(5, [{"\26\3\0\0f\1\0\0b\3\3U\314\244\">\242\351\5\25\306\225\244\261\361\227\22>C\225'x\237\252\251\2341\301\223\34\373X\246\0\0.\0k\0009\0\210\0g\0003\0E\0\26\0j\0008\0\207\0@\0002\0D\0\23\0f\0=\0005\0\204\0<\0/\0A\0\n\0\5\1\0\0\v\377\1\0\1\0\0\r\0\2\0\0", 107}], 1) = 107
5621  recvfrom(5,

[hangs forever]

At the same time, here is the end of `strace openvassd`

write(6, "*4\r\n$6\r\nHSETNX\r\n$23\r\nOpenVAS.__G"..., 60) = 60
read(6, ":0\r\n", 16384)                = 4
write(6, "*4\r\n$6\r\nHSETNX\r\n$23\r\nOpenVAS.__G"..., 60) = 60
read(6, ":0\r\n", 16384)                = 4
write(6, "*4\r\n$6\r\nHSETNX\r\n$23\r\nOpenVAS.__G"..., 60) = 60
read(6, ":0\r\n", 16384)                = 4
write(6, "*4\r\n$6\r\nHSETNX\r\n$23\r\nOpenVAS.__G"..., 60) = 60
read(6, ":0\r\n", 16384)                = 4
write(6, "*4\r\n$6\r\nHSETNX\r\n$23\r\nOpenVAS.__G"..., 60) = 60
read(6, ":0\r\n", 16384)                = 4
write(6, "*4\r\n$6\r\nHSETNX\r\n$23\r\nOpenVAS.__G"..., 60) = 60
read(6, ":0\r\n", 16384)                = 4
write(6, "*4\r\n$6\r\nHSETNX\r\n$23\r\nOpenVAS.__G"..., 60) = 60
read(6, ":0\r\n", 16384)                = 4
write(6, "*4\r\n$6\r\nHSETNX\r\n$23\r\nOpenVAS.__G"..., 60) = 60
read(6, ":0\r\n", 16384)                = 4
write(6, "*4\r\n$6\r\nHSETNX\r\n$23\r\nOpenVAS.__G"..., 60) = 60
read(6, ":0\r\n", 16384)                = 4
write(6, "*4\r\n$6\r\nHSETNX\r\n$23\r\nOpenVAS.__G"..., 61) = 61
read(6, ":0\r\n", 16384)                = 4
write(6, "*4\r\n$6\r\nHSETNX\r\n$23\r\nOpenVAS.__G"..., 61) = 61
read(6, ":0\r\n", 16384)                = 4
write(6, "*4\r\n$6\r\nHSETNX\r\n$23\r\nOpenVAS.__G"..., 61) = 61
read(6, ":0\r\n", 16384)                = 4
write(6, "*4\r\n$6\r\nHSETNX\r\n$23\r\nOpenVAS.__G"..., 61) = 61
read(6, ":0\r\n", 16384)                = 4
write(6, "*4\r\n$6\r\nHSETNX\r\n$23\r\nOpenVAS.__G"..., 61) = 61
read(6, ":0\r\n", 16384)                = 4
write(6, "*4\r\n$6\r\nHSETNX\r\n$23\r\nOpenVAS.__G"..., 61) = 61
read(6, ":0\r\n", 16384)                = 4
rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
rt_sigaction(SIGCHLD, NULL, {SIG_DFL, [], 0}, 8) = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
nanosleep({60, 0},0x7fffb7490720)      = 0

Le jeudi 13 août 2015, 14:53:34 Ferdinand Goldmann a écrit :
> On 12.08.2015, at 11:31, Arthur <agdebusschere at payplug.com> wrote:
> > I tried to run openvasmd with strace :
> > 
> > $ sudo strace openvasmd --progress --verbose —update
> 
> openvasmd creates child processes, try running strace like this:
> 
> strace -f -s 255 -o /tmp/openvasmd.trace openvasmd …
> 
> Regards,
> Ferdinand
> 
> > [truncated ; full trace at
> > https://zerobin.net/?0d4427b3a875038a#6YMcaDXY+ACD6973QGG3aUMbRiXNMb38t+3
> > GLqnO1Rc=]
> > 
> > fstat(3, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
> > mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) =
> > 0x7f72c27d8000 read(3, "nodev\tsysfs\nnodev\trootfs\nnodev\tr"..., 1024)
> > = 325
> > read(3, "", 1024)                       = 0
> > close(3)                                = 0
> > munmap(0x7f72c27d8000, 4096)            = 0
> > gettimeofday({1438351393, 525853}, NULL) = 0
> > futex(0x7f72c02b4400, FUTEX_WAKE_PRIVATE, 2147483647) = 0
> > rt_sigaction(SIGABRT, {0x423900, ~[RTMIN RT_1], SA_RESTORER,
> > 0x7f72c1cb3340}, NULL, 8) = 0 open("/usr/share/zoneinfo/utc 0",
> > O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory) umask(077)   
> >                           = 022
> > access("/etc/openvas/openvasmd_log.conf", F_OK) = 0
> > open("/usr/share/locale/locale.alias", O_RDONLY) = 3
> > fstat(3, {st_mode=S_IFREG|0644, st_size=2570, ...}) = 0
> > mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) =
> > 0x7f72c27d8000 read(3, "# Locale name alias data base.\n#"..., 4096) =
> > 2570
> > read(3, "", 4096)                       = 0
> > close(3)                                = 0
> > munmap(0x7f72c27d8000, 4096)            = 0
> > open("/etc/openvas/openvasmd_log.conf", O_RDONLY) = 3
> > fstat(3, {st_mode=S_IFREG|0644, st_size=1183, ...}) = 0
> > read(3, "# OpenVAS Manager logging config"..., 4096) = 1183
> > read(3, "", 4096)                       = 0
> > close(3)                                = 0
> > open("/var/log/openvas/openvasmd.log", O_WRONLY|O_CREAT|O_APPEND, 0666) =
> > 3
> > fstat(3, {st_mode=S_IFREG|0600, st_size=36806, ...}) = 0
> > write(3, "md   main:   INFO:2015-07-31 14h"..., 99) = 99
> > access("/etc/openvas/pwpolicy.conf", F_OK) = 0
> > fstat(1, {st_mode=S_IFCHR|0620, st_rdev=makedev(136, 1), ...}) = 0
> > mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) =
> > 0x7f72c27d8000 write(1, "Updating NVT cache... \\", 23Updating NVT
> > cache... \) = 23 write(3, "md   main:   INFO:2015-07-31 14h"..., 93) = 93
> > rt_sigaction(SIGCHLD, {SIG_DFL, [], SA_RESTORER, 0x7f72c1cb3340}, NULL, 8)
> > = 0 clone(child_stack=0,
> > flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGCHLD,
> > child_tidptr=0x7f72c27bcb90) = 2586 wait4(2586,\
> > 
> > [hang forever]
> > 
> > 2586 is openvasmd
> > 
> > $ ps aux | grep 2586
> > root      2586  0.0  0.2 164268  8364 pts/1    S+   14:03   0:00
> > openvasmd: Updating
> > 
> > $ ps aux | grep openvas
> > root      2492  2.5  0.5 143812 21848 ?        Ss   14:02   0:27
> > openvassd: Reloaded all the NVTs. root      2581  0.0  0.0  67896  2192
> > pts/1    S+   14:03   0:00 sudo strace openvasmd --progress --verbose
> > --update root      2582  0.0  0.0   4740   756 pts/1    S+   14:03   0:00
> > strace openvasmd --progress --verbose --update root      2585  0.0  0.0
> > 161368  3708 pts/1    S+   14:03   0:00 openvasmd: Reloading root     
> > 2586  0.0  0.2 164268  8364 pts/1    S+   14:03   0:00 openvasmd:
> > Updating
> > 
> > 
> > Also, I tried a port scan to ensure there is no firewall issue. All my
> > openvas stack is on the same host:
> > 
> > $ sudo nmap -Pn -sS -p 443,9000-9400 127.0.0.1
> > 
> > Starting Nmap 6.40 ( http://nmap.org ) at 2015-07-31 14:21 UTC
> > Nmap scan report for localhost (127.0.0.1)
> > Host is up (0.0000040s latency).
> > Not shown: 400 closed ports
> > PORT     STATE SERVICE
> > 443/tcp open  https
> > 9391/tcp open  unknown
> > 
> > Nmap done: 1 IP address (1 host up) scanned in 2.36 seconds
> > 
> > 
> > 
> > Thanks for your time. On I side note, I have been able to successfully
> > update the database and run a scan in the past (a few day before this
> > issue). Again, any suggestions appreciated
> > 
> > Arthur
> > 
> > Le vendredi 31 juillet 2015, 08:18:09 Ferdinand Goldmann a écrit :
> >> This might be a shot in the blue, but check your firewall settings. I
> >> came
> >> across an OpenVAS installation recently where openvasmd just hung
> >> forever.
> >> strace indicated some TLS bug, but in the end it was just a firewall
> >> setting blocking access on the loopback interface.
> >> 
> >> Cheers,
> >> Ferdinand
> >> 
> >> On 30.07.2015, at 17:34, Arthur <calvinh34 at gmail.com> wrote:
> >>> The box is an ec2 t2.medium instance on ec2, which have 4gb of ram and
> >>> some proc.
> >>> 
> >>> I wiped the current install (apt-get purge openvas && rm -Rvf
> >>> /var/lib/openvas) then reinstalled it and reconfigured it with the help
> >>> of openvas-check-set (client certificate generation, user creation)
> >>> 
> >>> When it comes to running `openvasmd --rebuild --progress`, I observe the
> >>> exact same behaviour : progress bar stop to move, and last line of log
> >>> are :
> >>> 
> >>> $ sudo openvasmd --rebuild --progress --verbose
> >>> Rebuilding NVT cache... \
> >>> 
> >>> 
> >>> $ sudo tail /var/log/openvas/openvasmd.log
> >>> md   main:   INFO:2015-07-30 15h01.44 utc:7057:    OpenVAS Manager
> >>> version
> >>> 6.0.1 (DB revision 146) md   main:   INFO:2015-07-30 15h01.44 utc:7057:
> >>> rebuild_nvt_cache_retry: Reloading NVT cache md   main:  
> >>> INFO:2015-07-30
> >>> 15h01.44 utc:7058: update_or_rebuild_nvt_cache: Rebuilding NVT cache md
> >>> main:   INFO:2015-07-30 15h01.44 utc:7058:    Updating NVT cache. md
> >>> otp:MESSAGE:2015-07-30 15h01.44 utc:7058: Scanner loading: 3700 / 40087
> >>> nvts. md   main:   INFO:2015-07-30 15h01.54 utc:7059:
> >>> update_or_rebuild_nvt_cache: Rebuilding NVT cache md   main:
> >>> INFO:2015-07-30 15h01.54 utc:7059:    Updating NVT cache. md
> >>> otp:MESSAGE:2015-07-30 15h01.54 utc:7059: Scanner loading: 14550 / 40087
> >>> nvts. md   main:   INFO:2015-07-30 15h02.04 utc:7060:
> >>> update_or_rebuild_nvt_cache: Rebuilding NVT cache md   main:
> >>> INFO:2015-07-30 15h02.04 utc:7060:    Updating NVT cache. md
> >>> otp:MESSAGE:2015-07-30 15h02.04 utc:7060: Scanner loading: 22750 / 40087
> >>> nvts. md   main:   INFO:2015-07-30 15h02.14 utc:7061:
> >>> update_or_rebuild_nvt_cache: Rebuilding NVT cache md   main:
> >>> INFO:2015-07-30 15h02.14 utc:7061:    Updating NVT cache. md
> >>> otp:MESSAGE:2015-07-30 15h02.14 utc:7061: Scanner loading: 29700 / 40087
> >>> nvts. md   main:   INFO:2015-07-30 15h02.24 utc:7062:
> >>> update_or_rebuild_nvt_cache: Rebuilding NVT cache md   main:
> >>> INFO:2015-07-30 15h02.24 utc:7062:    Updating NVT cache. md
> >>> otp:MESSAGE:2015-07-30 15h02.24 utc:7062: Scanner loading: 35800 / 40087
> >>> nvts. md   main:   INFO:2015-07-30 15h02.34 utc:7063:
> >>> update_or_rebuild_nvt_cache: Rebuilding NVT cache md   main:
> >>> INFO:2015-07-30 15h02.35 utc:7063:    Updating NVT cache.
> >>> 
> >>> Half an hour later, the process is still alive and the database is still
> >>> locked ;
> >>> 
> >>> $ ps aux | grep openvas
> >>> root      7054  1.9  0.5 145848 21864 ?        Ss   15:01   0:28
> >>> openvassd: Reloaded all the NVTs. root      7056  0.0  0.0  69960  2204
> >>> pts/1    S+   15:01   0:00 sudo openvasmd --rebuild --progress --verbose
> >>> root      7057  0.0  0.0 163432  3912 pts/1    S+   15:01   0:00
> >>> openvasmd: Reloading root      7063  0.0  0.2 166344  8456 pts/1    S+
> >>> 15:02   0:00 openvasmd: Rebuilding
> >>> 
> >>> 
> >>> $ echo ".schema" | sudo sqlite3 /var/lib/openvas/mgr/tasks.db
> >>> Error: database is locked
> >>> 
> >>> On that host, load is far below 1, and there is more than 1.5gb of free
> >>> ram, so I doubt it is a hardware issue.
> >>> 
> >>> I will let the process run and see where it goes.
> >>> Meanwhile, I appreciate any suggestions.
> >>> 
> >>> Thanks for your time.
> >>> Arthur
> >>> 
> >>> Le jeudi 30 juillet 2015, 09:46:04 Brandon Perry a écrit :
> >>>> Also, not sure what the specs on your box is. You should have at least
> >>>> 4gb
> >>>> RAM and a nice proc to nicely run OpenVAS.
> >>>> 
> >>>> On Thu, Jul 30, 2015 at 9:16 AM, Brandon Perry
> >>>> <bperry.volatile at gmail.com>
> >>>> 
> >>>> wrote:
> >>>>> Yes, while 30 mins is a bit long, let it sit a bit longer. I don't
> >>>>> have
> >>>>> much experience with the Ubuntu packages however.
> >>>>> 
> >>>>> Also, don't stop them halfway through with Ctrl+c, I am not sure what
> >>>>> kind
> >>>>> of state that leaves openvasmd in. If you have done that, probably
> >>>>> should
> >>>>> reinstall and try again.
> >>>>> 
> >>>>> --rebuild will completely rebuild the openvasmd database. --update
> >>>>> simply
> >>>>> takes the difference between what openvas-nvt-sync et al have added
> >>>>> and
> >>>>> updates the sqlite DB to include the new data. You probably do not
> >>>>> need
> >>>>> to
> >>>>> run --rebuild.
> >>>>> 
> >>>>> On Thu, Jul 30, 2015 at 9:11 AM, Arthur <calvinh34 at gmail.com> wrote:
> >>>>>> Haha good question ; around 30 minutes ? The first time I ran that
> >>>>>> command, it
> >>>>>> took at most 5 minutes. Should I wait more ?
> >>>>>> 
> >>>>>> Also, the animated progress bar (the slashes `/|\-`) do not move
> >>>>>> anymore
> >>>>>> when
> >>>>>> it hangs ; I assume a call is blocking somewhere.
> >>>>>> 
> >>>>>> 
> >>>>>> On a side note, I am using mrazavi packages for Ubuntu 14.04
> >>>>>> (https://launchpad.net/~mrazavi/+archive/ubuntu/openvas)
> >>>>>> 
> >>>>>> Le jeudi 30 juillet 2015 09:05:15, vous avez écrit :
> >>>>>>> How long is forever?
> >>>>>>> 
> >>>>>>> On Thu, Jul 30, 2015 at 9:04 AM, Arthur <calvinh34 at gmail.com> wrote:
> >>>>>>>> Hello,
> >>>>>>>> 
> >>>>>>>> As my title say, my problem today is that the command
> >>>>>>>> `openvasmd --progress --verbose --rebuild` never returns and hangs
> >>>>>>>> forever,
> >>>>>>>> locking the sqlite database and preventing any other action
> >>>>>>>> (including
> >>>>>>>> `openvas-check-setup` which is locked when trying to find users)
> >>>>>>>> 
> >>>>>>>> $ sudo openvasmd --progress --verbose --rebuild
> >>>>>>>> Rebuilding NVT cache... /
> >>>>>>>> [hangs forever]
> >>>>>>>> 
> >>>>>>>> 
> >>>>>>>> $ sudo tail -f /var/log/openvas/openvasmd.log
> >>>>>>>> md   main:WARNING:2015-07-30 13h35.46 utc:4045: database must be
> >>>>>>>> initialised
> >>>>>>>> from scanner (with --update or --rebuild)
> >>>>>>>> lib auth:   INFO:2015-07-30 13h35.47 utc:4045: Authentication
> >>>>>>>> configuration
> >>>>>>>> not found.
> >>>>>>>> md   main:   INFO:2015-07-30 13h35.52 utc:4049:    OpenVAS Manager
> >>>>>> 
> >>>>>> version
> >>>>>> 
> >>>>>>>> 6.0.1 (DB revision 146)
> >>>>>> 
> >>>>>>>> md   main:   INFO:2015-07-30 13h35.52 utc:4049:
> >>>>>> rebuild_nvt_cache_retry:
> >>>>>>>> Reloading NVT cache
> >>>>>>>> md   main:   INFO:2015-07-30 13h35.52 utc:4050:
> >>>>>>>> update_or_rebuild_nvt_cache:
> >>>>>>>> Rebuilding NVT cache
> >>>>>>>> md   main:   INFO:2015-07-30 13h35.52 utc:4050:    Updating NVT
> >>>>>>>> cache.
> >>>>>> 
> >>>>>>>> md    otp:MESSAGE:2015-07-30 13h35.52 utc:4050: Scanner loading:
> >>>>>> 16550 /
> >>>>>> 
> >>>>>>>> 40087
> >>>>>>>> nvts.
> >>>>>>>> md   main:   INFO:2015-07-30 13h36.02 utc:4060:
> >>>>>>>> update_or_rebuild_nvt_cache:
> >>>>>>>> Rebuilding NVT cache
> >>>>>>>> md   main:   INFO:2015-07-30 13h36.02 utc:4060:    Updating NVT
> >>>>>>>> cache.
> >>>>>> 
> >>>>>>>> md    otp:MESSAGE:2015-07-30 13h36.02 utc:4060: Scanner loading:
> >>>>>> 25400 /
> >>>>>> 
> >>>>>>>> 40087
> >>>>>>>> nvts.
> >>>>>>>> md   main:   INFO:2015-07-30 13h36.12 utc:4061:
> >>>>>>>> update_or_rebuild_nvt_cache:
> >>>>>>>> Rebuilding NVT cache
> >>>>>>>> md   main:   INFO:2015-07-30 13h36.12 utc:4061:    Updating NVT
> >>>>>>>> cache.
> >>>>>> 
> >>>>>>>> md    otp:MESSAGE:2015-07-30 13h36.12 utc:4061: Scanner loading:
> >>>>>> 32650 /
> >>>>>> 
> >>>>>>>> 40087
> >>>>>>>> nvts.
> >>>>>>>> md   main:   INFO:2015-07-30 13h36.22 utc:4062:
> >>>>>>>> update_or_rebuild_nvt_cache:
> >>>>>>>> Rebuilding NVT cache
> >>>>>>>> md   main:   INFO:2015-07-30 13h36.22 utc:4062:    Updating NVT
> >>>>>>>> cache.
> >>>>>> 
> >>>>>>>> md    otp:MESSAGE:2015-07-30 13h36.23 utc:4062: Scanner loading:
> >>>>>> 38800 /
> >>>>>> 
> >>>>>>>> 40087
> >>>>>>>> nvts.
> >>>>>>>> md   main:   INFO:2015-07-30 13h36.33 utc:4063:
> >>>>>>>> update_or_rebuild_nvt_cache:
> >>>>>>>> Rebuilding NVT cache
> >>>>>>>> md   main:   INFO:2015-07-30 13h36.33 utc:4063:    Updating NVT
> >>>>>>>> cache.
> >>>>>>>> lib auth:   INFO:2015-07-30 13h44.38 utc:4225: Authentication
> >>>>>>>> configuration
> >>>>>>>> not found.
> >>>>>>>> [hang forever]
> >>>>>>>> 
> >>>>>>>> 
> >>>>>>>> I have the exact same problem when update
> >>>>>>>> 
> >>>>>>>> $ sudo openvasmd --progress --verbose --update
> >>>>>>>> Updating NVT cache... \
> >>>>>>>> [hang forever]
> >>>>>>>> 
> >>>>>>>> $ sudo tail -f /var/log/openvas/openvasmd.log
> >>>>>>>> md   main:   INFO:2015-07-30 13h56.11 utc:4298:    OpenVAS Manager
> >>>>>> 
> >>>>>> version
> >>>>>> 
> >>>>>>>> 6.0.1 (DB revision 146)
> >>>>>> 
> >>>>>>>> md   main:   INFO:2015-07-30 13h56.11 utc:4298:
> >>>>>> rebuild_nvt_cache_retry:
> >>>>>>>> Reloading NVT cache
> >>>>>>>> md   main:   INFO:2015-07-30 13h56.11 utc:4299:
> >>>>>>>> update_or_rebuild_nvt_cache:
> >>>>>>>> Updating NVT cache
> >>>>>>>> 
> >>>>>>>> I tried to move the database (/var/lib/openvas/mgr/tasks.db) and
> >>>>>> 
> >>>>>> recreate
> >>>>>> 
> >>>>>>>> it,
> >>>>>>>> with the same results.
> >>>>>>>> 
> >>>>>>>> 
> >>>>>>>> Any suggestions ?  Is it some server-side issue (maybe an
> >>>>>>>> unreachable
> >>>>>> 
> >>>>>> host
> >>>>>> 
> >>>>>>>> ?)
> >>>>>>>> _______________________________________________
> >>>>>>>> Openvas-discuss mailing list
> >>>>>>>> Openvas-discuss at wald.intevation.org
> >>>>>> 
> >>>>>> https://lists.wald.intevation.org/cgi-bin/mailman/listinfo/openvas-di
> >>>>>> sc
> >>>>>> us
> >>>>>> s
> >>>>> 
> >>>>> --
> >>>>> http://volatile-minds.blogspot.com -- blog
> >>>>> http://www.volatileminds.net -- website
> >>> 
> >>> _______________________________________________
> >>> Openvas-discuss mailing list
> >>> Openvas-discuss at wald.intevation.org
> >>> https://lists.wald.intevation.org/cgi-bin/mailman/listinfo/openvas-discu
> >>> ss
> >>> 
> >>>> Ferdinand Goldmann
> >>>> Johannes Kepler University Linz - Information Management
> >>>> Mail: Ferdinand.Goldmann at jku.at Phone: +4373224683925 PGP: 0x13EAB993
> >>>> A lack of planning on your part doesn't constitute an emergency on my
> >>>> part.
> 
> --
> 
> >> Ferdinand Goldmann
> >> Johannes Kepler University Linz - Information Management
> >> Mail: Ferdinand.Goldmann at jku.at Phone: +4373224683925 PGP: 0x13EAB993
> >> A lack of planning on your part doesn't constitute an emergency on my
> >> part.



More information about the Openvas-discuss mailing list