Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
2019-11-12 07:30:37 (2687): vboxwrapper (7.7.26196): starting
2019-11-12 07:30:37 (2687): Feature: Checkpoint interval offset (115 seconds)
2019-11-12 07:30:37 (2687): Detected: VirtualBox VboxManage Interface (Version: 5.2.32)
2019-11-12 07:30:37 (2687): VBOX_JOB::parse(): unexpected text enable_screenshots_on_error/
2019-11-12 07:30:37 (2687): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-11-12 07:30:37 (2687): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-11-12 07:30:37 (2687): Successfully copied 'init_data.xml' to the shared directory.
2019-11-12 07:30:38 (2687): Create VM. (boinc_99ce971a8ceefee3, slot#17)
2019-11-12 07:30:38 (2687): Setting Memory Size for VM. (1500MB)
2019-11-12 07:30:38 (2687): Setting CPU Count for VM. (1)
2019-11-12 07:30:38 (2687): Setting Chipset Options for VM.
2019-11-12 07:30:38 (2687): Setting Boot Options for VM.
2019-11-12 07:30:38 (2687): Setting Network Configuration for NAT.
2019-11-12 07:30:38 (2687): Enabling VM Network Access.
2019-11-12 07:30:38 (2687): Disabling USB Support for VM.
2019-11-12 07:30:38 (2687): Disabling COM Port Support for VM.
2019-11-12 07:30:39 (2687): Disabling LPT Port Support for VM.
2019-11-12 07:30:39 (2687): Disabling Audio Support for VM.
2019-11-12 07:30:39 (2687): Disabling Clipboard Support for VM.
2019-11-12 07:30:39 (2687): Disabling Drag and Drop Support for VM.
2019-11-12 07:30:39 (2687): Adding storage controller(s) to VM.
2019-11-12 07:30:39 (2687): Adding virtual disk drive to VM. (vm_image.vdi)
2019-11-12 07:30:39 (2687): Adding VirtualBox Guest Additions to VM.
2019-11-12 07:30:39 (2687): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-11-12 07:30:39 (2687): forwarding host port 45173 to guest port 80
2019-11-12 07:30:39 (2687): Enabling remote desktop for VM.
2019-11-12 07:30:39 (2687): Enabling shared directory for VM.
2019-11-12 07:30:40 (2687): Starting VM. (boinc_99ce971a8ceefee3, slot#17)
2019-11-12 07:30:41 (2687): Successfully started VM. (PID = '3495')
2019-11-12 07:30:41 (2687): Reporting VM Process ID to BOINC.
2019-11-12 07:30:41 (2687): Guest Log: BIOS: VirtualBox 5.2.32
2019-11-12 07:30:41 (2687): Guest Log: CPUID EDX: 0x078bfbff
2019-11-12 07:30:41 (2687): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2019-11-12 07:30:41 (2687): VM state change detected. (old = 'poweroff', new = 'running')
2019-11-12 07:30:41 (2687): Detected: Web Application Enabled (http://localhost:45173)
2019-11-12 07:30:41 (2687): Detected: Remote Desktop Enabled (localhost:51765)
2019-11-12 07:30:42 (2687): Preference change detected
2019-11-12 07:30:42 (2687): Setting CPU throttle for VM. (100%)
2019-11-12 07:30:42 (2687): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-11-12 07:30:43 (2687): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2019-11-12 07:30:43 (2687): Guest Log: BIOS: Booting from Hard Disk...
2019-11-12 07:30:48 (2687): Guest Log: BIOS: KBD: unsupported int 16h function 03
2019-11-12 07:30:48 (2687): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2019-11-12 07:31:07 (2687): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2019-11-12 07:31:07 (2687): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2019-11-12 07:31:43 (2687): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2019-11-12 07:31:43 (2687): Guest Log: 00:00:00.000188 main     Log opened 2019-11-12T07:31:42.624085000Z
2019-11-12 07:31:43 (2687): Guest Log: 00:00:00.000516 main     OS Product: Linux
2019-11-12 07:31:43 (2687): Guest Log: 00:00:00.000559 main     OS Release: 4.14.44-11.cernvm.x86_64
2019-11-12 07:31:43 (2687): Guest Log: 00:00:00.000586 main     OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018
2019-11-12 07:31:43 (2687): Guest Log: 00:00:00.000611 main     Executable: /usr/share/vboxguest52/usr/sbin/VBoxService
2019-11-12 07:31:43 (2687): Guest Log: 00:00:00.000612 main     Process ID: 2956
2019-11-12 07:31:43 (2687): Guest Log: 00:00:00.000612 main     Package type: LINUX_64BITS_GENERIC
2019-11-12 07:31:43 (2687): Guest Log: 00:00:00.002014 main     5.2.6 r120293 started. Verbose level = 0
2019-11-12 07:32:01 (2687): Guest Log: [INFO] Mounting the shared directory
2019-11-12 07:32:01 (2687): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2019-11-12 07:32:01 (2687): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80
2019-11-12 07:32:01 (2687): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded!
2019-11-12 07:32:01 (2687): Guest Log: [DEBUG] 0
2019-11-12 07:32:01 (2687): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443
2019-11-12 07:32:01 (2687): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded!
2019-11-12 07:32:01 (2687): Guest Log: [DEBUG] 0
2019-11-12 07:32:01 (2687): Guest Log: [DEBUG] Testing connection to Condor server on port 9618
2019-11-12 07:32:01 (2687): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded!
2019-11-12 07:32:02 (2687): Guest Log: [DEBUG] 0
2019-11-12 07:33:06 (2687): Guest Log: [DEBUG] Probing CVMFS ...
2019-11-12 07:33:06 (2687): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2019-11-12 07:33:07 (2687): 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
2019-11-12 07:33:07 (2687): Guest Log: 2.4.4.0 3675 1 25840 10719 3 1 318381 10240000 2 65024 0 3 100 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2019-11-12 07:33:13 (2687): Guest Log: [INFO] Reading volunteer information
2019-11-12 07:33:13 (2687): Guest Log: [INFO] Volunteer: PRHumphrey (21541)
2019-11-12 07:33:13 (2687): Guest Log: [INFO] VMID: e5b01749-9626-457a-8945-16c64690aa2f
2019-11-12 07:33:13 (2687): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2019-11-12 07:33:17 (2687): Guest Log: [INFO] Running the fast benchmark.
2019-11-12 07:34:08 (2687): Guest Log: [INFO] Machine performance 9.55 HEPSPEC06
2019-11-12 07:34:08 (2687): Guest Log: [INFO] Theory application starting. Check log files.
2019-11-12 07:34:08 (2687): Guest Log: [DEBUG] HTCondor ping
2019-11-12 07:34:08 (2687): Guest Log: [DEBUG] 0
2019-11-12 07:35:01 (2687): Guest Log: [INFO] New Job Starting in slot1
2019-11-12 07:35:01 (2687): Guest Log: [INFO] Condor JobID:  514840.74 in slot1
2019-11-12 07:35:08 (2687): Guest Log: [INFO] MCPlots JobID: 52077797 in slot1
2019-11-12 07:35:12 (2687): Guest Log: [INFO] ===> [runRivet] Tue Nov 12 07:35:01 GMT 2019 [boinc pp ue 7000 - - sherpa 2.2.5 default 100000 157]
2019-11-12 09:10:42 (2687): Status Report: Job Duration: '129600.000000'
2019-11-12 09:10:42 (2687): Status Report: Elapsed Time: '6002.039247'
2019-11-12 09:10:42 (2687): Status Report: CPU Time: '5604.950000'
2019-11-12 09:27:17 (2687): VM state change detected. (old = 'running', new = 'paused')
2019-11-12 09:31:24 (2687): ERROR: Vboxwrapper lost communication with VirtualBox, rescheduling task for a later time.
2019-11-12 09:31:24 (2687): Powering off VM.
sh: line 1:  8772 Trace/breakpoint trap   VBoxManage -q showvminfo "boinc_99ce971a8ceefee3" --machinereadable 2>&1
2019-11-13 06:45:46 (8584): vboxwrapper (7.7.26196): starting
2019-11-13 06:45:46 (8584): Feature: Checkpoint interval offset (466 seconds)
2019-11-13 06:45:46 (8584): Detected: VirtualBox VboxManage Interface (Version: 5.2.32)
2019-11-13 06:45:46 (8584): VBOX_JOB::parse(): unexpected text enable_screenshots_on_error/
2019-11-13 06:45:46 (8584): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-11-13 06:45:46 (8584): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-11-13 06:45:46 (8584): Guest Log: BIOS: VirtualBox 5.2.32
2019-11-13 06:45:46 (8584): Guest Log: CPUID EDX: 0x078bfbff
2019-11-13 06:45:46 (8584): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63
2019-11-13 06:45:46 (8584): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032
2019-11-13 06:45:46 (8584): Guest Log: BIOS: Booting from Hard Disk...
2019-11-13 06:45:46 (8584): Guest Log: BIOS: KBD: unsupported int 16h function 03
2019-11-13 06:45:46 (8584): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 
2019-11-13 06:45:46 (8584): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds
2019-11-13 06:45:46 (8584): Guest Log: vboxguest: misc device minor 56, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)
2019-11-13 06:45:46 (8584): Guest Log: VBoxService 5.2.6 r120293 (verbosity: 0) linux.amd64 (Jan 15 2018 14:51:00) release log
2019-11-13 06:45:46 (8584): Guest Log: 00:00:00.000188 main     Log opened 2019-11-12T07:31:42.624085000Z
2019-11-13 06:45:46 (8584): Guest Log: 00:00:00.000516 main     OS Product: Linux
2019-11-13 06:45:46 (8584): Guest Log: 00:00:00.000559 main     OS Release: 4.14.44-11.cernvm.x86_64
2019-11-13 06:45:46 (8584): Guest Log: 00:00:00.000586 main     OS Version: #1 SMP Mon May 28 16:49:00 CEST 2018
2019-11-13 06:45:46 (8584): Guest Log: 00:00:00.000611 main     Executable: /usr/share/vboxguest52/usr/sbin/VBoxService
2019-11-13 06:45:46 (8584): Guest Log: 00:00:00.000612 main     Process ID: 2956
2019-11-13 06:45:46 (8584): Guest Log: 00:00:00.000612 main     Package type: LINUX_64BITS_GENERIC
2019-11-13 06:45:46 (8584): Guest Log: 00:00:00.002014 main     5.2.6 r120293 started. Verbose level = 0
2019-11-13 06:45:46 (8584): Guest Log: [INFO] Mounting the shared directory
2019-11-13 06:45:46 (8584): Guest Log: [INFO] Shared directory mounted, enabling vboxmonitor
2019-11-13 06:45:46 (8584): Guest Log: [DEBUG] Testing network connection to cern.ch on port 80
2019-11-13 06:45:46 (8584): Guest Log: [DEBUG] Connection to cern.ch 80 port [tcp/http] succeeded!
2019-11-13 06:45:46 (8584): Guest Log: [DEBUG] 0
2019-11-13 06:45:46 (8584): Guest Log: [DEBUG] Testing VCCS connection to vccs.cern.ch on port 443
2019-11-13 06:45:46 (8584): Guest Log: [DEBUG] Connection to vccs.cern.ch 443 port [tcp/https] succeeded!
2019-11-13 06:45:46 (8584): Guest Log: [DEBUG] 0
2019-11-13 06:45:46 (8584): Guest Log: [DEBUG] Testing connection to Condor server on port 9618
2019-11-13 06:45:46 (8584): Guest Log: [DEBUG] Connection to vccondor01.cern.ch 9618 port [tcp/condor] succeeded!
2019-11-13 06:45:46 (8584): Guest Log: [DEBUG] 0
2019-11-13 06:45:46 (8584): Guest Log: [DEBUG] Probing CVMFS ...
2019-11-13 06:45:46 (8584): Guest Log: Probing /cvmfs/grid.cern.ch... OK
2019-11-13 06:45:46 (8584): 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
2019-11-13 06:45:46 (8584): Guest Log: 2.4.4.0 3675 1 25840 10719 3 1 318381 10240000 2 65024 0 3 100 0 0 http://s1ral-cvmfs.openhtc.io/cvmfs/grid.cern.ch DIRECT 1
2019-11-13 06:45:46 (8584): Guest Log: [INFO] Reading volunteer information
2019-11-13 06:45:46 (8584): Guest Log: [INFO] Volunteer: PRHumphrey (21541)
2019-11-13 06:45:46 (8584): Guest Log: [INFO] VMID: e5b01749-9626-457a-8945-16c64690aa2f
2019-11-13 06:45:46 (8584): Guest Log: [INFO] Requesting an X509 credential from LHC@home
2019-11-13 06:45:46 (8584): Guest Log: [INFO] Running the fast benchmark.
2019-11-13 06:45:46 (8584): Guest Log: [INFO] Machine performance 9.55 HEPSPEC06
2019-11-13 06:45:46 (8584): Guest Log: [INFO] Theory application starting. Check log files.
2019-11-13 06:45:46 (8584): Guest Log: [DEBUG] HTCondor ping
2019-11-13 06:45:46 (8584): Guest Log: [DEBUG] 0
2019-11-13 06:45:46 (8584): Guest Log: [INFO] New Job Starting in slot1
2019-11-13 06:45:46 (8584): Guest Log: [INFO] Condor JobID:  514840.74 in slot1
2019-11-13 06:45:46 (8584): Guest Log: [INFO] MCPlots JobID: 52077797 in slot1
2019-11-13 06:45:46 (8584): Guest Log: [INFO] ===> [runRivet] Tue Nov 12 07:35:01 GMT 2019 [boinc pp ue 7000 - - sherpa 2.2.5 default 100000 157]
2019-11-13 06:45:46 (8584): Starting VM. (boinc_99ce971a8ceefee3, slot#17)
2019-11-13 06:45:48 (8584): Successfully started VM. (PID = '8695')
2019-11-13 06:45:48 (8584): Reporting VM Process ID to BOINC.
2019-11-13 06:45:48 (8584): VM state change detected. (old = 'poweroff', new = 'running')
2019-11-13 06:45:48 (8584): Detected: Web Application Enabled (http://localhost:45173)
2019-11-13 06:45:48 (8584): Detected: Remote Desktop Enabled (localhost:51765)
2019-11-13 06:45:48 (8584): Status Report: Job Duration: '129600.000000'
2019-11-13 06:45:48 (8584): Status Report: Elapsed Time: '6997.046132'
2019-11-13 06:45:48 (8584): Status Report: CPU Time: '6541.910000'
2019-11-13 06:45:48 (8584): Preference change detected
2019-11-13 06:45:48 (8584): Setting CPU throttle for VM. (100%)
2019-11-13 06:45:48 (8584): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-11-13 08:18:05 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:19:54 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:20:08 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:21:06 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:21:23 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:21:58 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:22:01 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:22:39 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:22:40 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:25:02 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:25:16 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:25:43 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:25:46 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:26:23 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:26:36 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:35:44 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:35:47 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:35:58 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:36:21 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:36:37 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:37:02 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:37:30 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:37:32 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:38:42 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:38:45 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:39:12 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:39:15 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:39:34 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:39:35 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:42:36 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:42:50 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:43:16 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:43:30 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:45:19 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:46:24 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:47:52 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:47:55 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:48:54 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:48:55 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 08:49:24 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 08:52:14 (8584): Status Report: Job Duration: '129600.000000'
2019-11-13 08:52:14 (8584): Status Report: Elapsed Time: '12997.064763'
2019-11-13 08:52:14 (8584): Status Report: CPU Time: '12326.570000'
2019-11-13 09:15:00 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 09:15:18 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 09:15:31 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 09:15:48 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 09:16:02 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 09:16:30 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 09:43:07 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 09:43:35 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 09:43:36 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 09:44:57 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 09:44:59 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 09:45:17 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 09:47:44 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 09:50:02 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 09:51:06 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 09:53:19 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 09:53:21 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 09:53:52 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 09:54:02 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 09:54:23 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 09:54:49 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 09:55:06 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 09:56:49 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 09:57:15 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 09:57:29 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 09:58:49 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 09:59:30 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 10:01:21 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 10:01:45 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 10:03:03 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 10:03:06 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 10:04:25 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 10:04:49 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 10:15:24 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 10:15:36 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 10:16:04 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 10:16:17 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 10:16:34 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 10:16:38 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 10:17:14 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 10:17:18 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 10:18:28 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 10:18:50 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 10:19:19 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 10:19:21 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 10:23:23 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 10:23:40 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 10:23:55 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 10:23:58 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 10:24:25 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 10:25:38 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 10:27:19 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 10:28:52 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 10:32:44 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 10:32:45 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 10:33:43 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 11:11:35 (8584): Status Report: Job Duration: '129600.000000'
2019-11-13 11:11:35 (8584): Status Report: Elapsed Time: '18997.105496'
2019-11-13 11:11:35 (8584): Status Report: CPU Time: '18175.990000'
2019-11-13 11:47:10 (8584): Guest Log: [INFO] Job finished in slot1 with 0.
2019-11-13 11:47:12 (8584): Guest Log: [INFO] New Job Starting in slot1
2019-11-13 11:47:12 (8584): Guest Log: [INFO] Condor JobID:  514782.12 in slot1
2019-11-13 11:47:18 (8584): Guest Log: [INFO] MCPlots JobID: 52068287 in slot1
2019-11-13 11:47:23 (8584): Guest Log: [INFO] ===> [runRivet] Wed Nov 13 11:47:12 GMT 2019 [boinc ppbar mb-inelastic 1800 - - herwig7 7.0.4 default 100000 156]
2019-11-13 12:06:31 (8584): Guest Log: [INFO] Job finished in slot1 with 0.
2019-11-13 12:06:35 (8584): Guest Log: [INFO] New Job Starting in slot1
2019-11-13 12:06:35 (8584): Guest Log: [INFO] Condor JobID:  514772.110 in slot1
2019-11-13 12:06:40 (8584): Guest Log: [INFO] MCPlots JobID: 52066861 in slot1
2019-11-13 12:06:45 (8584): Guest Log: [INFO] ===> [runRivet] Wed Nov 13 12:06:35 GMT 2019 [boinc pp z1j 7000 250 - pythia8 8.165 default 100000 156]
2019-11-13 12:41:41 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 12:41:59 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 12:42:12 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 12:42:29 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 12:45:14 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 12:45:31 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 12:45:34 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 12:45:51 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 12:46:25 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 12:47:02 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 12:47:15 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 12:47:33 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 12:48:47 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 12:49:15 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 12:52:19 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 12:52:37 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 12:53:20 (8584): Status Report: Job Duration: '129600.000000'
2019-11-13 12:53:20 (8584): Status Report: Elapsed Time: '24998.111392'
2019-11-13 12:53:20 (8584): Status Report: CPU Time: '23941.340000'
2019-11-13 12:54:53 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 12:55:20 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 12:56:44 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 12:57:00 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 12:57:04 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 12:57:42 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 12:59:26 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 12:59:42 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 12:59:46 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:00:04 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:01:31 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:02:06 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:02:29 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:03:48 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:04:32 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:05:10 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:07:25 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:07:42 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:07:57 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:08:13 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:10:08 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:10:46 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:11:29 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:11:46 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:12:19 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:12:37 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:14:30 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:14:47 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:15:11 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:15:29 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:15:52 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:16:12 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:16:42 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:16:59 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:17:02 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:17:19 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:17:42 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:18:53 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:19:04 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:19:31 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:20:25 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:21:14 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:22:48 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:23:36 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:24:39 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:25:07 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:26:21 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:26:48 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:27:21 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:27:48 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:28:22 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:29:50 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:30:15 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:30:32 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:30:36 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:31:04 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:31:06 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:33:19 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:33:41 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:34:01 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:34:04 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:35:16 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:35:17 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:35:53 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:40:40 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:40:57 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:41:11 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:41:38 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:41:51 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:42:19 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:42:22 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:43:43 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:43:44 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:44:14 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:44:57 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:45:13 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:46:11 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:46:25 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:47:10 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:47:40 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 13:48:31 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-13 13:49:21 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-13 14:55:54 (8584): Status Report: Job Duration: '129600.000000'
2019-11-13 14:55:54 (8584): Status Report: Elapsed Time: '30999.000449'
2019-11-13 14:55:54 (8584): Status Report: CPU Time: '29677.030000'
2019-11-13 15:08:32 (8584): Guest Log: [INFO] Job finished in slot1 with 0.
2019-11-13 15:08:35 (8584): Guest Log: [INFO] New Job Starting in slot1
2019-11-13 15:08:35 (8584): Guest Log: [INFO] Condor JobID:  514729.32 in slot1
2019-11-13 15:08:40 (8584): Guest Log: [INFO] MCPlots JobID: 52059535 in slot1
2019-11-13 15:08:45 (8584): Guest Log: [INFO] ===> [runRivet] Wed Nov 13 15:08:34 GMT 2019 [boinc pp jets 7000 80,-,1460 - pythia8 8.183 default-CD 100000 156]
2019-11-13 16:34:57 (8584): Status Report: Job Duration: '129600.000000'
2019-11-13 16:34:57 (8584): Status Report: Elapsed Time: '36999.723848'
2019-11-13 16:34:57 (8584): Status Report: CPU Time: '35577.340000'
2019-11-13 16:57:11 (8584): Guest Log: [INFO] Job finished in slot1 with 0.
2019-11-13 16:57:16 (8584): Guest Log: [INFO] New Job Starting in slot1
2019-11-13 16:57:16 (8584): Guest Log: [INFO] Condor JobID:  514788.53 in slot1
2019-11-13 16:57:21 (8584): Guest Log: [INFO] MCPlots JobID: 52069358 in slot1
2019-11-13 16:57:26 (8584): Guest Log: [INFO] ===> [runRivet] Wed Nov 13 16:57:15 GMT 2019 [boinc pp jets 7000 28 - pythia6 6.428 phard 100000 156]
2019-11-13 18:14:01 (8584): Status Report: Job Duration: '129600.000000'
2019-11-13 18:14:01 (8584): Status Report: Elapsed Time: '43000.547044'
2019-11-13 18:14:01 (8584): Status Report: CPU Time: '41496.820000'
2019-11-13 18:14:05 (8584): Guest Log: [INFO] Job finished in slot1 with 0.
2019-11-13 18:14:08 (8584): Guest Log: [INFO] New Job Starting in slot1
2019-11-13 18:14:08 (8584): Guest Log: [INFO] Condor JobID:  514704.82 in slot1
2019-11-13 18:14:13 (8584): Guest Log: [INFO] MCPlots JobID: 52055858 in slot1
2019-11-13 18:14:18 (8584): Guest Log: [INFO] ===> [runRivet] Wed Nov 13 18:14:07 GMT 2019 [boinc pp jets 7000 500 - sherpa 1.4.1 default 24000 156]
2019-11-13 19:53:03 (8584): Status Report: Job Duration: '129600.000000'
2019-11-13 19:53:03 (8584): Status Report: Elapsed Time: '49000.782949'
2019-11-13 19:53:03 (8584): Status Report: CPU Time: '47019.030000'
2019-11-13 21:32:06 (8584): Status Report: Job Duration: '129600.000000'
2019-11-13 21:32:06 (8584): Status Report: Elapsed Time: '55001.438199'
2019-11-13 21:32:06 (8584): Status Report: CPU Time: '52967.630000'
2019-11-13 23:11:08 (8584): Status Report: Job Duration: '129600.000000'
2019-11-13 23:11:08 (8584): Status Report: Elapsed Time: '61002.162142'
2019-11-13 23:11:08 (8584): Status Report: CPU Time: '58917.820000'
2019-11-14 00:50:11 (8584): Status Report: Job Duration: '129600.000000'
2019-11-14 00:50:11 (8584): Status Report: Elapsed Time: '67002.683406'
2019-11-14 00:50:11 (8584): Status Report: CPU Time: '64879.500000'
2019-11-14 02:29:13 (8584): Status Report: Job Duration: '129600.000000'
2019-11-14 02:29:13 (8584): Status Report: Elapsed Time: '73003.315912'
2019-11-14 02:29:13 (8584): Status Report: CPU Time: '70830.380000'
2019-11-14 04:09:10 (8584): Status Report: Job Duration: '129600.000000'
2019-11-14 04:09:10 (8584): Status Report: Elapsed Time: '79004.288739'
2019-11-14 04:09:10 (8584): Status Report: CPU Time: '76677.720000'
2019-11-14 05:49:04 (8584): Status Report: Job Duration: '129600.000000'
2019-11-14 05:49:04 (8584): Status Report: Elapsed Time: '85004.974589'
2019-11-14 05:49:04 (8584): Status Report: CPU Time: '82538.960000'
2019-11-14 07:29:03 (8584): Status Report: Job Duration: '129600.000000'
2019-11-14 07:29:03 (8584): Status Report: Elapsed Time: '91005.527896'
2019-11-14 07:29:03 (8584): Status Report: CPU Time: '88370.100000'
2019-11-14 09:06:19 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-14 09:06:34 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-14 09:06:48 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-14 09:07:14 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-14 09:07:36 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-14 09:08:48 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-14 09:09:05 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-14 09:09:29 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-14 09:09:47 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-14 09:10:01 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-14 09:11:52 (8584): Status Report: Job Duration: '129600.000000'
2019-11-14 09:11:52 (8584): Status Report: Elapsed Time: '97006.233041'
2019-11-14 09:11:52 (8584): Status Report: CPU Time: '94142.670000'
2019-11-14 09:11:55 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-14 09:12:07 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-14 09:16:08 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-14 09:18:10 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-14 09:18:34 (8584): VM state change detected. (old = 'running', new = 'paused')
2019-11-14 09:18:49 (8584): VM state change detected. (old = 'paused', new = 'running')
2019-11-14 10:54:22 (8584): Status Report: Job Duration: '129600.000000'
2019-11-14 10:54:22 (8584): Status Report: Elapsed Time: '103008.199087'
2019-11-14 10:54:22 (8584): Status Report: CPU Time: '99947.010000'
2019-11-14 12:34:19 (8584): Status Report: Job Duration: '129600.000000'
2019-11-14 12:34:19 (8584): Status Report: Elapsed Time: '109009.841816'
2019-11-14 12:34:19 (8584): Status Report: CPU Time: '105814.880000'
2019-11-14 13:05:01 (8584): Stopping VM.
2019-11-14 13:05:06 (8584): Successfully stopped VM.
2019-11-14 15:38:22 (2689): vboxwrapper (7.7.26196): starting
2019-11-14 15:38:22 (2689): Feature: Checkpoint interval offset (132 seconds)
2019-11-14 15:38:22 (2689): Detected: VirtualBox VboxManage Interface (Version: 5.2.32)
2019-11-14 15:38:22 (2689): VBOX_JOB::parse(): unexpected text enable_screenshots_on_error/
2019-11-14 15:38:22 (2689): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-11-14 15:38:22 (2689): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-11-14 15:38:22 (2689): Starting VM. (boinc_99ce971a8ceefee3, slot#17)
2019-11-14 15:38:30 (2689): Successfully started VM. (PID = '2895')
2019-11-14 15:38:30 (2689): Reporting VM Process ID to BOINC.
2019-11-14 15:38:33 (2689): VM state change detected. (old = 'poweroff', new = 'running')
2019-11-14 15:38:33 (2689): Detected: Web Application Enabled (http://localhost:45173)
2019-11-14 15:38:33 (2689): Detected: Remote Desktop Enabled (localhost:51765)
2019-11-14 15:38:35 (2689): Status Report: Job Duration: '129600.000000'
2019-11-14 15:38:35 (2689): Status Report: Elapsed Time: '110853.974825'
2019-11-14 15:38:35 (2689): Status Report: CPU Time: '107613.380000'
2019-11-14 15:38:35 (2689): Preference change detected
2019-11-14 15:38:35 (2689): Setting CPU throttle for VM. (100%)
2019-11-14 15:38:36 (2689): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-11-14 15:38:38 (2689): Guest Log: 28:42:23.281548 timesync vgsvcTimeSyncWorker: Radical host time change: 9 217 692 000 000ns (HostNow=1 573 745 917 035 000 000 ns HostLast=1 573 736 699 343 000 000 ns)
2019-11-14 15:38:47 (2689): Guest Log: 28:42:33.291752 timesync vgsvcTimeSyncWorker: Radical guest time change: 9 505 249 140 000ns (GuestNow=1 573 745 927 045 253 000 ns GuestLast=1 573 736 421 796 113 000 ns fSetTimeLastLoop=true )
2019-11-14 16:33:41 (2689): Stopping VM.
2019-11-14 16:33:46 (2689): Successfully stopped VM.
2019-11-14 18:10:32 (2674): vboxwrapper (7.7.26196): starting
2019-11-14 18:10:32 (2674): Feature: Checkpoint interval offset (139 seconds)
2019-11-14 18:10:32 (2674): Detected: VirtualBox VboxManage Interface (Version: 5.2.32)
2019-11-14 18:10:32 (2674): VBOX_JOB::parse(): unexpected text enable_screenshots_on_error/
2019-11-14 18:10:32 (2674): Detected: Minimum checkpoint interval (600.000000 seconds)
2019-11-14 18:10:32 (2674): Detected: Heartbeat check (file: 'heartbeat' every 1200.000000 seconds)
2019-11-14 18:10:32 (2674): Starting VM. (boinc_99ce971a8ceefee3, slot#17)
2019-11-14 18:10:40 (2674): Successfully started VM. (PID = '2897')
2019-11-14 18:10:40 (2674): Reporting VM Process ID to BOINC.
2019-11-14 18:10:44 (2674): VM state change detected. (old = 'poweroff', new = 'running')
2019-11-14 18:10:44 (2674): Detected: Web Application Enabled (http://localhost:45173)
2019-11-14 18:10:44 (2674): Detected: Remote Desktop Enabled (localhost:51765)
2019-11-14 18:10:47 (2674): Guest Log: 29:37:34.393298 timesync vgsvcTimeSyncWorker: Radical host time change: 5 828 075 000 000ns (HostNow=1 573 755 046 188 000 000 ns HostLast=1 573 749 218 113 000 000 ns)
2019-11-14 18:10:47 (2674): Status Report: Job Duration: '129600.000000'
2019-11-14 18:10:47 (2674): Status Report: Elapsed Time: '114165.497720'
2019-11-14 18:10:47 (2674): Status Report: CPU Time: '110849.210000'
2019-11-14 18:10:47 (2674): Preference change detected
2019-11-14 18:10:47 (2674): Setting CPU throttle for VM. (100%)
2019-11-14 18:10:47 (2674): Setting checkpoint interval to 600 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 600 seconds))
2019-11-14 18:10:58 (2674): Guest Log: 29:37:44.403477 timesync vgsvcTimeSyncWorker: Radical guest time change: 5 828 051 422 000ns (GuestNow=1 573 755 056 198 199 000 ns GuestLast=1 573 749 228 146 777 000 ns fSetTimeLastLoop=true )
2019-11-14 18:25:25 (2674): VM state change detected. (old = 'running', new = 'paused')
2019-11-14 18:25:36 (2674): VM state change detected. (old = 'paused', new = 'running')
2019-11-14 19:50:55 (2674): Status Report: Job Duration: '129600.000000'
2019-11-14 19:50:55 (2674): Status Report: Elapsed Time: '120167.990875'
2019-11-14 19:50:55 (2674): Status Report: CPU Time: '116741.000000'
2019-11-14 21:01:41 (2674): Guest Log: [INFO] Job finished in slot1 with 0.
2019-11-14 21:12:10 (2674): Guest Log: [INFO] Condor exited with return value N/A.
2019-11-14 21:12:10 (2674): Guest Log: [INFO] Shutting Down.
2019-11-14 21:12:10 (2674): VM Completion File Detected.
2019-11-14 21:12:10 (2674): VM Completion Message: Condor exited with return value N/A.
.
2019-11-14 21:12:10 (2674): Powering off VM.
2019-11-14 21:12:11 (2674): Successfully stopped VM.
2019-11-14 21:12:11 (2674): Deregistering VM. (boinc_99ce971a8ceefee3, slot#17)
2019-11-14 21:12:11 (2674): Removing network bandwidth throttle group from VM.
2019-11-14 21:12:11 (2674): Removing storage controller(s) from VM.
2019-11-14 21:12:11 (2674): Removing VM from VirtualBox.
2019-11-14 21:12:11 (2674): Removing virtual disk drive from VirtualBox.
21:12:16 (2674): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN