Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
2019-07-20 22:02:56 (1044): vboxwrapper (7.9.26200): starting
2019-07-20 22:02:56 (1044): Feature: Checkpoint interval offset (63 seconds)
2019-07-20 22:02:56 (1044): Detected: VirtualBox COM Interface (Version: 5.2.30)
2019-07-20 22:02:56 (1044): Detected: Minimum checkpoint interval (900.000000 seconds)
2019-07-20 22:02:56 (1044): Successfully copied 'init_data.xml' to the shared directory.
2019-07-20 22:02:56 (1044): Create VM. (boinc_7eb48b916a567825, slot#10)
2019-07-20 22:02:57 (1044): Setting Memory Size for VM. (4800MB)
2019-07-20 22:02:57 (1044): Setting CPU Count for VM. (2)
2019-07-20 22:02:57 (1044): Setting Chipset Options for VM.
2019-07-20 22:02:57 (1044): Setting Boot Options for VM.
2019-07-20 22:02:57 (1044): Enabling VM Network Access.
2019-07-20 22:02:57 (1044): Setting Network Configuration for NAT.
2019-07-20 22:02:57 (1044): Disabling USB Support for VM.
2019-07-20 22:02:57 (1044): Disabling COM Port Support for VM.
2019-07-20 22:02:57 (1044): Disabling LPT Port Support for VM.
2019-07-20 22:02:57 (1044): Disabling Audio Support for VM.
2019-07-20 22:02:57 (1044): Disabling Clipboard Support for VM.
2019-07-20 22:02:57 (1044): Disabling Drag and Drop Support for VM.
2019-07-20 22:02:57 (1044): Adding storage controller(s) to VM.
2019-07-20 22:02:57 (1044): Adding virtual disk drive to VM. (vm_image.vdi)
2019-07-20 22:02:57 (1044): Adding VirtualBox Guest Additions to VM.
2019-07-20 22:02:57 (1044): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-07-20 22:02:57 (1044): forwarding host port 62473 to guest port 80
2019-07-20 22:02:57 (1044): Enabling remote desktop for VM.
2019-07-20 22:02:57 (1044): Enabling shared directory for VM.
2019-07-20 22:02:57 (1044): Starting VM. (boinc_7eb48b916a567825, slot#10)
2019-07-20 22:03:06 (1044): Guest Log: BIOS: VirtualBox 5.2.30

2019-07-20 22:03:06 (1044): Guest Log: CPUID EDX: 0x178bfbff

2019-07-20 22:03:06 (1044): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-07-20 22:03:06 (1044): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-07-20 22:03:06 (1044): Guest Log: BIOS: Booting from Hard Disk...

2019-07-20 22:03:06 (1044): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-07-20 22:03:06 (1044): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-07-20 22:03:06 (1044): Successfully started VM. (PID = '5780')
2019-07-20 22:03:06 (1044): Reporting VM Process ID to BOINC.
2019-07-20 22:03:11 (1044): VM state change detected. (old = 'poweroff', new = 'running')
2019-07-20 22:03:16 (1044): Detected: Web Application Enabled (http://localhost:62473)
2019-07-20 22:03:16 (1044): Detected: Remote Desktop Enabled (localhost:62474)
2019-07-20 22:03:21 (1044): Preference change detected
2019-07-20 22:03:21 (1044): Setting CPU throttle for VM. (100%)
2019-07-20 22:03:21 (1044): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 120 seconds) or (Vbox_job.xml: 900 seconds))
2019-07-20 22:03:26 (1044): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-07-20 22:03:31 (1044): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013eedee10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-20 22:03:31 (1044): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013eee3210), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-20 22:03:31 (1044): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88014036d410), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-20 22:03:31 (1044): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88014036d410), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-20 22:04:01 (1044): Guest Log: Copying input files into RunAtlas.

2019-07-20 22:04:06 (1044): Guest Log: Copied input files into RunAtlas.

2019-07-20 22:04:17 (1044): Guest Log: copied the webapp to /var/www

2019-07-20 22:04:17 (1044): Guest Log: This vm does not need to setup http proxy

2019-07-20 22:04:17 (1044): Guest Log: ATHENA_PROC_NUMBER=2

2019-07-20 22:04:17 (1044): Guest Log: Starting ATLAS job. (Binary file (standard input) matches Binary file (standard input) matches)

2019-07-20 23:43:23 (1044): Status Report: Elapsed Time: '6002.747753'
2019-07-20 23:43:24 (1044): Status Report: CPU Time: '7142.833387'
2019-07-21 01:00:24 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::pause (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1570)
2019-07-21 01:00:25 (1044): Error Source     : ConsoleWrap
2019-07-21 01:00:25 (1044): Error Description: Already paused
2019-07-21 01:00:25 (1044): VM state change detected. (old = 'running', new = 'paused')
2019-07-21 10:40:10 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-07-21 10:40:10 (1044): Error Source     : ConsoleWrap
2019-07-21 10:40:10 (1044): Error Description: Cannot resume the machine as it is not paused (machine state: Running)
2019-07-21 10:40:11 (1044): VM state change detected. (old = 'paused', new = 'running')
2019-07-21 11:03:19 (1044): Status Report: Elapsed Time: '12003.276722'
2019-07-21 11:03:19 (1044): Status Report: CPU Time: '14636.637824'
2019-07-21 12:43:23 (1044): Status Report: Elapsed Time: '18006.598047'
2019-07-21 12:43:23 (1044): Status Report: CPU Time: '14664.655604'
2019-07-21 14:23:25 (1044): Status Report: Elapsed Time: '24008.843737'
2019-07-21 14:23:25 (1044): Status Report: CPU Time: '14692.969785'
2019-07-21 16:03:26 (1044): Status Report: Elapsed Time: '30009.504725'
2019-07-21 16:03:26 (1044): Status Report: CPU Time: '14698.804222'
2019-07-21 17:43:30 (1044): Status Report: Elapsed Time: '36013.342113'
2019-07-21 17:43:30 (1044): Status Report: CPU Time: '14707.384277'
2019-07-21 19:23:34 (1044): Status Report: Elapsed Time: '42017.736576'
2019-07-21 19:23:34 (1044): Status Report: CPU Time: '14720.737963'
2019-07-21 21:03:37 (1044): Status Report: Elapsed Time: '48021.196918'
2019-07-21 21:03:38 (1044): Status Report: CPU Time: '14735.448857'
2019-07-21 22:43:40 (1044): Status Report: Elapsed Time: '54023.536619'
2019-07-21 22:43:40 (1044): Status Report: CPU Time: '14748.162939'
2019-07-21 22:54:52 (1044): Error 0x80bb0003 in vbox52::VBOX_VM::pause (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1570)
2019-07-21 22:54:52 (1044): Error Source     : ConsoleWrap
2019-07-21 22:54:52 (1044): Error Description: Could not suspend the machine execution (VERR_VM_INVALID_VM_STATE)
2019-07-21 22:54:52 (1044): VM state change detected. (old = 'running', new = 'paused')
2019-07-22 18:21:39 (1044): Error 0x80bb0003 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-07-22 18:21:39 (1044): Error Source     : ConsoleWrap
2019-07-22 18:21:39 (1044): Error Description: Could not resume the machine execution (VERR_VM_INVALID_VM_STATE)
2019-07-22 18:21:40 (1044): VM state change detected. (old = 'paused', new = 'running')
2019-07-22 19:50:42 (1044): Status Report: Elapsed Time: '60026.054342'
2019-07-22 19:50:42 (1044): Status Report: CPU Time: '14867.940507'
2019-07-22 21:30:46 (1044): Status Report: Elapsed Time: '66030.781347'
2019-07-22 21:30:47 (1044): Status Report: CPU Time: '14999.199748'
2019-07-22 23:10:51 (1044): Status Report: Elapsed Time: '72035.488848'
2019-07-22 23:10:51 (1044): Status Report: CPU Time: '15136.184226'
2019-07-22 23:23:42 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::pause (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1570)
2019-07-22 23:23:43 (1044): Error Source     : ConsoleWrap
2019-07-22 23:23:43 (1044): Error Description: Already paused
2019-07-22 23:23:43 (1044): VM state change detected. (old = 'running', new = 'paused')
2019-07-23 20:26:54 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-07-23 20:26:55 (1044): Error Source     : ConsoleWrap
2019-07-23 20:26:55 (1044): Error Description: VM is paused due to host power management
2019-07-23 20:27:02 (1044): VM state change detected. (old = 'paused', new = 'running')
2019-07-23 20:28:15 (1044): VM state change detected. (old = 'running', new = 'paused')
2019-07-23 20:49:41 (1044): VM state change detected. (old = 'paused', new = 'running')
2019-07-23 22:15:49 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::pause (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1570)
2019-07-23 22:15:49 (1044): Error Source     : ConsoleWrap
2019-07-23 22:15:49 (1044): Error Description: Already paused
2019-07-23 22:15:49 (1044): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 19:30:42 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-07-24 19:30:42 (1044): Error Source     : ConsoleWrap
2019-07-24 19:30:43 (1044): Error Description: Cannot resume the machine as it is not paused (machine state: Running)
2019-07-24 19:30:45 (1044): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 19:30:51 (1044): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 21:57:12 (1044): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 21:57:22 (1044): Status Report: Elapsed Time: '78037.073452'
2019-07-24 21:57:22 (1044): Status Report: CPU Time: '15263.028639'
2019-07-24 21:59:07 (1044): VM state change detected. (old = 'running', new = 'paused')
2019-07-25 18:16:09 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-07-25 18:16:09 (1044): Error Source     : ConsoleWrap
2019-07-25 18:16:09 (1044): Error Description: Cannot resume the machine as it is not paused (machine state: Running)
2019-07-25 18:16:11 (1044): VM state change detected. (old = 'paused', new = 'running')
2019-07-25 19:54:29 (1044): Status Report: Elapsed Time: '84040.585300'
2019-07-25 19:54:29 (1044): Status Report: CPU Time: '15320.249806'
2019-07-25 21:34:34 (1044): Status Report: Elapsed Time: '90045.392313'
2019-07-25 21:34:34 (1044): Status Report: CPU Time: '15386.331830'
2019-07-25 22:37:20 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::pause (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1570)
2019-07-25 22:37:20 (1044): Error Source     : ConsoleWrap
2019-07-25 22:37:20 (1044): Error Description: Already paused
2019-07-25 22:37:20 (1044): VM state change detected. (old = 'running', new = 'paused')
2019-07-26 19:04:35 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-07-26 19:04:35 (1044): Error Source     : ConsoleWrap
2019-07-26 19:04:35 (1044): Error Description: Cannot resume the machine as it is not paused (machine state: Running)
2019-07-26 19:04:36 (1044): VM state change detected. (old = 'paused', new = 'running')
2019-07-26 19:41:59 (1044): Status Report: Elapsed Time: '96046.833900'
2019-07-26 19:41:59 (1044): Status Report: CPU Time: '15439.590571'
2019-07-26 21:22:04 (1044): Status Report: Elapsed Time: '102051.776430'
2019-07-26 21:22:04 (1044): Status Report: CPU Time: '15502.068972'
2019-07-26 23:02:09 (1044): Status Report: Elapsed Time: '108056.657455'
2019-07-26 23:02:09 (1044): Status Report: CPU Time: '15572.472223'
2019-07-26 23:24:46 (1044): Error 0x80bb0003 in vbox52::VBOX_VM::pause (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1570)
2019-07-26 23:24:46 (1044): Error Source     : ConsoleWrap
2019-07-26 23:24:46 (1044): Error Description: Could not suspend the machine execution (VERR_VM_INVALID_VM_STATE)
2019-07-26 23:24:46 (1044): VM state change detected. (old = 'running', new = 'paused')
2019-07-28 11:09:48 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-07-28 11:09:48 (1044): Error Source     : ConsoleWrap
2019-07-28 11:09:48 (1044): Error Description: Cannot resume the machine as it is not paused (machine state: Running)
2019-07-28 11:09:49 (1044): VM state change detected. (old = 'paused', new = 'running')
2019-07-28 12:27:18 (1044): Status Report: Elapsed Time: '114057.276381'
2019-07-28 12:27:18 (1044): Status Report: CPU Time: '15632.267406'
2019-07-28 14:07:18 (1044): Status Report: Elapsed Time: '120057.330288'
2019-07-28 14:07:18 (1044): Status Report: CPU Time: '15692.343391'
2019-07-28 15:47:19 (1044): Status Report: Elapsed Time: '126057.831757'
2019-07-28 15:47:19 (1044): Status Report: CPU Time: '15766.272265'
2019-07-28 17:27:19 (1044): Status Report: Elapsed Time: '132058.219709'
2019-07-28 17:27:19 (1044): Status Report: CPU Time: '15846.425579'
2019-07-28 19:07:20 (1044): Status Report: Elapsed Time: '138058.759178'
2019-07-28 19:07:20 (1044): Status Report: CPU Time: '15947.779429'
2019-07-28 20:47:21 (1044): Status Report: Elapsed Time: '144059.696201'
2019-07-28 20:47:21 (1044): Status Report: CPU Time: '16063.485370'
2019-07-28 22:27:21 (1044): Status Report: Elapsed Time: '150059.939135'
2019-07-28 22:27:21 (1044): Status Report: CPU Time: '16162.514805'
2019-07-28 23:51:10 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::pause (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1570)
2019-07-28 23:51:10 (1044): Error Source     : ConsoleWrap
2019-07-28 23:51:10 (1044): Error Description: Already paused
2019-07-28 23:51:10 (1044): VM state change detected. (old = 'running', new = 'paused')
2019-07-29 18:46:17 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-07-29 18:46:17 (1044): Error Source     : ConsoleWrap
2019-07-29 18:46:17 (1044): Error Description: Cannot resume the machine as it is not paused (machine state: Running)
2019-07-29 18:46:21 (1044): VM state change detected. (old = 'paused', new = 'running')
2019-07-29 19:02:45 (1044): Status Report: Elapsed Time: '156063.810030'
2019-07-29 19:02:45 (1044): Status Report: CPU Time: '16255.116999'
2019-07-29 20:42:46 (1044): Status Report: Elapsed Time: '162065.026091'
2019-07-29 20:42:46 (1044): Status Report: CPU Time: '16366.127310'
2019-07-29 22:22:47 (1044): Status Report: Elapsed Time: '168066.106630'
2019-07-29 22:22:48 (1044): Status Report: CPU Time: '16459.883911'
2019-07-29 23:19:49 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::pause (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1570)
2019-07-29 23:19:51 (1044): Error Source     : ConsoleWrap
2019-07-29 23:19:51 (1044): Error Description: Already paused
2019-07-29 23:19:51 (1044): VM state change detected. (old = 'running', new = 'paused')
2019-07-30 19:29:18 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-07-30 19:29:18 (1044): Error Source     : ConsoleWrap
2019-07-30 19:29:18 (1044): Error Description: Cannot resume the machine as it is not paused (machine state: Running)
2019-07-30 19:29:19 (1044): VM state change detected. (old = 'paused', new = 'running')
2019-07-30 20:12:34 (1044): Status Report: Elapsed Time: '174068.376322'
2019-07-30 20:12:34 (1044): Status Report: CPU Time: '16569.225012'
2019-07-30 21:52:35 (1044): Status Report: Elapsed Time: '180069.886417'
2019-07-30 21:52:35 (1044): Status Report: CPU Time: '16640.751471'
2019-07-30 23:13:33 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::pause (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1570)
2019-07-30 23:13:33 (1044): Error Source     : ConsoleWrap
2019-07-30 23:13:33 (1044): Error Description: Already paused
2019-07-30 23:13:33 (1044): VM state change detected. (old = 'running', new = 'paused')
2019-07-31 19:49:14 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-07-31 19:49:14 (1044): Error Source     : ConsoleWrap
2019-07-31 19:49:14 (1044): Error Description: VM is paused due to host power management
2019-07-31 19:49:15 (1044): VM state change detected. (old = 'paused', new = 'running')
2019-07-31 20:08:34 (1044): Status Report: Elapsed Time: '186073.319756'
2019-07-31 20:08:34 (1044): Status Report: CPU Time: '16698.752643'
2019-07-31 21:48:37 (1044): Status Report: Elapsed Time: '192076.792099'
2019-07-31 21:48:37 (1044): Status Report: CPU Time: '16750.404574'
2019-07-31 22:45:48 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::pause (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1570)
2019-07-31 22:45:48 (1044): Error Source     : ConsoleWrap
2019-07-31 22:45:48 (1044): Error Description: Already paused
2019-07-31 22:45:48 (1044): VM state change detected. (old = 'running', new = 'paused')
2019-08-01 19:06:21 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-08-01 19:06:21 (1044): Error Source     : ConsoleWrap
2019-08-01 19:06:21 (1044): Error Description: Cannot resume the machine as it is not paused (machine state: Running)
2019-08-01 19:06:24 (1044): VM state change detected. (old = 'paused', new = 'running')
2019-08-01 19:49:31 (1044): Status Report: Elapsed Time: '198080.483971'
2019-08-01 19:49:31 (1044): Status Report: CPU Time: '16813.600579'
2019-08-01 21:29:31 (1044): Status Report: Elapsed Time: '204080.941936'
2019-08-01 21:29:31 (1044): Status Report: CPU Time: '16860.166877'
2019-08-01 23:07:00 (1044): VM state change detected. (old = 'running', new = 'paused')
2019-08-01 23:07:11 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-08-01 23:07:11 (1044): Error Source     : ConsoleWrap
2019-08-01 23:07:11 (1044): Error Description: VM is paused due to host power management
2019-08-01 23:07:16 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-08-01 23:07:16 (1044): Error Source     : ConsoleWrap
2019-08-01 23:07:16 (1044): Error Description: VM is paused due to host power management
2019-08-01 23:09:16 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-08-01 23:09:16 (1044): Error Source     : ConsoleWrap
2019-08-01 23:09:16 (1044): Error Description: Cannot resume the machine as it is not paused (machine state: Running)
2019-08-01 23:09:17 (1044): VM state change detected. (old = 'paused', new = 'running')
2019-08-01 23:11:49 (1044): Status Report: Elapsed Time: '210081.785444'
2019-08-01 23:11:49 (1044): Status Report: CPU Time: '16920.726466'
2019-08-02 00:51:49 (1044): Status Report: Elapsed Time: '216082.050381'
2019-08-02 00:51:49 (1044): Status Report: CPU Time: '16982.315660'
2019-08-02 02:31:53 (1044): Status Report: Elapsed Time: '222086.338829'
2019-08-02 02:31:53 (1044): Status Report: CPU Time: '17039.037624'
2019-08-02 04:11:53 (1044): Status Report: Elapsed Time: '228086.339731'
2019-08-02 04:11:53 (1044): Status Report: CPU Time: '17102.171229'
2019-08-02 05:51:54 (1044): Status Report: Elapsed Time: '234087.238749'
2019-08-02 05:51:54 (1044): Status Report: CPU Time: '17152.933954'
2019-08-02 06:46:36 (1044): Error 0x80bb0003 in vbox52::VBOX_VM::pause (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1570)
2019-08-02 06:46:36 (1044): Error Source     : ConsoleWrap
2019-08-02 06:46:36 (1044): Error Description: Could not suspend the machine execution (VERR_VM_INVALID_VM_STATE)
2019-08-02 06:46:36 (1044): VM state change detected. (old = 'running', new = 'paused')
2019-08-02 18:44:40 (1044): Error 0x80bb0002 in vbox52::VBOX_VM::resume (c:\users\david\documents\boinc_git\boinc\samples\vboxwrapper\vbox_mscom_impl.cpp:1599)
2019-08-02 18:44:40 (1044): Error Source     : ConsoleWrap
2019-08-02 18:44:40 (1044): Error Description: Cannot resume the machine as it is not paused (machine state: Running)
2019-08-02 18:44:41 (1044): VM state change detected. (old = 'paused', new = 'running')
2019-08-02 19:30:09 (1044): Status Report: Elapsed Time: '240088.644329'
2019-08-02 19:30:09 (1044): Status Report: CPU Time: '17206.364297'
2019-08-02 21:10:10 (1044): Status Report: Elapsed Time: '246089.708868'
2019-08-02 21:10:10 (1044): Status Report: CPU Time: '17263.554263'
2019-08-02 22:50:11 (1044): Status Report: Elapsed Time: '252090.519875'
2019-08-02 22:50:11 (1044): Status Report: CPU Time: '17319.527422'
2019-08-02 23:12:26 (3720): vboxwrapper (7.9.26200): starting
2019-08-02 23:12:29 (3720): Feature: Checkpoint interval offset (223 seconds)
2019-08-02 23:12:29 (3720): Detected: VirtualBox COM Interface (Version: 5.2.30)
2019-08-02 23:12:29 (3720): Detected: Minimum checkpoint interval (900.000000 seconds)
2019-08-02 23:12:29 (3720): Guest Log: BIOS: VirtualBox 5.2.30

2019-08-02 23:12:29 (3720): Guest Log: CPUID EDX: 0x178bfbff

2019-08-02 23:12:29 (3720): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-08-02 23:12:29 (3720): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-08-02 23:12:29 (3720): Guest Log: BIOS: Booting from Hard Disk...

2019-08-02 23:12:29 (3720): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-08-02 23:12:29 (3720): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-08-02 23:12:29 (3720): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-08-02 23:12:29 (3720): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013eedee10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-02 23:12:29 (3720): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013eee3210), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-02 23:12:29 (3720): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88014036d410), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-02 23:12:29 (3720): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88014036d410), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-02 23:12:29 (3720): Guest Log: Copying input files into RunAtlas.

2019-08-02 23:12:29 (3720): Guest Log: Copied input files into RunAtlas.

2019-08-02 23:12:29 (3720): Guest Log: copied the webapp to /var/www

2019-08-02 23:12:29 (3720): Guest Log: This vm does not need to setup http proxy

2019-08-02 23:12:29 (3720): Guest Log: ATHENA_PROC_NUMBER=2

2019-08-02 23:12:29 (3720): Guest Log: Starting ATLAS job. (Binary file (standard input) matches Binary file (standard input) matches)

2019-08-02 23:12:29 (3720): Starting VM. (boinc_7eb48b916a567825, slot#10)
2019-08-02 23:13:01 (3720): Successfully started VM. (PID = '1224')
2019-08-02 23:13:01 (3720): Reporting VM Process ID to BOINC.
2019-08-02 23:13:06 (3720): VM state change detected. (old = 'poweroff', new = 'running')
2019-08-02 23:13:11 (3720): Detected: Web Application Enabled (http://localhost:62473)
2019-08-02 23:13:11 (3720): Detected: Remote Desktop Enabled (localhost:62474)
2019-08-02 23:13:16 (3720): Status Report: Elapsed Time: '252994.495167'
2019-08-02 23:13:16 (3720): Status Report: CPU Time: '17332.257104'
2019-08-02 23:13:16 (3720): Preference change detected
2019-08-02 23:13:16 (3720): Setting CPU throttle for VM. (100%)
2019-08-02 23:13:16 (3720): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 120 seconds) or (Vbox_job.xml: 900 seconds))
2019-08-02 23:15:41 (3720): Guest Log: Copying input files into RunAtlas.

