offener Betatest für neue cmsearch VM Anwendung

[Crystal Pellet]

Falls Ihr die Größe der stderr.txt nicht begrenzen könnt, würde ich daher vorschlagen, für diese beiden Arbeitspakete den rsc_disk_bound zu erhöhen. Die Preisfrage ist nur, wie hoch.

Das Deine stderr.txt so heranwächst ist nicht normal.
Meine Aufgabe läuft jetzt fast 4 Wochen und die stderr.txt ist 'nur' 673kB und beinhaltet nur SchnapSchuß- und Statusmeldungen.

Anscheinend ist Dein wrapper nicht im Stande die showvminfo aus zu führen und bleibt das dann weiterhin probieren,
weil Dein VM und/oder snapshot besetzt ist. Weshalb, warum?

Unterschiede zu meiner Konfiguration: Ich habe noch den etwas älteren wrapper laufen und ich sorge das der wrapper immer normale priorität kriegt und nicht den niedrigsten den es gibt.

[Beorn]

Hallo Crystal Pellet,

der wrapper vboxwrapper_26028_windows_x86_64.exe läuft lt. Taskmanager mit Prio. niedrig. Werde das mal erhöhen, danke für den Tipp.

Wenn man sich die Historie der beiden o.g. Arbeitspakete anschaut, sieht man dass der Fehler "Maximum disk usage exceeded" auch bei anderen Usern auftrat. Ich weiß nicht, ob die Ursache die gleiche war. Es ist natürlich auch möglich, dass der insgesamt für BOINC zur Verfügung stehende Festplattenplatz dort nicht ausreichend war oder andere Ursachen auftraten. Auf zwei Rechnern (darunter ein Linuxrechner von pepita) ist ja bisher gar kein Fehler aufgetreten (mal vorausgesetzt, dass die Workunits auch schon berechnet werden ). Interessanterweise benutzen beide auch eine ältere Version von cmsearch VM (VirtualBox) 1.0.2, nämlich v1.01 bzw. v1.02. Das würde für Deine Vermutung, dass es am wrapper liegt, sprechen.

[Dunuin]

Man soll VB 4.2.16 nehmen, weil die VB 4.2.18 einen bekannten Bug hat und mit dem Wrapper Probleme verursacht.

[Beorn]

Man soll VB 4.2.16 nehmen, weil die VB 4.2.18 einen bekannten Bug hat und mit dem Wrapper Probleme verursacht.

Meine Version: VirtualBox VM 4.2.16 r86992

[ChristianB]

@Beorn: Warum deine stderr.txt so schnell anwächst kann ich jetzt auch nicht erklären. Das ist aber nicht die Ursache für die vielen "Maximum disk usage exceeded". Die stderr.txt von denen sehen alle Normal aus und waren auch nicht sehr groß. Kommt denn nach deinem geposteten Auszug noch das VM Log? Ist schon komisch das nach dem Fehler das kein Log geholt werden konnte doch ein Log angezeigt wird, macht irgendwie keinen Sinn. Auch das dies bei den anderen Testunits nicht auftritt ist komisch.

Hast du selbst einen VirtualBox Manager laufen und überwachst du damit die VM? Das könnte zu dem Fehler das kein Log gelesen werden kann führen.

[Tom_unoduetre]

Virtualbox 4.3.0 ist veröffentlicht worden: https://www.virtualbox.org/wiki/Changelog

[Beorn]

@Beorn: Warum deine stderr.txt so schnell anwächst kann ich jetzt auch nicht erklären. Das ist aber nicht die Ursache für die vielen "Maximum disk usage exceeded". Die stderr.txt von denen sehen alle Normal aus und waren auch nicht sehr groß.

Macht die Sache noch seltsamer. Die Erhöhung der Wrapper-Prio. brachte übrigens keine Abhilfe. Der Fehler trat in der Zwischenzeit noch zweimal auf, ich habe wieder 150 MB stderr gelöscht und bin im Moment wieder bei knapp 10 MB angelangt.

Code: Alles auswählen

2013-10-15 22:35:54 (3680): Checkpoint completed.
2013-10-15 22:45:55 (3680): Creating new snapshot for VM.
2013-10-15 22:46:03 (3680): Deleting stale snapshot.
2013-10-15 22:46:05 (3680): Checkpoint completed.
2013-10-15 22:50:52 (3680): Error in get vm log for VM: 3
Arguments:
showvminfo "boinc_fb76a72fc6655131" --log 0 
Output:
VirtualBox VM 4.2.16 r86992 win.amd64 (Jul  4 2013 15:51:44) release log
00:00:00.056979 Log opened 2013-10-15T05:40:48.695716200Z

