Stderr output

<core_client_version>7.10.2</core_client_version>
<![CDATA[
<stderr_txt>
2019-08-18 18:31:50 (7648): vboxwrapper (7.9.26200): starting
2019-08-18 18:31:50 (7648): Feature: Checkpoint interval offset (458 seconds)
2019-08-18 18:31:50 (7648): Detected: VirtualBox COM Interface (Version: 5.2.10)
2019-08-18 18:31:50 (7648): Detected: Minimum checkpoint interval (900.000000 seconds)
2019-08-18 18:31:50 (7648): Successfully copied 'init_data.xml' to the shared directory.
2019-08-18 18:31:50 (7648): Create VM. (boinc_3f707a2b65cb8aa5, slot#8)
2019-08-18 18:31:50 (7648): Setting Memory Size for VM. (4800MB)
2019-08-18 18:31:50 (7648): Setting CPU Count for VM. (2)
2019-08-18 18:31:50 (7648): Setting Chipset Options for VM.
2019-08-18 18:31:50 (7648): Setting Boot Options for VM.
2019-08-18 18:31:50 (7648): Enabling VM Network Access.
2019-08-18 18:31:50 (7648): Setting Network Configuration for NAT.
2019-08-18 18:31:50 (7648): Disabling USB Support for VM.
2019-08-18 18:31:50 (7648): Disabling COM Port Support for VM.
2019-08-18 18:31:50 (7648): Disabling LPT Port Support for VM.
2019-08-18 18:31:50 (7648): Disabling Audio Support for VM.
2019-08-18 18:31:50 (7648): Disabling Clipboard Support for VM.
2019-08-18 18:31:50 (7648): Disabling Drag and Drop Support for VM.
2019-08-18 18:31:50 (7648): Adding storage controller(s) to VM.
2019-08-18 18:31:50 (7648): Adding virtual disk drive to VM. (vm_image.vdi)
2019-08-18 18:31:50 (7648): Adding VirtualBox Guest Additions to VM.
2019-08-18 18:31:50 (7648): Adding network bandwidth throttle group to VM. (Defaulting to 1024GB)
2019-08-18 18:31:50 (7648): forwarding host port 60314 to guest port 80
2019-08-18 18:31:50 (7648): Enabling remote desktop for VM.
2019-08-18 18:31:50 (7648): Enabling shared directory for VM.
2019-08-18 18:31:50 (7648): Starting VM. (boinc_3f707a2b65cb8aa5, slot#8)
2019-08-18 18:31:59 (7648): Guest Log: BIOS: VirtualBox 5.2.10

2019-08-18 18:31:59 (7648): Guest Log: CPUID EDX: 0x178bfbff

2019-08-18 18:31:59 (7648): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-08-18 18:31:59 (7648): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-08-18 18:31:59 (7648): Guest Log: BIOS: Booting from Hard Disk...

2019-08-18 18:31:59 (7648): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-08-18 18:31:59 (7648): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-08-18 18:31:59 (7648): Successfully started VM. (PID = '22896')
2019-08-18 18:31:59 (7648): Reporting VM Process ID to BOINC.
2019-08-18 18:32:04 (7648): VM state change detected. (old = 'poweroff', new = 'running')
2019-08-18 18:32:09 (7648): Detected: Web Application Enabled (http://localhost:60314)
2019-08-18 18:32:09 (7648): Detected: Remote Desktop Enabled (localhost:60315)
2019-08-18 18:32:14 (7648): Preference change detected
2019-08-18 18:32:14 (7648): Setting CPU throttle for VM. (100%)
2019-08-18 18:32:14 (7648): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2019-08-18 18:32:24 (7648): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-08-18 18:32:34 (7648): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013d51c010), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-18 18:32:34 (7648): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013d51da10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-18 18:32:34 (7648): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013d51da10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-18 18:32:34 (7648): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013d51dc10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-18 18:33:14 (7648): Guest Log: Copying input files into RunAtlas.

2019-08-18 18:33:19 (7648): Guest Log: Copied input files into RunAtlas.

2019-08-18 18:33:49 (7648): Guest Log: copied the webapp to /var/www

2019-08-18 18:33:49 (7648): Guest Log: This vm does not need to setup http proxy

2019-08-18 18:33:49 (7648): Guest Log: ATHENA_PROC_NUMBER=2

2019-08-18 18:33:49 (7648): Guest Log: Starting ATLAS job. (PandaID=4451362548 Binary file (standard input) matches)

2019-08-18 19:21:21 (7648): VM state change detected. (old = 'running', new = 'paused')
2019-08-18 19:40:52 (7648): VM state change detected. (old = 'paused', new = 'running')
2019-08-18 20:31:55 (7648): Status Report: Elapsed Time: '6004.251886'
2019-08-18 20:31:55 (7648): Status Report: CPU Time: '10339.703125'
2019-08-18 22:11:59 (7648): Status Report: Elapsed Time: '12008.029110'
2019-08-18 22:11:59 (7648): Status Report: CPU Time: '22331.921875'
2019-08-18 23:52:02 (7648): Status Report: Elapsed Time: '18011.428745'
2019-08-18 23:52:02 (7648): Status Report: CPU Time: '34320.656250'
2019-08-19 01:32:06 (7648): Status Report: Elapsed Time: '24015.470081'
2019-08-19 01:32:06 (7648): Status Report: CPU Time: '46224.078125'
2019-08-19 03:12:10 (7648): Status Report: Elapsed Time: '30018.847277'
2019-08-19 03:12:10 (7648): Status Report: CPU Time: '58233.312500'
2019-08-19 04:52:14 (7648): Status Report: Elapsed Time: '36022.917480'
2019-08-19 04:52:14 (7648): Status Report: CPU Time: '70229.125000'
2019-08-19 06:32:16 (7648): Status Report: Elapsed Time: '42025.548281'
2019-08-19 06:32:16 (7648): Status Report: CPU Time: '82245.375000'
2019-08-19 08:12:20 (7648): Status Report: Elapsed Time: '48028.938148'
2019-08-19 08:12:20 (7648): Status Report: CPU Time: '94258.546875'
2019-08-19 09:52:23 (7648): Status Report: Elapsed Time: '54031.856331'
2019-08-19 09:52:23 (7648): Status Report: CPU Time: '106170.203125'
2019-08-19 11:32:26 (7648): Status Report: Elapsed Time: '60035.279568'
2019-08-19 11:32:26 (7648): Status Report: CPU Time: '118187.156250'
2019-08-19 13:12:29 (7648): Status Report: Elapsed Time: '66038.405294'
2019-08-19 13:12:29 (7648): Status Report: CPU Time: '130204.484375'
2019-08-19 14:52:32 (7648): Status Report: Elapsed Time: '72041.327883'
2019-08-19 14:52:32 (7648): Status Report: CPU Time: '142215.406250'
2019-08-19 16:32:36 (7648): Status Report: Elapsed Time: '78045.501936'
2019-08-19 16:32:36 (7648): Status Report: CPU Time: '154238.015625'
2019-08-19 18:12:41 (7648): Status Report: Elapsed Time: '84049.796387'
2019-08-19 18:12:41 (7648): Status Report: CPU Time: '166153.968750'
2019-08-19 19:52:41 (7648): Status Report: Elapsed Time: '90050.551981'
2019-08-19 19:52:41 (7648): Status Report: CPU Time: '178153.187500'
2019-08-19 21:32:45 (7648): Status Report: Elapsed Time: '96054.101469'
2019-08-19 21:32:45 (7648): Status Report: CPU Time: '190136.703125'
2019-08-19 23:12:50 (7648): Status Report: Elapsed Time: '102059.038424'
2019-08-19 23:12:50 (7648): Status Report: CPU Time: '202132.765625'
2019-08-20 00:52:53 (7648): Status Report: Elapsed Time: '108062.473089'
2019-08-20 00:52:53 (7648): Status Report: CPU Time: '214043.921875'
2019-08-20 02:32:56 (7648): Status Report: Elapsed Time: '114065.518553'
2019-08-20 02:32:56 (7648): Status Report: CPU Time: '226059.312500'
2019-08-20 04:13:00 (7648): Status Report: Elapsed Time: '120069.713451'
2019-08-20 04:13:00 (7648): Status Report: CPU Time: '238075.046875'
2019-08-20 05:53:04 (7648): Status Report: Elapsed Time: '126073.230036'
2019-08-20 05:53:04 (7648): Status Report: CPU Time: '250080.546875'
2019-08-20 07:33:07 (7648): Status Report: Elapsed Time: '132076.211159'
2019-08-20 07:33:07 (7648): Status Report: CPU Time: '262096.296875'
2019-08-20 09:13:10 (7648): Status Report: Elapsed Time: '138079.411757'
2019-08-20 09:13:10 (7648): Status Report: CPU Time: '274018.093750'
2019-08-20 09:38:05 (7648): Guest Log: The last 10 lines of the pilot log.

2019-08-20 09:38:05 (7648): Guest Log: -rwx------  1 atlas01 atlas01 20463 Jul 25 10:38 pilot.py

2019-08-20 09:38:05 (7648): Guest Log: -rw-------  1 atlas01 atlas01     8 Aug  7 07:02 PILOTVERSION

2019-08-20 09:38:05 (7648): Guest Log: -rw-------  1 atlas01 atlas01  2251 Jul 25 10:38 README.md

2019-08-20 09:38:05 (7648): Guest Log: -rw-------  1 atlas01 atlas01   221 Jul 25 10:38 TODO.md

2019-08-20 09:38:05 (7648): Guest Log: 2019-08-20 13:20:48 UTC [wrapper] Test setup, not cleaning

2019-08-20 09:38:05 (7648): Guest Log: 2019-08-20 13:20:48 UTC [wrapper] ==== wrapper stdout END ====

2019-08-20 09:38:05 (7648): Guest Log: 2019-08-20 13:20:48 UTC [wrapper] ==== wrapper stderr END ====

2019-08-20 09:38:05 (7648): Guest Log: 2019-08-20 13:20:48 UTC [wrapper] wrapper wrapperexiting ec=0, duration=139619

2019-08-20 09:38:05 (7648): Guest Log: 2019-08-20 13:20:48 UTC [wrapper] apfmon messages muted

2019-08-20 09:38:05 (7648): Guest Log: Listing of results directory

2019-08-20 09:38:05 (7648): Guest Log: total 510200

2019-08-20 09:38:05 (7648): Guest Log: drwx------ 3 atlas01 atlas01      4096 Aug  7 07:02 pilot2

2019-08-20 09:38:05 (7648): Guest Log: -rw-r--r-- 1 atlas01 atlas01    253018 Aug 18 04:31 pilot2.tar.gz

2019-08-20 09:38:05 (7648): Guest Log: -rw-r--r-- 1 atlas01 atlas01      4468 Aug 18 05:25 queuedata.json

2019-08-20 09:38:05 (7648): Guest Log: -rwx------ 1 atlas01 atlas01     12641 Aug 18 05:25 runpilot2-wrapper.sh

2019-08-20 09:38:05 (7648): Guest Log: -rw------- 1 atlas01 atlas01      2948 Aug 18 05:25 pandaJobData.out

2019-08-20 09:38:05 (7648): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 240368334 Aug 18 18:33 EVNT.18605796._000192.pool.root.1

2019-08-20 09:38:05 (7648): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8628 Aug 18 18:33 init_data.xml

2019-08-20 09:38:05 (7648): Guest Log: -rwxr-xr-x 1 atlas01 atlas01    261624 Aug 18 18:33 input.tar.gz

2019-08-20 09:38:05 (7648): Guest Log: -rwxr-xr-x 1 atlas01 atlas01       815 Aug 18 18:33 RTE.tar.gz

2019-08-20 09:38:05 (7648): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8659 Aug 18 18:33 start_atlas.sh

2019-08-20 09:38:05 (7648): Guest Log: drwxr-xr-x 3 atlas01 atlas01      4096 Aug 18 18:33 APPS

2019-08-20 09:38:05 (7648): Guest Log: -rw------- 1 atlas01 atlas01   4379225 Aug 18 18:34 agis_schedconf.cvmfs.json

2019-08-20 09:38:05 (7648): Guest Log: -rw------- 1 atlas01 atlas01   7440670 Aug 18 18:35 agis_ddmendpoints.json

2019-08-20 09:38:05 (7648): Guest Log: -rw------- 1 atlas01 atlas01 249179979 Aug 20 09:17 HITS.18722552._010222.pool.root.1

2019-08-20 09:38:05 (7648): Guest Log: -rw------- 1 atlas01 atlas01    901859 Aug 20 09:19 log.18722552._010222.job.log.tgz.1

2019-08-20 09:38:05 (7648): Guest Log: -rw------- 1 atlas01 atlas01     75398 Aug 20 09:19 heartbeat.json

2019-08-20 09:38:05 (7648): Guest Log: -rw------- 1 atlas01 atlas01     10854 Aug 20 09:20 pilotlog.txt

2019-08-20 09:38:05 (7648): Guest Log: -rw------- 1 atlas01 atlas01   6154990 Aug 20 09:20 log.18722552._010222.job.log.1

2019-08-20 09:38:05 (7648): Guest Log: -rw------- 1 atlas01 atlas01       503 Aug 20 09:20 86dNDmlcWJvnShfckohDCDFpABFKDmABFKDmsEeLDmABFKDm8UHzwn.diag

2019-08-20 09:38:05 (7648): Guest Log: -rw------- 1 atlas01 atlas01       391 Aug 20 09:20 output.list

2019-08-20 09:38:05 (7648): Guest Log: -rw-r--r-- 1 atlas01 atlas01      6753 Aug 20 09:20 runtime_log.err

2019-08-20 09:38:05 (7648): Guest Log: -rw-r--r-- 1 atlas01 atlas01       692 Aug 20 09:20 runtime_log

2019-08-20 09:38:05 (7648): Guest Log: -rw------- 1 atlas01 atlas01  13301760 Aug 20 09:20 result.tar.gz

2019-08-20 09:38:05 (7648): Guest Log: HITS file was successfully produced

2019-08-20 09:38:05 (7648): Guest Log: -rw------- 1 atlas01 atlas01 249179979 Aug 20 09:17 /home/atlas01/RunAtlas/HITS.18722552._010222.pool.root.1

2019-08-20 09:38:10 (7648): Guest Log: Successfully finished the ATLAS job!

2019-08-20 09:38:10 (7648): Guest Log: Copying the results back to the shared directory!

2019-08-20 09:38:10 (7648): Guest Log: Copied the result file back to the shared directory and created atlas_done file!

2019-08-20 09:38:10 (7648): Guest Log: Success! Shutting down the machine.

2019-08-20 09:38:10 (7648): VM Completion File Detected.
2019-08-20 09:38:10 (7648): Powering off VM.
2019-08-20 09:38:12 (7648): Successfully stopped VM.
2019-08-20 09:38:17 (7648): Deregistering VM. (boinc_3f707a2b65cb8aa5, slot#8)
2019-08-20 09:38:17 (7648): Removing virtual disk drive(s) from VM.
2019-08-20 09:38:17 (7648): Removing network bandwidth throttle group from VM.
2019-08-20 09:38:17 (7648): Removing storage controller(s) from VM.
2019-08-20 09:38:17 (7648): Removing VM from VirtualBox.
09:38:22 (7648): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN