Name 6MHNDm8YrVvnShfckohDCDFpABFKDmABFKDmeorNDmABFKDmZGJGXn_0
Workunit 1940118
Created 21 Sep 2019, 10:13:15 UTC
Sent 21 Sep 2019, 11:01:02 UTC
Report deadline 28 Sep 2019, 11:01:02 UTC
Received 3 Oct 2019, 12:49:24 UTC
Server state Over
Outcome Success
Client state Done
Exit status 0 (0x00000000)
Computer ID 3626
Run time 2 days 16 hours 15 min 4 sec
CPU time 7 days 8 hours 57 min 23 sec
Validate state Task was reported too late to validate
Credit 0.00
Device peak FLOPS 12.25 GFLOPS
Application version ATLAS Simulation v0.86 (vbox64_mt_mcore_atlas)
windows_x86_64
Peak working set size 103.97 MB
Peak swap size 153.62 MB
Peak disk usage 6.06 GB

Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
2019-09-21 13:06:47 (2216): Detected: vboxwrapper 26197
2019-09-21 13:06:47 (2216): Detected: BOINC client v7.7
2019-09-21 13:06:48 (2216): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-09-21 13:06:48 (2216): Successfully copied 'init_data.xml' to the shared directory.
2019-09-21 13:06:51 (2216): Create VM. (boinc_10d337175ff50470, slot#7)
2019-09-21 13:06:52 (2216): Setting Memory Size for VM. (6600MB)
2019-09-21 13:06:52 (2216): Setting CPU Count for VM. (4)
2019-09-21 13:06:52 (2216): Setting Chipset Options for VM.
2019-09-21 13:06:53 (2216): Setting Boot Options for VM.
2019-09-21 13:06:53 (2216): Setting Network Configuration for NAT.
2019-09-21 13:06:53 (2216): Enabling VM Network Access.
2019-09-21 13:06:54 (2216): Disabling USB Support for VM.
2019-09-21 13:06:54 (2216): Disabling COM Port Support for VM.
2019-09-21 13:06:54 (2216): Disabling LPT Port Support for VM.
2019-09-21 13:06:55 (2216): Disabling Audio Support for VM.
2019-09-21 13:06:55 (2216): Disabling Clipboard Support for VM.
2019-09-21 13:06:55 (2216): Disabling Drag and Drop Support for VM.
2019-09-21 13:06:56 (2216): Adding storage controller(s) to VM.
2019-09-21 13:06:56 (2216): Adding virtual disk drive to VM. (vm_image.vdi)
2019-09-21 13:06:56 (2216): Adding VirtualBox Guest Additions to VM.
2019-09-21 13:06:57 (2216): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-09-21 13:06:57 (2216): forwarding host port 56736 to guest port 80
2019-09-21 13:06:57 (2216): Enabling remote desktop for VM.
2019-09-21 13:06:58 (2216): Enabling shared directory for VM.
2019-09-21 13:06:58 (2216): Starting VM using VBoxManage interface. (boinc_10d337175ff50470, slot#7)
2019-09-21 13:07:06 (2216): Successfully started VM. (PID = '7736')
2019-09-21 13:07:06 (2216): Reporting VM Process ID to BOINC.
2019-09-21 13:07:06 (2216): Guest Log: BIOS: VirtualBox 5.2.8

2019-09-21 13:07:06 (2216): Guest Log: CPUID EDX: 0x178bfbff

2019-09-21 13:07:06 (2216): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

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

2019-09-21 13:07:08 (2216): Guest Log: BIOS: Booting from Hard Disk...

2019-09-21 13:07:11 (2216): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-09-21 13:07:11 (2216): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f

2019-09-21 13:07:11 (2216): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f

2019-09-21 13:07:22 (2216): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2019-09-21 13:07:22 (2216): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-09-21 13:07:29 (2216): Guest Log: Checking CVMFS...

2019-09-21 13:07:46 (2216): Guest Log: CVMFS is ok

2019-09-21 13:07:46 (2216): Guest Log: Mounting shared directory

2019-09-21 13:07:46 (2216): Guest Log: Copying input files

2019-09-21 13:07:46 (2216): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log

2019-09-21 13:07:46 (2216): Guest Log: 00:00:00.000404 main     Log opened 2019-09-21T13:07:44.481110000Z

2019-09-21 13:07:46 (2216): Guest Log: 00:00:00.000808 main     OS Product: Linux

2019-09-21 13:07:46 (2216): Guest Log: 00:00:00.001148 main     OS Release: 3.10.0-957.27.2.el7.x86_64

2019-09-21 13:07:46 (2216): Guest Log: 00:00:00.001235 main     OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019

2019-09-21 13:07:46 (2216): Guest Log: 00:00:00.001454 main     Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService

2019-09-21 13:07:46 (2216): Guest Log: 00:00:00.001457 main     Process ID: 1871

2019-09-21 13:07:46 (2216): Guest Log: 00:00:00.001459 main     Package type: LINUX_64BITS_GENERIC

2019-09-21 13:07:46 (2216): Guest Log: 00:00:00.005817 main     5.2.32 r132073 started. Verbose level = 0

2019-09-21 13:07:46 (2216): Guest Log: 00:00:00.010622 main     Error: Service 'control' failed to initialize: VERR_INVALID_PARAMETER

2019-09-21 13:07:46 (2216): Guest Log: 00:00:00.011006 main     Session 0 is about to close ...

2019-09-21 13:07:46 (2216): Guest Log: 00:00:00.011126 main     Stopping all guest processes ...

2019-09-21 13:07:46 (2216): Guest Log: 00:00:00.011215 main     Closing all guest files ...

2019-09-21 13:07:46 (2216): Guest Log: 00:00:00.012023 main     Ended.

2019-09-21 13:07:51 (2216): Guest Log: Copied input files into RunAtlas.

2019-09-21 13:07:53 (2216): Guest Log: copied the webapp to /var/www

2019-09-21 13:07:53 (2216): Guest Log: This vm does not need to setup an http proxy

2019-09-21 13:07:53 (2216): Guest Log: ATHENA_PROC_NUMBER=4

2019-09-21 13:07:53 (2216): Guest Log:  *** Starting ATLAS job. (PandaID=4484649192 taskID=19056259) ***

2019-09-21 14:56:13 (2216): Status Report: Elapsed Time: '6000.831747'
2019-09-21 14:56:18 (2216): Status Report: CPU Time: '14765.968750'
2019-09-21 16:52:38 (2216): Status Report: Elapsed Time: '12001.341101'
2019-09-21 16:52:38 (2216): Status Report: CPU Time: '34985.875000'
2019-09-21 18:46:59 (2216): Status Report: Elapsed Time: '18001.538103'
2019-09-21 18:46:59 (2216): Status Report: CPU Time: '54734.328125'
2019-09-21 20:41:29 (2216): Status Report: Elapsed Time: '24002.178629'
2019-09-21 20:41:29 (2216): Status Report: CPU Time: '74545.671875'
2019-09-21 22:34:27 (2216): Status Report: Elapsed Time: '30002.660166'
2019-09-21 22:34:27 (2216): Status Report: CPU Time: '94026.437500'
2019-09-21 23:20:49 (2216): VM state change detected. (old = 'Running', new = 'Paused')
2019-09-24 22:11:01 (2216): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_10d337175ff50470" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp

2019-09-24 22:11:02 (2216): VM state change detected. (old = 'Paused', new = 'Running')
2019-09-24 23:15:03 (2216): Status Report: Elapsed Time: '36002.770554'
2019-09-24 23:15:03 (2216): Status Report: CPU Time: '111301.312500'
2019-09-24 23:25:18 (2216): VM state change detected. (old = 'Running', new = 'Paused')
2019-09-25 18:34:03 (2216): Error in resume VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_10d337175ff50470" resume
Output:

2019-09-25 18:34:04 (2216): VM state change detected. (old = 'Paused', new = 'Running')
2019-09-25 20:11:30 (2216): Status Report: Elapsed Time: '42003.410038'
2019-09-25 20:11:30 (2216): Status Report: CPU Time: '125530.875000'
2019-09-25 21:55:21 (2216): Status Report: Elapsed Time: '48004.032932'
2019-09-25 21:55:21 (2216): Status Report: CPU Time: '136018.078125'
2019-09-25 22:27:46 (2216): VM state change detected. (old = 'Running', new = 'Paused')
2019-09-26 18:26:05 (2216): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_10d337175ff50470" resume
Output:

2019-09-26 18:26:06 (2216): VM state change detected. (old = 'Paused', new = 'Running')
2019-09-26 18:56:13 (2216): Stopping VM.
2019-09-26 18:56:13 (2216): Error in stop VM for VM: -108
Command:
VBoxManage -q controlvm "boinc_10d337175ff50470" savestate
Output:

2019-09-26 18:56:13 (2216): VM did not stop when requested.
2019-09-26 18:56:13 (2216): VM was successfully terminated.
2019-09-27 18:54:29 (10876): Detected: vboxwrapper 26197
2019-09-27 18:54:29 (10876): Detected: BOINC client v7.7
2019-09-27 18:54:31 (10876): Detected: VirtualBox VboxManage Interface (Version: 5.2.8)
2019-09-27 18:54:31 (10876): Starting VM using VBoxManage interface. (boinc_10d337175ff50470, slot#7)
2019-09-27 18:54:38 (10876): Successfully started VM. (PID = '11028')
2019-09-27 18:54:38 (10876): Reporting VM Process ID to BOINC.
2019-09-27 18:54:38 (10876): Guest Log: BIOS: VirtualBox 5.2.8

2019-09-27 18:54:38 (10876): Guest Log: CPUID EDX: 0x178bfbff

2019-09-27 18:54:38 (10876): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-09-27 18:54:38 (10876): VM state change detected. (old = 'PoweredOff', new = 'Running')
2019-09-27 18:54:38 (10876): Detected: Web Application Enabled (http://localhost:56736)
2019-09-27 18:54:38 (10876): Detected: Remote Desktop Enabled (localhost:56737)
2019-09-27 18:54:38 (10876): Status Report: Elapsed Time: '51503.766760'
2019-09-27 18:54:38 (10876): Status Report: CPU Time: '145877.796875'
2019-09-27 18:54:38 (10876): Preference change detected
2019-09-27 18:54:38 (10876): Setting CPU throttle for VM. (100%)
2019-09-27 18:54:38 (10876): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2019-09-27 18:54:40 (10876): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-09-27 18:54:40 (10876): Guest Log: BIOS: Booting from Hard Disk...

2019-09-27 18:54:45 (10876): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-09-27 18:54:45 (10876): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=81

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=81

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=82

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=82

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=83

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=83

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=84

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=84

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=85

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=85

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=86

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=86

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=87

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=87

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=88

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=88

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=89

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=89

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8a

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8a

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8b

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8b

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8c

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8c

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8d

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8d

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8e

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8e

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk_ext: function 41, unmapped device for ELDL=8f

2019-09-27 18:54:45 (10876): Guest Log: int13_harddisk: function 02, unmapped device for ELDL=8f

2019-09-27 18:55:03 (10876): Guest Log: vgdrvHeartbeatInit: Setting up heartbeat to trigger every 2000 milliseconds

2019-09-27 18:55:03 (10876): Guest Log: vboxguest: misc device minor 58, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-09-27 18:55:17 (10876): Guest Log: Checking CVMFS...

2019-09-27 18:55:29 (10876): Guest Log: Failed to check CVMFS, check output from cvmfs_config probe:

2019-09-27 18:55:35 (10876): Guest Log: Probing /cvmfs/atlas.cern.ch... OK

2019-09-27 18:55:36 (10876): Guest Log: Probing /cvmfs/atlas-condb.cern.ch... OK

2019-09-27 18:55:37 (10876): Guest Log: Probing /cvmfs/grid.cern.ch... OK

2019-09-27 18:55:37 (10876): Guest Log: Mounting shared directory

2019-09-27 18:55:37 (10876): Guest Log: VBoxService 5.2.32 r132073 (verbosity: 0) linux.amd64 (Jul 12 2019 10:32:28) release log

2019-09-27 18:55:37 (10876): Guest Log: 00:00:00.000548 main     Log opened 2019-09-27T18:55:35.662141000Z

2019-09-27 18:55:37 (10876): Guest Log: 00:00:00.000703 main     OS Product: Linux

2019-09-27 18:55:37 (10876): Guest Log: 00:00:00.000846 main     OS Release: 3.10.0-957.27.2.el7.x86_64

2019-09-27 18:55:37 (10876): Guest Log: 00:00:00.000896 main     OS Version: #1 SMP Mon Jul 29 17:46:05 UTC 2019

2019-09-27 18:55:37 (10876): Guest Log: 00:00:00.000941 main     Executable: /opt/VBoxGuestAdditions-5.2.32/sbin/VBoxService

2019-09-27 18:55:37 (10876): Guest Log: 00:00:00.000943 main     Process ID: 2017

2019-09-27 18:55:37 (10876): Guest Log: 00:00:00.000944 main     Package type: LINUX_64BITS_GENERIC

2019-09-27 18:55:37 (10876): Guest Log: 00:00:00.003286 main     5.2.32 r132073 started. Verbose level = 0

2019-09-27 18:55:37 (10876): Guest Log: 00:00:00.005406 main     Error: Service 'control' failed to initialize: VERR_INVALID_PARAMETER

2019-09-27 18:55:37 (10876): Guest Log: 00:00:00.005628 main     Session 0 is about to close ...

2019-09-27 18:55:37 (10876): Guest Log: 00:00:00.005736 main     Stopping all guest processes ...

2019-09-27 18:55:37 (10876): Guest Log: 00:00:00.005791 main     Closing all guest files ...

2019-09-27 18:55:37 (10876): Guest Log: 00:00:00.006219 main     Ended.

2019-09-27 18:55:37 (10876): Guest Log: Copying input files

2019-09-27 18:55:41 (10876): Guest Log: Copied input files into RunAtlas.

2019-09-27 18:55:42 (10876): Guest Log: copied the webapp to /var/www

2019-09-27 18:55:42 (10876): Guest Log: This vm does not need to setup an http proxy

2019-09-27 18:55:42 (10876): Guest Log: ATHENA_PROC_NUMBER=4

2019-09-27 18:55:42 (10876): Guest Log:  *** Starting ATLAS job. (PandaID=4484649192 taskID=19056259) ***

2019-09-27 20:42:44 (10876): Status Report: Elapsed Time: '57504.591519'
2019-09-27 20:42:45 (10876): Status Report: CPU Time: '163198.078125'
2019-09-27 22:28:34 (10876): Status Report: Elapsed Time: '63505.394344'
2019-09-27 22:28:34 (10876): Status Report: CPU Time: '181405.359375'
2019-09-27 23:35:32 (10876): VM state change detected. (old = 'Running', new = 'Paused')
2019-09-28 10:29:51 (10876): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_10d337175ff50470" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp

2019-09-28 10:29:52 (10876): VM state change detected. (old = 'Paused', new = 'Running')
2019-09-28 11:11:41 (10876): Status Report: Elapsed Time: '69506.093655'
2019-09-28 11:11:42 (10876): Status Report: CPU Time: '200575.421875'
2019-09-28 13:00:38 (10876): Status Report: Elapsed Time: '75506.884838'
2019-09-28 13:00:38 (10876): Status Report: CPU Time: '219321.921875'
2019-09-28 14:49:31 (10876): Status Report: Elapsed Time: '81507.086744'
2019-09-28 14:49:31 (10876): Status Report: CPU Time: '237949.343750'
2019-09-28 16:36:06 (10876): Status Report: Elapsed Time: '87507.497110'
2019-09-28 16:36:07 (10876): Status Report: CPU Time: '255779.375000'
2019-09-28 18:22:47 (10876): Status Report: Elapsed Time: '93508.104305'
2019-09-28 18:22:47 (10876): Status Report: CPU Time: '273710.187500'
2019-09-28 20:09:35 (10876): Status Report: Elapsed Time: '99508.324047'
2019-09-28 20:09:35 (10876): Status Report: CPU Time: '291931.187500'
2019-09-28 21:56:33 (10876): Status Report: Elapsed Time: '105508.558138'
2019-09-28 21:56:33 (10876): Status Report: CPU Time: '309815.578125'
2019-09-28 23:43:53 (10876): Status Report: Elapsed Time: '111508.745184'
2019-09-28 23:43:53 (10876): Status Report: CPU Time: '327989.500000'
2019-09-28 23:58:44 (10876): VM state change detected. (old = 'Running', new = 'Paused')
2019-09-29 10:19:03 (10876): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_10d337175ff50470" resume
Output:

2019-09-29 10:19:05 (10876): VM state change detected. (old = 'Paused', new = 'Running')
2019-09-29 11:57:07 (10876): Status Report: Elapsed Time: '117508.884785'
2019-09-29 11:57:07 (10876): Status Report: CPU Time: '348357.750000'
2019-09-29 13:46:40 (10876): Status Report: Elapsed Time: '123509.756880'
2019-09-29 13:46:40 (10876): Status Report: CPU Time: '367174.937500'
2019-09-29 15:39:37 (10876): Status Report: Elapsed Time: '129509.862148'
2019-09-29 15:39:37 (10876): Status Report: CPU Time: '386614.359375'
2019-09-29 17:28:30 (10876): Status Report: Elapsed Time: '135510.650864'
2019-09-29 17:28:30 (10876): Status Report: CPU Time: '405350.093750'
2019-09-29 19:17:25 (10876): Status Report: Elapsed Time: '141511.135952'
2019-09-29 19:17:26 (10876): Status Report: CPU Time: '424078.984375'
2019-09-29 21:06:08 (10876): Status Report: Elapsed Time: '147511.530617'
2019-09-29 21:06:08 (10876): Status Report: CPU Time: '442756.109375'
2019-09-29 22:51:28 (10876): VM state change detected. (old = 'Running', new = 'Paused')
2019-09-30 18:54:00 (10876): Error in resume VM for VM: -2135228414
Command:
VBoxManage -q controlvm "boinc_10d337175ff50470" resume
Output:
VBoxManage.exe: error: Cannot resume the machine as it is not paused (machine state: Running)
VBoxManage.exe: error: Details: code VBOX_E_INVALID_VM_STATE (0x80bb0002), component ConsoleWrap, interface IConsole, callee IUnknown
VBoxManage.exe: error: Context: "Resume()" at line 410 of file VBoxManageControlVM.cpp

2019-09-30 18:54:01 (10876): VM state change detected. (old = 'Paused', new = 'Running')
2019-09-30 18:56:10 (10876): Status Report: Elapsed Time: '153511.641314'
2019-09-30 18:56:10 (10876): Status Report: CPU Time: '461925.093750'
2019-09-30 20:44:48 (10876): Status Report: Elapsed Time: '159511.908322'
2019-09-30 20:44:48 (10876): Status Report: CPU Time: '480484.625000'
2019-09-30 22:33:39 (10876): Status Report: Elapsed Time: '165511.997730'
2019-09-30 22:33:40 (10876): Status Report: CPU Time: '499178.734375'
2019-09-30 23:11:50 (10876): VM state change detected. (old = 'Running', new = 'Paused')
2019-10-01 18:40:30 (10876): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_10d337175ff50470" resume
Output:

2019-10-01 18:40:31 (10876): VM state change detected. (old = 'Paused', new = 'Running')
2019-10-01 19:52:59 (10876): Status Report: Elapsed Time: '171512.605215'
2019-10-01 19:53:04 (10876): Status Report: CPU Time: '518914.796875'
2019-10-01 21:45:01 (10876): Status Report: Elapsed Time: '177513.002510'
2019-10-01 21:45:01 (10876): Status Report: CPU Time: '538198.640625'
2019-10-01 23:15:57 (10876): VM state change detected. (old = 'Running', new = 'Paused')
2019-10-02 18:32:58 (10876): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_10d337175ff50470" resume
Output:

2019-10-02 18:32:59 (10876): VM state change detected. (old = 'Paused', new = 'Running')
2019-10-02 18:51:58 (10876): Status Report: Elapsed Time: '183513.811609'
2019-10-02 18:51:58 (10876): Status Report: CPU Time: '557990.203125'
2019-10-02 20:40:24 (10876): Status Report: Elapsed Time: '189514.440281'
2019-10-02 20:40:24 (10876): Status Report: CPU Time: '576582.671875'
2019-10-02 22:29:09 (10876): Status Report: Elapsed Time: '195514.744902'
2019-10-02 22:29:09 (10876): Status Report: CPU Time: '595221.234375'
2019-10-03 00:21:41 (10876): Status Report: Elapsed Time: '201515.095926'
2019-10-03 00:21:41 (10876): Status Report: CPU Time: '614510.937500'
2019-10-03 01:00:56 (10876): VM state change detected. (old = 'Running', new = 'Paused')
2019-10-03 12:55:31 (10876): Error in resume VM for VM: -182
Command:
VBoxManage -q controlvm "boinc_10d337175ff50470" resume
Output:

2019-10-03 12:55:32 (10876): VM state change detected. (old = 'Paused', new = 'Running')
2019-10-03 14:07:14 (10876): Status Report: Elapsed Time: '207515.866984'
2019-10-03 14:07:14 (10876): Status Report: CPU Time: '634123.093750'
2019-10-03 14:39:41 (10876): Guest Log:  *** Job finished ***

2019-10-03 14:39:41 (10876): Guest Log:  *** The last 20 lines of the pilot log: ***

2019-10-03 14:39:41 (10876): Guest Log: -rw-------.  1 atlas atlas 11357 Jul 25 14:38 LICENSE

2019-10-03 14:39:41 (10876): Guest Log: -rw-------.  1 atlas atlas    20 Sep  9 11:04 MANIFEST.IN

2019-10-03 14:39:41 (10876): Guest Log: -rw-------.  1 atlas atlas    11 Sep 27 18:56 pandaIDs.out

2019-10-03 14:39:41 (10876): Guest Log: drwx------. 14 atlas atlas   216 Sep 27 18:56 pilot

2019-10-03 14:39:41 (10876): Guest Log: -rwx------.  1 atlas atlas 20136 Sep  9 11:04 pilot.py

2019-10-03 14:39:41 (10876): Guest Log: -rw-------.  1 atlas atlas     9 Sep  9 11:04 PILOTVERSION

2019-10-03 14:39:41 (10876): Guest Log: -rw-------.  1 atlas atlas  2251 Jul 25 14:38 README.md

2019-10-03 14:39:41 (10876): Guest Log: -rw-------.  1 atlas atlas   760 Aug 22 09:01 setup.py

2019-10-03 14:39:41 (10876): Guest Log: -rw-------.  1 atlas atlas   221 Jul 25 14:38 TODO.md

2019-10-03 14:39:41 (10876): Guest Log: 2019-09-29 19:08:43 UTC [wrapper] pandaIDs.out files:

2019-10-03 14:39:41 (10876): Guest Log: -rw-------. 1 atlas atlas 11 Sep 27 18:56 /home/atlas/RunAtlas/pilot2/pandaIDs.out

2019-10-03 14:39:41 (10876): Guest Log: 2019-09-29 19:08:43 UTC [wrapper] pandaIDs.out content:

2019-10-03 14:39:41 (10876): Guest Log: 4484649192

2019-10-03 14:39:41 (10876): Guest Log: 2019-09-29 19:08:43 UTC [wrapper] Test setup, not cleaning

2019-10-03 14:39:41 (10876): Guest Log: 2019-09-29 19:08:43 UTC [wrapper] ==== wrapper stdout END ====

2019-10-03 14:39:41 (10876): Guest Log: 2019-09-29 19:08:43 UTC [wrapper] ==== wrapper stderr END ====

2019-10-03 14:39:41 (10876): Guest Log: 2019-09-29 19:08:43 UTC [wrapper] wrapper wrapperexiting ec=0, duration=173583

2019-10-03 14:39:41 (10876): Guest Log: 2019-09-29 19:08:43 UTC [wrapper] apfmon messages muted

2019-10-03 14:39:41 (10876): Guest Log:  *** Error codes and diagnostics ***

2019-10-03 14:39:41 (10876): Guest Log:     "exeErrorCode": 0,

2019-10-03 14:39:41 (10876): Guest Log:     "exeErrorDiag": "",

2019-10-03 14:39:41 (10876): Guest Log:     "pilotErrorCode": 0,

2019-10-03 14:39:41 (10876): Guest Log:     "pilotErrorDiag": "",

2019-10-03 14:39:41 (10876): Guest Log:  *** Listing of results directory ***

2019-10-03 14:39:41 (10876): Guest Log: total 274700

2019-10-03 14:39:41 (10876): Guest Log: -rw-r--r--. 1 atlas atlas    259319 Sep 21 09:37 pilot2.tar.gz

2019-10-03 14:39:41 (10876): Guest Log: -rw-r--r--. 1 atlas atlas      4480 Sep 21 10:10 queuedata.json

2019-10-03 14:39:41 (10876): Guest Log: -rwx------. 1 atlas atlas     12762 Sep 21 10:13 runpilot2-wrapper.sh

2019-10-03 14:39:41 (10876): Guest Log: -rw-------. 1 atlas atlas      2842 Sep 21 10:13 pandaJobData.out

2019-10-03 14:39:41 (10876): Guest Log: -rwxr-xr-x. 1 atlas atlas      6168 Sep 27 18:55 init_data.xml

2019-10-03 14:39:41 (10876): Guest Log: -rwxr-xr-x. 1 atlas atlas    267509 Sep 27 18:55 input.tar.gz

2019-10-03 14:39:41 (10876): Guest Log: -rwxr-xr-x. 1 atlas atlas       815 Sep 27 18:55 RTE.tar.gz

2019-10-03 14:39:41 (10876): Guest Log: -rwxr-xr-x. 1 atlas atlas      8659 Sep 27 18:55 start_atlas.sh

2019-10-03 14:39:41 (10876): Guest Log: lrwxrwxrwx. 1 atlas atlas        20 Sep 27 18:55 EVNT.18605762._000086.pool.root.1 -> /data/./ATLAS.root_0

2019-10-03 14:39:41 (10876): Guest Log: drwxr-xr-x. 3 atlas atlas        17 Sep 27 18:55 APPS

2019-10-03 14:39:41 (10876): Guest Log: -rw-------. 1 atlas atlas   4027855 Sep 27 18:56 agis_schedconf.cvmfs.json

2019-10-03 14:39:41 (10876): Guest Log: -rw-------. 1 atlas atlas   7579780 Sep 27 18:56 agis_ddmendpoints.json

2019-10-03 14:39:41 (10876): Guest Log: drwx------. 3 atlas atlas       229 Sep 27 18:56 pilot2

2019-10-03 14:39:41 (10876): Guest Log: -rw-------. 1 atlas atlas 247369507 Sep 29 19:06 HITS.19056259._004297.pool.root.1

2019-10-03 14:39:41 (10876): Guest Log: -rw-------. 1 atlas atlas       793 Sep 29 19:07 memory_monitor_summary.json

2019-10-03 14:39:41 (10876): Guest Log: -rw-------. 1 atlas atlas   1924137 Sep 29 19:07 log.19056259._004297.job.log.tgz.1

2019-10-03 14:39:41 (10876): Guest Log: -rw-------. 1 atlas atlas     71129 Sep 29 19:08 heartbeat.json

2019-10-03 14:39:41 (10876): Guest Log: -rw-------. 1 atlas atlas     11363 Sep 29 19:08 pilotlog.txt

2019-10-03 14:39:41 (10876): Guest Log: -rw-------. 1 atlas atlas   8836608 Sep 29 19:08 log.19056259._004297.job.log.1

2019-10-03 14:39:41 (10876): Guest Log: -rw-------. 1 atlas atlas       508 Sep 29 19:08 6MHNDm8YrVvnShfckohDCDFpABFKDmABFKDmeorNDmABFKDmZGJGXn.diag

2019-10-03 14:39:41 (10876): Guest Log: -rw-------. 1 atlas atlas       463 Sep 29 19:08 output.list

2019-10-03 14:39:41 (10876): Guest Log: -rw-r--r--. 1 atlas atlas      6749 Sep 29 19:08 runtime_log.err

2019-10-03 14:39:41 (10876): Guest Log: -rw-r--r--. 1 atlas atlas       692 Sep 29 19:08 runtime_log

2019-10-03 14:39:41 (10876): Guest Log: -rw-------. 1 atlas atlas  10844160 Sep 29 19:08 result.tar.gz

2019-10-03 14:39:41 (10876): Guest Log: Looking for outputfile HITS.19056259._004297.pool.root.1

2019-10-03 14:39:41 (10876): Guest Log: HITS file was successfully produced

2019-10-03 14:39:41 (10876): Guest Log: -rw-------. 1 atlas atlas 247369507 Sep 29 19:06 /home/atlas/RunAtlas/HITS.19056259._004297.pool.root.1

2019-10-03 14:39:43 (10876): Guest Log: Successfully finished the ATLAS job!

2019-10-03 14:39:43 (10876): Guest Log: Copying the results back to the shared directory!

2019-10-03 14:39:43 (10876): Guest Log:  *** Contents of shared directory: ***

2019-10-03 14:39:43 (10876): Guest Log: total 520288

2019-10-03 14:39:43 (10876): Guest Log: -rwxrwxrwx. 1 root root 274260279 Sep 21 11:06 ATLAS.root_0

2019-10-03 14:39:43 (10876): Guest Log: -rwxrwxrwx. 1 root root 247369507 Sep 29 19:06 HITS.pool.root.1

2019-10-03 14:39:43 (10876): Guest Log: -rwxrwxrwx. 1 root root      6168 Sep 21 11:06 init_data.xml

2019-10-03 14:39:43 (10876): Guest Log: -rwxrwxrwx. 1 root root    267509 Sep 21 11:03 input.tar.gz

2019-10-03 14:39:43 (10876): Guest Log: -rwxrwxrwx. 1 root root  10844160 Oct  3  2019 result.tar.gz

2019-10-03 14:39:43 (10876): Guest Log: -rwxrwxrwx. 1 root root       815 Sep 21 11:03 RTE.tar.gz

2019-10-03 14:39:43 (10876): Guest Log: -rwxrwxrwx. 1 root root      8659 Sep 21 11:03 start_atlas.sh

2019-10-03 14:39:43 (10876): Guest Log:  *** Success! Shutting down the machine. ***

2019-10-03 14:39:43 (10876): VM Completion File Detected.
2019-10-03 14:39:43 (10876): Powering off VM.
2019-10-03 14:44:45 (10876): VM did not power off when requested.
2019-10-03 14:44:45 (10876): VM was successfully terminated.
2019-10-03 14:44:45 (10876): Deregistering VM. (boinc_10d337175ff50470, slot#7)
2019-10-03 14:44:45 (10876): Removing network bandwidth throttle group from VM.
2019-10-03 14:44:45 (10876): Removing VM from VirtualBox.
14:44:51 (10876): called boinc_finish(0)

</stderr_txt>
]]>


©2024 CERN