<core_client_version>7.8.2</core_client_version>
<![CDATA[
<message>
finish file present too long
</message>
<stderr_txt>
2017-11-17 07:04:49 (1200): Detected: vboxwrapper 26197
2017-11-17 07:04:49 (1200): Detected: BOINC client v7.7
2017-11-17 07:04:50 (1200): Detected: VirtualBox VboxManage Interface (Version: 5.1.26)
2017-11-17 07:04:50 (1200): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2017-11-17 07:04:50 (1200): Successfully copied 'init_data.xml' to the shared directory.
2017-11-17 07:04:53 (1200): Create VM. (boinc_cc5064ab865fa2e0, slot#4)
2017-11-17 07:04:55 (1200): Setting Memory Size for VM. (630MB)
2017-11-17 07:04:55 (1200): Setting CPU Count for VM. (1)
2017-11-17 07:04:56 (1200): Setting Chipset Options for VM.
2017-11-17 07:04:56 (1200): Setting Boot Options for VM.
2017-11-17 07:04:58 (1200): Setting Network Configuration for NAT.
2017-11-17 07:05:00 (1200): Enabling VM Network Access.
2017-11-17 07:05:00 (1200): Disabling USB Support for VM.
2017-11-17 07:05:00 (1200): Disabling COM Port Support for VM.
2017-11-17 07:05:00 (1200): Disabling LPT Port Support for VM.
2017-11-17 07:05:01 (1200): Disabling Audio Support for VM.
2017-11-17 07:05:01 (1200): Disabling Clipboard Support for VM.
2017-11-17 07:05:01 (1200): Disabling Drag and Drop Support for VM.
2017-11-17 07:05:02 (1200): Adding storage controller(s) to VM.
2017-11-17 07:05:02 (1200): Adding virtual disk drive to VM. (vm_image.vdi)
2017-11-17 07:05:02 (1200): Adding VirtualBox Guest Additions to VM.
2017-11-17 07:05:02 (1200): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2017-11-17 07:05:03 (1200): forwarding host port 54637 to guest port 80
2017-11-17 07:05:03 (1200): Enabling remote desktop for VM.
2017-11-17 07:05:03 (1200): Required extension pack not installed, remote desktop not enabled.
2017-11-17 07:05:03 (1200): Enabling shared directory for VM.
2017-11-17 07:05:04 (1200): Starting VM using VBoxManage interface. (boinc_cc5064ab865fa2e0, slot#4)
2017-11-17 07:05:09 (1200): Successfully started VM. (PID = '13784')
2017-11-17 07:05:09 (1200): Reporting VM Process ID to BOINC.
2017-11-17 07:05:09 (1200): Guest Log: BIOS: VirtualBox 5.1.26

2017-11-17 07:05:09 (1200): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2017-11-17 07:05:09 (1200): VM state change detected. (old = 'PoweredOff', new = 'Running')
2017-11-17 07:05:09 (1200): Detected: Web Application Enabled (http://localhost:54637)
2017-11-17 07:05:10 (1200): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-17 07:05:16 (1200): Preference change detected
2017-11-17 07:05:16 (1200): Setting CPU throttle for VM. (100%)
2017-11-17 07:05:16 (1200): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2017-11-17 07:05:17 (1200): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2017-11-17 07:05:17 (1200): Guest Log: BIOS: Booting from Hard Disk...

2017-11-17 07:05:17 (1200): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-17 07:05:20 (1200): Guest Log: BIOS: KBD: unsupported int 16h function 03

2017-11-17 07:05:20 (1200): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2017-11-17 07:05:27 (1200): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-17 07:05:37 (1200): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-17 07:05:46 (1200): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2017-11-17 07:06:05 (1200): Guest Log: VBoxService 4.3.28 r100309 (verbosity: 0) linux.amd64 (May 13 2015 17:11:31) release log

2017-11-17 07:06:05 (1200): Guest Log: 00:00:00.000317 main     Log opened 2017-11-17T12:06:06.736347000Z

2017-11-17 07:06:05 (1200): Guest Log: 00:00:00.000863 main     OS Product: Linux

2017-11-17 07:06:05 (1200): Guest Log: 00:00:00.000938 main     OS Release: 4.1.39-28.cernvm.x86_64

2017-11-17 07:06:05 (1200): Guest Log: 00:00:00.000969 main     OS Version: #1 SMP Tue Mar 14 08:13:19 CET 2017

2017-11-17 07:06:05 (1200): Guest Log: 00:00:00.000992 main     OS Service Pack: #1 SMP Tue Mar 14 08:13:19 CET 2017

2017-11-17 07:06:05 (1200): Guest Log: 00:00:00.001014 main     Executable: /usr/sbin/VBoxService

2017-11-17 07:06:05 (1200): Guest Log: 00:00:00.001015 main     Process ID: 2736

2017-11-17 07:06:05 (1200): Guest Log: 00:00:00.001016 main     Package type: LINUX_64BITS_GENERIC

2017-11-17 07:06:05 (1200): Guest Log: 00:00:00.001772 main     4.3.28 r100309 started. Verbose level = 0

2017-11-17 07:06:30 (1200): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-17 07:06:41 (1200): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-17 07:06:51 (1200): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-17 07:07:02 (1200): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-17 07:07:07 (1200): Guest Log: [INFO] Mounting the shared directory

2017-11-17 07:07:07 (1200): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor

2017-11-17 07:07:08 (1200): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80

2017-11-17 07:07:08 (1200): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded!

2017-11-17 07:07:08 (1200): Guest Log: [DEBUG] 0

2017-11-17 07:07:08 (1200): Guest Log: [DEBUG] Testing CVMFS connection to lhchomeproxy.cern.ch on port 3125

2017-11-17 07:07:09 (1200): Guest Log: [DEBUG] Connection to lhchomeproxy.cern.ch 3125 port [tcp/a13-an] succeeded!

2017-11-17 07:07:09 (1200): Guest Log: [DEBUG] 0

2017-11-17 07:07:09 (1200): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443

2017-11-17 07:07:11 (1200): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded!

2017-11-17 07:07:11 (1200): Guest Log: [DEBUG] 0

2017-11-17 07:07:11 (1200): Guest Log: [DEBUG] Testing connection to Condor server on port 9618

2017-11-17 07:07:11 (1200): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded!

2017-11-17 07:07:12 (1200): Guest Log: [DEBUG] 0

2017-11-17 07:07:12 (1200): Guest Log: [DEBUG] Probing CVMFS ...

2017-11-17 07:07:12 (1200): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-17 07:07:23 (1200): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2017-11-17 07:07:23 (1200): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-17 07:07:46 (1200): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2017-11-17 07:07:47 (1200): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2017-11-17 07:07:47 (1200): Guest Log: 2.2.0.0 3439 0 19636 5088 14 1 481690 10240001 2 65024 0 20 95 20792 0 http://cvmfs.fnal.gov/cvmfs/grid.cern.ch http://131.225.205.133:3125 1

2017-11-17 07:08:23 (1200): Guest Log: [INFO] Reading volunteer information

2017-11-17 07:08:24 (1200): Guest Log: [INFO] Volunteer: DogBoy (540358) Host: 10495012

2017-11-17 07:08:24 (1200): Guest Log: [INFO] VMID: af6a5502-bdf8-44f3-94c4-46386c9eddf5

2017-11-17 07:08:24 (1200): Guest Log: [INFO] Requesting an X509 credential from LHC@home

2017-11-17 07:08:27 (1200): Guest Log: [INFO] Running the fast benchmark.

2017-11-17 07:09:13 (1200): Guest Log: [INFO] Machine performance 11.53 HEPSEC06

2017-11-17 07:09:13 (1200): Guest Log: [INFO] Theory application starting. Check log files.

2017-11-17 07:09:14 (1200): Guest Log: [DEBUG] HTCondor ping

2017-11-17 07:09:16 (1200): Guest Log: [DEBUG] 0

2017-11-17 07:10:17 (1200): Guest Log: [INFO] New Job Starting in slot1

2017-11-17 07:10:17 (1200): Guest Log: [INFO] Condor JobID:  2354.55 in slot1

2017-11-17 07:10:22 (1200): Guest Log: [INFO] MCPlots JobID: 39575526 in slot1

2017-11-17 07:11:09 (1200): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-17 07:11:19 (1200): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-17 07:11:39 (1200): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-17 07:11:49 (1200): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-17 07:12:10 (1200): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-17 07:12:20 (1200): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-17 07:25:53 (1200): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-17 07:26:03 (1200): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-17 07:28:27 (1200): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-17 07:28:47 (1200): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-17 07:29:49 (1200): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-17 07:29:59 (1200): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-17 08:00:29 (1200): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-17 08:00:40 (1200): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-17 08:00:40 (1200): Stopping VM.
2017-11-17 08:01:40 (12176): Detected: vboxwrapper 26197
2017-11-17 08:01:40 (12176): Detected: BOINC client v7.7
2017-11-17 08:01:41 (12176): Detected: VirtualBox VboxManage Interface (Version: 5.1.26)
2017-11-17 08:01:41 (12176): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2017-11-17 08:01:41 (12176): Starting VM using VBoxManage interface. (boinc_cc5064ab865fa2e0, slot#4)
2017-11-17 08:01:51 (12176): Successfully started VM. (PID = '10208')
2017-11-17 08:01:51 (12176): Reporting VM Process ID to BOINC.
2017-11-17 08:01:51 (12176): VM state change detected. (old = 'PoweredOff', new = 'Running')
2017-11-17 08:01:51 (12176): Detected: Web Application Enabled (http://localhost:54637)
2017-11-17 08:01:51 (12176): Preference change detected
2017-11-17 08:01:51 (12176): Setting CPU throttle for VM. (100%)
2017-11-17 08:01:52 (12176): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2017-11-17 08:01:55 (12176): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-17 08:02:05 (12176): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-17 08:04:38 (12176): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-17 08:04:49 (12176): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-17 08:04:49 (12176): Stopping VM.
2017-11-17 08:16:01 (8300): Detected: vboxwrapper 26197
2017-11-17 08:16:01 (8300): Detected: BOINC client v7.7
2017-11-17 08:16:02 (8300): Detected: VirtualBox VboxManage Interface (Version: 5.1.26)
2017-11-17 08:16:02 (8300): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2017-11-17 08:16:03 (8300): Starting VM using VBoxManage interface. (boinc_cc5064ab865fa2e0, slot#4)
2017-11-17 08:17:22 (8300): Error in start VM for VM: -2147467259
Command:
VBoxManage -q startvm "boinc_cc5064ab865fa2e0" --type headless
Output:
Waiting for VM "boinc_cc5064ab865fa2e0" to power on...
VBoxManage.exe: error: Timeout. (VERR_TIMEOUT)
VBoxManage.exe: error: Details: code E_FAIL (0x80004005), component ConsoleWrap, interface IConsole

2017-11-17 08:17:22 (8300): VM failed to start.
2017-11-17 08:17:22 (8300): Could not start 
2017-11-17 08:17:22 (8300): ERROR: VM failed to start
2017-11-17 08:17:22 (8300): Powering off VM.
2017-11-17 08:17:22 (8300): Deregistering VM. (boinc_cc5064ab865fa2e0, slot#4)
2017-11-17 08:30:25 (12092): Detected: vboxwrapper 26197
2017-11-17 08:30:25 (12092): Detected: BOINC client v7.7
2017-11-17 08:30:25 (12092): Detected: VirtualBox VboxManage Interface (Version: 5.1.26)
2017-11-17 08:30:25 (12092): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2017-11-17 08:31:12 (12092): Error: Timeout
2017-11-17 08:31:12 (12092): ERROR: VM failed to start
2017-11-17 08:31:17 (12092): 
   BOINC will be notified that it needs to clean up the environment.
 		    This is a temporary problem and so this job will be rescheduled for another time.

2017-11-18 12:27:17 (7424): Detected: vboxwrapper 26197
2017-11-18 12:27:17 (7424): Detected: BOINC client v7.7
2017-11-18 12:27:34 (7424): Detected: VirtualBox VboxManage Interface (Version: 5.1.26)
2017-11-18 12:27:35 (7424): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2017-11-18 12:27:36 (7424): Starting VM using VBoxManage interface. (boinc_cc5064ab865fa2e0, slot#4)
2017-11-18 12:27:46 (7424): Successfully started VM. (PID = '10088')
2017-11-18 12:27:46 (7424): Reporting VM Process ID to BOINC.
2017-11-18 12:27:46 (7424): Guest Log: BIOS: VirtualBox 5.1.26

2017-11-18 12:27:46 (7424): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2017-11-18 12:27:46 (7424): VM state change detected. (old = 'PoweredOff', new = 'Running')
2017-11-18 12:27:46 (7424): Detected: Web Application Enabled (http://localhost:54637)
2017-11-18 12:27:46 (7424): Preference change detected
2017-11-18 12:27:46 (7424): Setting CPU throttle for VM. (100%)
2017-11-18 12:27:47 (7424): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2017-11-18 12:27:48 (7424): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2017-11-18 12:27:48 (7424): Guest Log: BIOS: Booting from Hard Disk...

2017-11-18 12:27:59 (7424): Guest Log: BIOS: KBD: unsupported int 16h function 03

2017-11-18 12:27:59 (7424): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2017-11-18 12:30:02 (7424): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2017-11-18 12:30:25 (7424): Stopping VM.
2017-11-18 12:47:36 (256): Detected: vboxwrapper 26197
2017-11-18 12:47:36 (256): Detected: BOINC client v7.7
2017-11-18 12:47:37 (256): Detected: VirtualBox VboxManage Interface (Version: 5.1.26)
2017-11-18 12:47:37 (256): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2017-11-18 12:47:38 (256): Starting VM using VBoxManage interface. (boinc_cc5064ab865fa2e0, slot#4)
2017-11-18 12:48:59 (256): Successfully started VM. (PID = '4908')
2017-11-18 12:48:59 (256): Reporting VM Process ID to BOINC.
2017-11-18 12:48:59 (256): VM state change detected. (old = 'PoweredOff', new = 'Running')
2017-11-18 12:48:59 (256): Detected: Web Application Enabled (http://localhost:54637)
2017-11-18 12:48:59 (256): Preference change detected
2017-11-18 12:48:59 (256): Setting CPU throttle for VM. (100%)
2017-11-18 12:50:40 (256): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2017-11-18 12:50:41 (256): Guest Log: VBoxService 4.3.28 r100309 (verbosity: 0) linux.amd64 (May 13 2015 17:11:31) release log

2017-11-18 12:50:41 (256): Guest Log: 00:00:00.000046 main     Log opened 2017-11-18T17:50:18.795640000Z

2017-11-18 12:50:41 (256): Guest Log: 00:00:00.000248 main     OS Product: Linux

2017-11-18 12:50:41 (256): Guest Log: 00:00:00.000274 main     OS Release: 4.1.39-28.cernvm.x86_64

2017-11-18 12:50:41 (256): Guest Log: 00:00:00.000291 main     OS Version: #1 SMP Tue Mar 14 08:13:19 CET 2017

2017-11-18 12:50:41 (256): Guest Log: 00:00:00.000307 main     OS Service Pack: #1 SMP Tue Mar 14 08:13:19 CET 2017

2017-11-18 12:50:41 (256): Guest Log: 00:00:00.000322 main     Executable: /usr/sbin/VBoxService

2017-11-18 12:50:41 (256): Guest Log: 00:00:00.000323 main     Process ID: 2670

2017-11-18 12:50:41 (256): Guest Log: 00:00:00.000323 main     Package type: LINUX_64BITS_GENERIC

2017-11-18 12:50:41 (256): Guest Log: 00:00:00.001795 main     4.3.28 r100309 started. Verbose level = 0

2017-11-18 12:51:07 (256): Guest Log: [INFO] Mounting the shared directory

2017-11-18 12:51:07 (256): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor

2017-11-18 12:51:08 (256): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80

2017-11-18 12:51:08 (256): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded!

2017-11-18 12:51:08 (256): Guest Log: [DEBUG] 0

2017-11-18 12:51:08 (256): Guest Log: [DEBUG] Testing CVMFS connection to lhchomeproxy.cern.ch on port 3125

2017-11-18 12:51:09 (256): Guest Log: [DEBUG] Connection to lhchomeproxy.cern.ch 3125 port [tcp/a13-an] succeeded!

2017-11-18 12:51:09 (256): Guest Log: [DEBUG] 0

2017-11-18 12:51:09 (256): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443

2017-11-18 12:51:09 (256): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded!

2017-11-18 12:51:09 (256): Guest Log: [DEBUG] 0

2017-11-18 12:51:09 (256): Guest Log: [DEBUG] Testing connection to Condor server on port 9618

2017-11-18 12:51:10 (256): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded!

2017-11-18 12:51:10 (256): Guest Log: [DEBUG] 0

2017-11-18 12:51:10 (256): Guest Log: [DEBUG] Probing CVMFS ...

2017-11-18 12:51:12 (256): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2017-11-18 12:51:34 (256): Guest Log: Probing /cvmfs/sft.cern.ch... OK

2017-11-18 12:51:35 (256): Guest Log: VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE

2017-11-18 12:51:35 (256): Guest Log: 2.2.0.0 3351 0 19592 5093 14 1 605491 10240001 2 65024 0 20 100 20789 1 http://cvmfs.fnal.gov/cvmfs/grid.cern.ch http://131.225.205.134:3125 1

2017-11-18 12:51:38 (256): Guest Log: [INFO] Reading volunteer information

2017-11-18 12:51:39 (256): Guest Log: [INFO] Volunteer: DogBoy (540358) Host: 10495012

2017-11-18 12:51:39 (256): Guest Log: [INFO] VMID: af6a5502-bdf8-44f3-94c4-46386c9eddf5

2017-11-18 12:51:39 (256): Guest Log: [INFO] Requesting an X509 credential from LHC@home

2017-11-18 12:51:42 (256): Guest Log: [INFO] Running the fast benchmark.

2017-11-18 12:52:27 (256): Guest Log: [INFO] Machine performance 11.57 HEPSEC06

2017-11-18 12:52:27 (256): Guest Log: [INFO] Theory application starting. Check log files.

2017-11-18 12:52:28 (256): Guest Log: [DEBUG] HTCondor ping

2017-11-18 12:52:32 (256): Guest Log: [DEBUG] 0

2017-11-18 12:54:14 (256): Guest Log: [INFO] New Job Starting in slot1

2017-11-18 12:54:15 (256): Guest Log: [INFO] Condor JobID:  2654.27 in slot1

2017-11-18 12:54:20 (256): Guest Log: [INFO] MCPlots JobID: 39611094 in slot1

2017-11-18 12:54:42 (256): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 12:55:24 (256): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 12:55:34 (256): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 12:55:45 (256): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 13:13:17 (256): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 13:13:27 (256): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 13:14:47 (256): Guest Log: [INFO] Job finished in slot1 with 0.

2017-11-18 13:15:06 (256): Guest Log: [INFO] New Job Starting in slot1

2017-11-18 13:15:06 (256): Guest Log: [INFO] Condor JobID:  2656.151 in slot1

2017-11-18 13:15:12 (256): Guest Log: [INFO] MCPlots JobID: 39611281 in slot1

2017-11-18 13:19:17 (256): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 13:19:27 (256): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 13:34:26 (256): Status Report: Job Duration: '64800.000000'
2017-11-18 13:34:26 (256): Status Report: Elapsed Time: '6000.106937'
2017-11-18 13:34:26 (256): Status Report: CPU Time: '5544.390625'
2017-11-18 13:40:02 (256): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 13:40:13 (256): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 13:48:24 (256): Stopping VM.
2017-11-18 13:54:15 (11908): Detected: vboxwrapper 26197
2017-11-18 13:54:15 (11908): Detected: BOINC client v7.7
2017-11-18 13:54:16 (11908): Detected: VirtualBox VboxManage Interface (Version: 5.1.26)
2017-11-18 13:54:16 (11908): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2017-11-18 13:54:16 (11908): Starting VM using VBoxManage interface. (boinc_cc5064ab865fa2e0, slot#4)
2017-11-18 13:54:51 (11908): Successfully started VM. (PID = '3192')
2017-11-18 13:54:51 (11908): Reporting VM Process ID to BOINC.
2017-11-18 13:54:51 (11908): VM state change detected. (old = 'PoweredOff', new = 'Running')
2017-11-18 13:54:51 (11908): Detected: Web Application Enabled (http://localhost:54637)
2017-11-18 13:54:51 (11908): Status Report: Job Duration: '64800.000000'
2017-11-18 13:54:51 (11908): Status Report: Elapsed Time: '6815.411135'
2017-11-18 13:54:51 (11908): Status Report: CPU Time: '6362.921875'
2017-11-18 13:54:51 (11908): Preference change detected
2017-11-18 13:54:51 (11908): Setting CPU throttle for VM. (100%)
2017-11-18 13:54:51 (11908): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2017-11-18 14:44:06 (11908): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 14:44:16 (11908): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 14:55:03 (11908): Stopping VM.
2017-11-18 14:56:04 (3192): Detected: vboxwrapper 26197
2017-11-18 14:56:04 (3192): Detected: BOINC client v7.7
2017-11-18 14:56:05 (3192): Detected: VirtualBox VboxManage Interface (Version: 5.1.26)
2017-11-18 14:56:05 (3192): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2017-11-18 14:56:06 (3192): Starting VM using VBoxManage interface. (boinc_cc5064ab865fa2e0, slot#4)
2017-11-18 14:56:20 (3192): Successfully started VM. (PID = '15104')
2017-11-18 14:56:20 (3192): Reporting VM Process ID to BOINC.
2017-11-18 14:56:20 (3192): VM state change detected. (old = 'PoweredOff', new = 'Running')
2017-11-18 14:56:20 (3192): Detected: Web Application Enabled (http://localhost:54637)
2017-11-18 14:56:20 (3192): Status Report: Job Duration: '64800.000000'
2017-11-18 14:56:20 (3192): Status Report: Elapsed Time: '10365.653504'
2017-11-18 14:56:20 (3192): Status Report: CPU Time: '9951.546875'
2017-11-18 14:56:20 (3192): Preference change detected
2017-11-18 14:56:20 (3192): Setting CPU throttle for VM. (100%)
2017-11-18 14:56:21 (3192): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2017-11-18 15:27:46 (3192): Guest Log: [INFO] Job finished in slot1 with 0.

2017-11-18 15:27:57 (3192): Guest Log: [INFO] New Job Starting in slot1

2017-11-18 15:27:57 (3192): Guest Log: [INFO] Condor JobID:  2676.317 in slot1

2017-11-18 15:28:03 (3192): Guest Log: [INFO] MCPlots JobID: 39614076 in slot1

2017-11-18 15:46:04 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:46:14 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:46:20 (3192): Guest Log: [INFO] Job finished in slot1 with 0.

2017-11-18 15:46:25 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:46:36 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:46:43 (3192): Guest Log: [INFO] New Job Starting in slot1

2017-11-18 15:46:43 (3192): Guest Log: [INFO] Condor JobID:  2681.88 in slot1

2017-11-18 15:46:48 (3192): Guest Log: [INFO] MCPlots JobID: 39614801 in slot1

2017-11-18 15:47:38 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:49:00 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:49:10 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:49:41 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:50:01 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:50:32 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:50:43 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:51:34 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:51:44 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:51:54 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:52:04 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:52:24 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:52:34 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:53:05 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:53:15 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:53:25 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:53:35 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:53:56 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:54:07 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:54:17 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:54:27 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:55:18 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:55:28 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:55:38 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:55:48 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:56:19 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:56:29 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:56:39 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:56:50 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:57:00 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:57:10 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:57:20 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:57:41 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 15:58:11 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 15:58:21 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:00:22 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:00:32 (3192): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:00:42 (3192): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:00:44 (3192): Stopping VM.
2017-11-18 16:14:12 (3540): Detected: vboxwrapper 26197
2017-11-18 16:14:12 (3540): Detected: BOINC client v7.7
2017-11-18 16:14:13 (3540): Detected: VirtualBox VboxManage Interface (Version: 5.1.26)
2017-11-18 16:14:13 (3540): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2017-11-18 16:14:13 (3540): Starting VM using VBoxManage interface. (boinc_cc5064ab865fa2e0, slot#4)
2017-11-18 16:14:21 (3540): Successfully started VM. (PID = '5956')
2017-11-18 16:14:21 (3540): Reporting VM Process ID to BOINC.
2017-11-18 16:14:21 (3540): VM state change detected. (old = 'PoweredOff', new = 'Running')
2017-11-18 16:14:21 (3540): Detected: Web Application Enabled (http://localhost:54637)
2017-11-18 16:14:21 (3540): Status Report: Job Duration: '64800.000000'
2017-11-18 16:14:21 (3540): Status Report: Elapsed Time: '13576.653504'
2017-11-18 16:14:21 (3540): Status Report: CPU Time: '13148.593750'
2017-11-18 16:14:21 (3540): Preference change detected
2017-11-18 16:14:21 (3540): Setting CPU throttle for VM. (100%)
2017-11-18 16:14:22 (3540): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2017-11-18 16:14:23 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:14:33 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:14:45 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:16:09 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:16:17 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:16:29 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:16:37 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:18:21 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:18:28 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:21:32 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:21:40 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:21:51 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:22:01 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:22:11 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:22:21 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:22:36 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:22:53 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:23:14 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:23:22 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:23:32 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:23:43 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:23:55 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:24:34 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:24:49 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:24:55 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:25:07 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:25:26 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:25:39 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:25:47 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:25:57 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:26:07 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:26:18 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:26:28 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:26:40 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:26:49 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:26:59 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:27:09 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:27:21 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:27:29 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:27:41 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:28:00 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:28:15 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:28:22 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:28:33 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:28:42 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:28:54 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:29:03 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:29:15 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:29:23 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:29:35 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:29:55 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:30:06 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:30:15 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:30:30 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:30:37 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:30:47 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:30:56 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:31:07 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:31:28 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:31:39 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:31:49 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:31:59 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:32:50 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:33:11 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:33:31 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:33:41 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:33:52 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:34:02 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:34:33 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:34:53 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:35:54 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:36:24 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:36:36 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:36:46 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:37:06 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:37:27 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:39:28 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:39:38 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:39:59 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:40:09 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:40:29 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:40:40 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:41:00 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:41:11 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:41:31 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:41:41 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:42:22 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:42:33 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:42:53 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:43:13 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:43:23 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:43:34 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:43:44 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:43:54 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:44:05 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:44:16 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:44:35 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:44:47 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:46:07 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:46:18 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:46:28 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:46:38 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:46:47 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:46:58 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:47:08 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:47:19 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:47:59 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:48:40 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:48:51 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:49:01 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:49:11 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:49:21 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:49:32 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:49:42 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:49:51 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:50:01 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:50:53 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:51:14 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:53:06 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:53:26 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:53:36 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:53:47 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:53:57 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:54:17 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 16:54:27 (3540): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 16:54:38 (3540): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 17:14:58 (3540): Stopping VM.
2017-11-18 17:21:02 (3276): Detected: vboxwrapper 26197
2017-11-18 17:21:02 (3276): Detected: BOINC client v7.7
2017-11-18 17:21:02 (3276): Detected: VirtualBox VboxManage Interface (Version: 5.1.26)
2017-11-18 17:21:02 (3276): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2017-11-18 17:21:03 (3276): Starting VM using VBoxManage interface. (boinc_cc5064ab865fa2e0, slot#4)
2017-11-18 17:21:16 (3276): Successfully started VM. (PID = '7640')
2017-11-18 17:21:16 (3276): Reporting VM Process ID to BOINC.
2017-11-18 17:21:16 (3276): VM state change detected. (old = 'PoweredOff', new = 'Running')
2017-11-18 17:21:16 (3276): Detected: Web Application Enabled (http://localhost:54637)
2017-11-18 17:21:16 (3276): Status Report: Job Duration: '64800.000000'
2017-11-18 17:21:16 (3276): Status Report: Elapsed Time: '15807.534714'
2017-11-18 17:21:16 (3276): Status Report: CPU Time: '15623.421875'
2017-11-18 17:21:16 (3276): Preference change detected
2017-11-18 17:21:16 (3276): Setting CPU throttle for VM. (100%)
2017-11-18 17:21:17 (3276): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2017-11-18 17:57:12 (3276): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 17:57:23 (3276): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 17:57:33 (3276): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 17:57:43 (3276): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-18 18:07:41 (3276): Guest Log: [INFO] Job finished in slot1 with 0.

2017-11-18 18:09:19 (3276): Guest Log: [INFO] New Job Starting in slot1

2017-11-18 18:09:19 (3276): Guest Log: [INFO] Condor JobID:  2704.192 in slot1

2017-11-18 18:09:24 (3276): Guest Log: [INFO] MCPlots JobID: 39617448 in slot1

2017-11-18 18:24:54 (3276): Stopping VM.
2017-11-18 18:30:07 (3044): Detected: vboxwrapper 26197
2017-11-18 18:30:07 (3044): Detected: BOINC client v7.7
2017-11-18 18:30:07 (3044): Detected: VirtualBox VboxManage Interface (Version: 5.1.26)
2017-11-18 18:30:08 (3044): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2017-11-18 18:30:08 (3044): Starting VM using VBoxManage interface. (boinc_cc5064ab865fa2e0, slot#4)
2017-11-18 18:30:25 (3044): Successfully started VM. (PID = '10392')
2017-11-18 18:30:25 (3044): Reporting VM Process ID to BOINC.
2017-11-18 18:30:25 (3044): VM state change detected. (old = 'PoweredOff', new = 'Running')
2017-11-18 18:30:25 (3044): Detected: Web Application Enabled (http://localhost:54637)
2017-11-18 18:30:25 (3044): Status Report: Job Duration: '64800.000000'
2017-11-18 18:30:25 (3044): Status Report: Elapsed Time: '19583.534714'
2017-11-18 18:30:25 (3044): Status Report: CPU Time: '19302.890625'
2017-11-18 18:30:25 (3044): Preference change detected
2017-11-18 18:30:25 (3044): Setting CPU throttle for VM. (100%)
2017-11-18 18:30:26 (3044): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2017-11-18 18:41:55 (3044): Guest Log: [INFO] Job finished in slot1 with 0.

2017-11-18 18:42:48 (3044): Guest Log: [INFO] New Job Starting in slot1

2017-11-18 18:42:48 (3044): Guest Log: [INFO] Condor JobID:  2709.207 in slot1

2017-11-18 18:42:54 (3044): Guest Log: [INFO] MCPlots JobID: 39618016 in slot1

2017-11-18 19:13:00 (3044): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-18 19:13:07 (3044): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_cc5064ab865fa2e0" resume
Output:
VBoxManage.exe: error: VM is paused due to host power management
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 123 of file VBoxManageControlVM.cpp

2017-11-18 19:13:07 (3044): Stopping VM.
2017-11-18 19:14:31 (8008): Detected: vboxwrapper 26197
2017-11-18 19:14:31 (8008): Detected: BOINC client v7.7
2017-11-18 19:14:31 (8008): Detected: VirtualBox VboxManage Interface (Version: 5.1.26)
2017-11-18 19:14:32 (8008): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2017-11-18 19:14:32 (8008): Starting VM using VBoxManage interface. (boinc_cc5064ab865fa2e0, slot#4)
2017-11-18 19:14:43 (8008): Successfully started VM. (PID = '6216')
2017-11-18 19:14:43 (8008): Reporting VM Process ID to BOINC.
2017-11-18 19:14:43 (8008): VM state change detected. (old = 'PoweredOff', new = 'Running')
2017-11-18 19:14:43 (8008): Detected: Web Application Enabled (http://localhost:54637)
2017-11-18 19:14:43 (8008): Status Report: Job Duration: '64800.000000'
2017-11-18 19:14:43 (8008): Status Report: Elapsed Time: '22125.534714'
2017-11-18 19:14:43 (8008): Status Report: CPU Time: '21726.593750'
2017-11-18 19:14:43 (8008): Preference change detected
2017-11-18 19:14:43 (8008): Setting CPU throttle for VM. (100%)
2017-11-18 19:14:43 (8008): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2017-11-18 19:35:12 (8008): Stopping VM.
2017-11-18 19:46:45 (7344): Detected: vboxwrapper 26197
2017-11-18 19:46:45 (7344): Detected: BOINC client v7.7
2017-11-18 19:46:45 (7344): Detected: VirtualBox VboxManage Interface (Version: 5.1.26)
2017-11-18 19:46:46 (7344): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2017-11-18 19:46:46 (7344): Starting VM using VBoxManage interface. (boinc_cc5064ab865fa2e0, slot#4)
2017-11-18 19:47:10 (7344): Successfully started VM. (PID = '14864')
2017-11-18 19:47:10 (7344): Reporting VM Process ID to BOINC.
2017-11-18 19:47:10 (7344): VM state change detected. (old = 'PoweredOff', new = 'Running')
2017-11-18 19:47:10 (7344): Detected: Web Application Enabled (http://localhost:54637)
2017-11-18 19:47:10 (7344): Status Report: Job Duration: '64800.000000'
2017-11-18 19:47:10 (7344): Status Report: Elapsed Time: '23352.534714'
2017-11-18 19:47:10 (7344): Status Report: CPU Time: '22958.250000'
2017-11-18 19:47:10 (7344): Preference change detected
2017-11-18 19:47:10 (7344): Setting CPU throttle for VM. (100%)
2017-11-18 19:47:10 (7344): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2017-11-18 20:31:23 (7344): Guest Log: [INFO] Job finished in slot1 with 0.

2017-11-18 20:31:49 (7344): Guest Log: [INFO] New Job Starting in slot1

2017-11-18 20:31:50 (7344): Guest Log: [INFO] Condor JobID:  2729.43 in slot1

2017-11-18 20:31:55 (7344): Guest Log: [INFO] MCPlots JobID: 39620360 in slot1

2017-11-18 20:33:00 (7344): Guest Log: [INFO] Job finished in slot1 with 0.

2017-11-18 20:33:16 (7344): Guest Log: [INFO] New Job Starting in slot1

2017-11-18 20:33:16 (7344): Guest Log: [INFO] Condor JobID:  2729.90 in slot1

2017-11-18 20:33:21 (7344): Guest Log: [INFO] MCPlots JobID: 39620383 in slot1

2017-11-18 20:47:22 (7344): Stopping VM.
2017-11-19 09:23:25 (14084): Detected: vboxwrapper 26197
2017-11-19 09:23:26 (14084): Detected: BOINC client v7.7
2017-11-19 09:23:29 (14084): Detected: VirtualBox VboxManage Interface (Version: 5.1.26)
2017-11-19 09:23:30 (14084): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2017-11-19 09:23:31 (14084): Starting VM using VBoxManage interface. (boinc_cc5064ab865fa2e0, slot#4)
2017-11-19 09:26:02 (14084): Successfully started VM. (PID = '16708')
2017-11-19 09:26:02 (14084): Reporting VM Process ID to BOINC.
2017-11-19 09:26:02 (14084): VM state change detected. (old = 'PoweredOff', new = 'Running')
2017-11-19 09:26:02 (14084): Detected: Web Application Enabled (http://localhost:54637)
2017-11-19 09:26:02 (14084): Status Report: Job Duration: '64800.000000'
2017-11-19 09:26:02 (14084): Status Report: Elapsed Time: '26938.480086'
2017-11-19 09:26:02 (14084): Status Report: CPU Time: '26481.812500'
2017-11-19 09:26:02 (14084): Preference change detected
2017-11-19 09:26:02 (14084): Setting CPU throttle for VM. (100%)
2017-11-19 09:26:03 (14084): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2017-11-19 09:30:45 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 09:30:55 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 09:31:47 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 09:31:58 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 09:39:59 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 09:40:09 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 09:51:18 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 09:51:28 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 09:51:39 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 09:51:49 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 09:52:30 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 09:52:40 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 09:52:50 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 09:53:00 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 09:53:10 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 09:53:21 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 09:53:32 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 09:53:42 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 09:53:51 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 09:54:02 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 09:54:11 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 09:54:22 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 09:54:33 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 09:54:43 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 09:54:53 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 09:55:03 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 09:55:13 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 09:55:23 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 09:55:54 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 09:56:04 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 09:56:35 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 09:56:45 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 09:57:35 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 09:57:46 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 10:02:34 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 10:02:45 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 10:02:54 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 10:03:05 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 10:07:02 (14084): VM state change detected. (old = 'Running', new = 'Paused')
2017-11-19 10:07:12 (14084): VM state change detected. (old = 'Paused', new = 'Running')
2017-11-19 10:20:08 (14084): Guest Log: [INFO] Condor exited with return value N/A.

2017-11-19 10:20:08 (14084): Guest Log: [INFO] Shutting Down.

2017-11-19 10:20:08 (14084): VM Completion File Detected.
2017-11-19 10:20:08 (14084): VM Completion Message: Condor exited with return value N/A.
.
2017-11-19 10:20:08 (14084): Powering off VM.
2017-11-19 10:25:09 (14084): VM did not power off when requested.
2017-11-19 10:25:09 (14084): VM was successfully terminated.
2017-11-19 10:25:09 (14084): Deregistering VM. (boinc_cc5064ab865fa2e0, slot#4)
2017-11-19 10:25:09 (14084): Removing network bandwidth throttle group from VM.
2017-11-19 10:25:09 (14084): Removing storage controller(s) from VM.
2017-11-19 10:25:10 (14084): Removing VM from VirtualBox.
2017-11-19 10:25:10 (14084): Removing virtual disk drive from VirtualBox.
10:25:15 (14084): called boinc_finish(0)
20:16:50 (16912): start_timer_thread(): CreateThread() failed, errno 0
20:16:52 (15848): start_timer_thread(): CreateThread() failed, errno 0

</stderr_txt>
]]>