2019-08-02 23:15:51 (3720): Guest Log: Copied input files into RunAtlas.

2019-08-02 23:15:56 (3720): Guest Log: copied the webapp to /var/www

2019-08-02 23:15:56 (3720): Guest Log: This vm does not need to setup http proxy

2019-08-02 23:15:56 (3720): Guest Log: ATHENA_PROC_NUMBER=2

2019-08-02 23:15:56 (3720): Guest Log: Starting ATLAS job. (Binary file (standard input) matches Binary file (standard input) matches)

2019-08-02 23:30:07 (3720): Preference change detected
2019-08-02 23:30:07 (3720): Setting CPU throttle for VM. (100%)
2019-08-02 23:30:08 (3720): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 120 seconds) or (Vbox_job.xml: 900 seconds))
2019-08-03 00:53:17 (3720): Status Report: Elapsed Time: '258995.772734'
2019-08-03 00:53:17 (3720): Status Report: CPU Time: '28426.065418'
2019-08-03 02:33:20 (3720): Status Report: Elapsed Time: '264999.149065'
2019-08-03 02:33:20 (3720): Status Report: CPU Time: '40426.910346'
2019-08-03 04:13:22 (3720): Status Report: Elapsed Time: '271000.347620'
2019-08-03 04:13:22 (3720): Status Report: CPU Time: '52410.314362'
2019-08-03 04:37:07 (3720): Guest Log: The last 10 lines of the pilot log.

