Name hDdKDmCPm8unShfckohDCDFpABFKDmABFKDm2MiaDmABFKDmKtSu0n_3
Workunit 1909735
Created 20 Jul 2019, 2:49:44 UTC
Sent 20 Jul 2019, 3:24:10 UTC
Report deadline 27 Jul 2019, 3:24:10 UTC
Received 29 Jul 2019, 1:14:20 UTC
Server state Over
Outcome Validate error
Client state Done
Exit status 0 (0x00000000)
Computer ID 3678
Run time 2 days 9 hours 27 min 22 sec
CPU time 1 days 18 hours 56 min 13 sec
Validate state Invalid
Credit 0.00
Device peak FLOPS 3.14 GFLOPS
Application version ATLAS Simulation v0.60 (vbox64_mt_mcore_atlas)
windows_x86_64
Peak working set size 84.68 MB
Peak swap size 99.05 MB
Peak disk usage 5.88 GB

Stderr output

<core_client_version>7.14.2</core_client_version>
<![CDATA[
<stderr_txt>
2019-07-19 22:44:23 (17724): vboxwrapper (7.9.26200): starting
2019-07-19 22:44:25 (17724): Feature: Checkpoint interval offset (460 seconds)
2019-07-19 22:44:25 (17724): Detected: VirtualBox COM Interface (Version: 5.2.12)
2019-07-19 22:44:25 (17724): Detected: Minimum checkpoint interval (900.000000 seconds)
2019-07-19 22:44:25 (17724): Successfully copied 'init_data.xml' to the shared directory.
2019-07-19 22:44:25 (17724): Create VM. (boinc_c83d0b968f801d79, slot#0)
2019-07-19 22:44:25 (17724): Setting Memory Size for VM. (3900MB)
2019-07-19 22:44:26 (17724): Setting CPU Count for VM. (1)
2019-07-19 22:44:26 (17724): Setting Chipset Options for VM.
2019-07-19 22:44:26 (17724): Setting Boot Options for VM.
2019-07-19 22:44:26 (17724): Enabling VM Network Access.
2019-07-19 22:44:26 (17724): Setting Network Configuration for NAT.
2019-07-19 22:44:26 (17724): Disabling USB Support for VM.
2019-07-19 22:44:26 (17724): Disabling COM Port Support for VM.
2019-07-19 22:44:26 (17724): Disabling LPT Port Support for VM.
2019-07-19 22:44:26 (17724): Disabling Audio Support for VM.
2019-07-19 22:44:26 (17724): Disabling Clipboard Support for VM.
2019-07-19 22:44:26 (17724): Disabling Drag and Drop Support for VM.
2019-07-19 22:44:26 (17724): Adding storage controller(s) to VM.
2019-07-19 22:44:26 (17724): Adding virtual disk drive to VM. (vm_image.vdi)
2019-07-19 22:44:26 (17724): Adding VirtualBox Guest Additions to VM.
2019-07-19 22:44:27 (17724): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-07-19 22:44:27 (17724): forwarding host port 56053 to guest port 80
2019-07-19 22:44:27 (17724): Enabling remote desktop for VM.
2019-07-19 22:44:27 (17724): Required extension pack not installed, remote desktop not enabled.
2019-07-19 22:44:27 (17724): Enabling shared directory for VM.
2019-07-19 22:44:27 (17724): Starting VM. (boinc_c83d0b968f801d79, slot#0)
2019-07-19 22:44:42 (17724): Guest Log: BIOS: VirtualBox 5.2.12

2019-07-19 22:44:42 (17724): Guest Log: CPUID EDX: 0x078bfbff

2019-07-19 22:44:42 (17724): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-07-19 22:44:42 (17724): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-07-19 22:44:42 (17724): Guest Log: BIOS: Booting from Hard Disk...

2019-07-19 22:44:42 (17724): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-07-19 22:44:42 (17724): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-07-19 22:44:42 (17724): Successfully started VM. (PID = '15836')
2019-07-19 22:44:42 (17724): Reporting VM Process ID to BOINC.
2019-07-19 22:44:47 (17724): VM state change detected. (old = 'poweroff', new = 'running')
2019-07-19 22:44:52 (17724): Detected: Web Application Enabled (http://localhost:56053)
2019-07-19 22:44:57 (17724): Preference change detected
2019-07-19 22:44:57 (17724): Setting CPU throttle for VM. (100%)
2019-07-19 22:44:57 (17724): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2019-07-19 22:45:03 (17724): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-07-19 22:45:13 (17724): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801091c4a10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-19 22:45:13 (17724): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107ba6210), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-19 22:45:13 (17724): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107ba6010), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-19 22:45:13 (17724): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107ba6410), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-19 22:46:31 (17724): Guest Log: Copying input files into RunAtlas.

2019-07-19 22:46:41 (17724): Guest Log: Copied input files into RunAtlas.

2019-07-19 22:47:22 (17724): Guest Log: copied the webapp to /var/www

2019-07-19 22:47:22 (17724): Guest Log: This vm does not need to setup http proxy

2019-07-19 22:47:22 (17724): Guest Log: core_number=1

2019-07-19 22:47:22 (17724): Guest Log: Starting ATLAS job. (PandaID=4421214001 taskID=18605005)

2019-07-20 00:22:56 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-20 00:23:08 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-20 00:25:31 (17724): Status Report: Elapsed Time: '6010.608360'
2019-07-20 00:25:31 (17724): Status Report: CPU Time: '5340.046875'
2019-07-20 02:05:46 (17724): Status Report: Elapsed Time: '12013.172773'
2019-07-20 02:05:46 (17724): Status Report: CPU Time: '11224.609375'
2019-07-20 03:03:20 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-20 03:06:37 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-20 03:33:46 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-20 03:34:15 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-20 03:43:50 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-20 04:06:08 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-20 04:12:05 (17724): Status Report: Elapsed Time: '18014.670906'
2019-07-20 04:12:05 (17724): Status Report: CPU Time: '17170.375000'
2019-07-20 05:52:06 (17724): Status Report: Elapsed Time: '24016.163746'
2019-07-20 05:52:06 (17724): Status Report: CPU Time: '23167.500000'
2019-07-20 07:11:59 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-20 17:08:25 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-20 17:08:48 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-20 17:09:09 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-20 17:09:21 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-20 18:31:10 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-20 18:51:02 (17724): Status Report: Elapsed Time: '30020.452305'
2019-07-20 18:51:02 (17724): Status Report: CPU Time: '29117.109375'
2019-07-20 18:51:59 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-20 19:05:59 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-20 20:06:08 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-20 20:31:27 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-20 21:10:35 (17724): Status Report: Elapsed Time: '36023.614065'
2019-07-20 21:10:35 (17724): Status Report: CPU Time: '35054.109375'
2019-07-20 22:50:37 (17724): Status Report: Elapsed Time: '42025.664799'
2019-07-20 22:50:37 (17724): Status Report: CPU Time: '40974.546875'
2019-07-21 00:30:39 (17724): Status Report: Elapsed Time: '48027.972292'
2019-07-21 00:30:39 (17724): Status Report: CPU Time: '46886.625000'
2019-07-21 02:10:40 (17724): Status Report: Elapsed Time: '54028.905573'
2019-07-21 02:10:40 (17724): Status Report: CPU Time: '52820.187500'
2019-07-21 03:50:42 (17724): Status Report: Elapsed Time: '60030.895430'
2019-07-21 03:50:42 (17724): Status Report: CPU Time: '58796.359375'
2019-07-21 04:11:47 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-21 06:55:45 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-21 08:14:50 (17724): Status Report: Elapsed Time: '66035.129662'
2019-07-21 08:14:50 (17724): Status Report: CPU Time: '64779.484375'
2019-07-21 08:28:36 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-21 09:29:22 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-21 10:55:42 (17724): Status Report: Elapsed Time: '72035.956924'
2019-07-21 10:55:42 (17724): Status Report: CPU Time: '70714.375000'
2019-07-21 12:35:43 (17724): Status Report: Elapsed Time: '78037.105566'
2019-07-21 12:35:43 (17724): Status Report: CPU Time: '76640.078125'
2019-07-21 13:01:35 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-22 08:39:44 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-22 08:40:04 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-22 09:29:49 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-22 09:30:00 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-23 19:16:46 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-23 19:17:00 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-23 20:56:27 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-23 20:56:33 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 20:07:29 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 20:43:42 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 20:59:44 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 21:37:29 (17724): Status Report: Elapsed Time: '84042.001343'
2019-07-24 21:37:29 (17724): Status Report: CPU Time: '78366.312500'
2019-07-24 22:03:12 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 22:03:32 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 22:04:23 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 22:04:33 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 22:08:44 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 22:08:54 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 22:09:30 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 22:09:40 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 22:10:12 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 22:12:31 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 22:12:51 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 22:13:01 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 22:14:54 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 22:15:14 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 22:15:21 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 22:15:41 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 22:15:51 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 22:16:11 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 22:17:02 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 22:17:33 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 22:21:24 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 22:21:44 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 22:23:37 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 22:23:47 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 22:24:37 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 22:24:47 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 22:26:33 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 22:27:24 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 22:29:56 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 22:30:06 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 22:44:31 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-24 23:31:35 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-24 23:31:45 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-25 00:00:36 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-25 00:00:46 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-25 03:41:52 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-25 04:22:40 (17724): Status Report: Elapsed Time: '90045.475445'
2019-07-25 04:22:40 (17724): Status Report: CPU Time: '78495.828125'
2019-07-25 05:35:34 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-25 16:16:35 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-25 16:16:46 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-26 00:39:35 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-26 00:39:45 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-26 07:12:00 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-26 07:12:14 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-26 08:43:45 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-26 08:44:06 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-26 10:06:05 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-26 10:06:15 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-27 02:33:02 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-27 02:33:08 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-27 09:53:51 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-27 09:54:07 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-27 10:10:55 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-27 10:37:25 (17724): Status Report: Elapsed Time: '96047.426689'
2019-07-27 10:37:25 (17724): Status Report: CPU Time: '78623.750000'
2019-07-27 10:43:21 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-27 11:08:59 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-27 11:42:07 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-27 12:08:10 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-27 12:51:40 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-27 12:51:50 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-27 13:09:38 (17724): Status Report: Elapsed Time: '102050.781683'
2019-07-27 13:09:38 (17724): Status Report: CPU Time: '78755.234375'
2019-07-27 14:49:41 (17724): Status Report: Elapsed Time: '108055.771048'
2019-07-27 14:49:41 (17724): Status Report: CPU Time: '78875.484375'
2019-07-27 16:23:54 (17724): VM state change detected. (old = 'running', new = 'paused')
2019-07-27 16:49:48 (17724): VM state change detected. (old = 'paused', new = 'running')
2019-07-27 16:55:44 (17724): Status Report: Elapsed Time: '114060.558559'
2019-07-27 16:55:44 (17724): Status Report: CPU Time: '79000.937500'
2019-07-27 18:35:45 (17724): Status Report: Elapsed Time: '120061.000853'
2019-07-27 18:35:45 (17724): Status Report: CPU Time: '79124.984375'
2019-07-27 20:15:50 (17724): Status Report: Elapsed Time: '126065.882267'
2019-07-27 20:15:50 (17724): Status Report: CPU Time: '79247.015625'
2019-07-27 20:56:09 (10008): vboxwrapper (7.9.26200): starting
2019-07-27 20:56:10 (10008): Feature: Checkpoint interval offset (599 seconds)
2019-07-27 20:56:10 (10008): Detected: VirtualBox COM Interface (Version: 5.2.12)
2019-07-27 20:56:10 (10008): Detected: Minimum checkpoint interval (900.000000 seconds)
2019-07-27 20:56:10 (10008): Guest Log: BIOS: VirtualBox 5.2.12

2019-07-27 20:56:10 (10008): Guest Log: CPUID EDX: 0x078bfbff

2019-07-27 20:56:10 (10008): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-07-27 20:56:10 (10008): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-07-27 20:56:10 (10008): Guest Log: BIOS: Booting from Hard Disk...

2019-07-27 20:56:10 (10008): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-07-27 20:56:10 (10008): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-07-27 20:56:10 (10008): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-07-27 20:56:10 (10008): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801091c4a10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-27 20:56:10 (10008): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107ba6210), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-27 20:56:10 (10008): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107ba6010), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-27 20:56:10 (10008): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff880107ba6410), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-07-27 20:56:10 (10008): Guest Log: Copying input files into RunAtlas.

2019-07-27 20:56:10 (10008): Guest Log: Copied input files into RunAtlas.

2019-07-27 20:56:10 (10008): Guest Log: copied the webapp to /var/www

2019-07-27 20:56:10 (10008): Guest Log: This vm does not need to setup http proxy

2019-07-27 20:56:10 (10008): Guest Log: core_number=1

2019-07-27 20:56:10 (10008): Guest Log: Starting ATLAS job. (PandaID=4421214001 taskID=18605005)

2019-07-27 20:56:10 (10008): Starting VM. (boinc_c83d0b968f801d79, slot#0)
2019-07-27 20:56:38 (10008): Successfully started VM. (PID = '9152')
2019-07-27 20:56:38 (10008): Reporting VM Process ID to BOINC.
2019-07-27 20:56:43 (10008): VM state change detected. (old = 'poweroff', new = 'running')
2019-07-27 20:56:48 (10008): Detected: Web Application Enabled (http://localhost:56053)
2019-07-27 20:56:53 (10008): Status Report: Elapsed Time: '127693.663431'
2019-07-27 20:56:53 (10008): Status Report: CPU Time: '79291.156250'
2019-07-27 20:56:53 (10008): Preference change detected
2019-07-27 20:56:53 (10008): Setting CPU throttle for VM. (100%)
2019-07-27 20:56:53 (10008): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2019-07-27 20:59:58 (10008): Guest Log: Copied input files into RunAtlas.

2019-07-27 21:00:03 (10008): Guest Log: copied the webapp to /var/www

2019-07-27 21:00:03 (10008): Guest Log: This vm does not need to setup http proxy

2019-07-27 21:00:03 (10008): Guest Log: core_number=1

2019-07-27 21:00:03 (10008): Guest Log: Starting ATLAS job. (Binary file (standard input) matches Binary file (standard input) matches)

2019-07-27 22:36:55 (10008): Status Report: Elapsed Time: '133695.662657'
2019-07-27 22:36:55 (10008): Status Report: CPU Time: '84529.437500'
2019-07-28 00:16:57 (10008): Status Report: Elapsed Time: '139697.673311'
2019-07-28 00:16:57 (10008): Status Report: CPU Time: '90430.000000'
2019-07-28 01:15:36 (10008): VM state change detected. (old = 'running', new = 'paused')
2019-07-28 01:18:22 (10008): VM state change detected. (old = 'paused', new = 'running')
2019-07-28 01:59:54 (10008): Status Report: Elapsed Time: '145702.211494'
2019-07-28 01:59:54 (10008): Status Report: CPU Time: '96292.078125'
2019-07-28 03:40:07 (10008): Status Report: Elapsed Time: '151714.770418'
2019-07-28 03:40:07 (10008): Status Report: CPU Time: '102047.968750'
2019-07-28 05:20:07 (10008): Status Report: Elapsed Time: '157715.859379'
2019-07-28 05:20:07 (10008): Status Report: CPU Time: '107804.640625'
2019-07-28 07:00:09 (10008): Status Report: Elapsed Time: '163717.566704'
2019-07-28 07:00:09 (10008): Status Report: CPU Time: '113520.203125'
2019-07-28 08:40:11 (10008): Status Report: Elapsed Time: '169719.068231'
2019-07-28 08:40:11 (10008): Status Report: CPU Time: '119215.031250'
2019-07-28 09:43:03 (10008): VM state change detected. (old = 'running', new = 'paused')
2019-07-28 10:08:51 (10008): VM state change detected. (old = 'paused', new = 'running')
2019-07-28 10:46:05 (10008): Status Report: Elapsed Time: '175720.336647'
2019-07-28 10:46:05 (10008): Status Report: CPU Time: '125000.515625'
2019-07-28 10:56:28 (10008): VM state change detected. (old = 'running', new = 'paused')
2019-07-28 11:22:21 (10008): VM state change detected. (old = 'paused', new = 'running')
2019-07-28 11:29:22 (10008): VM state change detected. (old = 'running', new = 'paused')
2019-07-28 11:55:14 (10008): VM state change detected. (old = 'paused', new = 'running')
2019-07-28 13:18:04 (10008): Status Report: Elapsed Time: '181723.692526'
2019-07-28 13:18:04 (10008): Status Report: CPU Time: '130765.796875'
2019-07-28 14:58:08 (10008): Status Report: Elapsed Time: '187728.404735'
2019-07-28 14:58:08 (10008): Status Report: CPU Time: '136478.234375'
2019-07-28 16:38:12 (10008): Status Report: Elapsed Time: '193731.787409'
2019-07-28 16:38:12 (10008): Status Report: CPU Time: '142283.875000'
2019-07-28 18:18:14 (10008): Status Report: Elapsed Time: '199733.608524'
2019-07-28 18:18:14 (10008): Status Report: CPU Time: '148092.578125'
2019-07-28 19:05:49 (10008): Preference change detected
2019-07-28 19:05:49 (10008): Setting CPU throttle for VM. (100%)
2019-07-28 19:05:49 (10008): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2019-07-28 19:19:11 (10008): VM state change detected. (old = 'running', new = 'paused')
2019-07-28 19:19:21 (10008): VM state change detected. (old = 'paused', new = 'running')
2019-07-28 19:58:29 (10008): Status Report: Elapsed Time: '205733.636720'
2019-07-28 19:58:29 (10008): Status Report: CPU Time: '153915.171875'
2019-07-28 20:11:10 (10008): Guest Log: The last 10 lines of the pilot log.

2019-07-28 20:11:10 (10008): Guest Log: -rw-------  1 atlas01 atlas01 11357 Jun  3 19:18 LICENSE

2019-07-28 20:11:10 (10008): Guest Log: drwx------ 14 atlas01 atlas01  4096 Jul 28 04:02 pilot

2019-07-28 20:11:10 (10008): Guest Log: -rwx------  1 atlas01 atlas01 21428 Jul 11 17:38 pilot.py

2019-07-28 20:11:10 (10008): Guest Log: -rw-------  1 atlas01 atlas01     8 Jul 15 15:01 PILOTVERSION

2019-07-28 20:11:10 (10008): Guest Log: -rw-------  1 atlas01 atlas01  2251 Jun  3 19:18 README.md

2019-07-28 20:11:10 (10008): Guest Log: -rw-------  1 atlas01 atlas01   221 Jun  3 19:18 TODO.md

2019-07-28 20:11:10 (10008): Guest Log: 2019-07-29 01:12:08 UTC [wrapper] Test setup, not cleaning

2019-07-28 20:11:10 (10008): Guest Log: 2019-07-29 01:12:08 UTC [wrapper] ==== wrapper stdout END ====

2019-07-28 20:11:10 (10008): Guest Log: 2019-07-29 01:12:08 UTC [wrapper] ==== wrapper stderr END ====

2019-07-28 20:11:10 (10008): Guest Log: Listing of results directory

2019-07-28 20:11:10 (10008): Guest Log: total 504168

2019-07-28 20:11:10 (10008): Guest Log: drwx------ 5 atlas01 atlas01      4096 Jul 15 15:01 pilot2

2019-07-28 20:11:10 (10008): Guest Log: -rw-r--r-- 1 atlas01 atlas01   4056709 Jul 16 15:21 pilot2.tar.gz

2019-07-28 20:11:10 (10008): Guest Log: -rw-r--r-- 1 atlas01 atlas01      4468 Jul 19 20:01 queuedata.json

2019-07-28 20:11:10 (10008): Guest Log: -rw------- 1 atlas01 atlas01      2887 Jul 19 20:03 pandaJobData.out

2019-07-28 20:11:10 (10008): Guest Log: -rwx------ 1 atlas01 atlas01     15232 Jul 19 20:03 runpilot2-wrapper.sh

2019-07-28 20:11:10 (10008): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 376377547 Jul 28 04:01 EVNT.17323836._000403.pool.root.1

2019-07-28 20:11:10 (10008): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8753 Jul 28 04:01 init_data.xml

2019-07-28 20:11:10 (10008): Guest Log: -rwxr-xr-x 1 atlas01 atlas01   4066494 Jul 28 04:01 input.tar.gz

2019-07-28 20:11:10 (10008): Guest Log: -rwxr-xr-x 1 atlas01 atlas01       815 Jul 28 04:01 RTE.tar.gz

2019-07-28 20:11:10 (10008): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8659 Jul 28 04:01 start_atlas.sh

2019-07-28 20:11:10 (10008): Guest Log: drwxr-xr-x 3 atlas01 atlas01      4096 Jul 28 04:01 APPS

2019-07-28 20:11:10 (10008): Guest Log: -rw------- 1 atlas01 atlas01   4563844 Jul 28 04:02 agis_schedconf.cvmfs.json

2019-07-28 20:11:10 (10008): Guest Log: -rw------- 1 atlas01 atlas01   7414721 Jul 28 04:02 agis_ddmendpoints.json

2019-07-28 20:11:10 (10008): Guest Log: -rw------- 1 atlas01 atlas01 104813331 Jul 29 03:08 HITS.18605005._015252.pool.root.1

2019-07-28 20:11:10 (10008): Guest Log: -rw------- 1 atlas01 atlas01    395955 Jul 29 03:10 log.18605005._015252.job.log.tgz.1

2019-07-28 20:11:10 (10008): Guest Log: drwxrwx--- 2 atlas01 atlas01      4096 Jul 29 03:11 PanDA_Pilot-4421214001

2019-07-28 20:11:10 (10008): Guest Log: -rw------- 1 atlas01 atlas01     48675 Jul 29 03:11 heartbeat.json

2019-07-28 20:11:10 (10008): Guest Log: -rw------- 1 atlas01 atlas01   3474378 Jul 29 03:12 pilotlog.txt

2019-07-28 20:11:10 (10008): Guest Log: -rw------- 1 atlas01 atlas01   3490913 Jul 29 03:12 log.18605005._015252.job.log.1

2019-07-28 20:11:10 (10008): Guest Log: -rw------- 1 atlas01 atlas01       505 Jul 29 03:12 hDdKDmCPm8unShfckohDCDFpABFKDmABFKDm2MiaDmABFKDmKtSu0n.diag

2019-07-28 20:11:10 (10008): Guest Log: -rw------- 1 atlas01 atlas01       391 Jul 29 03:12 output.list

2019-07-28 20:11:10 (10008): Guest Log: -rw-r--r-- 1 atlas01 atlas01      6802 Jul 29 03:12 runtime_log.err

2019-07-28 20:11:10 (10008): Guest Log: -rw-r--r-- 1 atlas01 atlas01       692 Jul 29 03:12 runtime_log

2019-07-28 20:11:10 (10008): Guest Log: -rw------- 1 atlas01 atlas01   7434240 Jul 29 03:12 result.tar.gz

2019-07-28 20:11:10 (10008): Guest Log: HITS file was successfully produced

2019-07-28 20:11:10 (10008): Guest Log: -rw------- 1 atlas01 atlas01 104813331 Jul 29 03:08 /home/atlas01/RunAtlas/HITS.18605005._015252.pool.root.1

2019-07-28 20:11:10 (10008): Guest Log: Successfully finished the ATLAS job!

2019-07-28 20:11:10 (10008): Guest Log: Copying the results back to the shared directory!

2019-07-28 20:11:10 (10008): Guest Log: Copied the result file back to the shared directory and created atlas_done file!

2019-07-28 20:11:10 (10008): Guest Log: Success! Shutting down the machine.

2019-07-28 20:11:10 (10008): VM Completion File Detected.
2019-07-28 20:11:10 (10008): Powering off VM.
2019-07-28 20:11:11 (10008): Successfully stopped VM.
2019-07-28 20:11:16 (10008): Deregistering VM. (boinc_c83d0b968f801d79, slot#0)
2019-07-28 20:11:16 (10008): Removing virtual disk drive(s) from VM.
2019-07-28 20:11:16 (10008): Removing network bandwidth throttle group from VM.
2019-07-28 20:11:16 (10008): Removing storage controller(s) from VM.
2019-07-28 20:11:16 (10008): Removing VM from VirtualBox.
20:11:21 (10008): called boinc_finish(0)

</stderr_txt>
]]>


©2024 CERN