Development for LHC@home

The VirtualBox wrappers for Windows, Linux and Mac have been upgraded to 26160.

It contains the following fix:
* VBOX: If polling for the current VM state fails for any reason, like vboxsvc crashing, do a temp exit for 24 hours.


Let us know how it goes.

Aren't three day deadlines a bit short if you're going to hold things up for 24hrs?

Win7 and Ubuntu Linux running OK.

Win7 and Ubuntu Linux running OK.

SLC6 seems to be running OK, but I'm having a problem with Win7. The VBoxStartup.log shows:

1b18.1bd4: '\Device\HarddiskVolume2\Program Files\Oracle\VirtualBox\VBoxHeadless.exe' has no imports
1b18.1bd4: supHardenedWinVerifyImageByHandle: -> 0 (\Device\HarddiskVolume2\Program Files\Oracle\VirtualBox\VBoxHeadless.exe)
1b18.1bd4: SUPR3HardenedMain: Respawn #2
1b18.1bd4: supR3HardNtEnableThreadCreation:
1b18.1bd4: Error -104 in supR3HardenedWinReSpawn! (enmWhat=5)
1b18.1bd4: Error relaunching VirtualBox VM process: 5
Command line: '60eaff78-4bdd-042d-2e72-669728efd737-suplib-3rdchild
--comment boinc_9fa198ab087bb273 --startvm ba2f71c5-4f87-47c4-8e3e-d6ce0e15213b
--vrde config

Googling suggests it's a security issue somehow. There are reports in the log file:

1a38.53c: supR3HardenedScreenImage/NtCreateSection: cache hit
(Unknown Status 22900 (0x5974)) on \Device\HarddiskVolume2\Windows\System32\kernel32.dll
[lacks WinVerifyTrust]

---

[quote]Win7 and Ubuntu Linux running OK.

SLC6 seems to be running OK, but I'm having a problem with Win7. The VBoxStartup.log shows:

1b18.1bd4: '\Device\HarddiskVolume2\Program Files\Oracle\VirtualBox\VBoxHeadless.exe' has no imports
1b18.1bd4: supHardenedWinVerifyImageByHandle: -> 0 (\Device\HarddiskVolume2\Program Files\Oracle\VirtualBox\VBoxHeadless.exe)
1b18.1bd4: SUPR3HardenedMain: Respawn #2
1b18.1bd4: supR3HardNtEnableThreadCreation:
1b18.1bd4: Error -104 in supR3HardenedWinReSpawn! (enmWhat=5)
1b18.1bd4: Error relaunching VirtualBox VM process: 5
Command line: '60eaff78-4bdd-042d-2e72-669728efd737-suplib-3rdchild
--comment boinc_9fa198ab087bb273 --startvm ba2f71c5-4f87-47c4-8e3e-d6ce0e15213b
--vrde config

Doesn't mean much to me, I'm afraid. In case it helps for reference, this is the same part of the start up log from my Win7 system, which is running OK.