2019-08-03 04:37:08 (3720): Guest Log: -rw-------  1 atlas01 atlas01 11357 Jun  3 19:18 LICENSE

2019-08-03 04:37:08 (3720): Guest Log: drwx------ 14 atlas01 atlas01  4096 Aug  2 23:16 pilot

2019-08-03 04:37:08 (3720): Guest Log: -rwx------  1 atlas01 atlas01 21428 Jul 11 17:38 pilot.py

2019-08-03 04:37:08 (3720): Guest Log: -rw-------  1 atlas01 atlas01     8 Jul 17 19:00 PILOTVERSION

2019-08-03 04:37:08 (3720): Guest Log: -rw-------  1 atlas01 atlas01  2251 Jun  3 19:18 README.md

2019-08-03 04:37:08 (3720): Guest Log: -rw-------  1 atlas01 atlas01   221 Jun  3 19:18 TODO.md

2019-08-03 04:37:08 (3720): Guest Log: 2019-08-03 02:37:32 UTC [wrapper] Test setup, not cleaning

2019-08-03 04:37:08 (3720): Guest Log: 2019-08-03 02:37:32 UTC [wrapper] ==== wrapper stdout END ====

2019-08-03 04:37:08 (3720): Guest Log: 2019-08-03 02:37:32 UTC [wrapper] ==== wrapper stderr END ====