Code: Alles auswählen

2013-10-17 11:21:40 (816): Creating new snapshot for VM.
2013-10-17 11:21:48 (816): Deleting stale snapshot.
2013-10-17 11:21:49 (816): Checkpoint completed.
2013-10-17 11:26:42 (816): Error in get vm log for VM: 3
Arguments:
showvminfo "boinc_fb76a72fc6655131" --log 0 
Output:
VirtualBox VM 4.2.16 r86992 win.amd64 (Jul  4 2013 15:51:44) release log
00:00:00.042649 Log opened 2013-10-16T18:39:34.043413700Z

Kommt denn nach deinem geposteten Auszug noch das VM Log?

Ja, es kommt zunächst ein recht langer Auszug aus dem VM Log (inkl. CPUID Infos etc.), dann wiederholen sich aber offensichtlich bis zum Dateiende der stderr.txt nur zwei Abschnitte, nämlich:

Code: Alles auswählen

05:44:49.794013 ********************* End of CFGM dump **********************
05:44:50.074438 Changing the VM state from 'SUSPENDED' to 'RESUMING'.
05:44:50.074495 Changing the VM state from 'RESUMING' to 'RUNNING'.
05:44:55.318224 Changing the VM state from 'RUNNING' to 'SUSPENDING'.
05:44:55.774983 PDMR3Suspend: 456 736 124 ns run time
05:44:55.775003 Changing the VM state from 'SUSPENDING' to 'SUSPENDED'.
05:44:55.775847 DrvBlock: Flushes will be ignored
05:44:55.775855 DrvBlock: Async flushes will be passed to the disk
05:44:55.776116 VD: Opening the disk took 236410 ns
05:44:55.776131 PIIX3 ATA: LUN#0: disk, PCHS=4161/16/63, total number of sectors 4194304
05:44:55.776139 ************************* CFGM dump *************************
05:44:55.776140 [/Devices/piix3ide/0/] (level 0)
05:44:55.776142   PCIBusNo      <integer> = 0x0000000000000000 (0)
05:44:55.776145   PCIDeviceNo   <integer> = 0x0000000000000001 (1)
05:44:55.776146   PCIFunctionNo <integer> = 0x0000000000000001 (1)
05:44:55.776147   Trusted       <integer> = 0x0000000000000001 (1)
05:44:55.776148 
05:44:55.776149 [/Devices/piix3ide/0/Config/] (level 1) (restricted root)
05:44:55.776151   Type <string>  = "PIIX4" (cb=6)
05:44:55.776152 
05:44:55.776153 [/Devices/piix3ide/0/Config/PrimaryMaster/] (level 2)
05:44:55.776155   NonRotationalMedium <integer> = 0x0000000000000000 (0)
05:44:55.776156 
05:44:55.776156 [/Devices/piix3ide/0/LUN#0/] (level 1)
05:44:55.776158   Driver <string>  = "Block" (cb=6)
05:44:55.776159 
05:44:55.776159 [/Devices/piix3ide/0/LUN#0/AttachedDriver/] (level 2)
05:44:55.776161   Driver <string>  = "VD" (cb=3)
05:44:55.776162 
05:44:55.776163 [/Devices/piix3ide/0/LUN#0/AttachedDriver/Config/] (level 3) (restricted root)
05:44:55.776165   Format     <string>  = "VDI" (cb=4)
05:44:55.776166   Path       <string>  = "D:\ProgramData\BOINC\slots\9\boinc_fb76a72fc6655131\Snapshots\{650bac36-f84e-43c7-b30c-c8a078244a51}.vdi" (cb=105)
05:44:55.776167   SetupMerge <integer> = 0x0000000000000001 (1)
05:44:55.776168   Type       <string>  = "HardDisk" (cb=9)
05:44:55.776169 
05:44:55.776170 [/Devices/piix3ide/0/LUN#0/AttachedDriver/Config/Parent/] (level 4)
05:44:55.776172   Format      <string>  = "VDI" (cb=4)
05:44:55.776173   MergeSource <integer> = 0x0000000000000001 (1)
05:44:55.776174   Path        <string>  = "D:\ProgramData\BOINC\slots\9\boinc_fb76a72fc6655131\Snapshots\{ed98fbf7-ab54-4f4c-97d9-ec954d59d419}.vdi" (cb=105)
05:44:55.776176 
05:44:55.776176 [/Devices/piix3ide/0/LUN#0/AttachedDriver/Config/Parent/Parent/] (level 5)
05:44:55.776178   Format      <string>  = "VDI" (cb=4)
05:44:55.776179   MergeTarget <integer> = 0x0000000000000001 (1)
05:44:55.776180   Path        <string>  = "D:\ProgramData\BOINC\slots\9\vm_image.vdi" (cb=42)
05:44:55.776182 
05:44:55.776182 [/Devices/piix3ide/0/LUN#0/Config/] (level 2) (restricted root)
05:44:55.776184   Mountable <integer> = 0x0000000000000000 (0)
05:44:55.776185   Type      <string>  = "HardDisk" (cb=9)
05:44:55.776186 
05:44:55.776187 [/Devices/piix3ide/0/LUN#999/] (level 1)
05:44:55.776188   Driver <string>  = "MainStatus" (cb=11)
05:44:55.776189 
05:44:55.776190 [/Devices/piix3ide/0/LUN#999/Config/] (level 2) (restricted root)
05:44:55.776192   DeviceInstance        <string>  = "piix3ide/0" (cb=11)
05:44:55.776193   First                 <integer> = 0x0000000000000000 (0)
05:44:55.776194   Last                  <integer> = 0x0000000000000003 (3)
05:44:55.776196   pConsole              <integer> = 0x0000000001cc8280 (30 179 968)
05:44:55.776198   papLeds               <integer> = 0x0000000001cc8598 (30 180 760)
05:44:55.776200   pmapMediumAttachments <integer> = 0x0000000001cc88a0 (30 181 536)
05:44:55.776201 
05:44:55.776202 ********************* End of CFGM dump **********************
05:44:55.776213 Changing the VM state from 'SUSPENDED' to 'RESUMING'.

... u.s.w.

Hast du selbst einen VirtualBox Manager laufen und überwachst du damit die VM? Das könnte zu dem Fehler das kein Log gelesen werden kann führen.

Nein, ich habe selbst keinen Manager gestartet. Bis 13 Oct 2013, 17:54:05 UTC hatte ich noch eine Test4Theory Workunit auf gleichem Rechner laufen. Das kann aber wohl kaum die danach aufgetretenen Fehler erklären, oder?

[Norman]

hi, dieser oben beschriebene cfgm-dump hatte ich ebenfalls vor ein paar tagen :

Code: Alles auswählen

14:47:26.106813 ********************* End of CFGM dump **********************
14:47:26.185618 Changing the VM state from 'SUSPENDED' to 'RESUMING'.
14:47:26.185685 Changing the VM state from 'RESUMING' to 'RUNNING'.
14:47:27.034448 Changing the VM state from 'RUNNING' to 'SUSPENDING'.
14:47:27.385566 PDMR3Suspend: 351 105 825 ns run time
14:47:27.385578 Changing the VM state from 'SUSPENDING' to 'SUSPENDED'.
14:47:27.386090 DrvBlock: Flushes will be ignored
14:47:27.386095 DrvBlock: Async flushes will be passed to the disk
14:47:27.386217 VD: Opening the disk took 108320 ns
14:47:27.386227 PIIX3 ATA: LUN#0: disk, PCHS=4161/16/63, total number of sectors 4194304
14:47:27.386231 ************************* CFGM dump *************************
14:47:27.386231 [/Devices/piix3ide/0/] (level 0)
14:47:27.386233   PCIBusNo      <integer> = 0x0000000000000000 (0)
14:47:27.386234   PCIDeviceNo   <integer> = 0x0000000000000001 (1)
14:47:27.386235   PCIFunctionNo <integer> = 0x0000000000000001 (1)
14:47:27.386236   Trusted       <integer> = 0x0000000000000001 (1)
14:47:27.386236
14:47:27.386237 [/Devices/piix3ide/0/Config/] (level 1) (restricted root)
14:47:27.386238   Type <string>  = "PIIX4" (cb=6)
14:47:27.386238
14:47:27.386239 [/Devices/piix3ide/0/Config/PrimaryMaster/] (level 2)
14:47:27.386240   NonRotationalMedium <integer> = 0x0000000000000000 (0)
14:47:27.386241
14:47:27.386241 [/Devices/piix3ide/0/LUN#0/] (level 1)
14:47:27.386242   Driver <string>  = "Block" (cb=6)
14:47:27.386242
14:47:27.386243 [/Devices/piix3ide/0/LUN#0/AttachedDriver/] (level 2)
14:47:27.386244   Driver <string>  = "VD" (cb=3)
14:47:27.386244
14:47:27.386244 [/Devices/piix3ide/0/LUN#0/AttachedDriver/Config/] (level 3) (restricted root)
14:47:27.386246   Format     <string>  = "VDI" (cb=4)
14:47:27.386246   Path       <string>  = "C:\BOINC\slots\1\boinc_655bf4d3f92186e3\Snapshots\{4052f92e-70cd-4833-9f52-b1a4be87eba5}.vdi" (cb=93)
14:47:27.386247   SetupMerge <integer> = 0x0000000000000001 (1)
14:47:27.386248   Type       <string>  = "HardDisk" (cb=9)
14:47:27.386248
14:47:27.386249 [/Devices/piix3ide/0/LUN#0/AttachedDriver/Config/Parent/] (level 4)
14:47:27.386250   Format      <string>  = "VDI" (cb=4)
14:47:27.386250   MergeSource <integer> = 0x0000000000000001 (1)
14:47:27.386251   Path        <string>  = "C:\BOINC\slots\1\boinc_655bf4d3f92186e3\Snapshots\{3e7c23ec-f50d-48d6-9f5e-6bcef915c0d2}.vdi" (cb=93)
14:47:27.386252
14:47:27.386252 [/Devices/piix3ide/0/LUN#0/AttachedDriver/Config/Parent/Parent/] (level 5)
14:47:27.386253   Format      <string>  = "VDI" (cb=4)
14:47:27.386254   MergeTarget <integer> = 0x0000000000000001 (1)
14:47:27.386255   Path        <string>  = "C:\BOINC\slots\1\vm_image.vdi" (cb=30)
14:47:27.386255
14:47:27.386256 [/Devices/piix3ide/0/LUN#0/Config/] (level 2) (restricted root)
14:47:27.386257   Mountable <integer> = 0x0000000000000000 (0)
14:47:27.386257   Type      <string>  = "HardDisk" (cb=9)
14:47:27.386258
14:47:27.386258 [/Devices/piix3ide/0/LUN#999/] (level 1)
14:47:27.386259   Driver <string>  = "MainStatus" (cb=11)
14:47:27.386260
14:47:27.386260 [/Devices/piix3ide/0/LUN#999/Config/] (level 2) (restricted root)
14:47:27.386261   DeviceInstance        <string>  = "piix3ide/0" (cb=11)
14:47:27.386262   First                 <integer> = 0x0000000000000000 (0)
14:47:27.386263   Last                  <integer> = 0x0000000000000003 (3)
14:47:27.386264   pConsole              <integer> = 0x0000000001c37
2013-10-12 03:41:26 (2160): Creating new snapshot for VM.
2013-10-12 03:41:30 (2160): Deleting stale snapshot.
2013-10-12 03:41:31 (2160): Checkpoint completed.
2013-10-12 03:46:15 (2160): Error in get vm log for VM: -108
Arguments:
showvminfo "boinc_655bf4d3f92186e3" --log 0
Output:
VirtualBox VM 4.2.16 r86992 win.amd64 (Jul  4 2013 15:51:44) release log

aber nach einem neustart des rechners lief alles normal weiter.
scheint irgendwas nicht richtig rund zu laufen.
seit dem wächst die stderr.txt nur noch minimal paar kilobytechen an.

@Christian: das ist der log den ich dir geschickt habe

[Norman]

und nach über 5 tagen abgeschmiert
http://www.rnaworld.de/rnaworld/result. ... d=14920342

[ChristianB]

und nach über 5 tagen abgeschmiert
http://www.rnaworld.de/rnaworld/result. ... d=14920342

Das ist komisch weil kurz vorher nicht vom snapshot wiederhergestellt wurde sondern die VM komplett neu gestartet wurde und dann abgeschmiert. Ich hatte ja die Speichergrenze für die 5 Langläufer hochgesetzt so dass dein Nachfolger nicht auf dieses Problem stoßen sollte. Hast du die heute noch im Manager gesehen die hätte eine geringere Prozentzahl als gestern anzeigen müssen? Kannst du das bestätigen?

[Norman]

also wie die ganzen tage auch 98.xxx % und nichts außergewöhnliches.

[Michael H.W. Weber]

Also mein Phenom II X4 955BE Quadcore kommt mit der VM überhaupt nicht klar. Die Maschine ist quasi völlig unresponsiv. Die Fortschritssanzeigen sind vollkommen aus dem Ruder: Da werden nach Download der WU erst 300 Std. angezeigt, dann bricht man eine der vier parallel laufenden WUs ab und schon ändert sich als Folge dessen bei allen anderen die Laufzeitangabe auf je nach WU ca. 8-22 Std. (was realistisch bei den WUS erscheint) und der Fortschrittsbalken schnellt bei allen WUs auf 98,xy% hoch - was schon rein rechnerisch nicht geht bei Angabe von erledigter Zeit (1 Std.) und verbleibender (8 Std.).
Sehr seltsam.

Michael.

P.S.: Die totale Systemausbremsung erlebe ich auch bei nur zwei parallalen VM WUs und zweien von Einstein@home.