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 |
<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