a78.b4c: '\Device\HarddiskVolume2\Program Files\Oracle\VirtualBox\VBoxHeadless.exe' has no imports
a78.b4c: '\Device\HarddiskVolume2\Windows\System32\apisetschema.dll' has no imports
a78.b4c: '\Device\HarddiskVolume2\Windows\System32\ntdll.dll' has no imports
a78.b4c: supR3HardNtChildPurify: Done after 280 ms and 0 fixes (loop #0).
a38.b84: Log file opened: 4.3.26r98988 g_hStartupLog=0000000000000004 g_uNtVerCombined=0x611db110
a38.b84: supR3HardenedVmProcessInit: uNtDllAddr=0000000077780000
a38.b84: ntdll.dll: timestamp 0x4ce7c8f9 (rc=VINF_SUCCESS)
a38.b84: New simple heap: #1 0000000000260000 LB 0x400000 (for 1740800 allocation)
a78.b4c: supR3HardNtEnableThreadCreation:
a38.b84: System32:  \Device\HarddiskVolume2\Windows\System32
a38.b84: WinSxS:    \Device\HarddiskVolume2\Windows\winsxs
a38.b84: KnownDllPath: C:\Windows\system32
a38.b84: supR3HardenedVmProcessInit: Opening vboxdrv stub...
a38.b84: supR3HardenedVmProcessInit: Restoring LdrInitializeThunk...
a38.b84: supR3HardenedVmProcessInit: Returning to LdrInitializeThunk...
a38.b84: Registered Dll notification callback with NTDLL.

Let me know if you want anything from any other logs.

Let me know if you want anything from any other logs.

Can you check if you have any lines in that log that include "[lacks WinVerifyTrust]"? Thanks.

---

Let me know if you want anything from any other logs.

Can you check if you have any lines in that log that include "[lacks WinVerifyTrust]"? Thanks.

Yes.

(\Device\HarddiskVolume2\Windows\System32\kernel32.dll)
a7c.a80: supR3HardenedWinVerifyCacheInsert: \Device\HarddiskVolume2\Windows\System32\kernel32.dll
a7c.a80: supR3HardenedMonitor_LdrLoadDll: pName=C:\Windows\system32\kernel32.dll (Input=kernel32.dll, rcNtResolve=0xc0150008) *pfFlags=0xffffffff pwszSearchPath=0000000000000000:<flags> [calling]
a7c.a80: supR3HardenedScreenImage/NtCreateSection: cache hit (Unknown Status 22900 (0x5974)) on \Device\HarddiskVolume2\Windows\System32\kernel32.dll [lacks WinVerifyTrust]
a7c.a80: supR3HardenedDllNotificationCallback: load   0000000076ee0000 LB 0x0011f000 C:\Windows\system32\kernel32.dll [fFlags=0x0]
a7c.a80: supR3HardenedScreenImage/LdrLoadDll: cache hit (Unknown Status 22900 (0x5974)) on \Device\HarddiskVolume2\Windows\System32\kernel32.dll [lacks WinVerifyTrust]
a7c.a80: supR3HardenedDllNotificationCallback: load   000007fefd3b0000 LB 0x0006b000 C:\Windows\system32\KERNELBASE.dll [fFlags=0x0]
a7c.a80: supHardenedWinVerifyImageByHandle: -> 22900 (\Device\HarddiskVolume2\Windows\System32\KernelBase.dll)
a7c.a80: supR3HardenedWinVerifyCacheInsert: \Device\HarddiskVolume2\Windows\System32\KernelBase.dll
a7c.a80: supR3HardenedMonitor_LdrLoadDll: returns rcNt=0x0 hMod=0000000076ee0000 'C:\Windows\system32\kernel32.dll'

Edit:- Missed this bit off....

There are many lines, various dlls, which include this. Are there any other
criteria?

Just counted 'em 135.

Important Edit
I fear the "reference good file" may be misleading. There are several similar sections. The first occurrence which matches your file is:-

8dc.8d4: '\Device\HarddiskVolume2\Program Files\Oracle\VirtualBox\VBoxHeadless.exe' has no imports
8dc.8d4: supHardenedWinVerifyImageByHandle: -> 0 (\Device\HarddiskVolume2\Program Files\Oracle\VirtualBox\VBoxHeadless.exe)
8dc.8d4: supR3HardNtEnableThreadCreation:
8dc.8d4: supR3HardNtDisableThreadCreation: pvLdrInitThunk=000000007712c320 pvNtTerminateThread=0000000077151840
8dc.8d4: supR3HardenedWinDoReSpawn(1): New child a7c.a80 [kernel32].

OK, thanks. There was some speculation that an update had gone wrong, like it did last December, but I hadn't done any updates between my last successful run and these problems today. However, if you have the same messages and it works for you, then that's probably not the smoking gun.
The several similar sections seems to be the VM being re-spawned several times (you'll notice the prefix change on the lines when it does the "new child" bit).
I'm baffled but my home ISP has decided to trash my line rate last night and tonight, so I can't do much from home right now.

---

The several similar sections seems to be the VM being re-spawned several times (you'll notice the prefix change on the lines when it does the "new child" bit).
I'm baffled but my home ISP has decided to trash my line rate last night and tonight, so I can't do much from home right now.

I've got a copy of the whole file (ca 320k). It looks like VBox appends a new set of entries each time it starts. The file here appears to cover three non identical sessions (three "Log File Opened" entries) rather than re-spawning the VM but most of it is unintelligible to me.

OK, I upgraded BOINC from 7.4.36 to 7.4.42 and downgraded VBox to 4.3.12 using the BOINC-packaged copy (I'd uninstalled 4.3.26 first). Loaded up the 4.3.12 Extensions and started BOINC. It's downloaded a CMS task and is happily running it. So I'm still baffled as to what the problem was.

---

... So I'm still baffled as to what the problem was.

Me too. One reason could be that the new installations have both the right access privileges now.

Still wondering where

Loading settings file "C:\Users\admD405\VirtualBox VMs\Test_Cms\Test_Cms.vbox" with version "1.14-windows"

came from, what was loaded after settings file VirtualBox.xml

whereafter you got:
VM failed to enter an online state within the timeout period.
This might be a temporary problem and so this job will be rescheduled for another time.
Powering off VM.

OK, I upgraded BOINC from 7.4.36 to 7.4.42 and downgraded VBox to 4.3.12 using the BOINC-packaged copy (I'd uninstalled 4.3.26 first). Loaded up the 4.3.12 Extensions and started BOINC. It's downloaded a CMS task and is happily running it. So I'm still baffled as to what the problem was.

So now, after the 4.3.12 task completed, I uninstalled 4.3.12 and perforce rebooted. I removed the .VirtualBox directory and installed 4.3.26 and its Extensions again.

It's now running a new task OK. There must have been some misconfiguration crept into my install...

---

for me all WU crashing after reboot. VB switch of the task and say that the virtual HD is not available :(

for me all WU crashing after reboot. VB switch of the task and say that the virtual HD is not available :(

Did you change anything lately?

00:00:05.956003          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={05f2bbb6-a3a6-4fb9-9b49-6d0dda7142ac}
aComponent={Medium} aText={UUID {b53840d8-db3c-4573-a719-b40d953e793e} of the medium
'D:\boinc\slots\9\vm_image.vdi' does not match the value {12087c04-2482-4bdb-9beb-5d509bfe0400}
stored in the media registry ('C:\Users\Marcus/.VirtualBox\VirtualBox.xml')}, preserve=false

Somewhere (upthread or elsewhere) CP recommended that I delete .VirtualBox\VirtualBox.xml and its derivatives before enabling a new task. Can you try that when you get a chance?

---

Again after starting up my PC this morning the task in BOINC was running,
but looking in VirtualBox Manager I saw that the HD vm_image.vdi was not accessible.

Hello Ivan,

As I had not changed anything I have tried deleting the files. This worked!!! Even after several reboots the WU is finished and validated. Many thanks for your help :)

Greetings,
Marcus

Again after starting up my PC this morning the task in BOINC was running,
but looking in VirtualBox Manager I saw that the HD vm_image.vdi was not accessible.

The same this morning: VM-hd not accessible and BOINC-task postponed for 1 day, what will not solve it.
Released and removed the hd and attached a new copy from the project directory.

But for the next WU the same problem again. VB has the status "saved" this time but is not assessed by the running WU.

06.04.2015 13:03:49 | CMS-dev | task postponed 86400.000000 sec: VM Hypervisor failed to enter an online state in a timely fashion.

Virtual LHC running in parallel don´t have any problems at all. So do I need to delete the VirtualBox.xml always before getting a new CMS WU?