Task 2797898

Name	6sENDm2u8FvnShfckohDCDFpABFKDmABFKDmRbiXDmABFKDmK4zGIn_0
Workunit	1915266
Created	9 Aug 2019, 1:30:47 UTC
Sent	9 Aug 2019, 1:50:51 UTC
Report deadline	16 Aug 2019, 1:50:51 UTC
Received	9 Aug 2019, 21:29:55 UTC
Server state	Over
Outcome	Success
Client state	Done
Exit status	0 (0x00000000)
Computer ID	3679
Run time	15 hours 40 min 20 sec
CPU time	15 hours 19 min 46 sec
Validate state	Valid
Credit	170.87
Device peak FLOPS	3.03 GFLOPS
Application version	ATLAS Simulation v0.60 (vbox64_mt_mcore_atlas) windows_x86_64
Peak working set size	560.17 MB
Peak swap size	552.24 MB
Peak disk usage	4.22 GB
Stderr output

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

2019-08-08 20:56:36 (18264): Guest Log: CPUID EDX: 0x078bfbff

2019-08-08 20:56:36 (18264): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-08-08 20:56:36 (18264): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-08-08 20:56:36 (18264): Guest Log: BIOS: Booting from Hard Disk...

2019-08-08 20:56:36 (18264): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-08-08 20:56:36 (18264): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-08-08 20:56:36 (18264): Successfully started VM. (PID = '2580')
2019-08-08 20:56:36 (18264): Reporting VM Process ID to BOINC.
2019-08-08 20:56:41 (18264): VM state change detected. (old = 'poweroff', new = 'running')
2019-08-08 20:56:46 (18264): Detected: Web Application Enabled (http://localhost:56411)
2019-08-08 20:56:46 (18264): Detected: Remote Desktop Enabled (localhost:56412)
2019-08-08 20:56:51 (18264): Preference change detected
2019-08-08 20:56:51 (18264): Setting CPU throttle for VM. (100%)
2019-08-08 20:56:51 (18264): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2019-08-08 20:57:11 (18264): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-08-08 20:57:16 (18264): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801091cd010), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-08 20:57:16 (18264): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801091cd410), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-08 20:57:16 (18264): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801091cd410), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-08 20:57:16 (18264): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801091cd410), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-08 20:58:21 (18264): Guest Log: Copying input files into RunAtlas.

2019-08-08 20:58:32 (18264): Guest Log: Copied input files into RunAtlas.

2019-08-08 20:58:57 (18264): Guest Log: copied the webapp to /var/www

2019-08-08 20:58:57 (18264): Guest Log: This vm does not need to setup http proxy

2019-08-08 20:58:57 (18264): Guest Log: core_number=1

2019-08-08 20:58:57 (18264): Guest Log: Starting ATLAS job. (Binary file (standard input) matches Binary file (standard input) matches)

2019-08-08 22:36:52 (18264): Status Report: Elapsed Time: '6001.011610'
2019-08-08 22:36:52 (18264): Status Report: CPU Time: '5160.671875'
2019-08-08 23:10:11 (18264): VM state change detected. (old = 'running', new = 'paused')
2019-08-08 23:47:54 (18264): VM state change detected. (old = 'paused', new = 'running')
2019-08-09 00:54:42 (18264): Status Report: Elapsed Time: '12002.397295'
2019-08-09 00:54:42 (18264): Status Report: CPU Time: '11177.968750'
2019-08-09 02:34:43 (18264): Status Report: Elapsed Time: '18004.015120'
2019-08-09 02:34:43 (18264): Status Report: CPU Time: '17112.375000'
2019-08-09 04:14:44 (18264): Status Report: Elapsed Time: '24004.857908'
2019-08-09 04:14:44 (18264): Status Report: CPU Time: '23105.437500'
2019-08-09 04:32:51 (18264): VM state change detected. (old = 'running', new = 'paused')
2019-08-09 04:41:27 (18264): VM state change detected. (old = 'paused', new = 'running')
2019-08-09 04:44:02 (18264): VM state change detected. (old = 'running', new = 'paused')
2019-08-09 05:29:51 (18264): VM state change detected. (old = 'paused', new = 'running')
2019-08-09 06:36:48 (18264): VM state change detected. (old = 'running', new = 'paused')
2019-08-09 06:44:54 (18264): VM state change detected. (old = 'paused', new = 'running')
2019-08-09 06:57:31 (18264): Status Report: Elapsed Time: '30006.361624'
2019-08-09 06:57:31 (18264): Status Report: CPU Time: '29085.109375'
2019-08-09 06:59:36 (18264): VM state change detected. (old = 'running', new = 'paused')
2019-08-09 07:06:57 (18264): VM state change detected. (old = 'paused', new = 'running')
2019-08-09 07:19:38 (18264): VM state change detected. (old = 'running', new = 'paused')
2019-08-09 07:26:49 (18264): VM state change detected. (old = 'paused', new = 'running')
2019-08-09 08:50:58 (18264): VM state change detected. (old = 'running', new = 'paused')
2019-08-09 08:59:44 (18264): VM state change detected. (old = 'paused', new = 'running')
2019-08-09 09:01:04 (18264): Status Report: Elapsed Time: '36007.458229'
2019-08-09 09:01:04 (18264): Status Report: CPU Time: '35067.359375'
2019-08-09 09:15:56 (18264): VM state change detected. (old = 'running', new = 'paused')
2019-08-09 09:22:56 (18264): VM state change detected. (old = 'paused', new = 'running')
2019-08-09 09:25:56 (18264): VM state change detected. (old = 'running', new = 'paused')
2019-08-09 10:33:13 (18264): VM state change detected. (old = 'paused', new = 'running')
2019-08-09 11:41:40 (18264): VM state change detected. (old = 'running', new = 'paused')
2019-08-09 11:49:25 (18264): VM state change detected. (old = 'paused', new = 'running')
2019-08-09 11:51:31 (18264): VM state change detected. (old = 'running', new = 'paused')
2019-08-09 12:00:21 (18264): VM state change detected. (old = 'paused', new = 'running')
2019-08-09 12:02:22 (18264): VM state change detected. (old = 'running', new = 'paused')
2019-08-09 12:02:57 (18264): VM state change detected. (old = 'paused', new = 'running')
2019-08-09 12:13:03 (18264): Status Report: Elapsed Time: '42011.953484'
2019-08-09 12:13:03 (18264): Status Report: CPU Time: '41030.046875'
2019-08-09 13:49:28 (18264): VM state change detected. (old = 'running', new = 'paused')
2019-08-09 13:57:09 (18264): VM state change detected. (old = 'paused', new = 'running')
2019-08-09 14:00:49 (18264): Status Report: Elapsed Time: '48012.364006'
2019-08-09 14:00:49 (18264): Status Report: CPU Time: '47011.859375'
2019-08-09 15:40:50 (18264): Status Report: Elapsed Time: '54013.199930'
2019-08-09 15:40:50 (18264): Status Report: CPU Time: '53006.437500'
2019-08-09 15:52:21 (18264): VM state change detected. (old = 'running', new = 'paused')
2019-08-09 16:00:47 (18264): VM state change detected. (old = 'paused', new = 'running')
2019-08-09 16:27:44 (18264): Guest Log: The last 10 lines of the pilot log.

2019-08-09 16:27:44 (18264): Guest Log: -rw-------  1 atlas01 atlas01 11357 Jul 25 16:38 LICENSE

2019-08-09 16:27:44 (18264): Guest Log: drwx------ 14 atlas01 atlas01  4096 Aug  9 03:59 pilot

2019-08-09 16:27:44 (18264): Guest Log: -rwx------  1 atlas01 atlas01 20463 Jul 25 16:38 pilot.py

2019-08-09 16:27:44 (18264): Guest Log: -rw-------  1 atlas01 atlas01     8 Aug  7 13:02 PILOTVERSION

2019-08-09 16:27:44 (18264): Guest Log: -rw-------  1 atlas01 atlas01  2251 Jul 25 16:38 README.md

2019-08-09 16:27:44 (18264): Guest Log: -rw-------  1 atlas01 atlas01   221 Jul 25 16:38 TODO.md

2019-08-09 16:27:44 (18264): Guest Log: 2019-08-09 21:19:18 UTC [wrapper] Test setup, not cleaning

2019-08-09 16:27:44 (18264): Guest Log: 2019-08-09 21:19:18 UTC [wrapper] ==== wrapper stdout END ====

2019-08-09 16:27:44 (18264): Guest Log: 2019-08-09 21:19:18 UTC [wrapper] ==== wrapper stderr END ====

2019-08-09 16:27:44 (18264): Guest Log: Listing of results directory

2019-08-09 16:27:44 (18264): Guest Log: total 501688

2019-08-09 16:27:44 (18264): Guest Log: drwx------ 3 atlas01 atlas01      4096 Aug  7 13:02 pilot2

2019-08-09 16:27:44 (18264): Guest Log: -rw-r--r-- 1 atlas01 atlas01    253018 Aug  9 03:26 pilot2.tar.gz

2019-08-09 16:27:44 (18264): Guest Log: -rw-r--r-- 1 atlas01 atlas01      4468 Aug  9 03:30 queuedata.json

2019-08-09 16:27:44 (18264): Guest Log: -rwx------ 1 atlas01 atlas01     15232 Aug  9 03:30 runpilot2-wrapper.sh

2019-08-09 16:27:44 (18264): Guest Log: -rw------- 1 atlas01 atlas01      2887 Aug  9 03:30 pandaJobData.out

2019-08-09 16:27:44 (18264): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 378276430 Aug  9 03:58 EVNT.17323836._001046.pool.root.1

2019-08-09 16:27:44 (18264): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8653 Aug  9 03:58 init_data.xml

2019-08-09 16:27:44 (18264): Guest Log: -rwxr-xr-x 1 atlas01 atlas01    262351 Aug  9 03:58 input.tar.gz

2019-08-09 16:27:44 (18264): Guest Log: -rwxr-xr-x 1 atlas01 atlas01       815 Aug  9 03:58 RTE.tar.gz

2019-08-09 16:27:44 (18264): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8659 Aug  9 03:58 start_atlas.sh

2019-08-09 16:27:44 (18264): Guest Log: drwxr-xr-x 3 atlas01 atlas01      4096 Aug  9 03:58 APPS

2019-08-09 16:27:44 (18264): Guest Log: -rw------- 1 atlas01 atlas01   4533755 Aug  9 03:59 agis_schedconf.cvmfs.json

2019-08-09 16:27:44 (18264): Guest Log: -rw------- 1 atlas01 atlas01   7409227 Aug  9 04:00 agis_ddmendpoints.json

2019-08-09 16:27:44 (18264): Guest Log: -rw------- 1 atlas01 atlas01 114348559 Aug  9 23:16 HITS.18605005._052088.pool.root.1

2019-08-09 16:27:44 (18264): Guest Log: -rw------- 1 atlas01 atlas01    341453 Aug  9 23:18 log.18605005._052088.job.log.tgz.1

2019-08-09 16:27:44 (18264): Guest Log: -rw------- 1 atlas01 atlas01     56389 Aug  9 23:18 heartbeat.json

2019-08-09 16:27:44 (18264): Guest Log: -rw------- 1 atlas01 atlas01      9894 Aug  9 23:19 pilotlog.txt

2019-08-09 16:27:44 (18264): Guest Log: -rw------- 1 atlas01 atlas01   2571719 Aug  9 23:19 log.18605005._052088.job.log.1

2019-08-09 16:27:44 (18264): Guest Log: -rw------- 1 atlas01 atlas01       503 Aug  9 23:19 6sENDm2u8FvnShfckohDCDFpABFKDmABFKDmRbiXDmABFKDmK4zGIn.diag

2019-08-09 16:27:44 (18264): Guest Log: -rw-r--r-- 1 atlas01 atlas01      6799 Aug  9 23:19 runtime_log.err

2019-08-09 16:27:44 (18264): Guest Log: -rw------- 1 atlas01 atlas01       391 Aug  9 23:19 output.list

2019-08-09 16:27:44 (18264): Guest Log: -rw-r--r-- 1 atlas01 atlas01       692 Aug  9 23:19 runtime_log

2019-08-09 16:27:44 (18264): Guest Log: -rw------- 1 atlas01 atlas01   5550080 Aug  9 23:19 result.tar.gz

2019-08-09 16:27:44 (18264): Guest Log: HITS file was successfully produced

2019-08-09 16:27:44 (18264): Guest Log: -rw------- 1 atlas01 atlas01 114348559 Aug  9 23:16 /home/atlas01/RunAtlas/HITS.18605005._052088.pool.root.1

2019-08-09 16:27:44 (18264): Guest Log: Successfully finished the ATLAS job!

2019-08-09 16:27:44 (18264): Guest Log: Copying the results back to the shared directory!

2019-08-09 16:27:44 (18264): Guest Log: Copied the result file back to the shared directory and created atlas_done file!

2019-08-09 16:27:44 (18264): Guest Log: Success! Shutting down the machine.

2019-08-09 16:27:44 (18264): VM Completion File Detected.
2019-08-09 16:27:44 (18264): Powering off VM.
2019-08-09 16:27:46 (18264): Successfully stopped VM.
2019-08-09 16:27:51 (18264): Deregistering VM. (boinc_d07ddb945fb4d7ef, slot#19)
2019-08-09 16:27:51 (18264): Removing virtual disk drive(s) from VM.
2019-08-09 16:27:51 (18264): Removing network bandwidth throttle group from VM.
2019-08-09 16:27:51 (18264): Removing storage controller(s) from VM.
2019-08-09 16:27:51 (18264): Removing VM from VirtualBox.
16:27:56 (18264): called boinc_finish(0)

</stderr_txt>
]]>
Development for LHC@home

Stderr output
