Task 7941336
| Name | 07597968_50_0 |
| Workunit | 6466536 |
| Created | 30 Nov 2019, 8:05:35 UTC |
| Sent | 1 Dec 2019, 0:29:36 UTC |
| Report deadline | 6 Dec 2019, 0:29:36 UTC |
| Received | 1 Dec 2019, 4:14:27 UTC |
| Server state | Over |
| Outcome | Computation error |
| Client state | Compute error |
| Exit status | 197 (0x000000C5) EXIT_TIME_LIMIT_EXCEEDED |
| Computer ID | 4237 |
| Run time | 2 hours 31 min 21 sec |
| CPU time | 5 sec |
| Validate state | Invalid |
| Credit | 0.00 |
| Device peak FLOPS | 3.57 GFLOPS |
| Application version | boinc2docker v1.12 (vbox64_mt) windows_x86_64 |
| Peak working set size | 91.93 MB |
| Peak swap size | 101.55 MB |
| Peak disk usage | 273.93 MB |
Stderr output
<core_client_version>7.14.2</core_client_version>
<![CDATA[
<message>
exceeded elapsed time limit 9057.11 (86400.00G/9.54G)</message>
<stderr_txt>
2019-11-30 17:30:16 (19416): vboxwrapper (7.9.26200): starting
2019-11-30 17:30:17 (19416): Feature: Checkpoint interval offset (561 seconds)
2019-11-30 17:30:17 (19416): Detected: VirtualBox COM Interface (Version: 5.2.8)
2019-11-30 17:30:17 (19416): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-11-30 17:30:17 (19416): Create VM. (boinc_36dd1ab46218db90, slot#6)
2019-11-30 17:30:17 (19416): Updating drive controller type and model for desired configuration.
2019-11-30 17:30:18 (19416): Setting Memory Size for VM. (3072MB)
2019-11-30 17:30:18 (19416): Setting CPU Count for VM. (1)
2019-11-30 17:30:18 (19416): Setting Chipset Options for VM.
2019-11-30 17:30:18 (19416): Setting Boot Options for VM.
2019-11-30 17:30:18 (19416): Enabling VM Network Access.
2019-11-30 17:30:18 (19416): Setting Network Configuration for NAT.
2019-11-30 17:30:18 (19416): Disabling USB Support for VM.
2019-11-30 17:30:18 (19416): Disabling COM Port Support for VM.
2019-11-30 17:30:18 (19416): Disabling LPT Port Support for VM.
2019-11-30 17:30:18 (19416): Disabling Audio Support for VM.
2019-11-30 17:30:18 (19416): Disabling Clipboard Support for VM.
2019-11-30 17:30:18 (19416): Disabling Drag and Drop Support for VM.
2019-11-30 17:30:18 (19416): Adding storage controller(s) to VM.
2019-11-30 17:30:18 (19416): Adding virtual ISO 9660 disk drive to VM. (vm_isocontext.iso)
2019-11-30 17:30:18 (19416): Adding VirtualBox Guest Additions to VM.
2019-11-30 17:30:18 (19416): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-11-30 17:30:18 (19416): Enabling shared directory for VM.
2019-11-30 17:30:19 (19416): Starting VM. (boinc_36dd1ab46218db90, slot#6)
2019-11-30 17:30:29 (19416): Guest Log: BIOS: VirtualBox 5.2.8
2019-11-30 17:30:29 (19416): Guest Log: CPUID EDX: 0x078bf3ff
2019-11-30 17:30:29 (19416): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2019-11-30 17:30:29 (19416): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80
2019-11-30 17:30:29 (19416): Guest Log: BIOS: Boot from Hard Disk 0 failed
2019-11-30 17:30:29 (19416): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003
2019-11-30 17:30:29 (19416): Guest Log: BIOS: Booting from CD-ROM...
2019-11-30 17:30:29 (19416): Successfully started VM. (PID = '7000')
2019-11-30 17:30:29 (19416): Reporting VM Process ID to BOINC.
2019-11-30 17:30:34 (19416): VM state change detected. (old = 'poweroff', new = 'running')
2019-11-30 17:30:44 (19416): Preference change detected
2019-11-30 17:30:44 (19416): Setting CPU throttle for VM. (100%)
2019-11-30 17:30:44 (19416): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 600 seconds) or (Vbox_job.xml: 600 seconds))
2019-11-30 17:49:20 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 17:50:20 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:02:31 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:02:41 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:05:12 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:05:22 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:05:32 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:06:42 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:06:52 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:07:22 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:08:12 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:08:22 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:08:42 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:08:52 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:09:42 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:10:42 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:11:02 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:11:12 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:11:22 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:11:32 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:12:13 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:12:23 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:12:33 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:12:53 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:13:13 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:13:43 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:13:53 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:14:43 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:18:03 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:19:14 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:19:24 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:19:44 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:19:54 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:20:04 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:20:14 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:20:34 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:24:54 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:25:14 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:28:35 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:29:25 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:29:55 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:30:15 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:31:05 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:31:25 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:32:15 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:32:35 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:40:36 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:41:16 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:54:37 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:54:57 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 18:56:18 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 18:56:28 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 19:01:58 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 19:02:08 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 19:09:49 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 19:09:59 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 19:11:49 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 19:12:19 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 19:25:55 (19416): Status Report: Elapsed Time: '6003.695154'
2019-11-30 19:25:55 (19416): Status Report: CPU Time: '5.140625'
2019-11-30 19:56:38 (19416): VM state change detected. (old = 'running', new = 'paused')
2019-11-30 19:56:58 (19416): VM state change detected. (old = 'paused', new = 'running')
2019-11-30 20:14:19 (19416): Powering off VM.
2019-11-30 20:14:21 (19416): Successfully stopped VM.
2019-11-30 20:14:26 (19416): Deregistering VM. (boinc_36dd1ab46218db90, slot#6)
2019-11-30 20:14:26 (19416): Removing virtual disk drive(s) from VM.
2019-11-30 20:14:26 (19416): Removing network bandwidth throttle group from VM.
2019-11-30 20:14:26 (19416): Removing storage controller(s) from VM.
2019-11-30 20:14:26 (19416): Removing VM from VirtualBox.
Hypervisor System Log:
00:00:00.101369 main AssertLogRel F:\tinderbox\win-5.2\src\VBox\Main\src-all\win\VBoxProxyStub.c(842) long __cdecl vbpsCreateRegKeyA(struct VBPSREGSTATE *,struct HKEY__ *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:00.101369 main 1234: create key '{3c02f46d-c9d2-4f11-a384-53f0cf917214}' -> 5
00:00:00.101369 main AssertLogRel F:\tinderbox\win-5.2\src\VBox\Main\src-all\win\VBoxProxyStub.c(937) long __cdecl vbpsCreateRegKeyWithDefaultValueAAEx(struct VBPSREGSTATE *,struct HKEY__ *,const char *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:00.101369 main 1234: create key '{3c02f46d-c9d2-4f11-a384-53f0cf917214}'(/Default='Session Class') -> 5
00:00:00.101369 main AssertLogRel F:\tinderbox\win-5.2\src\VBox\Main\src-all\win\VBoxProxyStub.c(842) long __cdecl vbpsCreateRegKeyA(struct VBPSREGSTATE *,struct HKEY__ *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:00.101369 main 1148: create key 'VirtualBox.VirtualBoxClient.1' -> 5
00:00:00.101369 main AssertLogRel F:\tinderbox\win-5.2\src\VBox\Main\src-all\win\VBoxProxyStub.c(937) long __cdecl vbpsCreateRegKeyWithDefaultValueAAEx(struct VBPSREGSTATE *,struct HKEY__ *,const char *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:00.101369 main 1148: create key 'VirtualBox.VirtualBoxClient.1'(/Default='VirtualBoxClient Class') -> 5
00:00:00.101872 main AssertLogRel F:\tinderbox\win-5.2\src\VBox\Main\src-all\win\VBoxProxyStub.c(842) long __cdecl vbpsCreateRegKeyA(struct VBPSREGSTATE *,struct HKEY__ *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:00.101872 main 1148: create key 'VirtualBox.VirtualBoxClient' -> 5
00:00:00.101872 main AssertLogRel F:\tinderbox\win-5.2\src\VBox\Main\src-all\win\VBoxProxyStub.c(937) long __cdecl vbpsCreateRegKeyWithDefaultValueAAEx(struct VBPSREGSTATE *,struct HKEY__ *,const char *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:00.101872 main 1148: create key 'VirtualBox.VirtualBoxClient'(/Default='VirtualBoxClient Class') -> 5
00:00:00.102404 main AssertLogRel F:\tinderbox\win-5.2\src\VBox\Main\src-all\win\VBoxProxyStub.c(842) long __cdecl vbpsCreateRegKeyA(struct VBPSREGSTATE *,struct HKEY__ *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:00.102404 main 1234: create key '{dd3fc71d-26c0-4fe1-bf6f-67f633265bba}' -> 5
00:00:00.102404 main AssertLogRel F:\tinderbox\win-5.2\src\VBox\Main\src-all\win\VBoxProxyStub.c(937) long __cdecl vbpsCreateRegKeyWithDefaultValueAAEx(struct VBPSREGSTATE *,struct HKEY__ *,const char *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:00.102404 main 1234: create key '{dd3fc71d-26c0-4fe1-bf6f-67f633265bba}'(/Default='VirtualBoxClient Class') -> 5
00:00:00.105958 VirtualBox: object creation starts
00:00:00.106966 Home directory: 'C:\Users\Jeff Davis\.VirtualBox'
00:00:00.106966 Installed Drivers:
00:00:00.114578 C:\WINDOWS\system32\DRIVERS\VBoxNetLwf.sys (Version: 5.2.8.21009)
00:00:00.117630 C:\WINDOWS\system32\DRIVERS\VBoxUSBMon.sys (Version: 5.2.8.21009)
00:00:00.120177 C:\WINDOWS\system32\DRIVERS\VBoxDrv.sys (Version: 5.2.8.21009)
00:00:00.121689 C:\WINDOWS\system32\DRIVERS\VBoxNetAdp6.sys (Version: 5.2.8.21009)
00:00:00.269434 USB: Unknown USB device detected (idVendor: 0x04b4, idProduct: 0xf02f)
00:00:00.413780 HostDnsMonitor: old information
00:00:00.413780 no server entries
00:00:00.413780 no domain set
00:00:00.413780 no search string entries
00:00:00.413780 HostDnsMonitor: new information
00:00:00.413780 server 1: 75.75.75.75
00:00:00.413780 server 2: 75.75.76.76
00:00:00.413780 no domain set
00:00:00.413780 no search string entries
00:00:00.413780 HostDnsMonitorProxy::notify
00:00:00.522489 SUPR0QueryVTCaps -> VERR_SVM_DISABLED
00:00:00.523495 VD: VDInit finished with VINF_SUCCESS
00:00:00.525507 VirtualBox: object created
00:00:00.629169 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={9570b9d5-f1a1-448a-10c5-e12f5285adad} aComponent={VirtualBoxWrap} aText={Could not find a registered machine named 'boinc_36dd1ab46218db90'}, preserve=false aResultDetail=0
00:00:00.641263 Saving settings file "D:\BOINC\data\slots\6\boinc_36dd1ab46218db90\boinc_36dd1ab46218db90.vbox" with version "1.16-windows"
00:00:01.152633 Saving settings file "C:\Users\Jeff Davis\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:00:01.342357 Saving settings file "D:\BOINC\data\slots\6\boinc_36dd1ab46218db90\boinc_36dd1ab46218db90.vbox" with version "1.16-windows"
00:00:06.947758 ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The given session is busy}, preserve=false aResultDetail=0
00:00:27.005324 Saving settings file "D:\BOINC\data\slots\6\boinc_36dd1ab46218db90\boinc_36dd1ab46218db90.vbox" with version "1.16-windows"
01:39:18.708718 ERROR [COM]: aRC=VBOX_E_OBJECT_NOT_FOUND (0x80bb0001) aIID={9570b9d5-f1a1-448a-10c5-e12f5285adad} aComponent={VirtualBoxWrap} aText={Could not find a registered machine named 'boinc_258106f815d0a661'}, preserve=false aResultDetail=0
01:39:18.717509 Saving settings file "D:\BOINC\data\slots\3\boinc_258106f815d0a661\boinc_258106f815d0a661.vbox" with version "1.16-windows"
01:39:18.733131 Saving settings file "C:\Users\Jeff Davis\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
01:39:18.769279 Saving settings file "D:\BOINC\data\slots\3\boinc_258106f815d0a661\boinc_258106f815d0a661.vbox" with version "1.16-windows"
01:39:22.473126 ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={MachineWrap} aText={The given session is busy}, preserve=false aResultDetail=0
01:39:42.525804 Saving settings file "D:\BOINC\data\slots\3\boinc_258106f815d0a661\boinc_258106f815d0a661.vbox" with version "1.16-windows"
02:44:02.944427 Saving settings file "D:\BOINC\data\slots\6\boinc_36dd1ab46218db90\boinc_36dd1ab46218db90.vbox" with version "1.16-windows"
02:44:09.007548 ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={85cd948e-a71f-4289-281e-0ca7ad48cd89} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
02:44:09.013407 Saving settings file "D:\BOINC\data\slots\6\boinc_36dd1ab46218db90\boinc_36dd1ab46218db90.vbox" with version "1.16-windows"
02:44:09.029033 Saving settings file "D:\BOINC\data\slots\6\boinc_36dd1ab46218db90\boinc_36dd1ab46218db90.vbox" with version "1.16-windows"
02:44:09.046576 ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox/VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0
02:44:09.047551 Saving settings file "C:\Users\Jeff Davis\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
02:44:09.057317 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0
02:44:09.057317 ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={Medium 'C:\Program Files\Oracle\VirtualBox/VBoxGuestAdditions.iso' is locked for reading by another task}, preserve=false aResultDetail=0
VM Execution Log:
VM Startup Log:
VM Trace Log:
20:14:36 (19416): called boinc_finish(194)
</stderr_txt>
]]>
©2024 COPYRIGHT 2017-2018 NCN