Task 3645210

Name 07318292_117_0
Workunit 2865999
Created 16 Jul 2019, 19:41:46 UTC
Sent 16 Jul 2019, 22:33:38 UTC
Report deadline 21 Jul 2019, 22:33:38 UTC
Received 17 Jul 2019, 12:37:41 UTC
Server state Over
Outcome Computation error
Client state Compute error
Exit status 197 (0x000000C5) EXIT_TIME_LIMIT_EXCEEDED
Computer ID 2562
Run time 8 hours 48 min 53 sec
CPU time 1 min 8 sec
Validate state Invalid
Credit 0.00
Device peak FLOPS 0.75 GFLOPS
Application version boinc2docker v1.12 (vbox64_mt)
windows_x86_64
Peak working set size 80.66 MB
Peak swap size 86.84 MB
Peak disk usage 499.37 MB

Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<message>
exceeded elapsed time limit 31710.87 (86400.00G/2.72G)</message>
<stderr_txt>
2019-07-16 15:36:23 (864): vboxwrapper (7.9.26200): starting
2019-07-16 15:36:23 (864): Feature: Checkpoint interval offset (52 seconds)
2019-07-16 15:36:23 (864): Detected: VirtualBox COM Interface (Version: 5.1.26)
2019-07-16 15:36:23 (864): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-07-16 15:36:23 (864): Create VM. (boinc_2de5a5a09b70bd48, slot#3)
2019-07-16 15:36:23 (864): Updating drive controller type and model for desired configuration.
2019-07-16 15:36:23 (864): Setting Memory Size for VM. (3072MB)
2019-07-16 15:36:23 (864): Setting CPU Count for VM. (1)
2019-07-16 15:36:23 (864): Setting Chipset Options for VM.
2019-07-16 15:36:23 (864): Setting Boot Options for VM.
2019-07-16 15:36:23 (864): Enabling VM Network Access.
2019-07-16 15:36:23 (864): Setting Network Configuration for NAT.
2019-07-16 15:36:23 (864): Disabling USB Support for VM.
2019-07-16 15:36:23 (864): Disabling COM Port Support for VM.
2019-07-16 15:36:23 (864): Disabling LPT Port Support for VM.
2019-07-16 15:36:23 (864): Disabling Audio Support for VM.
2019-07-16 15:36:23 (864): Disabling Clipboard Support for VM.
2019-07-16 15:36:23 (864): Disabling Drag and Drop Support for VM.
2019-07-16 15:36:23 (864): Adding storage controller(s) to VM.
2019-07-16 15:36:23 (864): Adding virtual ISO 9660 disk drive to VM. (vm_isocontext.iso)
2019-07-16 15:36:23 (864): Adding VirtualBox Guest Additions to VM.
2019-07-16 15:36:23 (864): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-07-16 15:36:23 (864): Enabling shared directory for VM.
2019-07-16 15:36:24 (864): Starting VM. (boinc_2de5a5a09b70bd48, slot#3)
2019-07-16 15:36:36 (864): Guest Log: BIOS: VirtualBox 5.1.26

2019-07-16 15:36:36 (864): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-07-16 15:36:36 (864): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-07-16 15:36:36 (864): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-07-16 15:36:36 (864): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-07-16 15:36:36 (864): Guest Log: BIOS: Booting from CD-ROM...

2019-07-16 15:36:36 (864): Successfully started VM. (PID = '4888')
2019-07-16 15:36:36 (864): Reporting VM Process ID to BOINC.
2019-07-16 15:36:41 (864): VM state change detected. (old = 'poweroff', new = 'running')
2019-07-16 15:36:51 (864): Preference change detected
2019-07-16 15:36:51 (864): Setting CPU throttle for VM. (96%)
2019-07-16 15:36:51 (864): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-07-16 16:41:40 (864): Preference change detected
2019-07-16 16:41:40 (864): Setting CPU throttle for VM. (96%)
2019-07-16 16:41:40 (864): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-07-16 17:16:54 (864): Status Report: Elapsed Time: '6003.333826'
2019-07-16 17:16:54 (864): Status Report: CPU Time: '34.133019'
2019-07-16 17:39:17 (4732): vboxwrapper (7.9.26200): starting
2019-07-16 17:39:20 (4732): Feature: Checkpoint interval offset (283 seconds)
2019-07-16 17:39:20 (4732): Detected: VirtualBox COM Interface (Version: 5.1.26)
2019-07-16 17:39:20 (4732): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-07-16 17:39:21 (4732): Guest Log: BIOS: VirtualBox 5.1.26

2019-07-16 17:39:21 (4732): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-07-16 17:39:21 (4732): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-07-16 17:39:21 (4732): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-07-16 17:39:21 (4732): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-07-16 17:39:21 (4732): Guest Log: BIOS: Booting from CD-ROM...

2019-07-16 17:39:21 (4732): Starting VM. (boinc_2de5a5a09b70bd48, slot#3)
2019-07-16 17:39:35 (4732): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-07-16 17:39:35 (4732): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-07-16 17:39:35 (4732): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-07-16 17:39:35 (4732): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-07-16 17:39:35 (4732): Guest Log: BIOS: Booting from CD-ROM...

2019-07-16 17:39:35 (4732): Successfully started VM. (PID = '2032')
2019-07-16 17:39:35 (4732): Reporting VM Process ID to BOINC.
2019-07-16 17:39:40 (4732): VM state change detected. (old = 'poweroff', new = 'running')
2019-07-16 17:39:50 (4732): Status Report: Elapsed Time: '6048.427553'
2019-07-16 17:39:50 (4732): Status Report: CPU Time: '34.164219'
2019-07-16 17:39:50 (4732): Preference change detected
2019-07-16 17:39:50 (4732): Setting CPU throttle for VM. (96%)
2019-07-16 17:39:50 (4732): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-07-16 19:19:54 (4732): Status Report: Elapsed Time: '12052.116426'
2019-07-16 19:19:54 (4732): Status Report: CPU Time: '51.651931'
2019-07-17 00:35:43 (4288): vboxwrapper (7.9.26200): starting
2019-07-17 00:41:21 (4848): vboxwrapper (7.9.26200): starting
2019-07-17 00:41:26 (4848): Feature: Checkpoint interval offset (290 seconds)
2019-07-17 00:41:26 (4848): Detected: VirtualBox COM Interface (Version: 5.1.26)
2019-07-17 00:41:26 (4848): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-07-17 00:41:26 (4848): Guest Log: BIOS: VirtualBox 5.1.26

2019-07-17 00:41:26 (4848): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-07-17 00:41:26 (4848): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-07-17 00:41:26 (4848): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-07-17 00:41:26 (4848): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-07-17 00:41:26 (4848): Guest Log: BIOS: Booting from CD-ROM...

2019-07-17 00:41:26 (4848): Starting VM. (boinc_2de5a5a09b70bd48, slot#3)
2019-07-17 00:41:45 (4848): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-07-17 00:41:45 (4848): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=80

2019-07-17 00:41:45 (4848): Guest Log: BIOS: Boot from Hard Disk 0 failed

2019-07-17 00:41:45 (4848): Guest Log: BIOS: Boot : bseqnr=2, bootseq=0003

2019-07-17 00:41:45 (4848): Guest Log: BIOS: Booting from CD-ROM...

2019-07-17 00:41:45 (4848): Successfully started VM. (PID = '1512')
2019-07-17 00:41:45 (4848): Reporting VM Process ID to BOINC.
2019-07-17 00:41:50 (4848): VM state change detected. (old = 'poweroff', new = 'running')
2019-07-17 00:42:00 (4848): Status Report: Elapsed Time: '13977.017856'
2019-07-17 00:42:00 (4848): Status Report: CPU Time: '52.151134'
2019-07-17 00:42:00 (4848): Preference change detected
2019-07-17 00:42:00 (4848): Setting CPU throttle for VM. (96%)
2019-07-17 00:42:08 (4848): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-07-17 02:22:04 (4848): Status Report: Elapsed Time: '19981.315305'
2019-07-17 02:22:04 (4848): Status Report: CPU Time: '67.673233'
2019-07-17 04:02:06 (4848): Status Report: Elapsed Time: '25982.601871'
2019-07-17 04:02:06 (4848): Status Report: CPU Time: '67.954035'
2019-07-17 05:35:46 (4848): Powering off VM.
2019-07-17 05:35:47 (4848): Successfully stopped VM.
2019-07-17 05:35:52 (4848): Deregistering VM. (boinc_2de5a5a09b70bd48, slot#3)
2019-07-17 05:35:52 (4848): Removing virtual disk drive(s) from VM.
2019-07-17 05:35:52 (4848): Removing network bandwidth throttle group from VM.
2019-07-17 05:35:52 (4848): Removing storage controller(s) from VM.
2019-07-17 05:35:52 (4848): Removing VM from VirtualBox.

    Hypervisor System Log:

00:00:00.265200 main     AssertLogRel F:\tinderbox\win-5.1\src\VBox\Main\src-all\win\VBoxProxyStub.c(837) long __cdecl vbpsCreateRegKeyA(struct VBPSREGSTATE *,struct HKEY__ *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:00.265200 main     1115: create key 'VirtualBox.Session' -> 5
00:00:00.265200 main     AssertLogRel F:\tinderbox\win-5.1\src\VBox\Main\src-all\win\VBoxProxyStub.c(932) 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.265200 main     1115: create key 'VirtualBox.Session'(/Default='Session Class') -> 5
00:00:00.265200 main     AssertLogRel F:\tinderbox\win-5.1\src\VBox\Main\src-all\win\VBoxProxyStub.c(837) long __cdecl vbpsCreateRegKeyA(struct VBPSREGSTATE *,struct HKEY__ *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:00.265200 main     1201: create key '{3c02f46d-c9d2-4f11-a384-53f0cf917214}' -> 5
00:00:00.265200 main     AssertLogRel F:\tinderbox\win-5.1\src\VBox\Main\src-all\win\VBoxProxyStub.c(932) 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.265200 main     1201: create key '{3c02f46d-c9d2-4f11-a384-53f0cf917214}'(/Default='Session Class') -> 5
00:00:00.265200 main     AssertLogRel F:\tinderbox\win-5.1\src\VBox\Main\src-all\win\VBoxProxyStub.c(837) long __cdecl vbpsCreateRegKeyA(struct VBPSREGSTATE *,struct HKEY__ *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:00.265200 main     1115: create key 'VirtualBox.VirtualBoxClient.1' -> 5
00:00:00.265200 main     AssertLogRel F:\tinderbox\win-5.1\src\VBox\Main\src-all\win\VBoxProxyStub.c(932) 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.265200 main     1115: create key 'VirtualBox.VirtualBoxClient.1'(/Default='VirtualBoxClient Class') -> 5
00:00:00.265200 main     AssertLogRel F:\tinderbox\win-5.1\src\VBox\Main\src-all\win\VBoxProxyStub.c(837) long __cdecl vbpsCreateRegKeyA(struct VBPSREGSTATE *,struct HKEY__ *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:00.265200 main     1115: create key 'VirtualBox.VirtualBoxClient' -> 5
00:00:00.265200 main     AssertLogRel F:\tinderbox\win-5.1\src\VBox\Main\src-all\win\VBoxProxyStub.c(932) 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.265200 main     1115: create key 'VirtualBox.VirtualBoxClient'(/Default='VirtualBoxClient Class') -> 5
00:00:00.265200 main     AssertLogRel F:\tinderbox\win-5.1\src\VBox\Main\src-all\win\VBoxProxyStub.c(837) long __cdecl vbpsCreateRegKeyA(struct VBPSREGSTATE *,struct HKEY__ *,const char *,struct HKEY__ **,unsigned int): VBPS_LOGREL_NO_ASSERT(rc == ERROR_ACCESS_DENIED)
00:00:00.265200 main     1201: create key '{dd3fc71d-26c0-4fe1-bf6f-67f633265bba}' -> 5
00:00:00.265200 main     AssertLogRel F:\tinderbox\win-5.1\src\VBox\Main\src-all\win\VBoxProxyStub.c(932) 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.265200 main     1201: create key '{dd3fc71d-26c0-4fe1-bf6f-67f633265bba}'(/Default='VirtualBoxClient Class') -> 5
00:00:00.280800          VirtualBox: object creation starts
00:00:00.280800          Home directory: 'C:\Users\SAB\.VirtualBox'
00:00:00.280800          Installed Drivers:
00:00:00.296400            C:\Windows\system32\DRIVERS\VBoxNetLwf.sys (Version: 5.1.26.17224)
00:00:00.312000            C:\Windows\system32\DRIVERS\VBoxUSBMon.sys (Version: 5.1.26.17224)
00:00:00.327600            C:\Windows\system32\DRIVERS\VBoxDrv.sys (Version: 5.1.26.17224)
00:00:00.358800            C:\Windows\system32\DRIVERS\VBoxNetAdp6.sys (Version: 5.1.26.17224)
00:00:00.374400          Loading settings file "C:\Users\SAB\.VirtualBox\VirtualBox.xml" with version "1.12-windows"
00:00:00.546000          usbLibDevCfgDrGet: DeviceIoControl 1 fail dwErr (31)
00:00:00.546000          USB: Unknown USB device detected (idVendor: 0x5986, idProduct: 0x02ac)
00:00:00.967201          HostDnsMonitor: old information
00:00:00.967201            no server entries
00:00:00.967201            no domain set
00:00:00.967201            no search string entries
00:00:00.967201          HostDnsMonitor: new information
00:00:00.967201            server 1: 192.168.1.1
00:00:00.967201            no domain set
00:00:00.967201            no search string entries
00:00:00.967201          HostDnsMonitorProxy::notify
00:00:02.449204          SUPR0QueryVTCaps -> VERR_SVM_DISABLED
00:00:02.464804          VD: VDInit finished
00:00:02.480404          Loading settings file "C:\ProgramData\BOINC\slots\1\boinc_d8d831b7a591c23e\boinc_d8d831b7a591c23e.vbox" with version "1.16-windows"
00:00:02.496004          Loading settings file "C:\ProgramData\BOINC\slots\0\boinc_35efcbb64751a4b7\boinc_35efcbb64751a4b7.vbox" with version "1.16-windows"
00:00:02.511604          Loading settings file "C:\ProgramData\BOINC\slots\2\boinc_0442c405749829b2\boinc_0442c405749829b2.vbox" with version "1.16-windows"
00:00:02.527204          Loading settings file "C:\ProgramData\BOINC\slots\3\boinc_2de5a5a09b70bd48\boinc_2de5a5a09b70bd48.vbox" with version "1.16-windows"
00:00:02.542804          Loading settings file "C:\ProgramData\BOINC\slots\4\boinc_2e1102eaf7d27520\boinc_2e1102eaf7d27520.vbox" with version "1.16-windows"
00:00:02.542804          VirtualBox: object created
00:00:14.860803          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The given session is busy}, preserve=false aResultDetail=0
00:00:15.089332          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The given session is busy}, preserve=false aResultDetail=0
00:00:15.096833          ERROR [COM]: aRC=VBOX_E_INVALID_OBJECT_STATE (0x80bb0007) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={MachineWrap} aText={The given session is busy}, preserve=false aResultDetail=0
00:00:22.282745 USBPROXY usbLibDevCfgDrGet: DeviceIoControl 1 fail dwErr (31)
00:00:50.048771 USBPROXY usbLibDevCfgDrGet: DeviceIoControl 1 fail dwErr (31)
00:00:50.050271 USBPROXY USB: Unknown USB device detected (idVendor: 0x5986, idProduct: 0x02ac)
00:01:05.105683 USBPROXY usbLibDevCfgDrGet: DeviceIoControl 1 fail dwErr (31)
00:25:04.369946 USBPROXY usbLibDevCfgDrGet: DeviceIoControl 1 fail dwErr (31)
04:54:28.961589          ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={b2547866-a0a1-4391-8b86-6952d82efaa0} aComponent={SessionMachine} aText={This machine does not have any snapshots}, preserve=false aResultDetail=0
04:54:28.992093          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
04:54:29.002594          ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={4afe423b-43e0-e9d0-82e8-ceb307940dda} aComponent={MediumWrap} aText={The object is not ready}, preserve=false aResultDetail=0
04:54:29.003094          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:

05:36:02 (4848): called boinc_finish(194)

</stderr_txt>
]]>


©2024 COPYRIGHT 2017-2018 NCN