2019-08-03 04:37:08 (3720): Guest Log: Listing of results directory

2019-08-03 04:37:08 (3720): Guest Log: total 503324

2019-08-03 04:37:08 (3720): Guest Log: drwx------ 5 atlas01 atlas01      4096 Jul 17 19:00 pilot2

2019-08-03 04:37:08 (3720): Guest Log: -rw-r--r-- 1 atlas01 atlas01   4118444 Jul 20 14:29 pilot2.tar.gz

2019-08-03 04:37:08 (3720): Guest Log: -rw-r--r-- 1 atlas01 atlas01      4468 Jul 20 19:20 queuedata.json

2019-08-03 04:37:08 (3720): Guest Log: -rw------- 1 atlas01 atlas01      2887 Jul 20 19:23 pandaJobData.out

2019-08-03 04:37:08 (3720): Guest Log: -rwx------ 1 atlas01 atlas01     15232 Jul 20 19:23 runpilot2-wrapper.sh

2019-08-03 04:37:08 (3720): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 379182807 Aug  2 23:16 EVNT.17323836._000470.pool.root.1

2019-08-03 04:37:08 (3720): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      5984 Aug  2 23:16 init_data.xml

2019-08-03 04:37:08 (3720): Guest Log: -rwxr-xr-x 1 atlas01 atlas01   4128163 Aug  2 23:16 input.tar.gz

2019-08-03 04:37:08 (3720): Guest Log: -rwxr-xr-x 1 atlas01 atlas01       815 Aug  2 23:16 RTE.tar.gz

2019-08-03 04:37:08 (3720): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8659 Aug  2 23:16 start_atlas.sh

2019-08-03 04:37:08 (3720): Guest Log: drwxr-xr-x 3 atlas01 atlas01      4096 Aug  2 23:16 APPS

2019-08-03 04:37:08 (3720): Guest Log: -rw------- 1 atlas01 atlas01   4564160 Aug  2 23:16 agis_schedconf.cvmfs.json

2019-08-03 04:37:08 (3720): Guest Log: -rw------- 1 atlas01 atlas01   7400801 Aug  2 23:16 agis_ddmendpoints.json

2019-08-03 04:37:08 (3720): Guest Log: -rw------- 1 atlas01 atlas01 110552444 Aug  3 04:34 HITS.18605005._018582.pool.root.1

2019-08-03 04:37:08 (3720): Guest Log: -rw------- 1 atlas01 atlas01    593111 Aug  3 04:36 log.18605005._018582.job.log.tgz.1

2019-08-03 04:37:08 (3720): Guest Log: drwxrwx--- 3 atlas01 atlas01      4096 Aug  3 04:36 PanDA_Pilot-4422243594

2019-08-03 04:37:08 (3720): Guest Log: -rw------- 1 atlas01 atlas01     57358 Aug  3 04:36 heartbeat.json

2019-08-03 04:37:08 (3720): Guest Log: -rw------- 1 atlas01 atlas01    994246 Aug  3 04:37 pilotlog.txt

2019-08-03 04:37:08 (3720): Guest Log: -rw------- 1 atlas01 atlas01   1010823 Aug  3 04:37 log.18605005._018582.job.log.1

2019-08-03 04:37:08 (3720): Guest Log: -rw------- 1 atlas01 atlas01       502 Aug  3 04:37 gVqNDmLG88unShfckohDCDFpABFKDmABFKDmd3FbDmABFKDm1gbM4n.diag

2019-08-03 04:37:08 (3720): Guest Log: -rw-r--r-- 1 atlas01 atlas01      6796 Aug  3 04:37 runtime_log.err

2019-08-03 04:37:08 (3720): Guest Log: -rw------- 1 atlas01 atlas01       391 Aug  3 04:37 output.list

2019-08-03 04:37:08 (3720): Guest Log: -rw-r--r-- 1 atlas01 atlas01       692 Aug  3 04:37 runtime_log

2019-08-03 04:37:08 (3720): Guest Log: -rw------- 1 atlas01 atlas01   2682880 Aug  3 04:37 result.tar.gz

2019-08-03 04:37:08 (3720): Guest Log: HITS file was successfully produced

2019-08-03 04:37:08 (3720): Guest Log: -rw------- 1 atlas01 atlas01 110552444 Aug  3 04:34 /home/atlas01/RunAtlas/HITS.18605005._018582.pool.root.1

2019-08-03 04:37:08 (3720): Guest Log: Successfully finished the ATLAS job!

2019-08-03 04:37:08 (3720): Guest Log: Copying the results back to the shared directory!

2019-08-03 04:37:08 (3720): Guest Log: Copied the result file back to the shared directory and created atlas_done file!

2019-08-03 04:37:08 (3720): Guest Log: Success! Shutting down the machine.

2019-08-03 04:37:08 (3720): VM Completion File Detected.
2019-08-03 04:37:08 (3720): Powering off VM.
2019-08-03 04:37:25 (3720): Successfully stopped VM.
2019-08-03 04:37:30 (3720): Deregistering VM. (boinc_7eb48b916a567825, slot#10)
2019-08-03 04:37:30 (3720): Removing virtual disk drive(s) from VM.
2019-08-03 04:37:31 (3720): Removing network bandwidth throttle group from VM.
2019-08-03 04:37:31 (3720): Removing storage controller(s) from VM.
2019-08-03 04:37:31 (3720): Removing VM from VirtualBox.
04:37:37 (3720): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN