Stderr output

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

2019-08-01 04:13:38 (68968): Guest Log: CPUID EDX: 0x178bfbff

2019-08-01 04:13:38 (68968): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-08-01 04:13:38 (68968): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-08-01 04:13:38 (68968): Guest Log: BIOS: Booting from Hard Disk...

2019-08-01 04:13:38 (68968): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-08-01 04:13:38 (68968): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-08-01 04:13:38 (68968): Successfully started VM. (PID = '70068')
2019-08-01 04:13:38 (68968): Reporting VM Process ID to BOINC.
2019-08-01 04:13:43 (68968): VM state change detected. (old = 'poweroff', new = 'running')
2019-08-01 04:13:48 (68968): Detected: Web Application Enabled (http://localhost:54916)
2019-08-01 04:13:48 (68968): Detected: Remote Desktop Enabled (localhost:54917)
2019-08-01 04:13:53 (68968): Preference change detected
2019-08-01 04:13:53 (68968): Setting CPU throttle for VM. (100%)
2019-08-01 04:13:53 (68968): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2019-08-01 04:14:08 (68968): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-08-01 04:14:18 (68968): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013ea6d610), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-01 04:14:18 (68968): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013ea6d410), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-01 04:14:18 (68968): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013fd60210), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-01 04:14:18 (68968): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013fd60010), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-01 04:15:33 (68968): Guest Log: Copying input files into RunAtlas.

2019-08-01 04:15:33 (68968): Guest Log: Copied input files into RunAtlas.

2019-08-01 04:16:03 (68968): Guest Log: copied the webapp to /var/www

2019-08-01 04:16:03 (68968): Guest Log: This vm does not need to setup http proxy

2019-08-01 04:16:03 (68968): Guest Log: ATHENA_PROC_NUMBER=2

2019-08-01 04:16:03 (68968): Guest Log: Starting ATLAS job. (PandaID=4434517472 taskID=18722326)

2019-08-01 05:13:41 (68968): VM state change detected. (old = 'running', new = 'paused')
2019-08-01 05:15:01 (68968): VM state change detected. (old = 'paused', new = 'running')
2019-08-01 05:55:21 (68968): Status Report: Elapsed Time: '6002.706579'
2019-08-01 05:55:21 (68968): Status Report: CPU Time: '10613.531250'
2019-08-01 06:28:51 (68968): VM state change detected. (old = 'running', new = 'paused')
2019-08-01 06:29:46 (68968): VM state change detected. (old = 'paused', new = 'running')
2019-08-01 07:36:25 (68968): Status Report: Elapsed Time: '12006.229137'
2019-08-01 07:36:25 (68968): Status Report: CPU Time: '22590.296875'
2019-08-01 07:54:08 (68968): VM state change detected. (old = 'running', new = 'paused')
2019-08-01 07:56:13 (68968): VM state change detected. (old = 'paused', new = 'running')
2019-08-01 09:05:20 (68968): VM state change detected. (old = 'running', new = 'paused')
2019-08-01 09:06:45 (68968): VM state change detected. (old = 'paused', new = 'running')
2019-08-01 09:20:07 (68968): Status Report: Elapsed Time: '18007.044449'
2019-08-01 09:20:07 (68968): Status Report: CPU Time: '34543.578125'
2019-08-01 10:12:09 (68968): VM state change detected. (old = 'running', new = 'paused')
2019-08-01 10:13:59 (68968): VM state change detected. (old = 'paused', new = 'running')
2019-08-01 11:02:05 (68968): Status Report: Elapsed Time: '24010.093567'
2019-08-01 11:02:05 (68968): Status Report: CPU Time: '46518.093750'
2019-08-01 12:42:07 (68968): Status Report: Elapsed Time: '30012.404701'
2019-08-01 12:42:07 (68968): Status Report: CPU Time: '58517.390625'
2019-08-01 13:59:32 (68968): VM state change detected. (old = 'running', new = 'paused')
2019-08-01 13:59:52 (68968): VM state change detected. (old = 'paused', new = 'running')
2019-08-01 14:22:35 (68968): Status Report: Elapsed Time: '36014.968695'
2019-08-01 14:22:35 (68968): Status Report: CPU Time: '70483.406250'
2019-08-01 15:24:03 (68968): VM state change detected. (old = 'running', new = 'paused')
2019-08-01 15:26:28 (68968): VM state change detected. (old = 'paused', new = 'running')
2019-08-01 16:05:08 (68968): Status Report: Elapsed Time: '42017.580732'
2019-08-01 16:05:08 (68968): Status Report: CPU Time: '82503.281250'
2019-08-01 16:32:17 (68968): VM state change detected. (old = 'running', new = 'paused')
2019-08-01 16:34:07 (68968): VM state change detected. (old = 'paused', new = 'running')
2019-08-01 17:38:30 (68968): VM state change detected. (old = 'running', new = 'paused')
2019-08-01 17:40:40 (68968): VM state change detected. (old = 'paused', new = 'running')
2019-08-01 17:49:21 (68968): Status Report: Elapsed Time: '48019.999796'
2019-08-01 17:49:21 (68968): Status Report: CPU Time: '94519.656250'
2019-08-01 18:44:23 (68968): VM state change detected. (old = 'running', new = 'paused')
2019-08-01 18:46:24 (68968): VM state change detected. (old = 'paused', new = 'running')
2019-08-01 19:31:29 (68968): Status Report: Elapsed Time: '54022.698725'
2019-08-01 19:31:29 (68968): Status Report: CPU Time: '106487.484375'
2019-08-01 19:51:37 (68968): VM state change detected. (old = 'running', new = 'paused')
2019-08-01 19:54:02 (68968): VM state change detected. (old = 'paused', new = 'running')
2019-08-01 20:58:36 (68968): VM state change detected. (old = 'running', new = 'paused')
2019-08-01 21:00:46 (68968): VM state change detected. (old = 'paused', new = 'running')
2019-08-01 21:16:18 (68968): Status Report: Elapsed Time: '60025.670990'
2019-08-01 21:16:18 (68968): Status Report: CPU Time: '118765.781250'
2019-08-01 22:05:14 (68968): VM state change detected. (old = 'running', new = 'paused')
2019-08-01 22:06:50 (68968): VM state change detected. (old = 'paused', new = 'running')
2019-08-01 22:58:01 (68968): Status Report: Elapsed Time: '66028.234308'
2019-08-01 22:58:01 (68968): Status Report: CPU Time: '130521.546875'
2019-08-01 23:12:48 (68968): VM state change detected. (old = 'running', new = 'paused')
2019-08-01 23:14:53 (68968): VM state change detected. (old = 'paused', new = 'running')
2019-08-02 00:20:43 (68968): VM state change detected. (old = 'running', new = 'paused')
2019-08-02 00:22:53 (68968): VM state change detected. (old = 'paused', new = 'running')
2019-08-02 00:42:31 (68968): Status Report: Elapsed Time: '72032.883774'
2019-08-02 00:42:31 (68968): Status Report: CPU Time: '142469.578125'
2019-08-02 01:28:24 (68968): VM state change detected. (old = 'running', new = 'paused')
2019-08-02 01:30:19 (68968): VM state change detected. (old = 'paused', new = 'running')
2019-08-02 02:24:36 (68968): Status Report: Elapsed Time: '78036.651127'
2019-08-02 02:24:36 (68968): Status Report: CPU Time: '154443.500000'
2019-08-02 04:04:38 (68968): Status Report: Elapsed Time: '84038.680256'
2019-08-02 04:04:38 (68968): Status Report: CPU Time: '166435.546875'
2019-08-02 05:44:39 (68968): Status Report: Elapsed Time: '90040.159389'
2019-08-02 05:44:39 (68968): Status Report: CPU Time: '178420.968750'
2019-08-02 07:24:41 (68968): Status Report: Elapsed Time: '96041.711662'
2019-08-02 07:24:41 (68968): Status Report: CPU Time: '190423.312500'
2019-08-02 09:04:43 (68968): Status Report: Elapsed Time: '102044.273439'
2019-08-02 09:04:43 (68968): Status Report: CPU Time: '202412.109375'
2019-08-02 10:04:05 (68968): Guest Log: The last 10 lines of the pilot log.

2019-08-02 10:04:05 (68968): Guest Log: -rw-------  1 atlas01 atlas01 11357 Jul 25 10:38 LICENSE

2019-08-02 10:04:05 (68968): Guest Log: drwx------ 14 atlas01 atlas01  4096 Aug  1 04:17 pilot

2019-08-02 10:04:05 (68968): Guest Log: -rwx------  1 atlas01 atlas01 20463 Jul 25 10:38 pilot.py

2019-08-02 10:04:05 (68968): Guest Log: -rw-------  1 atlas01 atlas01     8 Jul 31 13:00 PILOTVERSION

2019-08-02 10:04:05 (68968): Guest Log: -rw-------  1 atlas01 atlas01  2251 Jul 25 10:38 README.md

2019-08-02 10:04:05 (68968): Guest Log: -rw-------  1 atlas01 atlas01   221 Jul 25 10:38 TODO.md

2019-08-02 10:04:05 (68968): Guest Log: 2019-08-02 13:57:03 UTC [wrapper] Test setup, not cleaning

2019-08-02 10:04:05 (68968): Guest Log: 2019-08-02 13:57:03 UTC [wrapper] ==== wrapper stdout END ====

2019-08-02 10:04:05 (68968): Guest Log: 2019-08-02 13:57:03 UTC [wrapper] ==== wrapper stderr END ====

2019-08-02 10:04:05 (68968): Guest Log: Listing of results directory

2019-08-02 10:04:05 (68968): Guest Log: total 545304

2019-08-02 10:04:05 (68968): Guest Log: drwx------ 3 atlas01 atlas01      4096 Jul 31 13:00 pilot2

2019-08-02 10:04:05 (68968): Guest Log: -rw-r--r-- 1 atlas01 atlas01    252463 Jul 31 23:54 pilot2.tar.gz

2019-08-02 10:04:05 (68968): Guest Log: -rwx------ 1 atlas01 atlas01     15232 Aug  1 03:13 runpilot2-wrapper.sh

2019-08-02 10:04:05 (68968): Guest Log: -rw-r--r-- 1 atlas01 atlas01      4468 Aug  1 03:13 queuedata.json

2019-08-02 10:04:05 (68968): Guest Log: -rw------- 1 atlas01 atlas01      2913 Aug  1 03:13 pandaJobData.out

2019-08-02 10:04:05 (68968): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 279409309 Aug  1 04:15 EVNT.18605758._000077.pool.root.1

2019-08-02 10:04:05 (68968): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8629 Aug  1 04:15 init_data.xml

2019-08-02 10:04:05 (68968): Guest Log: -rwxr-xr-x 1 atlas01 atlas01    261804 Aug  1 04:15 input.tar.gz

2019-08-02 10:04:05 (68968): Guest Log: -rwxr-xr-x 1 atlas01 atlas01       815 Aug  1 04:15 RTE.tar.gz

2019-08-02 10:04:05 (68968): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8659 Aug  1 04:15 start_atlas.sh

2019-08-02 10:04:05 (68968): Guest Log: drwxr-xr-x 3 atlas01 atlas01      4096 Aug  1 04:16 APPS

2019-08-02 10:04:05 (68968): Guest Log: -rw------- 1 atlas01 atlas01   4556051 Aug  1 04:17 agis_schedconf.cvmfs.json

2019-08-02 10:04:05 (68968): Guest Log: -rw------- 1 atlas01 atlas01   7395723 Aug  1 04:17 agis_ddmendpoints.json

2019-08-02 10:04:05 (68968): Guest Log: -rw------- 1 atlas01 atlas01 250315199 Aug  2 09:54 HITS.18722326._003638.pool.root.1

2019-08-02 10:04:05 (68968): Guest Log: -rw------- 1 atlas01 atlas01    816096 Aug  2 09:56 log.18722326._003638.job.log.tgz.1

2019-08-02 10:04:05 (68968): Guest Log: -rw------- 1 atlas01 atlas01     78241 Aug  2 09:56 heartbeat.json

2019-08-02 10:04:05 (68968): Guest Log: -rw------- 1 atlas01 atlas01      9795 Aug  2 09:57 pilotlog.txt

2019-08-02 10:04:05 (68968): Guest Log: -rw------- 1 atlas01 atlas01   4756030 Aug  2 09:57 log.18722326._003638.job.log.1

2019-08-02 10:04:05 (68968): Guest Log: -rw------- 1 atlas01 atlas01       503 Aug  2 09:57 JhINDmwRKDvnShfckohDCDFpABFKDmABFKDmiO6QDmABFKDmYUxvFm.diag

2019-08-02 10:04:05 (68968): Guest Log: -rw------- 1 atlas01 atlas01       391 Aug  2 09:57 output.list

2019-08-02 10:04:05 (68968): Guest Log: -rw-r--r-- 1 atlas01 atlas01      6787 Aug  2 09:57 runtime_log.err

2019-08-02 10:04:05 (68968): Guest Log: -rw-r--r-- 1 atlas01 atlas01       692 Aug  2 09:57 runtime_log

2019-08-02 10:04:05 (68968): Guest Log: -rw------- 1 atlas01 atlas01  10414080 Aug  2 09:57 result.tar.gz

2019-08-02 10:04:05 (68968): Guest Log: HITS file was successfully produced

2019-08-02 10:04:05 (68968): Guest Log: -rw------- 1 atlas01 atlas01 250315199 Aug  2 09:54 /home/atlas01/RunAtlas/HITS.18722326._003638.pool.root.1

2019-08-02 10:04:10 (68968): Guest Log: Successfully finished the ATLAS job!

2019-08-02 10:04:10 (68968): Guest Log: Copying the results back to the shared directory!

2019-08-02 10:04:10 (68968): Guest Log: Copied the result file back to the shared directory and created atlas_done file!

2019-08-02 10:04:10 (68968): Guest Log: Success! Shutting down the machine.

2019-08-02 10:04:10 (68968): VM Completion File Detected.
2019-08-02 10:04:10 (68968): Powering off VM.
2019-08-02 10:04:12 (68968): Successfully stopped VM.
2019-08-02 10:04:17 (68968): Deregistering VM. (boinc_f2dcf4b7ab45b834, slot#4)
2019-08-02 10:04:17 (68968): Removing virtual disk drive(s) from VM.
2019-08-02 10:04:17 (68968): Removing network bandwidth throttle group from VM.
2019-08-02 10:04:17 (68968): Removing storage controller(s) from VM.
2019-08-02 10:04:17 (68968): Removing VM from VirtualBox.
10:04:22 (68968): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN