Message boards : ATLAS application : ATLAS problem - long running but not using any CPU
Message board moderation

To post messages, you must log in.

1 · 2 · Next

AuthorMessage
broz69

Send message
Joined: 28 Nov 08
Posts: 30
Credit: 14,604,829
RAC: 17,458
Message 41452 - Posted: 3 Feb 2020, 9:52:59 UTC
Last modified: 3 Feb 2020, 10:29:03 UTC

Hi,

I have a problem with ATLAS tasks. I've read around couple of threads here but I still can't resolve the issue.
https://lhcathome.cern.ch/lhcathome/forum_thread.php?id=5261#41133
https://lhcathome.cern.ch/lhcathome/forum_thread.php?id=5226
https://lhcathome.cern.ch/lhcathome/forum_thread.php?id=5256#41063
https://lhcathome.cern.ch/lhcathome/forum_thread.php?id=5255#41051
https://lhcathome.cern.ch/lhcathome/forum_thread.php?id=5162#40094
https://lhcathome.cern.ch/lhcathome/forum_thread.php?id=5195#40391
https://lhcathome.cern.ch/lhcathome/forum_thread.php?id=5135#39821

I've seen a similar thing a month or two back when I was testing another computer. ATLAS tasks seem to be running but not using any CPU (CPU usage is around 1%). This means that while they are running they are "using" all available CPUs so other BOINC tasks can't run. Another thing is that the task somehow doesn't know that it should be terminated/cancelled.

Computer ID: 10570926
Windows 10 Pro 1903 (OS Build 18362.592)
BOINC 7.14.2

WU ID: 131084784
WU ID: 130921208
WU 130921208 started under VirtualBox 6.0.8 r130520 (Qt5.6.2), 1-Feb-2020 upgraded to 6.1.2 r135662 (Qt5.6.2).

I changed setting a bit in these days. My CPU has 8C/16T with 64GB RAM. Normally (and when the jobs were downloaded) BOINC is allowed to use 50% of CPUs and 50% of RAM. At the moment BOINC can use 100% of CPUs and 50% of RAM.
Normally I also don't use app_config.xml on my computer. But at the moment I have one:
<app_config>
<app>
<name>ATLAS</name>
<max_concurrent>2</max_concurrent>
</app>
<app_version>
<app_name>ATLAS</app_name>
<plan_class>vbox64_mt_mcore_atlas</plan_class>
<avg_ncpus>8.0</avg_ncpus>
<cmdline>--nthreads 8</cmdline>
</app_version>
</app_config>

I'll post logs and any attachments in another message.[url][/url]
ID: 41452 · Report as offensive     Reply Quote
broz69

Send message
Joined: 28 Nov 08
Posts: 30
Credit: 14,604,829
RAC: 17,458
Message 41453 - Posted: 3 Feb 2020, 10:00:09 UTC - in response to Message 41452.  

WU ID: 130921208
1-2-2020 22:39
Application
ATLAS Simulation 2.00 (vbox64_mt_mcore_atlas)
Name
aCbLDm850Dwn9Rq4apoT9bVoABFKDmABFKDmLD8WDmABFKDmC74yGn
State
Running
Received
21/01/2020 17:24:37
Report deadline
28/01/2020 17:24:37
Resources
4 CPUs
Estimated computation size
43,200 GFLOPs
CPU time
00:15:42
CPU time since checkpoint
00:00:00
Elapsed time
4d 17:14:53
Estimated time remaining
---
Fraction done
100.000%
Virtual memory size
83.26 MB
Working set size
9.96 GB
Directory
slots/15
Process ID
9664
Progress rate
0.720% per hour
Executable
vboxwrapper_26198ab7_windows_x86_64.exe

3-2-2020 09:16
Application
ATLAS Simulation 2.00 (vbox64_mt_mcore_atlas)
Name
aCbLDm850Dwn9Rq4apoT9bVoABFKDmABFKDmLD8WDmABFKDmC74yGn
State
Running
Received
21/01/2020 17:24:37
Report deadline
28/01/2020 17:24:37
Resources
4 CPUs
Estimated computation size
43,200 GFLOPs
CPU time
00:20:11
CPU time since checkpoint
---
Elapsed time
6d 04:26:16
Estimated time remaining
---
Fraction done
100.000%
Virtual memory size
83.50 MB
Working set size
9.96 GB
Directory
slots/15
Process ID
9664
Progress rate
0.720% per hour
Executable
vboxwrapper_26198ab7_windows_x86_64.exe

stderr.txt from slots directory:
2020-01-25 10:20:56 (2352): Detected: vboxwrapper 26197
2020-01-25 10:20:56 (2352): Detected: BOINC client v7.7
2020-01-25 10:20:57 (2352): Detected: VirtualBox VboxManage Interface (Version: 6.0.8)
2020-01-25 10:20:57 (2352): Successfully copied 'init_data.xml' to the shared directory.
2020-01-25 10:20:58 (2352): Create VM. (boinc_959537d37d2ec3c6, slot#15)
2020-01-25 10:20:59 (2352): Setting Memory Size for VM. (10200MB)
2020-01-25 10:20:59 (2352): Setting CPU Count for VM. (8)
2020-01-25 10:20:59 (2352): Setting Chipset Options for VM.
2020-01-25 10:21:00 (2352): Setting Boot Options for VM.
2020-01-25 10:21:00 (2352): Setting Network Configuration for NAT.
2020-01-25 10:21:00 (2352): Enabling VM Network Access.
2020-01-25 10:21:01 (2352): Disabling USB Support for VM.
2020-01-25 10:21:01 (2352): Disabling COM Port Support for VM.
2020-01-25 10:21:01 (2352): Disabling LPT Port Support for VM.
2020-01-25 10:21:01 (2352): Disabling Audio Support for VM.
2020-01-25 10:21:02 (2352): Disabling Clipboard Support for VM.
2020-01-25 10:21:02 (2352): Disabling Drag and Drop Support for VM.
2020-01-25 10:21:02 (2352): Adding storage controller(s) to VM.
2020-01-25 10:21:02 (2352): Adding virtual disk drive to VM. (vm_image.vdi)
2020-01-25 10:21:03 (2352): Adding VirtualBox Guest Additions to VM.
2020-01-25 10:21:03 (2352): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2020-01-25 10:21:03 (2352): forwarding host port 50549 to guest port 80
2020-01-25 10:21:04 (2352): Enabling remote desktop for VM.
2020-01-25 10:21:04 (2352): Enabling shared directory for VM.
2020-01-25 10:21:04 (2352): Starting VM using VBoxManage interface. (boinc_959537d37d2ec3c6, slot#15)
2020-01-25 10:21:07 (2352): Successfully started VM. (PID = '2168')
2020-01-25 10:21:07 (2352): Reporting VM Process ID to BOINC.
2020-01-25 10:21:07 (2352): Guest Log: BIOS: VirtualBox 6.0.8

2020-01-25 10:21:07 (2352): Guest Log: CPUID EDX: 0x178bfbff

2020-01-25 10:21:07 (2352): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-01-25 10:21:07 (2352): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-01-25 10:21:07 (2352): Detected: Web Application Enabled (http://localhost:50549)
2020-01-25 10:21:07 (2352): Detected: Remote Desktop Enabled (localhost:50550)
2020-01-25 10:21:07 (2352): Preference change detected
2020-01-25 10:21:07 (2352): Setting CPU throttle for VM. (100%)
2020-01-25 10:21:08 (2352): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-01-25 10:21:09 (2352): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-01-25 10:21:09 (2352): Guest Log: BIOS: Booting from Hard Disk...

2020-01-25 10:21:12 (2352): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-01-25 10:21:12 (2352): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f

2020-01-25 10:21:12 (2352): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f

2020-01-25 10:21:21 (2352): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-01-25 10:21:21 (2352): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-01-25 10:21:31 (2352): Guest Log: Checking CVMFS...

2020-01-25 10:21:32 (2352): Guest Log: Failed to check CVMFS, check output from cvmfs_config probe:

2020-01-25 10:21:32 (2352): Guest Log: Probing /cvmfs/atlas.cern.ch... Failed!

2020-01-25 10:21:32 (2352): Guest Log: Probing /cvmfs/atlas-condb.cern.ch... Failed!

2020-01-25 10:21:32 (2352): Guest Log: Probing /cvmfs/grid.cern.ch... Failed!

2020-01-25 10:21:45 (2352): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log

2020-01-25 10:21:45 (2352): Guest Log: 00:00:00.000123 main Log opened 2020-01-25T10:21:44.015521000Z

2020-01-25 10:21:45 (2352): Guest Log: 00:00:00.000186 main OS Product: Linux

2020-01-25 10:21:45 (2352): Guest Log: 00:00:00.000209 main OS Release: 3.10.0-957.27.2.el7.x86_64

2020-01-25 10:21:45 (2352): Guest Log: 00:00:00.000227 main OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019

2020-01-25 10:21:45 (2352): Guest Log: 00:00:00.000245 main Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService

2020-01-25 10:21:45 (2352): Guest Log: 00:00:00.000245 main Process ID: 1862

2020-01-25 10:21:45 (2352): Guest Log: 00:00:00.000246 main Package type: LINUX_64BITS_GENERIC

2020-01-25 10:21:45 (2352): Guest Log: 00:00:00.000703 main 5.2.32 r132073 started. Verbose level = 0

2020-01-25 10:21:45 (2352): Guest Log: 00:00:00.001539 main Error: Service 'control' failed to initialize: VERR_INVALID_FUNCTION

2020-01-25 10:21:45 (2352): Guest Log: 00:00:00.001714 main Session 0 is about to close ...

2020-01-25 10:21:45 (2352): Guest Log: 00:00:00.001738 main Stopping all guest processes ...

2020-01-25 10:21:45 (2352): Guest Log: 00:00:00.001758 main Closing all guest files ...

2020-01-25 10:21:45 (2352): Guest Log: 00:00:00.001848 main Ended.

2020-01-25 12:01:12 (2352): Status Report: Elapsed Time: '6000.000000'
2020-01-25 12:01:12 (2352): Status Report: CPU Time: '31.578125'
2020-01-25 13:41:16 (2352): Status Report: Elapsed Time: '12000.000000'
2020-01-25 13:41:16 (2352): Status Report: CPU Time: '42.328125'
2020-01-25 15:21:20 (2352): Status Report: Elapsed Time: '18000.000000'
2020-01-25 15:21:20 (2352): Status Report: CPU Time: '54.390625'
2020-01-25 17:01:23 (2352): Status Report: Elapsed Time: '24000.000000'
2020-01-25 17:01:23 (2352): Status Report: CPU Time: '65.859375'
2020-01-25 18:41:27 (2352): Status Report: Elapsed Time: '30000.000000'
2020-01-25 18:41:27 (2352): Status Report: CPU Time: '76.921875'
2020-01-25 20:21:31 (2352): Status Report: Elapsed Time: '36000.000000'
2020-01-25 20:21:31 (2352): Status Report: CPU Time: '88.421875'
2020-01-25 22:01:36 (2352): Status Report: Elapsed Time: '42000.000000'
2020-01-25 22:01:36 (2352): Status Report: CPU Time: '100.296875'
2020-01-25 23:41:40 (2352): Status Report: Elapsed Time: '48000.000000'
2020-01-25 23:41:40 (2352): Status Report: CPU Time: '111.625000'
2020-01-26 00:02:44 (2352): Stopping VM.
2020-01-26 00:02:44 (2352): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_959537d37d2ec3c6" savestate
Output:

2020-01-26 00:02:44 (2352): VM did not stop when requested.
2020-01-26 00:02:44 (2352): VM was successfully terminated.
2020-01-26 08:10:05 (7360): Detected: vboxwrapper 26197
2020-01-26 08:10:05 (7360): Detected: BOINC client v7.7
2020-01-26 08:10:07 (7360): Detected: VirtualBox VboxManage Interface (Version: 6.0.8)
2020-01-26 08:10:07 (7360): Starting VM using VBoxManage interface. (boinc_959537d37d2ec3c6, slot#15)
2020-01-26 08:10:11 (7360): Successfully started VM. (PID = '7512')
2020-01-26 08:10:11 (7360): Reporting VM Process ID to BOINC.
2020-01-26 08:10:11 (7360): Guest Log: BIOS: VirtualBox 6.0.8

2020-01-26 08:10:11 (7360): Guest Log: CPUID EDX: 0x178bfbff

2020-01-26 08:10:11 (7360): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-01-26 08:10:11 (7360): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-01-26 08:10:11 (7360): Detected: Web Application Enabled (http://localhost:50549)
2020-01-26 08:10:11 (7360): Detected: Remote Desktop Enabled (localhost:50550)
2020-01-26 08:10:11 (7360): Status Report: Elapsed Time: '49263.000000'
2020-01-26 08:10:11 (7360): Status Report: CPU Time: '114.046875'
2020-01-26 08:10:11 (7360): Preference change detected
2020-01-26 08:10:11 (7360): Setting CPU throttle for VM. (100%)
2020-01-26 08:10:12 (7360): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-01-26 08:10:13 (7360): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-01-26 08:10:13 (7360): Guest Log: BIOS: Booting from Hard Disk...

2020-01-26 08:10:16 (7360): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-01-26 08:10:16 (7360): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f

2020-01-26 08:10:16 (7360): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f

2020-01-26 08:10:26 (7360): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-01-26 08:10:26 (7360): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-01-26 08:10:35 (7360): Guest Log: Checking CVMFS...

2020-01-26 08:10:37 (7360): Guest Log: Failed to check CVMFS, check output from cvmfs_config probe:

2020-01-26 08:10:37 (7360): Guest Log: Probing /cvmfs/atlas.cern.ch... Failed!

2020-01-26 08:10:37 (7360): Guest Log: Probing /cvmfs/atlas-condb.cern.ch... Failed!

2020-01-26 08:10:37 (7360): Guest Log: Probing /cvmfs/grid.cern.ch... Failed!

2020-01-26 08:10:49 (7360): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log

2020-01-26 08:10:49 (7360): Guest Log: 00:00:00.000128 main Log opened 2020-01-26T08:10:47.898874000Z

2020-01-26 08:10:49 (7360): Guest Log: 00:00:00.000194 main OS Product: Linux

2020-01-26 08:10:49 (7360): Guest Log: 00:00:00.000217 main OS Release: 3.10.0-957.27.2.el7.x86_64

2020-01-26 08:10:49 (7360): Guest Log: 00:00:00.000236 main OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019

2020-01-26 08:10:49 (7360): Guest Log: 00:00:00.000255 main Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService

2020-01-26 08:10:49 (7360): Guest Log: 00:00:00.000255 main Process ID: 1860

2020-01-26 08:10:49 (7360): Guest Log: 00:00:00.000256 main Package type: LINUX_64BITS_GENERIC

2020-01-26 08:10:49 (7360): Guest Log: 00:00:00.000725 main 5.2.32 r132073 started. Verbose level = 0

2020-01-26 08:10:49 (7360): Guest Log: 00:00:00.001876 main Error: Service 'control' failed to initialize: VERR_INVALID_FUNCTION

2020-01-26 08:10:49 (7360): Guest Log: 00:00:00.002071 main Session 0 is about to close ...

2020-01-26 08:10:49 (7360): Guest Log: 00:00:00.002118 main Stopping all guest processes ...

2020-01-26 08:10:49 (7360): Guest Log: 00:00:00.002140 main Closing all guest files ...

2020-01-26 08:10:49 (7360): Guest Log: 00:00:00.002232 main Ended.

2020-01-26 09:50:16 (7360): Status Report: Elapsed Time: '55263.000000'
2020-01-26 09:50:16 (7360): Status Report: CPU Time: '142.718750'
2020-01-26 11:30:20 (7360): Status Report: Elapsed Time: '61263.000000'
2020-01-26 11:30:20 (7360): Status Report: CPU Time: '154.687500'
2020-01-26 13:10:25 (7360): Status Report: Elapsed Time: '67263.000000'
2020-01-26 13:10:25 (7360): Status Report: CPU Time: '167.531250'
2020-01-26 14:50:29 (7360): Status Report: Elapsed Time: '73263.000000'
2020-01-26 14:50:29 (7360): Status Report: CPU Time: '179.703125'
2020-01-26 16:30:33 (7360): Status Report: Elapsed Time: '79263.000000'
2020-01-26 16:30:33 (7360): Status Report: CPU Time: '191.328125'
2020-01-26 18:10:37 (7360): Status Report: Elapsed Time: '85263.000000'
2020-01-26 18:10:37 (7360): Status Report: CPU Time: '202.859375'
2020-01-26 19:50:42 (7360): Status Report: Elapsed Time: '91263.000000'
2020-01-26 19:50:42 (7360): Status Report: CPU Time: '215.468750'
2020-01-26 21:30:46 (7360): Status Report: Elapsed Time: '97263.000000'
2020-01-26 21:30:46 (7360): Status Report: CPU Time: '227.296875'
2020-01-26 23:10:51 (7360): Status Report: Elapsed Time: '103263.000000'
2020-01-26 23:10:51 (7360): Status Report: CPU Time: '239.203125'
2020-01-26 23:35:05 (7360): VM state change detected. (old = 'Running', new = 'Paused')
2020-01-26 23:35:05 (7360): Stopping VM.
2020-01-27 07:10:38 (7360): VM did not stop when requested.
2020-01-27 07:10:38 (7360): VM was successfully terminated.
2020-01-27 07:10:50 (9076): Detected: vboxwrapper 26197
2020-01-27 07:10:50 (9076): Detected: BOINC client v7.7
2020-01-27 07:10:51 (9076): Detected: VirtualBox VboxManage Interface (Version: 6.0.8)
2020-01-27 07:10:51 (9076): Starting VM using VBoxManage interface. (boinc_959537d37d2ec3c6, slot#15)
2020-01-27 07:10:58 (9076): Successfully started VM. (PID = '1252')
2020-01-27 07:10:58 (9076): Reporting VM Process ID to BOINC.
2020-01-27 07:10:58 (9076): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-01-27 07:10:58 (9076): Detected: Web Application Enabled (http://localhost:50549)
2020-01-27 07:10:58 (9076): Detected: Remote Desktop Enabled (localhost:50550)
2020-01-27 07:10:58 (9076): Status Report: Elapsed Time: '104716.000000'
2020-01-27 07:10:58 (9076): Status Report: CPU Time: '242.156250'
2020-01-27 07:10:58 (9076): Preference change detected
2020-01-27 07:10:58 (9076): Setting CPU throttle for VM. (100%)
2020-01-27 07:10:59 (9076): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-01-27 08:51:03 (9076): Status Report: Elapsed Time: '110716.000000'
2020-01-27 08:51:03 (9076): Status Report: CPU Time: '257.609375'
2020-01-27 10:31:07 (9076): Status Report: Elapsed Time: '116716.000000'
2020-01-27 10:31:07 (9076): Status Report: CPU Time: '268.625000'
2020-01-27 12:11:12 (9076): Status Report: Elapsed Time: '122716.000000'
2020-01-27 12:11:12 (9076): Status Report: CPU Time: '281.906250'
2020-01-27 13:51:16 (9076): Status Report: Elapsed Time: '128716.000000'
2020-01-27 13:51:16 (9076): Status Report: CPU Time: '294.859375'
2020-01-27 15:31:21 (9076): Status Report: Elapsed Time: '134716.000000'
2020-01-27 15:31:21 (9076): Status Report: CPU Time: '308.390625'
2020-01-27 17:11:25 (9076): Status Report: Elapsed Time: '140716.000000'
2020-01-27 17:11:25 (9076): Status Report: CPU Time: '321.296875'
2020-01-27 18:51:29 (9076): Status Report: Elapsed Time: '146716.000000'
2020-01-27 18:51:29 (9076): Status Report: CPU Time: '334.421875'
2020-01-27 20:31:32 (9076): Status Report: Elapsed Time: '152716.000000'
2020-01-27 20:31:32 (9076): Status Report: CPU Time: '346.640625'
2020-01-27 22:11:36 (9076): Status Report: Elapsed Time: '158716.000000'
2020-01-27 22:11:36 (9076): Status Report: CPU Time: '358.890625'
2020-01-27 23:30:55 (9076): VM state change detected. (old = 'Running', new = 'Paused')
2020-01-27 23:30:55 (9076): Stopping VM.
2020-01-28 07:10:39 (9076): VM did not stop when requested.
2020-01-28 07:10:39 (9076): VM was successfully terminated.
2020-01-28 07:10:49 (3896): Detected: vboxwrapper 26197
2020-01-28 07:10:49 (3896): Detected: BOINC client v7.7
2020-01-28 07:10:50 (3896): Detected: VirtualBox VboxManage Interface (Version: 6.0.8)
2020-01-28 07:10:50 (3896): Starting VM using VBoxManage interface. (boinc_959537d37d2ec3c6, slot#15)
2020-01-28 07:10:58 (3896): Successfully started VM. (PID = '8640')
2020-01-28 07:10:58 (3896): Reporting VM Process ID to BOINC.
2020-01-28 07:10:58 (3896): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-01-28 07:10:58 (3896): Detected: Web Application Enabled (http://localhost:50549)
2020-01-28 07:10:58 (3896): Detected: Remote Desktop Enabled (localhost:50550)
2020-01-28 07:10:58 (3896): Status Report: Elapsed Time: '163472.000000'
2020-01-28 07:10:58 (3896): Status Report: CPU Time: '368.187500'
2020-01-28 07:10:58 (3896): Preference change detected
2020-01-28 07:10:58 (3896): Setting CPU throttle for VM. (100%)
2020-01-28 07:10:58 (3896): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-01-28 07:35:48 (3896): VM state change detected. (old = 'Running', new = 'Paused')
2020-01-28 07:35:59 (3896): VM state change detected. (old = 'Paused', new = 'Running')
2020-01-28 08:51:15 (3896): Status Report: Elapsed Time: '169472.000000'
2020-01-28 08:51:15 (3896): Status Report: CPU Time: '384.468750'
2020-01-28 09:16:10 (3896): Stopping VM.
2020-01-28 09:16:10 (3896): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_959537d37d2ec3c6" savestate
Output:

2020-01-28 09:16:10 (3896): VM did not stop when requested.
2020-01-28 09:16:10 (3896): VM was successfully terminated.
2020-01-28 09:17:33 (4796): Detected: vboxwrapper 26197
2020-01-28 09:17:33 (4796): Detected: BOINC client v7.7
2020-01-28 09:17:35 (4796): Detected: VirtualBox VboxManage Interface (Version: 6.0.8)
2020-01-28 09:17:35 (4796): Starting VM using VBoxManage interface. (boinc_959537d37d2ec3c6, slot#15)
2020-01-28 09:17:39 (4796): Successfully started VM. (PID = '10136')
2020-01-28 09:17:39 (4796): Reporting VM Process ID to BOINC.
2020-01-28 09:17:39 (4796): Guest Log: BIOS: VirtualBox 6.0.8

2020-01-28 09:17:39 (4796): Guest Log: CPUID EDX: 0x178bfbff

2020-01-28 09:17:39 (4796): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-01-28 09:17:39 (4796): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-01-28 09:17:39 (4796): Detected: Web Application Enabled (http://localhost:50549)
2020-01-28 09:17:39 (4796): Detected: Remote Desktop Enabled (localhost:50550)
2020-01-28 09:17:39 (4796): Status Report: Elapsed Time: '170966.000000'
2020-01-28 09:17:39 (4796): Status Report: CPU Time: '387.250000'
2020-01-28 09:17:39 (4796): Preference change detected
2020-01-28 09:17:39 (4796): Setting CPU throttle for VM. (100%)
2020-01-28 09:17:40 (4796): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-01-28 09:17:41 (4796): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-01-28 09:17:41 (4796): Guest Log: BIOS: Booting from Hard Disk...

2020-01-28 09:17:44 (4796): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-01-28 09:17:44 (4796): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f

2020-01-28 09:17:44 (4796): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f

2020-01-28 09:17:53 (4796): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-01-28 09:17:53 (4796): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-01-28 09:18:01 (4796): Guest Log: Checking CVMFS...

2020-01-28 09:18:03 (4796): Guest Log: Failed to check CVMFS, check output from cvmfs_config probe:

2020-01-28 09:18:03 (4796): Guest Log: Probing /cvmfs/atlas.cern.ch... Failed!

2020-01-28 09:18:03 (4796): Guest Log: Probing /cvmfs/atlas-condb.cern.ch... Failed!

2020-01-28 09:18:03 (4796): Guest Log: Probing /cvmfs/grid.cern.ch... Failed!

2020-01-28 09:18:15 (4796): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log

2020-01-28 09:18:15 (4796): Guest Log: 00:00:00.000128 main Log opened 2020-01-28T09:18:14.000148000Z

2020-01-28 09:18:15 (4796): Guest Log: 00:00:00.000196 main OS Product: Linux

2020-01-28 09:18:15 (4796): Guest Log: 00:00:00.000220 main OS Release: 3.10.0-957.27.2.el7.x86_64

2020-01-28 09:18:15 (4796): Guest Log: 00:00:00.000238 main OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019

2020-01-28 09:18:15 (4796): Guest Log: 00:00:00.000257 main Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService

2020-01-28 09:18:15 (4796): Guest Log: 00:00:00.000257 main Process ID: 1886

2020-01-28 09:18:15 (4796): Guest Log: 00:00:00.000258 main Package type: LINUX_64BITS_GENERIC

2020-01-28 09:18:15 (4796): Guest Log: 00:00:00.000741 main 5.2.32 r132073 started. Verbose level = 0

2020-01-28 09:18:15 (4796): Guest Log: 00:00:00.001166 main Error: Service 'control' failed to initialize: VERR_INVALID_FUNCTION

2020-01-28 09:18:15 (4796): Guest Log: 00:00:00.001242 main Session 0 is about to close ...

2020-01-28 09:18:15 (4796): Guest Log: 00:00:00.001265 main Stopping all guest processes ...

2020-01-28 09:18:15 (4796): Guest Log: 00:00:00.001283 main Closing all guest files ...

2020-01-28 09:18:15 (4796): Guest Log: 00:00:00.001349 main Ended.

2020-01-28 09:40:33 (4796): Preference change detected
2020-01-28 09:40:33 (4796): Setting CPU throttle for VM. (100%)
2020-01-28 09:40:34 (4796): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-01-28 09:41:46 (4796): Preference change detected
2020-01-28 09:41:46 (4796): Setting CPU throttle for VM. (100%)
2020-01-28 09:41:47 (4796): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-01-28 09:42:02 (4796): Stopping VM.
2020-01-28 09:43:14 (8512): Detected: vboxwrapper 26197
2020-01-28 09:43:14 (8512): Detected: BOINC client v7.7
2020-01-28 09:43:15 (8512): Detected: VirtualBox VboxManage Interface (Version: 6.0.8)
2020-01-28 09:43:16 (8512): Starting VM using VBoxManage interface. (boinc_959537d37d2ec3c6, slot#15)
2020-01-28 09:43:21 (8512): Successfully started VM. (PID = '2436')
2020-01-28 09:43:21 (8512): Reporting VM Process ID to BOINC.
2020-01-28 09:43:21 (8512): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-01-28 09:43:21 (8512): Detected: Web Application Enabled (http://localhost:50549)
2020-01-28 09:43:21 (8512): Detected: Remote Desktop Enabled (localhost:50550)
2020-01-28 09:43:21 (8512): Status Report: Elapsed Time: '172428.000000'
2020-01-28 09:43:21 (8512): Status Report: CPU Time: '407.312500'
2020-01-28 09:43:21 (8512): Preference change detected
2020-01-28 09:43:21 (8512): Setting CPU throttle for VM. (100%)
2020-01-28 09:43:21 (8512): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-01-28 11:23:25 (8512): Status Report: Elapsed Time: '178428.000000'
2020-01-28 11:23:25 (8512): Status Report: CPU Time: '422.281250'
2020-01-28 13:03:30 (8512): Status Report: Elapsed Time: '184428.000000'
2020-01-28 13:03:30 (8512): Status Report: CPU Time: '434.296875'
2020-01-28 14:43:34 (8512): Status Report: Elapsed Time: '190428.000000'
2020-01-28 14:43:34 (8512): Status Report: CPU Time: '446.171875'
2020-01-28 16:23:39 (8512): Status Report: Elapsed Time: '196428.000000'
2020-01-28 16:23:39 (8512): Status Report: CPU Time: '458.562500'
2020-01-28 18:03:43 (8512): Status Report: Elapsed Time: '202428.000000'
2020-01-28 18:03:43 (8512): Status Report: CPU Time: '470.562500'
2020-01-28 19:43:48 (8512): Status Report: Elapsed Time: '208428.000000'
2020-01-28 19:43:48 (8512): Status Report: CPU Time: '483.000000'
2020-01-28 21:23:52 (8512): Status Report: Elapsed Time: '214428.000000'
2020-01-28 21:23:52 (8512): Status Report: CPU Time: '495.234375'
2020-01-28 23:03:57 (8512): Status Report: Elapsed Time: '220428.000000'
2020-01-28 23:03:57 (8512): Status Report: CPU Time: '507.906250'
2020-01-28 23:36:34 (8512): Stopping VM.
2020-01-28 23:36:34 (8512): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_959537d37d2ec3c6" savestate
Output:

2020-01-28 23:36:34 (8512): VM did not stop when requested.
2020-01-28 23:36:34 (8512): VM was successfully terminated.
2020-01-29 08:33:28 (7712): Detected: vboxwrapper 26197
2020-01-29 08:33:28 (7712): Detected: BOINC client v7.7
2020-01-29 08:33:29 (7712): Detected: VirtualBox VboxManage Interface (Version: 6.0.8)
2020-01-29 08:33:30 (7712): Starting VM using VBoxManage interface. (boinc_959537d37d2ec3c6, slot#15)
2020-01-29 08:33:34 (7712): Successfully started VM. (PID = '7864')
2020-01-29 08:33:34 (7712): Reporting VM Process ID to BOINC.
2020-01-29 08:33:34 (7712): Guest Log: BIOS: VirtualBox 6.0.8

2020-01-29 08:33:34 (7712): Guest Log: CPUID EDX: 0x178bfbff

2020-01-29 08:33:34 (7712): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-01-29 08:33:34 (7712): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-01-29 08:33:34 (7712): Detected: Web Application Enabled (http://localhost:50549)
2020-01-29 08:33:34 (7712): Detected: Remote Desktop Enabled (localhost:50550)
2020-01-29 08:33:34 (7712): Status Report: Elapsed Time: '222383.000000'
2020-01-29 08:33:34 (7712): Status Report: CPU Time: '512.390625'
2020-01-29 08:33:34 (7712): Preference change detected
2020-01-29 08:33:34 (7712): Setting CPU throttle for VM. (100%)
2020-01-29 08:33:34 (7712): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-01-29 08:33:36 (7712): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-01-29 08:33:36 (7712): Guest Log: BIOS: Booting from Hard Disk...

2020-01-29 08:33:39 (7712): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-01-29 08:33:39 (7712): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f

2020-01-29 08:33:39 (7712): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f

2020-01-29 08:33:52 (7712): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-01-29 08:33:52 (7712): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-01-29 08:34:24 (7712): Guest Log: Checking CVMFS...

2020-01-29 08:34:26 (7712): Guest Log: Failed to check CVMFS, check output from cvmfs_config probe:

2020-01-29 08:34:26 (7712): Guest Log: Probing /cvmfs/atlas.cern.ch... Failed!

2020-01-29 08:34:26 (7712): Guest Log: Probing /cvmfs/atlas-condb.cern.ch... Failed!

2020-01-29 08:34:26 (7712): Guest Log: Probing /cvmfs/grid.cern.ch... Failed!

2020-01-29 08:34:38 (7712): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log

2020-01-29 08:34:38 (7712): Guest Log: 00:00:00.000130 main Log opened 2020-01-29T08:34:36.487864000Z

2020-01-29 08:34:38 (7712): Guest Log: 00:00:00.000201 main OS Product: Linux

2020-01-29 08:34:38 (7712): Guest Log: 00:00:00.000224 main OS Release: 3.10.0-957.27.2.el7.x86_64

2020-01-29 08:34:38 (7712): Guest Log: 00:00:00.000242 main OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019

2020-01-29 08:34:38 (7712): Guest Log: 00:00:00.000261 main Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService

2020-01-29 08:34:38 (7712): Guest Log: 00:00:00.000261 main Process ID: 1864

2020-01-29 08:34:38 (7712): Guest Log: 00:00:00.000262 main Package type: LINUX_64BITS_GENERIC

2020-01-29 08:34:38 (7712): Guest Log: 00:00:00.000729 main 5.2.32 r132073 started. Verbose level = 0

2020-01-29 08:34:38 (7712): Guest Log: 00:00:00.001148 main Error: Service 'control' failed to initialize: VERR_INVALID_FUNCTION

2020-01-29 08:34:38 (7712): Guest Log: 00:00:00.001218 main Session 0 is about to close ...

2020-01-29 08:34:38 (7712): Guest Log: 00:00:00.001239 main Stopping all guest processes ...

2020-01-29 08:34:38 (7712): Guest Log: 00:00:00.001257 main Closing all guest files ...

2020-01-29 08:34:38 (7712): Guest Log: 00:00:00.001329 main Ended.

2020-01-29 10:13:39 (7712): Status Report: Elapsed Time: '228383.000000'
2020-01-29 10:13:39 (7712): Status Report: CPU Time: '539.562500'
2020-01-29 11:53:43 (7712): Status Report: Elapsed Time: '234383.000000'
2020-01-29 11:53:43 (7712): Status Report: CPU Time: '552.468750'
2020-01-29 13:33:48 (7712): Status Report: Elapsed Time: '240383.000000'
2020-01-29 13:33:48 (7712): Status Report: CPU Time: '565.062500'
2020-01-29 15:13:52 (7712): Status Report: Elapsed Time: '246383.000000'
2020-01-29 15:13:52 (7712): Status Report: CPU Time: '578.203125'
2020-01-29 16:53:56 (7712): Status Report: Elapsed Time: '252383.000000'
2020-01-29 16:53:56 (7712): Status Report: CPU Time: '590.843750'
2020-01-29 18:34:01 (7712): Status Report: Elapsed Time: '258383.000000'
2020-01-29 18:34:01 (7712): Status Report: CPU Time: '603.296875'
2020-01-29 20:14:05 (7712): Status Report: Elapsed Time: '264383.000000'
2020-01-29 20:14:05 (7712): Status Report: CPU Time: '615.250000'
2020-01-29 21:54:10 (7712): Status Report: Elapsed Time: '270383.000000'
2020-01-29 21:54:10 (7712): Status Report: CPU Time: '626.781250'
2020-01-29 22:27:39 (7712): Stopping VM.
2020-01-29 22:27:39 (7712): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_959537d37d2ec3c6" savestate
Output:

2020-01-29 22:27:39 (7712): VM did not stop when requested.
2020-01-29 22:27:39 (7712): VM was successfully terminated.
2020-01-30 07:46:15 (7308): Detected: vboxwrapper 26197
2020-01-30 07:46:15 (7308): Detected: BOINC client v7.7
2020-01-30 07:46:16 (7308): Detected: VirtualBox VboxManage Interface (Version: 6.0.8)
2020-01-30 07:46:17 (7308): Starting VM using VBoxManage interface. (boinc_959537d37d2ec3c6, slot#15)
2020-01-30 07:46:21 (7308): Successfully started VM. (PID = '7532')
2020-01-30 07:46:21 (7308): Reporting VM Process ID to BOINC.
2020-01-30 07:46:21 (7308): Guest Log: BIOS: VirtualBox 6.0.8

2020-01-30 07:46:21 (7308): Guest Log: CPUID EDX: 0x178bfbff

2020-01-30 07:46:21 (7308): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-01-30 07:46:21 (7308): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-01-30 07:46:21 (7308): Detected: Web Application Enabled (http://localhost:50549)
2020-01-30 07:46:21 (7308): Detected: Remote Desktop Enabled (localhost:50550)
2020-01-30 07:46:21 (7308): Status Report: Elapsed Time: '272391.000000'
2020-01-30 07:46:21 (7308): Status Report: CPU Time: '630.968750'
2020-01-30 07:46:21 (7308): Preference change detected
2020-01-30 07:46:21 (7308): Setting CPU throttle for VM. (100%)
2020-01-30 07:46:21 (7308): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-01-30 07:46:23 (7308): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-01-30 07:46:23 (7308): Guest Log: BIOS: Booting from Hard Disk...

2020-01-30 07:46:26 (7308): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-01-30 07:46:26 (7308): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f

2020-01-30 07:46:26 (7308): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f

2020-01-30 07:46:36 (7308): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-01-30 07:46:36 (7308): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-01-30 07:47:00 (7308): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log

2020-01-30 07:47:00 (7308): Guest Log: 00:00:00.000195 main Log opened 2020-01-30T07:46:59.309790000Z

2020-01-30 07:47:00 (7308): Guest Log: 00:00:00.000263 main OS Product: Linux

2020-01-30 07:47:00 (7308): Guest Log: 00:00:00.000285 main OS Release: 3.10.0-957.27.2.el7.x86_64

2020-01-30 07:47:00 (7308): Guest Log: 00:00:00.000304 main OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019

2020-01-30 07:47:00 (7308): Guest Log: 00:00:00.000322 main Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService

2020-01-30 07:47:00 (7308): Guest Log: 00:00:00.000323 main Process ID: 1095

2020-01-30 07:47:00 (7308): Guest Log: 00:00:00.000323 main Package type: LINUX_64BITS_GENERIC

2020-01-30 07:47:00 (7308): Guest Log: 00:00:00.000825 main 5.2.32 r132073 started. Verbose level = 0

2020-01-30 07:47:00 (7308): Guest Log: 00:00:00.001245 main Error: Service 'control' failed to initialize: VERR_INVALID_FUNCTION

2020-01-30 07:47:00 (7308): Guest Log: 00:00:00.001318 main Session 0 is about to close ...

2020-01-30 07:47:00 (7308): Guest Log: 00:00:00.001341 main Stopping all guest processes ...

2020-01-30 07:47:00 (7308): Guest Log: 00:00:00.001360 main Closing all guest files ...

2020-01-30 07:47:00 (7308): Guest Log: 00:00:00.001429 main Ended.

2020-01-30 07:47:05 (7308): Guest Log: Checking CVMFS...

2020-01-30 07:47:06 (7308): Guest Log: Failed to check CVMFS, check output from cvmfs_config probe:

2020-01-30 07:47:06 (7308): Guest Log: Probing /cvmfs/atlas.cern.ch... Failed!

2020-01-30 07:47:06 (7308): Guest Log: Probing /cvmfs/atlas-condb.cern.ch... Failed!

2020-01-30 07:47:06 (7308): Guest Log: Probing /cvmfs/grid.cern.ch... Failed!

2020-01-30 09:26:25 (7308): Status Report: Elapsed Time: '278391.000000'
2020-01-30 09:26:25 (7308): Status Report: CPU Time: '658.312500'
2020-01-30 11:06:30 (7308): Status Report: Elapsed Time: '284391.000000'
2020-01-30 11:06:30 (7308): Status Report: CPU Time: '670.203125'
2020-01-30 12:46:34 (7308): Status Report: Elapsed Time: '290391.000000'
2020-01-30 12:46:34 (7308): Status Report: CPU Time: '681.796875'
2020-01-30 14:26:39 (7308): Status Report: Elapsed Time: '296391.000000'
2020-01-30 14:26:39 (7308): Status Report: CPU Time: '694.187500'
2020-01-30 16:06:43 (7308): Status Report: Elapsed Time: '302391.000000'
2020-01-30 16:06:43 (7308): Status Report: CPU Time: '706.296875'
2020-01-30 17:46:48 (7308): Status Report: Elapsed Time: '308391.000000'
2020-01-30 17:46:48 (7308): Status Report: CPU Time: '718.312500'
2020-01-30 19:26:52 (7308): Status Report: Elapsed Time: '314391.000000'
2020-01-30 19:26:52 (7308): Status Report: CPU Time: '730.281250'
2020-01-30 21:06:57 (7308): Status Report: Elapsed Time: '320391.000000'
2020-01-30 21:06:57 (7308): Status Report: CPU Time: '742.031250'
2020-01-30 22:47:01 (7308): Status Report: Elapsed Time: '326391.000000'
2020-01-30 22:47:01 (7308): Status Report: CPU Time: '753.390625'
2020-01-30 23:14:16 (7308): Stopping VM.
2020-01-30 23:14:17 (7308): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_959537d37d2ec3c6" savestate
Output:

2020-01-30 23:14:17 (7308): VM did not stop when requested.
2020-01-30 23:14:17 (7308): VM was successfully terminated.
2020-01-31 09:11:55 (8088): Detected: vboxwrapper 26197
2020-01-31 09:11:55 (8088): Detected: BOINC client v7.7
2020-01-31 09:11:56 (8088): Detected: VirtualBox VboxManage Interface (Version: 6.0.8)
2020-01-31 09:11:57 (8088): Starting VM using VBoxManage interface. (boinc_959537d37d2ec3c6, slot#15)
2020-01-31 09:12:01 (8088): Successfully started VM. (PID = '8176')
2020-01-31 09:12:01 (8088): Reporting VM Process ID to BOINC.
2020-01-31 09:12:01 (8088): Guest Log: BIOS: VirtualBox 6.0.8

2020-01-31 09:12:01 (8088): Guest Log: CPUID EDX: 0x178bfbff

2020-01-31 09:12:01 (8088): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-01-31 09:12:01 (8088): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-01-31 09:12:01 (8088): Detected: Web Application Enabled (http://localhost:50549)
2020-01-31 09:12:01 (8088): Detected: Remote Desktop Enabled (localhost:50550)
2020-01-31 09:12:01 (8088): Status Report: Elapsed Time: '328025.000000'
2020-01-31 09:12:01 (8088): Status Report: CPU Time: '756.531250'
2020-01-31 09:12:01 (8088): Preference change detected
2020-01-31 09:12:01 (8088): Setting CPU throttle for VM. (100%)
2020-01-31 09:12:01 (8088): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-01-31 09:12:03 (8088): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-01-31 09:12:03 (8088): Guest Log: BIOS: Booting from Hard Disk...

2020-01-31 09:12:06 (8088): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-01-31 09:12:06 (8088): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f

2020-01-31 09:12:06 (8088): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f

2020-01-31 09:12:16 (8088): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-01-31 09:12:16 (8088): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-01-31 09:12:26 (8088): Guest Log: Checking CVMFS...

2020-01-31 09:12:28 (8088): Guest Log: Failed to check CVMFS, check output from cvmfs_config probe:

2020-01-31 09:12:28 (8088): Guest Log: Probing /cvmfs/atlas.cern.ch... Failed!

2020-01-31 09:12:28 (8088): Guest Log: Probing /cvmfs/atlas-condb.cern.ch... Failed!

2020-01-31 09:12:28 (8088): Guest Log: Probing /cvmfs/grid.cern.ch... Failed!

2020-01-31 09:12:38 (8088): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log

2020-01-31 09:12:38 (8088): Guest Log: 00:00:00.000124 main Log opened 2020-01-31T09:12:36.563997000Z

2020-01-31 09:12:38 (8088): Guest Log: 00:00:00.000190 main OS Product: Linux

2020-01-31 09:12:38 (8088): Guest Log: 00:00:00.000213 main OS Release: 3.10.0-957.27.2.el7.x86_64

2020-01-31 09:12:38 (8088): Guest Log: 00:00:00.000232 main OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019

2020-01-31 09:12:38 (8088): Guest Log: 00:00:00.000250 main Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService

2020-01-31 09:12:38 (8088): Guest Log: 00:00:00.000250 main Process ID: 1862

2020-01-31 09:12:38 (8088): Guest Log: 00:00:00.000251 main Package type: LINUX_64BITS_GENERIC

2020-01-31 09:12:38 (8088): Guest Log: 00:00:00.000725 main 5.2.32 r132073 started. Verbose level = 0

2020-01-31 09:12:38 (8088): Guest Log: 00:00:00.001197 main Error: Service 'control' failed to initialize: VERR_INVALID_FUNCTION

2020-01-31 09:12:38 (8088): Guest Log: 00:00:00.001293 main Session 0 is about to close ...

2020-01-31 09:12:38 (8088): Guest Log: 00:00:00.001316 main Stopping all guest processes ...

2020-01-31 09:12:38 (8088): Guest Log: 00:00:00.001336 main Closing all guest files ...

2020-01-31 09:12:38 (8088): Guest Log: 00:00:00.001424 main Ended.

2020-01-31 10:52:05 (8088): Status Report: Elapsed Time: '334025.000000'
2020-01-31 10:52:05 (8088): Status Report: CPU Time: '782.546875'
2020-01-31 12:32:10 (8088): Status Report: Elapsed Time: '340025.000000'
2020-01-31 12:32:10 (8088): Status Report: CPU Time: '795.671875'
2020-01-31 14:12:14 (8088): Status Report: Elapsed Time: '346025.000000'
2020-01-31 14:12:14 (8088): Status Report: CPU Time: '808.093750'
2020-01-31 15:52:19 (8088): Status Report: Elapsed Time: '352025.000000'
2020-01-31 15:52:19 (8088): Status Report: CPU Time: '820.828125'
2020-01-31 17:32:23 (8088): Status Report: Elapsed Time: '358025.000000'
2020-01-31 17:32:23 (8088): Status Report: CPU Time: '833.109375'
2020-01-31 19:12:28 (8088): Status Report: Elapsed Time: '364025.000000'
2020-01-31 19:12:28 (8088): Status Report: CPU Time: '844.343750'
2020-01-31 20:52:32 (8088): Status Report: Elapsed Time: '370025.000000'
2020-01-31 20:52:32 (8088): Status Report: CPU Time: '856.218750'
2020-01-31 22:32:36 (8088): Status Report: Elapsed Time: '376025.000000'
2020-01-31 22:32:36 (8088): Status Report: CPU Time: '868.546875'
2020-01-31 23:00:40 (8088): Stopping VM.
2020-01-31 23:05:54 (4236): Detected: vboxwrapper 26197
2020-01-31 23:05:54 (4236): Detected: BOINC client v7.7
2020-01-31 23:05:55 (4236): Detected: VirtualBox VboxManage Interface (Version: 6.0.8)
2020-01-31 23:05:55 (4236): Starting VM using VBoxManage interface. (boinc_959537d37d2ec3c6, slot#15)
2020-01-31 23:06:02 (4236): Successfully started VM. (PID = '8604')
2020-01-31 23:06:02 (4236): Reporting VM Process ID to BOINC.
2020-01-31 23:06:02 (4236): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-01-31 23:06:02 (4236): Detected: Web Application Enabled (http://localhost:50549)
2020-01-31 23:06:02 (4236): Detected: Remote Desktop Enabled (localhost:50550)
2020-01-31 23:06:02 (4236): Status Report: Elapsed Time: '377707.000000'
2020-01-31 23:06:02 (4236): Status Report: CPU Time: '871.531250'
2020-01-31 23:06:02 (4236): Preference change detected
2020-01-31 23:06:02 (4236): Setting CPU throttle for VM. (100%)
2020-01-31 23:06:03 (4236): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-01-31 23:17:46 (4236): Stopping VM.
2020-02-01 08:52:59 (7760): Detected: vboxwrapper 26197
2020-02-01 08:52:59 (7760): Detected: BOINC client v7.7
2020-02-01 08:53:00 (7760): Detected: VirtualBox VboxManage Interface (Version: 6.0.8)
2020-02-01 08:53:00 (7760): Starting VM using VBoxManage interface. (boinc_959537d37d2ec3c6, slot#15)
2020-02-01 08:53:06 (7760): Successfully started VM. (PID = '7180')
2020-02-01 08:53:06 (7760): Reporting VM Process ID to BOINC.
2020-02-01 08:53:06 (7760): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-02-01 08:53:06 (7760): Detected: Web Application Enabled (http://localhost:50549)
2020-02-01 08:53:06 (7760): Detected: Remote Desktop Enabled (localhost:50550)
2020-02-01 08:53:06 (7760): Status Report: Elapsed Time: '378410.524935'
2020-02-01 08:53:06 (7760): Status Report: CPU Time: '876.187500'
2020-02-01 08:53:06 (7760): Preference change detected
2020-02-01 08:53:06 (7760): Setting CPU throttle for VM. (100%)
2020-02-01 08:53:06 (7760): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-02-01 10:33:10 (7760): Status Report: Elapsed Time: '384410.524935'
2020-02-01 10:33:10 (7760): Status Report: CPU Time: '890.531250'
2020-02-01 12:13:13 (7760): Status Report: Elapsed Time: '390410.524935'
2020-02-01 12:13:13 (7760): Status Report: CPU Time: '901.890625'
2020-02-01 13:53:17 (7760): Status Report: Elapsed Time: '396410.524935'
2020-02-01 13:53:17 (7760): Status Report: CPU Time: '913.281250'
2020-02-01 15:33:22 (7760): Status Report: Elapsed Time: '402410.524935'
2020-02-01 15:33:22 (7760): Status Report: CPU Time: '925.578125'
2020-02-01 15:50:37 (7760): Stopping VM.
2020-02-01 20:53:27 (9664): Detected: vboxwrapper 26197
2020-02-01 20:53:27 (9664): Detected: BOINC client v7.7
2020-02-01 20:53:28 (9664): Detected: VirtualBox VboxManage Interface (Version: 6.1.2)
2020-02-01 20:53:29 (9664): Starting VM using VBoxManage interface. (boinc_959537d37d2ec3c6, slot#15)
2020-02-01 20:53:37 (9664): Successfully started VM. (PID = '9880')
2020-02-01 20:53:37 (9664): Reporting VM Process ID to BOINC.
2020-02-01 20:53:37 (9664): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-02-01 20:53:37 (9664): Detected: Web Application Enabled (http://localhost:50549)
2020-02-01 20:53:37 (9664): Detected: Remote Desktop Enabled (localhost:50550)
2020-02-01 20:53:37 (9664): Status Report: Elapsed Time: '403445.524935'
2020-02-01 20:53:37 (9664): Status Report: CPU Time: '927.718750'
2020-02-01 20:53:37 (9664): Preference change detected
2020-02-01 20:53:37 (9664): Setting CPU throttle for VM. (100%)
2020-02-01 20:53:37 (9664): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-02-01 20:55:56 (9664): Preference change detected
2020-02-01 20:55:56 (9664): Setting CPU throttle for VM. (100%)
2020-02-01 20:55:56 (9664): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-02-01 20:58:18 (9664): Preference change detected
2020-02-01 20:58:18 (9664): Setting CPU throttle for VM. (100%)
2020-02-01 20:58:18 (9664): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-02-01 22:32:35 (9664): Preference change detected
2020-02-01 22:32:35 (9664): Setting CPU throttle for VM. (100%)
2020-02-01 22:32:35 (9664): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-02-01 22:33:42 (9664): Status Report: Elapsed Time: '409445.524935'
2020-02-01 22:33:42 (9664): Status Report: CPU Time: '946.812500'
2020-02-02 00:13:48 (9664): Status Report: Elapsed Time: '415445.524935'
2020-02-02 00:13:48 (9664): Status Report: CPU Time: '962.718750'
2020-02-02 01:53:54 (9664): Status Report: Elapsed Time: '421445.524935'
2020-02-02 01:53:54 (9664): Status Report: CPU Time: '975.937500'
2020-02-02 03:34:00 (9664): Status Report: Elapsed Time: '427445.524935'
2020-02-02 03:34:00 (9664): Status Report: CPU Time: '988.953125'
2020-02-02 05:14:05 (9664): Status Report: Elapsed Time: '433445.524935'
2020-02-02 05:14:05 (9664): Status Report: CPU Time: '1002.250000'
2020-02-02 06:54:11 (9664): Status Report: Elapsed Time: '439445.524935'
2020-02-02 06:54:11 (9664): Status Report: CPU Time: '1015.078125'
2020-02-02 08:34:17 (9664): Status Report: Elapsed Time: '445445.524935'
2020-02-02 08:34:17 (9664): Status Report: CPU Time: '1027.875000'
2020-02-02 10:14:23 (9664): Status Report: Elapsed Time: '451445.524935'
2020-02-02 10:14:23 (9664): Status Report: CPU Time: '1040.125000'
2020-02-02 11:54:29 (9664): Status Report: Elapsed Time: '45744
ID: 41453 · Report as offensive     Reply Quote
broz69

Send message
Joined: 28 Nov 08
Posts: 30
Credit: 14,604,829
RAC: 17,458
Message 41454 - Posted: 3 Feb 2020, 10:04:12 UTC - in response to Message 41452.  

WU ID: 131084784
1-2-2020 22:39
Application
ATLAS Simulation 2.00 (vbox64_mt_mcore_atlas)
Name
4N8LDmi5JFwnsSi4apGgGQJmABFKDmABFKDmhOqTDmABFKDm5ix9Un
State
Running
Received
24/01/2020 23:28:14
Report deadline
31/01/2020 23:28:14
Resources
4 CPUs
Estimated computation size
43,200 GFLOPs
CPU time
00:02:25
CPU time since checkpoint
00:00:00
Elapsed time
16:10:08
Estimated time remaining
00:07:55
Fraction done
99.189%
Virtual memory size
78.23 MB
Working set size
9.96 GB
Directory
slots/14
Process ID
8436
Progress rate
6.120% per hour
Executable
vboxwrapper_26198ab7_windows_x86_64.exe

3-2-2020 09:16
Application
ATLAS Simulation 2.00 (vbox64_mt_mcore_atlas)
Name
4N8LDmi5JFwnsSi4apGgGQJmABFKDmABFKDmhOqTDmABFKDm5ix9Un
State
Running
Received
24/01/2020 23:28:14
Report deadline
31/01/2020 23:28:14
Resources
4 CPUs
Estimated computation size
43,200 GFLOPs
CPU time
00:06:50
CPU time since checkpoint
00:00:00
Elapsed time
2d 02:50:54
Estimated time remaining
00:00:00
Fraction done
100.000%
Virtual memory size
79.19 MB
Working set size
9.96 GB
Directory
slots/14
Process ID
8436
Progress rate
1.800% per hour
Executable
vboxwrapper_26198ab7_windows_x86_64.exe

stderr.txt from slots directory:
2020-01-31 23:17:56 (3532): Detected: vboxwrapper 26197
2020-01-31 23:17:56 (3532): Detected: BOINC client v7.7
2020-01-31 23:17:57 (3532): Detected: VirtualBox VboxManage Interface (Version: 6.0.8)
2020-01-31 23:17:57 (3532): Successfully copied 'init_data.xml' to the shared directory.
2020-01-31 23:17:58 (3532): Create VM. (boinc_241d70e82b605c8a, slot#14)
2020-01-31 23:17:59 (3532): Setting Memory Size for VM. (10200MB)
2020-01-31 23:17:59 (3532): Setting CPU Count for VM. (8)
2020-01-31 23:17:59 (3532): Setting Chipset Options for VM.
2020-01-31 23:18:00 (3532): Setting Boot Options for VM.
2020-01-31 23:18:00 (3532): Setting Network Configuration for NAT.
2020-01-31 23:18:00 (3532): Enabling VM Network Access.
2020-01-31 23:18:00 (3532): Disabling USB Support for VM.
2020-01-31 23:18:01 (3532): Disabling COM Port Support for VM.
2020-01-31 23:18:01 (3532): Disabling LPT Port Support for VM.
2020-01-31 23:18:01 (3532): Disabling Audio Support for VM.
2020-01-31 23:18:02 (3532): Disabling Clipboard Support for VM.
2020-01-31 23:18:02 (3532): Disabling Drag and Drop Support for VM.
2020-01-31 23:18:02 (3532): Adding storage controller(s) to VM.
2020-01-31 23:18:03 (3532): Adding virtual disk drive to VM. (vm_image.vdi)
2020-01-31 23:18:03 (3532): Adding VirtualBox Guest Additions to VM.
2020-01-31 23:18:03 (3532): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2020-01-31 23:18:04 (3532): forwarding host port 51008 to guest port 80
2020-01-31 23:18:04 (3532): Enabling remote desktop for VM.
2020-01-31 23:18:04 (3532): Enabling shared directory for VM.
2020-01-31 23:18:05 (3532): Starting VM using VBoxManage interface. (boinc_241d70e82b605c8a, slot#14)
2020-01-31 23:18:08 (3532): Successfully started VM. (PID = '4676')
2020-01-31 23:18:08 (3532): Reporting VM Process ID to BOINC.
2020-01-31 23:18:08 (3532): Guest Log: BIOS: VirtualBox 6.0.8

2020-01-31 23:18:08 (3532): Guest Log: CPUID EDX: 0x178bfbff

2020-01-31 23:18:08 (3532): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2020-01-31 23:18:08 (3532): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-01-31 23:18:08 (3532): Detected: Web Application Enabled (http://localhost:51008)
2020-01-31 23:18:08 (3532): Detected: Remote Desktop Enabled (localhost:51009)
2020-01-31 23:18:08 (3532): Preference change detected
2020-01-31 23:18:08 (3532): Setting CPU throttle for VM. (100%)
2020-01-31 23:18:08 (3532): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-01-31 23:18:10 (3532): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2020-01-31 23:18:10 (3532): Guest Log: BIOS: Booting from Hard Disk...

2020-01-31 23:18:13 (3532): Guest Log: BIOS: KBD: unsupported int 16h function 03

2020-01-31 23:18:13 (3532): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f

2020-01-31 23:18:13 (3532): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f

2020-01-31 23:18:51 (3532): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2020-01-31 23:18:51 (3532): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2020-01-31 23:18:56 (3532): Guest Log: Checking CVMFS...

2020-01-31 23:18:57 (3532): Guest Log: Failed to check CVMFS, check output from cvmfs_config probe:

2020-01-31 23:18:57 (3532): Guest Log: Probing /cvmfs/atlas.cern.ch... Failed!

2020-01-31 23:18:57 (3532): Guest Log: Probing /cvmfs/atlas-condb.cern.ch... Failed!

2020-01-31 23:18:57 (3532): Guest Log: Probing /cvmfs/grid.cern.ch... Failed!

2020-01-31 23:19:04 (3532): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log

2020-01-31 23:19:04 (3532): Guest Log: 00:00:00.000103 main Log opened 2020-01-31T23:19:03.177454000Z

2020-01-31 23:19:04 (3532): Guest Log: 00:00:00.000170 main OS Product: Linux

2020-01-31 23:19:04 (3532): Guest Log: 00:00:00.000195 main OS Release: 3.10.0-957.27.2.el7.x86_64

2020-01-31 23:19:04 (3532): Guest Log: 00:00:00.000215 main OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019

2020-01-31 23:19:04 (3532): Guest Log: 00:00:00.000234 main Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService

2020-01-31 23:19:04 (3532): Guest Log: 00:00:00.000234 main Process ID: 1884

2020-01-31 23:19:04 (3532): Guest Log: 00:00:00.000235 main Package type: LINUX_64BITS_GENERIC

2020-01-31 23:19:04 (3532): Guest Log: 00:00:00.000706 main 5.2.32 r132073 started. Verbose level = 0

2020-01-31 23:19:04 (3532): Guest Log: 00:00:00.001103 main Error: Service 'control' failed to initialize: VERR_INVALID_FUNCTION

2020-01-31 23:19:04 (3532): Guest Log: 00:00:00.001177 main Session 0 is about to close ...

2020-01-31 23:19:04 (3532): Guest Log: 00:00:00.001199 main Stopping all guest processes ...

2020-01-31 23:19:04 (3532): Guest Log: 00:00:00.001219 main Closing all guest files ...

2020-01-31 23:19:04 (3532): Guest Log: 00:00:00.001293 main Ended.

2020-02-01 00:58:12 (3532): Status Report: Elapsed Time: '6000.000000'
2020-02-01 00:58:12 (3532): Status Report: CPU Time: '30.484375'
2020-02-01 02:38:16 (3532): Status Report: Elapsed Time: '12000.000000'
2020-02-01 02:38:16 (3532): Status Report: CPU Time: '41.515625'
2020-02-01 04:18:20 (3532): Status Report: Elapsed Time: '18000.000000'
2020-02-01 04:18:20 (3532): Status Report: CPU Time: '53.140625'
2020-02-01 05:58:25 (3532): Status Report: Elapsed Time: '24000.000000'
2020-02-01 05:58:25 (3532): Status Report: CPU Time: '65.156250'
2020-02-01 07:38:29 (3532): Status Report: Elapsed Time: '30000.000000'
2020-02-01 07:38:29 (3532): Status Report: CPU Time: '77.156250'
2020-02-01 08:53:00 (3532): Stopping VM.
2020-02-01 15:50:37 (2252): Detected: vboxwrapper 26197
2020-02-01 15:50:37 (2252): Detected: BOINC client v7.7
2020-02-01 15:50:37 (2252): Detected: VirtualBox VboxManage Interface (Version: 6.0.8)
2020-02-01 15:50:38 (2252): Starting VM using VBoxManage interface. (boinc_241d70e82b605c8a, slot#14)
2020-02-01 15:50:43 (2252): Successfully started VM. (PID = '4472')
2020-02-01 15:50:43 (2252): Reporting VM Process ID to BOINC.
2020-02-01 15:50:43 (2252): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-02-01 15:50:43 (2252): Detected: Web Application Enabled (http://localhost:51008)
2020-02-01 15:50:43 (2252): Detected: Remote Desktop Enabled (localhost:51009)
2020-02-01 15:50:43 (2252): Status Report: Elapsed Time: '34468.000000'
2020-02-01 15:50:43 (2252): Status Report: CPU Time: '86.218750'
2020-02-01 15:50:43 (2252): Preference change detected
2020-02-01 15:50:43 (2252): Setting CPU throttle for VM. (100%)
2020-02-01 15:50:43 (2252): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-02-01 16:58:30 (2252): Preference change detected
2020-02-01 16:58:30 (2252): Setting CPU throttle for VM. (100%)
2020-02-01 16:58:31 (2252): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-02-01 17:30:47 (2252): Status Report: Elapsed Time: '40468.000000'
2020-02-01 17:30:47 (2252): Status Report: CPU Time: '101.078125'
2020-02-01 19:10:52 (2252): Status Report: Elapsed Time: '46468.000000'
2020-02-01 19:10:52 (2252): Status Report: CPU Time: '112.531250'
2020-02-01 20:35:23 (2252): Stopping VM.
2020-02-01 20:38:00 (6072): Detected: vboxwrapper 26197
2020-02-01 20:38:00 (6072): Detected: BOINC client v7.7
2020-02-01 20:38:01 (6072): Detected: VirtualBox VboxManage Interface (Version: 6.0.8)
2020-02-01 20:38:02 (6072): Register VM. (boinc_241d70e82b605c8a, slot#14)
2020-02-01 20:38:02 (6072): Starting VM using VBoxManage interface. (boinc_241d70e82b605c8a, slot#14)
2020-02-01 20:38:08 (6072): Successfully started VM. (PID = '8844')
2020-02-01 20:38:08 (6072): Reporting VM Process ID to BOINC.
2020-02-01 20:38:08 (6072): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-02-01 20:38:08 (6072): Detected: Web Application Enabled (http://localhost:51008)
2020-02-01 20:38:08 (6072): Detected: Remote Desktop Enabled (localhost:51009)
2020-02-01 20:38:08 (6072): Status Report: Elapsed Time: '51536.000000'
2020-02-01 20:38:08 (6072): Status Report: CPU Time: '121.937500'
2020-02-01 20:38:08 (6072): Preference change detected
2020-02-01 20:38:08 (6072): Setting CPU throttle for VM. (100%)
2020-02-01 20:38:08 (6072): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-02-01 20:41:37 (6072): Stopping VM.
2020-02-01 20:48:12 (8436): Detected: vboxwrapper 26197
2020-02-01 20:48:12 (8436): Detected: BOINC client v7.7
2020-02-01 20:48:13 (8436): Detected: VirtualBox VboxManage Interface (Version: 6.1.2)
2020-02-01 20:48:14 (8436): Starting VM using VBoxManage interface. (boinc_241d70e82b605c8a, slot#14)
2020-02-01 20:49:06 (8436): Successfully started VM. (PID = '3356')
2020-02-01 20:49:06 (8436): Reporting VM Process ID to BOINC.
2020-02-01 20:49:06 (8436): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-02-01 20:49:06 (8436): Detected: Web Application Enabled (http://localhost:51008)
2020-02-01 20:49:06 (8436): Detected: Remote Desktop Enabled (localhost:51009)
2020-02-01 20:49:06 (8436): Status Report: Elapsed Time: '51745.000000'
2020-02-01 20:49:06 (8436): Status Report: CPU Time: '126.406250'
2020-02-01 20:49:06 (8436): Preference change detected
2020-02-01 20:49:06 (8436): Setting CPU throttle for VM. (100%)
2020-02-01 20:49:06 (8436): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-02-01 20:55:55 (8436): Preference change detected
2020-02-01 20:55:55 (8436): Setting CPU throttle for VM. (100%)
2020-02-01 20:55:56 (8436): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-02-01 20:58:17 (8436): Preference change detected
2020-02-01 20:58:17 (8436): Setting CPU throttle for VM. (100%)
2020-02-01 20:58:18 (8436): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-02-01 22:29:12 (8436): Status Report: Elapsed Time: '57745.386425'
2020-02-01 22:29:12 (8436): Status Report: CPU Time: '143.906250'
2020-02-01 22:32:35 (8436): Preference change detected
2020-02-01 22:32:35 (8436): Setting CPU throttle for VM. (100%)
2020-02-01 22:32:35 (8436): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2020-02-02 00:09:17 (8436): Status Report: Elapsed Time: '63745.386425'
2020-02-02 00:09:17 (8436): Status Report: CPU Time: '159.593750'
2020-02-02 01:49:23 (8436): Status Report: Elapsed Time: '69745.386425'
2020-02-02 01:49:23 (8436): Status Report: CPU Time: '172.765625'
2020-02-02 03:29:29 (8436): Status Report: Elapsed Time: '75745.386425'
2020-02-02 03:29:29 (8436): Status Report: CPU Time: '184.875000'
2020-02-02 05:09:35 (8436): Status Report: Elapsed Time: '81745.386425'
2020-02-02 05:09:35 (8436): Status Report: CPU Time: '197.093750'
2020-02-02 06:49:41 (8436): Status Report: Elapsed Time: '87745.386425'
2020-02-02 06:49:41 (8436): Status Report: CPU Time: '210.328125'
2020-02-02 08:29:47 (8436): Status Report: Elapsed Time: '93745.386425'
2020-02-02 08:29:47 (8436): Status Report: CPU Time: '223.359375'
2020-02-02 10:09:53 (8436): Status Report: Elapsed Time: '99745.386425'
2020-02-02 10:09:53 (8436): Status Report: CPU Time: '236.531250'
2020-02-02 11:49:59 (8436): Status Report: Elapsed Time: '105745.386425'
2020-02-02 11:49:59 (8436): Status Report: CPU Time: '248.781250'
2020-02-02 13:30:04 (8436): Status Report: Elapsed Time: '111745.386425'
2020-02-02 13:30:04 (8436): Status Report: CPU Time: '261.937500'
2020-02-02 15:10:10 (8436): Status Report: Elapsed Time: '117745.386425'
2020-02-02 15:10:10 (8436): Status Report: CPU Time: '274.078125'
2020-02-02 16:50:15 (8436): Status Report: Elapsed Time: '123745.386425'
2020-02-02 16:50:15 (8436): Status Report: CPU Time: '286.578125'
2020-02-02 18:30:21 (8436): Status Report: Elapsed Time: '129745.386425'
2020-02-02 18:30:21 (8436): Status Report: CPU Time: '299.484375'
2020-02-02 20:10:27 (8436): Status Report: Elapsed Time: '135745.386425'
2020-02-02 20:10:27 (8436): Status Report: CPU Time: '311.578125'
2020-02-02 21:50:33 (8436): Status Report: Elapsed Time: '141745.386425'
2020-02-02 21:50:33 (8436): Status Report: CPU Time: '323.671875'
2020-02-02 23:30:39 (8436): Status Report: Elapsed Time: '147745.386425'
2020-02-02 23:30:39 (8436): Status Report: CPU Time: '336.062500'
2020-02-03 01:10:45 (8436): Status Report: Elapsed Time: '153745.386425'
2020-02-03 01:10:45 (8436): Status Report: CPU Time: '348.906250'
2020-02-03 02:50:51 (8436): Status Report: Elapsed Time: '159745.386425'
2020-02-03 02:50:51 (8436): Status Report: CPU Time: '361.421875'
2020-02-03 04:30:57 (8436): Status Report: Elapsed Time: '165745.386425'
2020-02-03 04:30:57 (8436): Status Report: CPU Time: '374.718750'
2020-02-03 06:11:03 (8436): Status Report: Elapsed Time: '171745.386425'
2020-02-03 06:11:03 (8436): Status Report: CPU Time: '387.343750'
2020-02-03 07:51:08 (8436): Status Report: Elapsed Time: '177745.386425'
2020-02-03 07:51:08 (8436): Status Report: CPU Time: '399.937500'
2020-02-03 09:31:14 (8436): Status Report: Elapsed Time: '183745.386425'
2020-02-03 09:31:14 (8436): Status Report: CPU Time: '412.750000'
2020-02-03 09:56:04 (8436): Stopping VM.
2020-02-03 09:57:56 (1188): Detected: vboxwrapper 26197
2020-02-03 09:57:56 (1188): Detected: BOINC client v7.7
2020-02-03 09:57:57 (1188): Detected: VirtualBox VboxManage Interface (Version: 6.1.2)
2020-02-03 09:57:57 (1188): Starting VM using VBoxManage interface. (boinc_241d70e82b605c8a, slot#14)
2020-02-03 09:58:03 (1188): Successfully started VM. (PID = '10104')
2020-02-03 09:58:03 (1188): Reporting VM Process ID to BOINC.
2020-02-03 09:58:03 (1188): VM state change detected. (old = 'PoweredOff', new = 'Running')
2020-02-03 09:58:03 (1188): Detected: Web Application Enabled (http://localhost:51008)
2020-02-03 09:58:03 (1188): Detected: Remote Desktop Enabled (localhost:51009)
2020-02-03 09:58:03 (1188): Status Report: Elapsed Time: '185233.386425'
2020-02-03 09:58:03 (1188): Status Report: CPU Time: '415.796875'
2020-02-03 09:58:03 (1188): Preference change detected
2020-02-03 09:58:03 (1188): Setting CPU throttle for VM. (100%)
2020-02-03 09:58:03 (1188): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
ID: 41454 · Report as offensive     Reply Quote
broz69

Send message
Joined: 28 Nov 08
Posts: 30
Credit: 14,604,829
RAC: 17,458
Message 41455 - Posted: 3 Feb 2020, 10:07:06 UTC - in response to Message 41452.  

BOINC event log:
01/02/2020 20:48:11 | | Starting BOINC client version 7.14.2 for windows_x86_64
01/02/2020 20:48:11 | | log flags: file_xfer, sched_ops, task
01/02/2020 20:48:11 | | Libraries: libcurl/7.47.1 OpenSSL/1.0.2g zlib/1.2.8
01/02/2020 20:48:11 | | Data directory: F:\var\BOINC
01/02/2020 20:48:11 | | OpenCL: AMD/ATI GPU 0: AMD Radeon (TM) R9 390 Series (driver version 2841.19, device version OpenCL 2.0 AMD-APP (2841.19), 8192MB, 8192MB available, 5970 GFLOPS peak)
01/02/2020 20:48:11 | | Processor: 16 AuthenticAMD AMD Ryzen 7 2700X Eight-Core Processor [Family 23 Model 8 Stepping 2]
01/02/2020 20:48:11 | | Processor features: fpu vme de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 htt pni ssse3 fma cx16 sse4_1 sse4_2 movebe popcnt aes f16c rdrandsyscall nx lm avx avx2 svm sse4a osvw skinit wdt tce topx page1gb rdtscp fsgsbase bmi1 smep
01/02/2020 20:48:11 | | OS: Microsoft Windows 10: Professional x64 Edition, (10.00.18362.00)
01/02/2020 20:48:11 | | Memory: 63.95 GB physical, 72.93 GB virtual
01/02/2020 20:48:11 | | Disk: 698.63 GB total, 228.35 GB free
01/02/2020 20:48:11 | | Local time is UTC +1 hours
01/02/2020 20:48:11 | | No WSL found.
01/02/2020 20:48:11 | | VirtualBox version: 6.1.2
01/02/2020 20:48:11 | LHC@home | Found app_config.xml
01/02/2020 20:48:11 | | Config: GUI RPCs allowed from:
01/02/2020 20:48:11 | | Config: don't use GPUs while Origin.exe is running
01/02/2020 20:48:11 | | Config: don't use GPUs while PerformanceTest64.exe is running
01/02/2020 20:48:11 | | Config: don't use GPUs while Steam.exe is running
01/02/2020 20:48:11 | | Config: don't use GPUs while upc.exe is running
01/02/2020 20:48:11 | LHC@home | URL https://lhcathome.cern.ch/lhcathome/; Computer ID 10570926; resource share 400
01/02/2020 20:48:11 | Milkyway@Home | URL http://milkyway.cs.rpi.edu/milkyway/; Computer ID 788453; resource share 100
01/02/2020 20:48:11 | SETI@home | URL http://setiathome.berkeley.edu/; Computer ID 8610505; resource share 300
01/02/2020 20:48:11 | SETI@home | General prefs: from SETI@home (last modified 24-May-2019 10:53:38)
01/02/2020 20:48:11 | SETI@home | Host location: none
01/02/2020 20:48:11 | SETI@home | General prefs: using your defaults
01/02/2020 20:48:11 | | Reading preferences override file
01/02/2020 20:48:11 | | Preferences:
01/02/2020 20:48:11 | | max memory usage when active: 32741.81 MB
01/02/2020 20:48:11 | | max memory usage when idle: 49112.71 MB
01/02/2020 20:48:11 | | max disk usage: 253.60 GB
01/02/2020 20:48:11 | | max CPUs used: 8
01/02/2020 20:48:11 | | suspend work if non-BOINC CPU load exceeds 49%
01/02/2020 20:48:11 | | (to change preferences, visit a project web site or select Preferences in the Manager)
01/02/2020 20:48:11 | | Setting up project and slot directories
01/02/2020 20:48:11 | | Checking active tasks
01/02/2020 20:48:11 | LHC@home | Task Theory_2363-877514-16_0 is 2.21 days overdue; you may not get credit for it. Consider aborting it.
01/02/2020 20:48:11 | LHC@home | Task Theory_2363-929450-16_0 is 2.21 days overdue; you may not get credit for it. Consider aborting it.
01/02/2020 20:48:11 | LHC@home | Task Theory_2363-877881-16_0 is 2.20 days overdue; you may not get credit for it. Consider aborting it.
01/02/2020 20:48:11 | LHC@home | Task Theory_2363-912279-16_0 is 2.18 days overdue; you may not get credit for it. Consider aborting it.
01/02/2020 20:48:11 | LHC@home | Task Theory_2363-888863-16_0 is 2.18 days overdue; you may not get credit for it. Consider aborting it.
01/02/2020 20:48:11 | LHC@home | Task aCbLDm850Dwn9Rq4apoT9bVoABFKDmABFKDmLD8WDmABFKDmC74yGn_2 is 4.14 days overdue; you may not get credit for it. Consider aborting it.
01/02/2020 20:48:11 | LHC@home | Task 4N8LDmi5JFwnsSi4apGgGQJmABFKDmABFKDmhOqTDmABFKDm5ix9Un_0 is 0.89 days overdue; you may not get credit for it. Consider aborting it.
01/02/2020 20:48:11 | LHC@home | Task MmhNDm9CKFwnsSi4apGgGQJmABFKDmABFKDmR1zTDmABFKDm9fdQpm_0 is 0.89 days overdue; you may not get credit for it. Consider aborting it.
01/02/2020 20:48:11 | | Setting up GUI RPC socket
01/02/2020 20:48:11 | | Checking presence of 259 project files
01/02/2020 20:48:42 | SETI@home | task 23ap08ae.27865.15201.8.35.245_1 suspended by user
01/02/2020 20:48:42 | SETI@home | task 23ap08ae.13647.13156.9.36.67_1 suspended by user
01/02/2020 20:48:42 | SETI@home | task 23jl08aa.19969.2526.11.38.234_0 suspended by user
01/02/2020 20:48:42 | SETI@home | task blc35_2bit_guppi_58691_85456_HIP79991_0109.18867.409.21.44.39.vlar_2 suspended by user
01/02/2020 20:48:42 | SETI@home | task 23jl08aa.19317.25021.8.35.0_1 suspended by user
01/02/2020 20:49:14 | LHC@home | task aCbLDm850Dwn9Rq4apoT9bVoABFKDmABFKDmLD8WDmABFKDmC74yGn_2 resumed by user
01/02/2020 20:51:23 | LHC@home | Sending scheduler request: Requested by project.
01/02/2020 20:51:23 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 20:51:25 | LHC@home | Scheduler request completed
01/02/2020 20:53:27 | LHC@home | Computation for task Theory_2363-929450-16_0 finished
01/02/2020 20:53:29 | LHC@home | Started upload of Theory_2363-929450-16_0_r691766450_result
01/02/2020 20:53:32 | LHC@home | Finished upload of Theory_2363-929450-16_0_r691766450_result
01/02/2020 20:53:35 | LHC@home | Sending scheduler request: To report completed tasks.
01/02/2020 20:53:35 | LHC@home | Reporting 1 completed tasks
01/02/2020 20:53:35 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 20:53:37 | LHC@home | Scheduler request completed
01/02/2020 20:55:55 | SETI@home | General prefs: from SETI@home (last modified 24-May-2019 10:53:38)
01/02/2020 20:55:55 | SETI@home | Host location: none
01/02/2020 20:55:55 | SETI@home | General prefs: using your defaults
01/02/2020 20:55:55 | | Reading preferences override file
01/02/2020 20:55:55 | | Preferences:
01/02/2020 20:55:55 | | max memory usage when active: 32741.81 MB
01/02/2020 20:55:55 | | max memory usage when idle: 49112.71 MB
01/02/2020 20:55:55 | | max disk usage: 254.40 GB
01/02/2020 20:55:55 | | Number of usable CPUs has changed from 8 to 10.
01/02/2020 20:55:55 | | max CPUs used: 10
01/02/2020 20:55:55 | | suspend work if non-BOINC CPU load exceeds 49%
01/02/2020 20:55:55 | | (to change preferences, visit a project web site or select Preferences in the Manager)
01/02/2020 20:58:17 | SETI@home | General prefs: from SETI@home (last modified 24-May-2019 10:53:38)
01/02/2020 20:58:17 | SETI@home | Host location: none
01/02/2020 20:58:17 | SETI@home | General prefs: using your defaults
01/02/2020 20:58:17 | | Reading preferences override file
01/02/2020 20:58:17 | | Preferences:
01/02/2020 20:58:17 | | max memory usage when active: 32741.81 MB
01/02/2020 20:58:17 | | max memory usage when idle: 49112.71 MB
01/02/2020 20:58:17 | | max disk usage: 253.87 GB
01/02/2020 20:58:17 | | Number of usable CPUs has changed from 10 to 16.
01/02/2020 20:58:17 | | suspend work if non-BOINC CPU load exceeds 49%
01/02/2020 20:58:17 | | (to change preferences, visit a project web site or select Preferences in the Manager)
01/02/2020 21:23:11 | LHC@home | Computation for task Theory_2363-888863-16_0 finished
01/02/2020 21:23:13 | LHC@home | Started upload of Theory_2363-888863-16_0_r1270884496_result
01/02/2020 21:23:16 | LHC@home | Finished upload of Theory_2363-888863-16_0_r1270884496_result
01/02/2020 21:23:19 | LHC@home | Sending scheduler request: To report completed tasks.
01/02/2020 21:23:19 | LHC@home | Reporting 1 completed tasks
01/02/2020 21:23:19 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 21:23:21 | LHC@home | Scheduler request completed
01/02/2020 21:35:50 | LHC@home | Computation for task Theory_2363-912279-16_0 finished
01/02/2020 21:35:52 | LHC@home | Started upload of Theory_2363-912279-16_0_r955799384_result
01/02/2020 21:35:55 | LHC@home | Finished upload of Theory_2363-912279-16_0_r955799384_result
01/02/2020 21:35:58 | LHC@home | Sending scheduler request: To report completed tasks.
01/02/2020 21:35:58 | LHC@home | Reporting 1 completed tasks
01/02/2020 21:35:58 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 21:36:00 | LHC@home | Scheduler request completed
01/02/2020 21:36:47 | SETI@home | Computation for task blc61_2bit_guppi_58692_58812_HIP21705_0023.29308.818.21.44.145.vlar_1 finished
01/02/2020 21:36:49 | SETI@home | Started upload of blc61_2bit_guppi_58692_58812_HIP21705_0023.29308.818.21.44.145.vlar_1_r32682124_0
01/02/2020 21:36:53 | SETI@home | Finished upload of blc61_2bit_guppi_58692_58812_HIP21705_0023.29308.818.21.44.145.vlar_1_r32682124_0
01/02/2020 21:36:55 | SETI@home | Sending scheduler request: To report completed tasks.
01/02/2020 21:36:55 | SETI@home | Reporting 1 completed tasks
01/02/2020 21:36:55 | SETI@home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 21:36:57 | SETI@home | Scheduler request completed
01/02/2020 21:40:55 | SETI@home | Computation for task blc61_2bit_guppi_58692_58812_HIP21705_0023.29308.818.21.44.140.vlar_1 finished
01/02/2020 21:40:57 | SETI@home | Started upload of blc61_2bit_guppi_58692_58812_HIP21705_0023.29308.818.21.44.140.vlar_1_r1057949798_0
01/02/2020 21:40:59 | SETI@home | Finished upload of blc61_2bit_guppi_58692_58812_HIP21705_0023.29308.818.21.44.140.vlar_1_r1057949798_0
01/02/2020 21:42:03 | SETI@home | Sending scheduler request: To report completed tasks.
01/02/2020 21:42:03 | SETI@home | Reporting 1 completed tasks
01/02/2020 21:42:03 | SETI@home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 21:42:04 | SETI@home | Scheduler request completed
01/02/2020 21:42:26 | SETI@home | Computation for task blc61_2bit_guppi_58692_58812_HIP21705_0023.29312.818.22.45.151.vlar_1 finished
01/02/2020 21:42:28 | SETI@home | Started upload of blc61_2bit_guppi_58692_58812_HIP21705_0023.29312.818.22.45.151.vlar_1_r2090817839_0
01/02/2020 21:42:32 | SETI@home | Finished upload of blc61_2bit_guppi_58692_58812_HIP21705_0023.29312.818.22.45.151.vlar_1_r2090817839_0
01/02/2020 21:47:10 | SETI@home | Sending scheduler request: To report completed tasks.
01/02/2020 21:47:10 | SETI@home | Reporting 1 completed tasks
01/02/2020 21:47:10 | SETI@home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 21:47:12 | SETI@home | Scheduler request completed
01/02/2020 21:53:42 | LHC@home | Computation for task Theory_2363-877881-16_0 finished
01/02/2020 21:53:44 | LHC@home | Started upload of Theory_2363-877881-16_0_r179002926_result
01/02/2020 21:53:47 | LHC@home | Finished upload of Theory_2363-877881-16_0_r179002926_result
01/02/2020 21:53:48 | LHC@home | Sending scheduler request: To report completed tasks.
01/02/2020 21:53:48 | LHC@home | Reporting 1 completed tasks
01/02/2020 21:53:48 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 21:53:50 | LHC@home | Scheduler request completed
01/02/2020 22:03:56 | SETI@home | Computation for task blc61_2bit_guppi_58692_58812_HIP21705_0023.29312.818.22.45.148.vlar_1 finished
01/02/2020 22:03:58 | SETI@home | Started upload of blc61_2bit_guppi_58692_58812_HIP21705_0023.29312.818.22.45.148.vlar_1_r276114749_0
01/02/2020 22:04:01 | SETI@home | Finished upload of blc61_2bit_guppi_58692_58812_HIP21705_0023.29312.818.22.45.148.vlar_1_r276114749_0
01/02/2020 22:04:01 | SETI@home | Sending scheduler request: To report completed tasks.
01/02/2020 22:04:01 | SETI@home | Reporting 1 completed tasks
01/02/2020 22:04:01 | SETI@home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 22:04:03 | SETI@home | Scheduler request completed
01/02/2020 22:22:56 | LHC@home | Computation for task Theory_2363-877514-16_0 finished
01/02/2020 22:22:58 | LHC@home | Started upload of Theory_2363-877514-16_0_r1511274365_result
01/02/2020 22:23:02 | LHC@home | Finished upload of Theory_2363-877514-16_0_r1511274365_result
01/02/2020 22:23:04 | LHC@home | Sending scheduler request: To report completed tasks.
01/02/2020 22:23:04 | LHC@home | Reporting 1 completed tasks
01/02/2020 22:23:04 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 22:23:06 | LHC@home | Scheduler request completed
01/02/2020 22:23:46 | SETI@home | Computation for task 23ap08ae.27370.13156.7.34.160_1 finished
01/02/2020 22:23:48 | SETI@home | Started upload of 23ap08ae.27370.13156.7.34.160_1_r1156124_0
01/02/2020 22:23:52 | SETI@home | Finished upload of 23ap08ae.27370.13156.7.34.160_1_r1156124_0
01/02/2020 22:23:52 | SETI@home | Sending scheduler request: To report completed tasks.
01/02/2020 22:23:52 | SETI@home | Reporting 1 completed tasks
01/02/2020 22:23:52 | SETI@home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 22:23:54 | SETI@home | Scheduler request completed
01/02/2020 22:34:03 | Milkyway@Home | task de_modfit_83_bundle4_4s_south4s_bgset_2_1580162702_2471544_1 resumed by user
01/02/2020 22:34:03 | Milkyway@Home | task de_modfit_14_bundle4_testing_3s4f_3_1580162702_2478729_0 resumed by user
01/02/2020 22:34:03 | Milkyway@Home | task de_modfit_14_bundle5_testing_4s3f_1_1580162702_2471635_1 resumed by user
01/02/2020 22:34:03 | SETI@home | task 23ap08ae.27865.15201.8.35.245_1 resumed by user
01/02/2020 22:34:03 | SETI@home | task 23ap08ae.13647.13156.9.36.67_1 resumed by user
01/02/2020 22:34:03 | SETI@home | task 23jl08aa.19969.2526.11.38.234_0 resumed by user
01/02/2020 22:34:03 | SETI@home | task blc35_2bit_guppi_58691_85456_HIP79991_0109.18867.409.21.44.39.vlar_2 resumed by user
01/02/2020 22:34:03 | SETI@home | task 23jl08aa.19317.25021.8.35.0_1 resumed by user
01/02/2020 22:34:03 | SETI@home | Starting task 23ap08ae.27865.15201.8.35.245_1
01/02/2020 22:34:03 | SETI@home | Starting task 23ap08ae.13647.13156.9.36.67_1
01/02/2020 22:34:04 | SETI@home | Starting task 23jl08aa.19969.2526.11.38.234_0
01/02/2020 22:34:04 | SETI@home | Starting task blc35_2bit_guppi_58691_85456_HIP79991_0109.18867.409.21.44.39.vlar_2
01/02/2020 22:34:04 | SETI@home | Starting task 23jl08aa.19317.25021.8.35.0_1
01/02/2020 22:34:04 | Milkyway@Home | Starting task de_modfit_83_bundle4_4s_south4s_bgset_2_1580162702_2471544_1
01/02/2020 22:34:04 | Milkyway@Home | Starting task de_modfit_14_bundle4_testing_3s4f_3_1580162702_2478729_0
01/02/2020 22:34:04 | Milkyway@Home | Starting task de_modfit_14_bundle5_testing_4s3f_1_1580162702_2471635_1
01/02/2020 22:34:23 | SETI@home | Computation for task 23jl08aa.19317.25021.8.35.0_1 finished
01/02/2020 22:34:25 | SETI@home | Started upload of 23jl08aa.19317.25021.8.35.0_1_r1977650626_0
01/02/2020 22:34:27 | SETI@home | Finished upload of 23jl08aa.19317.25021.8.35.0_1_r1977650626_0
01/02/2020 22:34:30 | SETI@home | Sending scheduler request: To report completed tasks.
01/02/2020 22:34:30 | SETI@home | Reporting 1 completed tasks
01/02/2020 22:34:30 | SETI@home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 22:34:32 | SETI@home | Scheduler request completed
01/02/2020 22:38:01 | LHC@home | task Theory_2363-928580-20_0 resumed by user
01/02/2020 22:38:02 | LHC@home | Starting task Theory_2363-928580-20_0
01/02/2020 23:12:03 | SETI@home | Computation for task blc35_2bit_guppi_58691_85456_HIP79991_0109.18867.409.21.44.39.vlar_2 finished
01/02/2020 23:12:05 | SETI@home | Started upload of blc35_2bit_guppi_58691_85456_HIP79991_0109.18867.409.21.44.39.vlar_2_r2031750402_0
01/02/2020 23:12:08 | SETI@home | Finished upload of blc35_2bit_guppi_58691_85456_HIP79991_0109.18867.409.21.44.39.vlar_2_r2031750402_0
01/02/2020 23:12:12 | SETI@home | Sending scheduler request: To report completed tasks.
01/02/2020 23:12:12 | SETI@home | Reporting 1 completed tasks
01/02/2020 23:12:12 | SETI@home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 23:12:14 | SETI@home | Scheduler request completed
01/02/2020 23:12:17 | LHC@home | task Theory_2363-925569-20_0 resumed by user
01/02/2020 23:12:18 | LHC@home | Starting task Theory_2363-925569-20_0
01/02/2020 23:19:36 | Milkyway@Home | Computation for task de_modfit_14_bundle4_testing_3s4f_3_1580162702_2478729_0 finished
01/02/2020 23:19:38 | Milkyway@Home | Computation for task de_modfit_83_bundle4_4s_south4s_bgset_2_1580162702_2471544_1 finished
01/02/2020 23:19:41 | Milkyway@Home | Sending scheduler request: To report completed tasks.
01/02/2020 23:19:41 | Milkyway@Home | Reporting 2 completed tasks
01/02/2020 23:19:41 | Milkyway@Home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 23:19:43 | Milkyway@Home | Scheduler request completed
01/02/2020 23:20:52 | LHC@home | task Theory_2363-934468-16_1 resumed by user
01/02/2020 23:20:53 | LHC@home | Starting task Theory_2363-934468-16_1
01/02/2020 23:21:35 | LHC@home | task Theory_2363-938541-20_1 resumed by user
01/02/2020 23:21:36 | LHC@home | Starting task Theory_2363-938541-20_1
01/02/2020 23:22:26 | Milkyway@Home | Computation for task de_modfit_14_bundle5_testing_4s3f_1_1580162702_2471635_1 finished
01/02/2020 23:22:28 | Milkyway@Home | Sending scheduler request: To report completed tasks.
01/02/2020 23:22:28 | Milkyway@Home | Reporting 1 completed tasks
01/02/2020 23:22:28 | Milkyway@Home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 23:22:30 | Milkyway@Home | Scheduler request completed
01/02/2020 23:24:39 | LHC@home | task Theory_2363-898535-20_1 resumed by user
01/02/2020 23:24:40 | LHC@home | Starting task Theory_2363-898535-20_1
01/02/2020 23:25:36 | LHC@home | task MmhNDm9CKFwnsSi4apGgGQJmABFKDmABFKDmR1zTDmABFKDm9fdQpm_0 aborted by user
01/02/2020 23:25:36 | LHC@home | Computation for task MmhNDm9CKFwnsSi4apGgGQJmABFKDmABFKDmR1zTDmABFKDm9fdQpm_0 finished
01/02/2020 23:26:57 | LHC@home | Sending scheduler request: To report completed tasks.
01/02/2020 23:26:57 | LHC@home | Reporting 1 completed tasks
01/02/2020 23:26:57 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 23:26:59 | LHC@home | Scheduler request completed
01/02/2020 23:27:14 | LHC@home | task Theory_2363-933848-20_0 resumed by user
01/02/2020 23:28:18 | LHC@home | task Theory_2363-873955-18_2 resumed by user
01/02/2020 23:28:21 | LHC@home | task Theory_2363-935645-20_0 resumed by user
01/02/2020 23:28:24 | LHC@home | task Theory_2363-923874-20_1 resumed by user
01/02/2020 23:28:28 | LHC@home | task Theory_2363-924854-20_0 resumed by user
01/02/2020 23:28:31 | LHC@home | task Theory_2363-903403-20_0 resumed by user
01/02/2020 23:29:21 | LHC@home | Computation for task Theory_2363-928580-20_0 finished
01/02/2020 23:29:21 | LHC@home | Starting task Theory_2363-933848-20_0
01/02/2020 23:29:23 | LHC@home | Started upload of Theory_2363-928580-20_0_r1040240675_result
01/02/2020 23:29:26 | LHC@home | Finished upload of Theory_2363-928580-20_0_r1040240675_result
01/02/2020 23:29:30 | LHC@home | Sending scheduler request: To report completed tasks.
01/02/2020 23:29:30 | LHC@home | Reporting 1 completed tasks
01/02/2020 23:29:30 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 23:29:32 | LHC@home | Scheduler request completed
01/02/2020 23:30:35 | LHC@home | Computation for task Theory_2363-925569-20_0 finished
01/02/2020 23:30:35 | LHC@home | Starting task Theory_2363-873955-18_2
01/02/2020 23:30:37 | LHC@home | Started upload of Theory_2363-925569-20_0_r1051028521_result
01/02/2020 23:30:40 | LHC@home | Finished upload of Theory_2363-925569-20_0_r1051028521_result
01/02/2020 23:30:44 | LHC@home | Sending scheduler request: To report completed tasks.
01/02/2020 23:30:44 | LHC@home | Reporting 1 completed tasks
01/02/2020 23:30:44 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 23:30:48 | LHC@home | Scheduler request completed
01/02/2020 23:56:20 | SETI@home | Computation for task 23ap08ae.13647.13156.9.36.67_1 finished
01/02/2020 23:56:20 | LHC@home | Starting task Theory_2363-935645-20_0
01/02/2020 23:56:22 | SETI@home | Started upload of 23ap08ae.13647.13156.9.36.67_1_r302429995_0
01/02/2020 23:56:26 | SETI@home | Finished upload of 23ap08ae.13647.13156.9.36.67_1_r302429995_0
01/02/2020 23:56:31 | SETI@home | Sending scheduler request: To report completed tasks.
01/02/2020 23:56:31 | SETI@home | Reporting 1 completed tasks
01/02/2020 23:56:31 | SETI@home | Not requesting tasks: "no new tasks" requested via Manager
01/02/2020 23:56:33 | SETI@home | Scheduler request completed
02/02/2020 00:02:21 | SETI@home | Computation for task 23ap08ae.27865.15201.8.35.245_1 finished
02/02/2020 00:02:21 | LHC@home | Starting task Theory_2363-923874-20_1
02/02/2020 00:02:23 | SETI@home | Started upload of 23ap08ae.27865.15201.8.35.245_1_r817039278_0
02/02/2020 00:02:26 | SETI@home | Finished upload of 23ap08ae.27865.15201.8.35.245_1_r817039278_0
02/02/2020 00:02:29 | SETI@home | Sending scheduler request: To report completed tasks.
02/02/2020 00:02:29 | SETI@home | Reporting 1 completed tasks
02/02/2020 00:02:29 | SETI@home | Not requesting tasks: "no new tasks" requested via Manager
02/02/2020 00:02:32 | SETI@home | Scheduler request completed
02/02/2020 00:04:01 | LHC@home | Computation for task Theory_2363-935645-20_0 finished
02/02/2020 00:04:01 | LHC@home | Starting task Theory_2363-924854-20_0
02/02/2020 00:04:03 | LHC@home | Started upload of Theory_2363-935645-20_0_r1502588198_result
02/02/2020 00:04:06 | LHC@home | Finished upload of Theory_2363-935645-20_0_r1502588198_result
02/02/2020 00:04:07 | LHC@home | Sending scheduler request: To report completed tasks.
02/02/2020 00:04:07 | LHC@home | Reporting 1 completed tasks
02/02/2020 00:04:07 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
02/02/2020 00:04:10 | LHC@home | Scheduler request completed
02/02/2020 00:04:17 | SETI@home | Computation for task 23jl08aa.19969.2526.11.38.234_0 finished
02/02/2020 00:04:17 | LHC@home | Starting task Theory_2363-903403-20_0
02/02/2020 00:04:20 | SETI@home | Started upload of 23jl08aa.19969.2526.11.38.234_0_r1491268335_0
02/02/2020 00:04:24 | SETI@home | Finished upload of 23jl08aa.19969.2526.11.38.234_0_r1491268335_0
02/02/2020 00:07:36 | SETI@home | Sending scheduler request: To report completed tasks.
02/02/2020 00:07:36 | SETI@home | Reporting 1 completed tasks
02/02/2020 00:07:36 | SETI@home | Not requesting tasks: "no new tasks" requested via Manager
02/02/2020 00:07:38 | SETI@home | Scheduler request completed
02/02/2020 00:18:18 | LHC@home | Computation for task Theory_2363-924854-20_0 finished
02/02/2020 00:18:20 | LHC@home | Started upload of Theory_2363-924854-20_0_r1816741428_result
02/02/2020 00:18:23 | LHC@home | Finished upload of Theory_2363-924854-20_0_r1816741428_result
02/02/2020 00:18:24 | LHC@home | Sending scheduler request: To report completed tasks.
02/02/2020 00:18:24 | LHC@home | Reporting 1 completed tasks
02/02/2020 00:18:24 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
02/02/2020 00:18:26 | LHC@home | Scheduler request completed
02/02/2020 00:27:54 | LHC@home | Computation for task Theory_2363-923874-20_1 finished
02/02/2020 00:27:57 | LHC@home | Started upload of Theory_2363-923874-20_1_r1749176074_result
02/02/2020 00:28:02 | LHC@home | Finished upload of Theory_2363-923874-20_1_r1749176074_result
02/02/2020 00:28:02 | LHC@home | Sending scheduler request: To report completed tasks.
02/02/2020 00:28:02 | LHC@home | Reporting 1 completed tasks
02/02/2020 00:28:02 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
02/02/2020 00:28:04 | LHC@home | Scheduler request completed
02/02/2020 00:34:30 | LHC@home | Computation for task Theory_2363-933848-20_0 finished
02/02/2020 00:34:32 | LHC@home | Started upload of Theory_2363-933848-20_0_r1180716501_result
02/02/2020 00:34:35 | LHC@home | Finished upload of Theory_2363-933848-20_0_r1180716501_result
02/02/2020 00:34:39 | LHC@home | Sending scheduler request: To report completed tasks.
02/02/2020 00:34:39 | LHC@home | Reporting 1 completed tasks
02/02/2020 00:34:39 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
02/02/2020 00:34:41 | LHC@home | Scheduler request completed
02/02/2020 00:38:18 | LHC@home | Computation for task Theory_2363-898535-20_1 finished
02/02/2020 00:38:20 | LHC@home | Started upload of Theory_2363-898535-20_1_r1088673743_result
02/02/2020 00:38:23 | LHC@home | Finished upload of Theory_2363-898535-20_1_r1088673743_result
02/02/2020 00:38:27 | LHC@home | Sending scheduler request: To report completed tasks.
02/02/2020 00:38:27 | LHC@home | Reporting 1 completed tasks
02/02/2020 00:38:27 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
02/02/2020 00:38:29 | LHC@home | Scheduler request completed
02/02/2020 01:22:50 | LHC@home | Computation for task Theory_2363-903403-20_0 finished
02/02/2020 01:22:52 | LHC@home | Started upload of Theory_2363-903403-20_0_r1081851329_result
02/02/2020 01:22:55 | LHC@home | Finished upload of Theory_2363-903403-20_0_r1081851329_result
02/02/2020 01:22:56 | LHC@home | Sending scheduler request: To report completed tasks.
02/02/2020 01:22:56 | LHC@home | Reporting 1 completed tasks
02/02/2020 01:22:56 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
02/02/2020 01:22:58 | LHC@home | Scheduler request completed
02/02/2020 02:11:45 | LHC@home | Computation for task Theory_2363-938541-20_1 finished
02/02/2020 02:11:47 | LHC@home | Started upload of Theory_2363-938541-20_1_r1093400431_result
02/02/2020 02:11:50 | LHC@home | Finished upload of Theory_2363-938541-20_1_r1093400431_result
02/02/2020 02:11:50 | LHC@home | Sending scheduler request: To report completed tasks.
02/02/2020 02:11:50 | LHC@home | Reporting 1 completed tasks
02/02/2020 02:11:50 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
02/02/2020 02:11:52 | LHC@home | Scheduler request completed
02/02/2020 02:58:52 | LHC@home | Computation for task Theory_2363-934468-16_1 finished
02/02/2020 02:58:54 | LHC@home | Started upload of Theory_2363-934468-16_1_r314453649_result
02/02/2020 02:58:57 | LHC@home | Finished upload of Theory_2363-934468-16_1_r314453649_result
02/02/2020 02:58:59 | LHC@home | Sending scheduler request: To report completed tasks.
02/02/2020 02:58:59 | LHC@home | Reporting 1 completed tasks
02/02/2020 02:58:59 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
02/02/2020 02:59:01 | LHC@home | Scheduler request completed
02/02/2020 07:59:05 | LHC@home | Sending scheduler request: Requested by project.
02/02/2020 07:59:05 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
02/02/2020 07:59:08 | LHC@home | Scheduler request completed
02/02/2020 12:59:08 | LHC@home | Sending scheduler request: Requested by project.
02/02/2020 12:59:08 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
02/02/2020 12:59:10 | LHC@home | Scheduler request completed
02/02/2020 17:59:14 | LHC@home | Sending scheduler request: Requested by project.
02/02/2020 17:59:14 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
02/02/2020 17:59:16 | LHC@home | Scheduler request completed
02/02/2020 22:59:21 | LHC@home | Sending scheduler request: Requested by project.
02/02/2020 22:59:21 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
02/02/2020 22:59:23 | LHC@home | Scheduler request completed
03/02/2020 03:59:27 | LHC@home | Sending scheduler request: Requested by project.
03/02/2020 03:59:27 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
03/02/2020 03:59:29 | LHC@home | Scheduler request completed
03/02/2020 08:59:33 | LHC@home | Sending scheduler request: Requested by project.
03/02/2020 08:59:33 | LHC@home | Not requesting tasks: "no new tasks" requested via Manager
03/02/2020 08:59:35 | LHC@home | Scheduler request completed
ID: 41455 · Report as offensive     Reply Quote
computezrmle
Volunteer moderator
Volunteer developer
Volunteer tester
Help desk expert
Avatar

Send message
Joined: 15 Jun 08
Posts: 2386
Credit: 222,903,594
RAC: 137,988
Message 41456 - Posted: 3 Feb 2020, 10:19:44 UTC - in response to Message 41454.  

A few general hints first:
1. Make your computers visible for other volunteers in your preferences.
2. Mark links as URLs when you post a message.
3. Avoid posting huge logs. Most of them are already available via links.


Specific hint:
2020-01-25 10:21:31 (2352): Guest Log: Checking CVMFS...
2020-01-25 10:21:32 (2352): Guest Log: Failed to check CVMFS, check output from cvmfs_config probe:
2020-01-25 10:21:32 (2352): Guest Log: Probing /cvmfs/atlas.cern.ch... Failed!
2020-01-25 10:21:32 (2352): Guest Log: Probing /cvmfs/atlas-condb.cern.ch... Failed!
2020-01-25 10:21:32 (2352): Guest Log: Probing /cvmfs/grid.cern.ch... Failed!

Might be a firewall issue.
You may check if all reqired ports are open:
http://lhcathome.web.cern.ch/test4theory/my-firewall-complaining-which-ports-does-project-use
ATLAS downloads lots of functions via CVMFS, even functions for error handling.
ID: 41456 · Report as offensive     Reply Quote
broz69

Send message
Joined: 28 Nov 08
Posts: 30
Credit: 14,604,829
RAC: 17,458
Message 41457 - Posted: 3 Feb 2020, 10:38:54 UTC - in response to Message 41456.  
Last modified: 3 Feb 2020, 10:54:48 UTC

1. Done
2. Done
3. OK, sorry.

About the ports - I don't have any ports explicitly closed to the outside world. Except TCP/UDP 135-139 and TCP/UDP 445 which are not required for any LHC applications.
VirtualBox has only one network.
Virtual machines (VMs) are connected to a NAT (I didn't change this so I guess that is the default setting for all LHC VMs).
Network setting inside VirtualBox shows:
Network
Adapter 1: Intel PRO/1000 MT Desktop (NAT)

Also - the last contact with ATLAS was 1 Feb 2020, 22:28:14 UTC or 29 Jan 2020, 16:24:37 UTC and since then I haven't changed any port settings...
ID: 41457 · Report as offensive     Reply Quote
computezrmle
Volunteer moderator
Volunteer developer
Volunteer tester
Help desk expert
Avatar

Send message
Joined: 15 Jun 08
Posts: 2386
Credit: 222,903,594
RAC: 137,988
Message 41460 - Posted: 3 Feb 2020, 14:29:24 UTC

Noticed this line in your OP:
WU 130921208 started under VirtualBox 6.0.8 r130520 (Qt5.6.2), 1-Feb-2020 upgraded to 6.1.2 r135662 (Qt5.6.2).

Vbox tasks often do weird things if you change the vbox version while they are running.
In addition there are a couple of posters who report VirtualBox 6.1.2 being less reliable than previous versions.
Even my own (CMS-)tasks were affected.
Hence I downgraded to 6.0.14/6.0.16 which seems to be reliable at least under linux.
ID: 41460 · Report as offensive     Reply Quote
Erich56

Send message
Joined: 18 Dec 15
Posts: 1686
Credit: 100,346,826
RAC: 101,872
Message 41461 - Posted: 3 Feb 2020, 15:28:09 UTC - in response to Message 41460.  

In addition there are a couple of posters who report VirtualBox 6.1.2 being less reliable than previous versions.
Even my own (CMS-)tasks were affected.
Hence I downgraded to 6.0.14/6.0.16 which seems to be reliable at least under linux.
Yesterday, on an old netbook which I upgraded from Win7 to Win10, I installed VB 6.1.2, and so far 2 Theory tasks got finished. Everything was going well; although it's clear that only 2 tasks are a too small number to give a sound judgement.
But thanks for the "warning", so I can take it into consideration if problems should come up when processing the next tasks.
ID: 41461 · Report as offensive     Reply Quote
broz69

Send message
Joined: 28 Nov 08
Posts: 30
Credit: 14,604,829
RAC: 17,458
Message 41462 - Posted: 3 Feb 2020, 16:19:28 UTC - in response to Message 41460.  

Noticed this line in your OP:
WU 130921208 started under VirtualBox 6.0.8 r130520 (Qt5.6.2), 1-Feb-2020 upgraded to 6.1.2 r135662 (Qt5.6.2).

Vbox tasks often do weird things if you change the vbox version while they are running.
In addition there are a couple of posters who report VirtualBox 6.1.2 being less reliable than previous versions.
Even my own (CMS-)tasks were affected.
Hence I downgraded to 6.0.14/6.0.16 which seems to be reliable at least under linux.


Hi,

Thank you for this info. I'll keep that in mind.
WU 130921208 has already been running for some days without using CPU at the time when I decided to upgrade VirtualBox.
At the moment I'm testing another ATLAS job with VirtualBox 6.1.2 r135662 (Qt5.6.2).

In the meantime I discovered this in vbox_trace.txt:
2020-02-03 15:19:14 (10108): 
Command: VBoxManage -q modifyvm "boinc_d8f3620062e00255" --clipboard disabled 
Exit Code: -108
Output:
Oracle VM VirtualBox Command Line Management Interface Version 6.1.2
(C) 2005-2020 Oracle Corporation
All rights reserved.
VBoxManage.exe: error: Unknown option: --clipboard
ID: 41462 · Report as offensive     Reply Quote
computezrmle
Volunteer moderator
Volunteer developer
Volunteer tester
Help desk expert
Avatar

Send message
Joined: 15 Jun 08
Posts: 2386
Credit: 222,903,594
RAC: 137,988
Message 41463 - Posted: 3 Feb 2020, 17:13:11 UTC - in response to Message 41462.  

In the meantime I discovered this in vbox_trace.txt:
2020-02-03 15:19:14 (10108): 
Command: VBoxManage -q modifyvm "boinc_d8f3620062e00255" --clipboard disabled 
Exit Code: -108
Output:
Oracle VM VirtualBox Command Line Management Interface Version 6.1.2
(C) 2005-2020 Oracle Corporation
All rights reserved.
VBoxManage.exe: error: Unknown option: --clipboard

Hm, yes. Oracle has obviously renamed the vboxmanage option "--clipboard" to "--clipboard-manage".
Bad news - although I didn't check other vbox options - as this means vbox 6.1.x became incompatible to the vboxwrapper that is used by LHC@home. If LHC@home would change the vboxwrapper to support the new option it would make itself incompatible on all computers running vbox prior to 6.1.x.

Suggestion:
Avoid VirtualBox 6.1.x
ID: 41463 · Report as offensive     Reply Quote
broz69

Send message
Joined: 28 Nov 08
Posts: 30
Credit: 14,604,829
RAC: 17,458
Message 41475 - Posted: 5 Feb 2020, 10:23:41 UTC - in response to Message 41463.  
Last modified: 5 Feb 2020, 10:25:33 UTC

In the meantime I discovered this in vbox_trace.txt:
2020-02-03 15:19:14 (10108): 
Command: VBoxManage -q modifyvm "boinc_d8f3620062e00255" --clipboard disabled 
Exit Code: -108
Output:
Oracle VM VirtualBox Command Line Management Interface Version 6.1.2
(C) 2005-2020 Oracle Corporation
All rights reserved.
VBoxManage.exe: error: Unknown option: --clipboard

Hm, yes. Oracle has obviously renamed the vboxmanage option "--clipboard" to "--clipboard-manage".
Bad news - although I didn't check other vbox options - as this means vbox 6.1.x became incompatible to the vboxwrapper that is used by LHC@home. If LHC@home would change the vboxwrapper to support the new option it would make itself incompatible on all computers running vbox prior to 6.1.x.

Suggestion:
Avoid VirtualBox 6.1.x


Hi,

I tested a bit and this is what I found so far:
WU ID: 131869350 - I tested it with VBox 6.1.2 and it ran for cca 4h+. It reached around 46% and started to exibit the same behaviour - CPUs down to 1%. This is where are I discovered this --clipboard option which I don't think is responsible for the CPU behaviour... I aborted the job.
I downgraded VBox to version 6.0.16 (Qt5.6.2).
WU ID: 131909275 - I tested with VBox 6.0.16 and after running 23h+ it reached 99,906% - CPU is down to 1%. The normal running time would be between 4-10h.
From other ATLAS jobs I see that they are mainly crunched by Xeon processors on Linux. I see that my other machine that is running Linux on Intel has only occasional problems with ATLAS jobs but I guess nothing serious.
At the moment I will follow these steps https://lhcathome.cern.ch/lhcathome/forum_thread.php?id=5226 to clean the complete environment. At the end, before allowing new tasts, I'll also reset all the projects. I'll keep three directories under slots and can make them available to anyone who wishes to debug or analyse the situation more closely.
My guess would be that there is something between Windows 10, Ryzen 7 and VBox that causes this strange behaviour of ATLAS jobs. User greg_be has similar computer configuration as me and also has similar problems.

For now thank you for your help. I'll get in touch if I discover something more or if the steps above don't resolve the issue.

Best regards and keep up the good work!
ID: 41475 · Report as offensive     Reply Quote
maeax

Send message
Joined: 2 May 07
Posts: 2071
Credit: 156,086,454
RAC: 104,307
Message 41476 - Posted: 5 Feb 2020, 10:35:30 UTC
Last modified: 5 Feb 2020, 11:29:27 UTC

2020-02-03 15:19:10 (10108): Setting Memory Size for VM. (2241MB)
2020-02-03 15:19:11 (10108): Setting CPU Count for VM. (8)
This is not possible for Atlas.
Have also a Ryzen 2700. You have 64 GByte Total RAM.
Edit: When you clean up your Virtualbox and delete all virtuell VM's
you can deinstalling Virtualbox and do a fresh installation.
You can find in Yeti's checklist how many RAM you need for Atlas.
ID: 41476 · Report as offensive     Reply Quote
broz69

Send message
Joined: 28 Nov 08
Posts: 30
Credit: 14,604,829
RAC: 17,458
Message 41479 - Posted: 5 Feb 2020, 17:28:05 UTC - in response to Message 41476.  

2020-02-03 15:19:10 (10108): Setting Memory Size for VM. (2241MB)
2020-02-03 15:19:11 (10108): Setting CPU Count for VM. (8)
This is not possible for Atlas.
Have also a Ryzen 2700. You have 64 GByte Total RAM.
Edit: When you clean up your Virtualbox and delete all virtuell VM's
you can deinstalling Virtualbox and do a fresh installation.
You can find in Yeti's checklist how many RAM you need for Atlas.


Hi,

I checked and for the WU IDs 130921208 and 131084784 - I have this in my boinc_959537d37d2ec3c6.vbox and boinc_241d70e82b605c8a.vbox files in <Hardware> section:
      <CPU count="8">
        <PAE enabled="true"/>
        <LongMode enabled="true"/>
        <X2APIC enabled="true"/>
        <HardwareVirtExLargePages enabled="true"/>
      </CPU>
      <Memory RAMSize="10200"/>


It matches requirements from Yeti's checklist.

When I downgraded VBox to version 6.0.16 I did a fresh install - uninstalled 6.1.2 and installed 6.0.16.

At the moment there are a couple of ATLAS jobs that completed successfuly.
ID: 41479 · Report as offensive     Reply Quote
Erich56

Send message
Joined: 18 Dec 15
Posts: 1686
Credit: 100,346,826
RAC: 101,872
Message 41480 - Posted: 5 Feb 2020, 17:59:10 UTC - in response to Message 41475.  

I tested a bit and this is what I found so far:
WU ID: 131869350 - I tested it with VBox 6.1.2 and it ran for cca 4h+. It reached around 46% and started to exibit the same behaviour - CPUs down to 1%. This is where are I discovered this --clipboard option which I don't think is responsible for the CPU behaviour... I aborted the job.
two of my machines are running Vbox 6.1.2 and Theory tasks without any problem (https://lhcathome.cern.ch/lhcathome/result.php?resultid=261937953)
Hence, it's interesting to read that there are problems with ATLAS.
Unfortunately, I cannot try ATLAS on these 2 notebooks due to too low RAM.
ID: 41480 · Report as offensive     Reply Quote
Toby Broom
Volunteer moderator

Send message
Joined: 27 Sep 08
Posts: 798
Credit: 644,697,403
RAC: 235,135
Message 41481 - Posted: 5 Feb 2020, 18:31:35 UTC

I have 6.1.0 on all of my computers, I see less than 1% error rate.
ID: 41481 · Report as offensive     Reply Quote
computezrmle
Volunteer moderator
Volunteer developer
Volunteer tester
Help desk expert
Avatar

Send message
Joined: 15 Jun 08
Posts: 2386
Credit: 222,903,594
RAC: 137,988
Message 41483 - Posted: 5 Feb 2020, 18:59:38 UTC - in response to Message 41479.  

This logfile states that your VMs use only 2241MB although a 4-core setup requires 6600MB:
https://lhcathome.cern.ch/lhcathome/result.php?resultid=262028302
2020-02-05 11:49:38 (6456): Setting Memory Size for VM. (2241MB)
2020-02-05 11:49:39 (6456): Setting CPU Count for VM. (4)

You may switch to ALT-F3 of a running VM to check what RAM size is reported by top.
A local app_config.xml might set this wrong value.


Even your linux computer reports weird logfile entries:
https://lhcathome.cern.ch/lhcathome/result.php?resultid=262024433
2020-02-05 15:27:27 (23810): Detected: VirtualBox VboxManage Interface (Version: 5.2.34)
.
.
.
2020-02-05 15:27:32 (23810): Guest Log: BIOS: VirtualBox 5.2.33

You may upgrade VirtualBox or at least keep the version of your vbox additions in sync with your main VirtualBox version.
ID: 41483 · Report as offensive     Reply Quote
broz69

Send message
Joined: 28 Nov 08
Posts: 30
Credit: 14,604,829
RAC: 17,458
Message 41488 - Posted: 6 Feb 2020, 15:47:19 UTC - in response to Message 41483.  

This logfile states that your VMs use only 2241MB although a 4-core setup requires 6600MB:
https://lhcathome.cern.ch/lhcathome/result.php?resultid=262028302
2020-02-05 11:49:38 (6456): Setting Memory Size for VM. (2241MB)
2020-02-05 11:49:39 (6456): Setting CPU Count for VM. (4)

You may switch to ALT-F3 of a running VM to check what RAM size is reported by top.
A local app_config.xml might set this wrong value.


Even your linux computer reports weird logfile entries:
https://lhcathome.cern.ch/lhcathome/result.php?resultid=262024433
2020-02-05 15:27:27 (23810): Detected: VirtualBox VboxManage Interface (Version: 5.2.34)
.
.
.
2020-02-05 15:27:32 (23810): Guest Log: BIOS: VirtualBox 5.2.33

You may upgrade VirtualBox or at least keep the version of your vbox additions in sync with your main VirtualBox version.


Hello,

Thank you for your answer. And sorry for this mess. My Linux machine was even worse - it had Extension 5.2.10 and VBox 5.2.34... No comment... Seems I'm becoming a lazy Windows user - just waiting that somebody else fixes my problems with IT...

For WU ID 132298221 - I thought I fixed that. It must have been a job that started while I was playing with settings... Now it should have been 4 CPUs and 7168 MB RAM.

Thank you for your patientce.
ID: 41488 · Report as offensive     Reply Quote
broz69

Send message
Joined: 28 Nov 08
Posts: 30
Credit: 14,604,829
RAC: 17,458
Message 41498 - Posted: 8 Feb 2020, 21:40:59 UTC - in response to Message 41483.  

This logfile states that your VMs use only 2241MB although a 4-core setup requires 6600MB:
https://lhcathome.cern.ch/lhcathome/result.php?resultid=262028302
2020-02-05 11:49:38 (6456): Setting Memory Size for VM. (2241MB)
2020-02-05 11:49:39 (6456): Setting CPU Count for VM. (4)

You may switch to ALT-F3 of a running VM to check what RAM size is reported by top.
A local app_config.xml might set this wrong value.


Even your linux computer reports weird logfile entries:
https://lhcathome.cern.ch/lhcathome/result.php?resultid=262024433
2020-02-05 15:27:27 (23810): Detected: VirtualBox VboxManage Interface (Version: 5.2.34)
.
.
.
2020-02-05 15:27:32 (23810): Guest Log: BIOS: VirtualBox 5.2.33

You may upgrade VirtualBox or at least keep the version of your vbox additions in sync with your main VirtualBox version.

Hi,

I figured out what was wrong. It happened this morning with WU ID 132365918.
My computer is not on 24/7. Every evening instead of shutting it down I use sleep function. This morning after I turned the computer on I've checked the BOINC queue and all the machines that were running (there were 8 of them, all LHC jobs). The ATLAS job for WU ID 132365918 was in emergency mode. BOINC manager was showing it as running but VM console showed a lot of I/O error, dev sda. The host disk (physical disk in host system) seems OK, no errors there. So it must have been something connected with how VM reacts on waking from sleep. Maybe the host disk was not ready yet when BOINC or VBox were already waking up the machine with ATLAS job? The funny part was that it happend only to ATLAS job, 7 Theory jobs seem to be OK.
ID: 41498 · Report as offensive     Reply Quote
broz69

Send message
Joined: 28 Nov 08
Posts: 30
Credit: 14,604,829
RAC: 17,458
Message 41500 - Posted: 9 Feb 2020, 9:22:46 UTC - in response to Message 41498.  

This logfile states that your VMs use only 2241MB although a 4-core setup requires 6600MB:
https://lhcathome.cern.ch/lhcathome/result.php?resultid=262028302
2020-02-05 11:49:38 (6456): Setting Memory Size for VM. (2241MB)
2020-02-05 11:49:39 (6456): Setting CPU Count for VM. (4)

You may switch to ALT-F3 of a running VM to check what RAM size is reported by top.
A local app_config.xml might set this wrong value.


Even your linux computer reports weird logfile entries:
https://lhcathome.cern.ch/lhcathome/result.php?resultid=262024433
2020-02-05 15:27:27 (23810): Detected: VirtualBox VboxManage Interface (Version: 5.2.34)
.
.
.
2020-02-05 15:27:32 (23810): Guest Log: BIOS: VirtualBox 5.2.33

You may upgrade VirtualBox or at least keep the version of your vbox additions in sync with your main VirtualBox version.

Hi,

I figured out what was wrong. It happened this morning with WU ID 132365918.
My computer is not on 24/7. Every evening instead of shutting it down I use sleep function. This morning after I turned the computer on I've checked the BOINC queue and all the machines that were running (there were 8 of them, all LHC jobs). The ATLAS job for WU ID 132365918 was in emergency mode. BOINC manager was showing it as running but VM console showed a lot of I/O error, dev sda. The host disk (physical disk in host system) seems OK, no errors there. So it must have been something connected with how VM reacts on waking from sleep. Maybe the host disk was not ready yet when BOINC or VBox were already waking up the machine with ATLAS job? The funny part was that it happend only to ATLAS job, 7 Theory jobs seem to be OK.


Hi,

I have another 2 ATLAS jobs that seem to be stalled - running but no CPU used - WU IDs 132365983 and 132365606.
Last night I shutdown the machine. This morning I switched it back on and I checked LHC jobs. At the moment there seem to be 5 Theory jobs running and two ATLAS (2 CPUs) jobs. That would mean that BOINC is using 9 CPUs. Which is a bit funny as I only allow 8 CPUs to be used and this hasn't changed since friday.

current app_config (last changed 6-2-2020 12:13):
<app_config>
  <app>
    <name>ATLAS</name>
    <max_concurrent>4</max_concurrent>
  </app>
  <app_version>
    <app_name>ATLAS</app_name>
    <plan_class>vbox64_mt_mcore_atlas</plan_class>
    <avg_ncpus>2.0</avg_ncpus>
    <cmdline>--nthreads 2</cmdline>
  </app_version>
</app_config>


current ATLAS_vbox_2.00_job (last changed 7-2-2020 10:09):
<vbox_job>
  <os_name>Linux26_64</os_name>
  <memory_size_mb>5120</memory_size_mb>
  <enable_network/>
  <enable_remotedesktop/>
  <enable_shared_directory/>
  <copy_to_shared>init_data.xml</copy_to_shared>
  <completion_trigger_file>atlas_done</completion_trigger_file>
  <disable_automatic_checkpoints/> 
  <enable_vm_savestate_usage/>
  <minimum_checkpoint_interval>900</minimum_checkpoint_interval>
  <pf_guest_port>80</pf_guest_port>
</vbox_job>
ID: 41500 · Report as offensive     Reply Quote
Crystal Pellet
Volunteer moderator
Volunteer tester

Send message
Joined: 14 Jan 10
Posts: 1268
Credit: 8,421,616
RAC: 2,139
Message 41502 - Posted: 9 Feb 2020, 10:15:23 UTC - in response to Message 41498.  

Every evening instead of shutting it down I use sleep function.
Before bringing your host to bed, suspend your BOINC-tasks where you have selected Leave non-GPU tasks in memory while suspended in your local BOINC preferences.
The next morning after awaking your host you resume the tasks again.
ID: 41502 · Report as offensive     Reply Quote
1 · 2 · Next

Message boards : ATLAS application : ATLAS problem - long running but not using any CPU


©2024 CERN