Task 214996
| Name | 06476986_135_4 |
| Workunit | 141759 |
| Created | 20 Feb 2018, 3:36:20 UTC |
| Sent | 20 Feb 2018, 3:36:26 UTC |
| Report deadline | 20 Feb 2018, 11:36:26 UTC |
| Received | 20 Feb 2018, 5:31:56 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 136 |
| Run time | 2 min 50 sec |
| CPU time | |
| Validate state | Valid |
| Credit | 0.18 |
| Device peak FLOPS | 2.62 GFLOPS |
| Application version | boinc2docker v1.11 (vbox64_mt) windows_x86_64 |
| Peak working set size | 92.40 MB |
| Peak swap size | 103.05 MB |
| Peak disk usage | 223.68 MB |
Stderr output
<core_client_version>7.8.2</core_client_version>
<![CDATA[
<stderr_txt>
2018-02-20 13:49:32 (89508): vboxwrapper (7.7.26197): starting
2018-02-20 13:49:32 (89508): Feature: Checkpoint interval offset (552 seconds)
2018-02-20 13:49:32 (89508): Detected: VirtualBox COM Interface (Version: 5.1.26)
2018-02-20 13:49:32 (89508): Detected: Minimum checkpoint interval (600.000000 seconds)
2018-02-20 13:49:32 (89508): Create VM. (boinc_a70bb71d5a5d61a6, slot#1)
2018-02-20 13:49:32 (89508): Updating drive controller type and model for desired configuration.
2018-02-20 13:49:32 (89508): Setting Memory Size for VM. (3072MB)
2018-02-20 13:49:32 (89508): Setting CPU Count for VM. (1)
2018-02-20 13:49:32 (89508): Setting Chipset Options for VM.
2018-02-20 13:49:32 (89508): Setting Boot Options for VM.
2018-02-20 13:49:32 (89508): Enabling VM Network Access.
2018-02-20 13:49:32 (89508): Setting Network Configuration for NAT.
2018-02-20 13:49:32 (89508): Disabling USB Support for VM.
2018-02-20 13:49:32 (89508): Disabling COM Port Support for VM.
2018-02-20 13:49:32 (89508): Disabling LPT Port Support for VM.
2018-02-20 13:49:32 (89508): Disabling Audio Support for VM.
2018-02-20 13:49:32 (89508): Disabling Clipboard Support for VM.
2018-02-20 13:49:32 (89508): Disabling Drag and Drop Support for VM.
2018-02-20 13:49:32 (89508): Adding storage controller(s) to VM.
2018-02-20 13:49:32 (89508): Adding virtual ISO 9660 disk drive to VM. (vm_isocontext.iso)
2018-02-20 13:49:32 (89508): Adding VirtualBox Guest Additions to VM.
2018-02-20 13:49:32 (89508): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2018-02-20 13:49:32 (89508): Enabling shared directory for VM.
2018-02-20 13:49:32 (89508): Starting VM. (boinc_a70bb71d5a5d61a6, slot#1)
2018-02-20 13:55:50 (89508): Successfully started VM. (PID = '89212')
2018-02-20 13:55:50 (89508): Reporting VM Process ID to BOINC.
2018-02-20 14:00:50 (89508): VM is no longer is a running state. It is in 'poweroff'.
2018-02-20 14:00:56 (89508):
NOTE: 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.
Hypervisor System Log:
VM Execution Log:
ver/Config/] (level 6)
00:00:59.984515 Object <integer> = 0x0000000004921cc0 (76 684 480)
00:00:59.984518
00:00:59.984518 [/Devices/pckbd/0/LUN#0/Config/] (level 5)
00:00:59.984520 QueueSize <integer> = 0x0000000000000040 (64)
00:00:59.984522
00:00:59.984522 [/Devices/pckbd/0/LUN#1/] (level 4)
00:00:59.984524 Driver <string> = "MouseQueue" (cb=11)
00:00:59.984525
00:00:59.984525 [/Devices/pckbd/0/LUN#1/AttachedDriver/] (level 5)
00:00:59.984527 Driver <string> = "MainMouse" (cb=10)
00:00:59.984528
00:00:59.984528 [/Devices/pckbd/0/LUN#1/AttachedDriver/Config/] (level 6)
00:00:59.984530 Object <integer> = 0x0000000004922300 (76 686 080)
00:00:59.984532
00:00:59.984532 [/Devices/pckbd/0/LUN#1/Config/] (level 5)
00:00:59.984534 QueueSize <integer> = 0x0000000000000080 (128)
00:00:59.984535
00:00:59.984536 [/Devices/pcnet/] (level 2)
00:00:59.984537
00:00:59.984537 [/Devices/serial/] (level 2)
00:00:59.984538
00:00:59.984539 [/Devices/vga/] (level 2)
00:00:59.984540
00:00:59.984540 [/Devices/vga/0/] (level 3)
00:00:59.984547 PCIBusNo <integer> = 0x0000000000000000 (0)
00:00:59.984549 PCIDeviceNo <integer> = 0x0000000000000002 (2)
00:00:59.984550 PCIFunctionNo <integer> = 0x0000000000000000 (0)
00:00:59.984551 Trusted <integer> = 0x0000000000000001 (1)
00:00:59.984552
00:00:59.984552 [/Devices/vga/0/Config/] (level 4)
00:00:59.984556 CustomVideoModes <integer> = 0x0000000000000000 (0)
00:00:59.984557 FadeIn <integer> = 0x0000000000000001 (1)
00:00:59.984558 FadeOut <integer> = 0x0000000000000001 (1)
00:00:59.984559 HeightReduction <integer> = 0x0000000000000000 (0)
00:00:59.984560 LogoFile <string> = "" (cb=1)
00:00:59.984561 LogoTime <integer> = 0x0000000000000000 (0)
00:00:59.984562 MonitorCount <integer> = 0x0000000000000001 (1)
00:00:59.984563 ShowBootMenu <integer> = 0x0000000000000002 (2)
00:00:59.984564 VRamSize <integer> = 0x0000000000800000 (8 388 608, 8 MB)
00:00:59.984567
00:00:59.984567 [/Devices/vga/0/LUN#0/] (level 4)
00:00:59.984568 Driver <string> = "MainDisplay" (cb=12)
00:00:59.984573
00:00:59.984573 [/Devices/vga/0/LUN#0/Config/] (level 5)
00:00:59.984575 Object <integer> = 0x0000000004923370 (76 690 288)
00:00:59.984576
00:00:59.984576 [/Devices/vga/0/LUN#999/] (level 4)
00:00:59.984578 Driver <string> = "MainStatus" (cb=11)
00:00:59.984579
00:00:59.984579 [/Devices/vga/0/LUN#999/Config/] (level 5)
00:00:59.984581 First <integer> = 0x0000000000000000 (0)
00:00:59.984582 Last <integer> = 0x0000000000000000 (0)
00:00:59.984583 papLeds <integer> = 0x0000000003a7d350 (61 330 256)
00:00:59.984584
00:00:59.984585 [/Devices/virtio-net/] (level 2)
00:00:59.984586
00:00:59.984586 [/EM/] (level 1)
00:00:59.984587 TripleFaultReset <integer> = 0x0000000000000000 (0)
00:00:59.984588
00:00:59.984589 [/GIM/] (level 1)
00:00:59.984590 Provider <string> = "KVM" (cb=4)
00:00:59.984591
00:00:59.984591 [/HM/] (level 1)
00:00:59.984593 64bitEnabled <integer> = 0x0000000000000001 (1)
00:00:59.984594 EnableLargePages <integer> = 0x0000000000000001 (1)
00:00:59.984595 EnableNestedPaging <integer> = 0x0000000000000001 (1)
00:00:59.984596 EnableUX <integer> = 0x0000000000000001 (1)
00:00:59.984597 EnableVPID <integer> = 0x0000000000000001 (1)
00:00:59.984598 Exclusive <integer> = 0x0000000000000000 (0)
00:00:59.984599 HMForced <integer> = 0x0000000000000001 (1)
00:00:59.984604
00:00:59.984604 [/MM/] (level 1)
00:00:59.984605 CanUseLargerHeap <integer> = 0x0000000000000000 (0)
00:00:59.984606
00:00:59.984607 [/PDM/] (level 1)
00:00:59.984608
00:00:59.984608 [/PDM/AsyncCompletion/] (level 2)
00:00:59.984609
00:00:59.984610 [/PDM/AsyncCompletion/File/] (level 3)
00:00:59.984611
00:00:59.984612 [/PDM/AsyncCompletion/File/BwGroups/] (level 4)
00:00:59.984613
00:00:59.984614 [/PDM/BlkCache/] (level 2)
00:00:59.984615 CacheSize <integer> = 0x0000000000500000 (5 242 880, 5 MB)
00:00:59.984616
00:00:59.984617 [/PDM/Devices/] (level 2)
00:00:59.984618
00:00:59.984618 [/PDM/Drivers/] (level 2)
00:00:59.984619
00:00:59.984620 [/PDM/Drivers/VBoxC/] (level 3)
00:00:59.984621 Path <string> = "VBoxC" (cb=6)
00:00:59.984622
00:00:59.984623 [/PDM/NetworkShaper/] (level 2)
00:00:59.984624
00:00:59.984624 [/PDM/NetworkShaper/BwGroups/] (level 3)
00:00:59.984626
00:00:59.984626 [/PDM/NetworkShaper/BwGroups/boinc_a70bb71d5a5d61a6_net/] (level 4)
00:00:59.989161 Max <integer> = 0x0000010000000000 (1 099 511 627 776)
00:00:59.989165
00:00:59.989166 [/TM/] (level 1)
00:00:59.989167 UTCOffset <integer> = 0x0000000000000000 (0)
00:00:59.989169
00:00:59.989170 ********************* End of CFGM dump **********************
00:01:00.106171 VM: fHMEnabled=true (configured) fRecompileUser=false fRecompileSupervisor=false
00:01:00.106179 VM: fRawRing1Enabled=false CSAM=true PATM=true
00:01:00.287685 HM: HMR3Init: VT-x w/ nested paging and unrestricted guest execution hw support
00:01:00.302812 MM: cbHyperHeap=0x100000 (1048576)
00:01:00.384264 CPUM: fXStateHostMask=0x7; initial: 0x7; host XCR0=0x7
00:01:00.558549 CPUM: Matched host CPU INTEL 0x6/0x3f/0x1 Intel_Core7_Haswell with CPU DB entry 'Intel Core i7-5600U' (INTEL 0x6/0x3d/0x4 Intel_Core7_Broadwell)
00:01:00.588325 CPUM: MXCSR_MASK=0xffff (host: 0xffff)
00:01:00.593578 CPUM: Microcode revision 0x80000013
00:01:00.598669 CPUM: SetGuestCpuIdFeature: Enabled PAE
00:01:00.602833 CPUM: VCPU 0: Cached APIC base MSR = 0x0
00:01:00.642835 PGM: HCPhysInterPD=00000000349c0000 HCPhysInterPaePDPT=0000000034594000 HCPhysInterPaePML4=0000000034555000
00:01:00.644331 PGM: apInterPTs={00000000345a6000,000000003459d000} apInterPaePTs={00000002d92db000,0000000451d85000} apInterPaePDs={0000000237adf000,0000000269c53000,00000003b77db000,000000049bd78000} pInterPaePDPT64=000000003455c000
00:01:00.644356 PGM: Host paging mode: AMD64+PGE+NX
00:01:00.649603 PGM: PGMPool: cMaxPages=1584 (u64MaxPages=1571)
00:01:00.649619 PGM: pgmR3PoolInit: cMaxPages=0x630 cMaxUsers=0xc60 cMaxPhysExts=0xc60 fCacheEnable=true
00:01:01.372120 MMR3HyperAllocOnceNoRel: cb=0xc00080 uAlignment=0x0 returns VERR_MM_HYPER_NO_MEMORY
00:01:01.425195 AssertLogRel F:\tinderbox\win-5.1\src\VBox\VMM\VMMR3\PGMPhys.cpp(1743) int __cdecl PGMR3PhysRegisterRam(struct VM *,unsigned __int64,unsigned __int64,const char *): RT_SUCCESS_NP(rc)
00:01:01.464859 cbRamRange=12583040
00:01:02.497382 VMSetError: F:\tinderbox\win-5.1\src\VBox\VMM\VMMR3\VM.cpp(363) int __cdecl VMR3Create(unsigned int,const struct VMM2USERMETHODS *,void (__cdecl *)(struct UVM *,void *,int,const char *,unsigned int,const char *,const char *,char *),void *,int (__cdecl *)(struct UVM *,struct VM *,void *),void *,struct VM **,struct UVM **); rc=VERR_MM_HYPER_NO_MEMORY
00:01:02.502708 VMSetError: Hypervisor memory allocation failed.
00:01:04.885936 ERROR [COM]: aRC=E_FAIL (0x80004005) aIID={872da645-4a9b-1727-bee2-5585105b9eed} aComponent={ConsoleWrap} aText={Hypervisor memory allocation failed. (VERR_MM_HYPER_NO_MEMORY)}, preserve=false aResultDetail=0
00:01:05.366159 Console: Machine state changed to 'PoweredOff'
00:01:08.552373 Power up failed (vrc=VERR_MM_HYPER_NO_MEMORY, rc=E_FAIL (0X80004005))
00:01:09.566824 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={872da645-4a9b-1727-bee2-5585105b9eed} aComponent={ConsoleWrap} aText={The object is not ready}, preserve=false aResultDetail=0
00:01:09.569394 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={872da645-4a9b-1727-bee2-5585105b9eed} aComponent={ConsoleWrap} aText={The object is not ready}, preserve=false aResultDetail=0
00:01:09.571329 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={872da645-4a9b-1727-bee2-5585105b9eed} aComponent={ConsoleWrap} aText={The object is not ready}, preserve=false aResultDetail=0
00:01:09.577840 ERROR [COM]: aRC=E_ACCESSDENIED (0x80070005) aIID={872da645-4a9b-1727-bee2-5585105b9eed} aComponent={ConsoleWrap} aText={The object is not ready}, preserve=false aResultDetail=0
VM Startup Log:
VM Trace Log:
2018-02-20 15:25:32 (4412): vboxwrapper (7.7.26197): starting
2018-02-20 15:25:39 (4412): Feature: Checkpoint interval offset (264 seconds)
2018-02-20 15:25:39 (4412): Detected: VirtualBox COM Interface (Version: 5.1.26)
2018-02-20 15:25:39 (4412): Detected: Minimum checkpoint interval (600.000000 seconds)
2018-02-20 15:25:43 (4412): Starting VM. (boinc_a70bb71d5a5d61a6, slot#1)
2018-02-20 15:25:59 (4412): Successfully started VM. (PID = '1300')
2018-02-20 15:25:59 (4412): Reporting VM Process ID to BOINC.
2018-02-20 15:26:09 (4412): VM state change detected. (old = 'poweroff', new = 'running')
2018-02-20 15:26:29 (4412): Preference change detected
2018-02-20 15:26:29 (4412): Setting CPU throttle for VM. (100%)
2018-02-20 15:26:29 (4412): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2018-02-20 15:28:09 (4412): VM Completion File Detected.
2018-02-20 15:28:09 (4412): Powering off VM.
2018-02-20 15:28:11 (4412): Successfully stopped VM.
2018-02-20 15:28:16 (4412): Deregistering VM. (boinc_a70bb71d5a5d61a6, slot#1)
2018-02-20 15:28:16 (4412): Removing virtual disk drive(s) from VM.
2018-02-20 15:28:16 (4412): Removing network bandwidth throttle group from VM.
2018-02-20 15:28:16 (4412): Removing storage controller(s) from VM.
2018-02-20 15:28:16 (4412): Removing VM from VirtualBox.
15:28:21 (4412): called boinc_finish(0)
</stderr_txt>
]]>
©2025 COPYRIGHT 2017-2018 NCN