Stderr output

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

2019-08-20 11:52:37 (14288): Guest Log: CPUID EDX: 0x178bfbff

2019-08-20 11:52:37 (14288): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-08-20 11:52:37 (14288): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-08-20 11:52:37 (14288): Guest Log: BIOS: Booting from Hard Disk...

2019-08-20 11:52:37 (14288): Successfully started VM. (PID = '17860')
2019-08-20 11:52:37 (14288): Reporting VM Process ID to BOINC.
2019-08-20 11:52:42 (14288): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-08-20 11:52:42 (14288): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-08-20 11:52:42 (14288): VM state change detected. (old = 'poweroff', new = 'running')
2019-08-20 11:52:47 (14288): Detected: Web Application Enabled (http://localhost:49255)
2019-08-20 11:52:47 (14288): Detected: Remote Desktop Enabled (localhost:49256)
2019-08-20 11:52:52 (14288): Preference change detected
2019-08-20 11:52:52 (14288): Setting CPU throttle for VM. (100%)
2019-08-20 11:52:52 (14288): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 60 seconds) or (Vbox_job.xml: 900 seconds))
2019-08-20 11:53:02 (14288): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-08-20 11:53:17 (14288): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013d7a4410), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-20 11:53:17 (14288): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013d7a4e10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-20 11:53:17 (14288): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013d7a4a10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-20 11:53:17 (14288): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff88013d75e610), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-08-20 11:54:12 (14288): Guest Log: Copying input files into RunAtlas.

2019-08-20 11:54:17 (14288): Guest Log: Copied input files into RunAtlas.

2019-08-20 11:54:27 (14288): Guest Log: copied the webapp to /var/www

2019-08-20 11:54:27 (14288): Guest Log: This vm does not need to setup http proxy

2019-08-20 11:54:27 (14288): Guest Log: ATHENA_PROC_NUMBER=2

2019-08-20 11:54:27 (14288): Guest Log: Starting ATLAS job. (PandaID=4453620000 Binary file (standard input) matches)

2019-08-20 13:32:55 (14288): Status Report: Elapsed Time: '6003.265966'
2019-08-20 13:32:55 (14288): Status Report: CPU Time: '10476.468750'
2019-08-20 15:12:58 (14288): Status Report: Elapsed Time: '12006.306934'
2019-08-20 15:12:58 (14288): Status Report: CPU Time: '22478.234375'
2019-08-20 16:53:01 (14288): Status Report: Elapsed Time: '18009.427055'
2019-08-20 16:53:01 (14288): Status Report: CPU Time: '34495.031250'
2019-08-20 18:33:05 (14288): Status Report: Elapsed Time: '24012.663826'
2019-08-20 18:33:05 (14288): Status Report: CPU Time: '46513.531250'
2019-08-20 19:27:04 (14288): VM state change detected. (old = 'running', new = 'paused')
2019-08-20 19:46:21 (14288): VM state change detected. (old = 'paused', new = 'running')
2019-08-20 19:50:31 (14288): VM state change detected. (old = 'running', new = 'paused')
2019-08-20 19:55:32 (14288): VM state change detected. (old = 'paused', new = 'running')
2019-08-20 20:21:50 (14288): VM state change detected. (old = 'running', new = 'paused')
2019-08-20 20:26:56 (14288): VM state change detected. (old = 'paused', new = 'running')
2019-08-20 20:42:43 (14288): Status Report: Elapsed Time: '30013.634993'
2019-08-20 20:42:43 (14288): Status Report: CPU Time: '58472.937500'
2019-08-20 20:44:08 (14288): VM state change detected. (old = 'running', new = 'paused')
2019-08-20 20:49:13 (14288): VM state change detected. (old = 'paused', new = 'running')
2019-08-20 20:56:30 (14288): VM state change detected. (old = 'running', new = 'paused')
2019-08-20 21:01:30 (14288): VM state change detected. (old = 'paused', new = 'running')
2019-08-20 21:10:32 (14288): VM state change detected. (old = 'running', new = 'paused')
2019-08-20 21:15:32 (14288): VM state change detected. (old = 'paused', new = 'running')
2019-08-20 21:52:42 (14288): VM state change detected. (old = 'running', new = 'paused')
2019-08-20 21:57:42 (14288): VM state change detected. (old = 'paused', new = 'running')
2019-08-20 22:15:50 (14288): VM state change detected. (old = 'running', new = 'paused')
2019-08-20 22:20:50 (14288): VM state change detected. (old = 'paused', new = 'running')
2019-08-20 22:44:44 (14288): VM state change detected. (old = 'running', new = 'paused')
2019-08-20 22:49:44 (14288): VM state change detected. (old = 'paused', new = 'running')
2019-08-20 22:53:24 (14288): Status Report: Elapsed Time: '36017.224451'
2019-08-20 22:53:24 (14288): Status Report: CPU Time: '70558.171875'
2019-08-20 23:29:45 (14288): VM state change detected. (old = 'running', new = 'paused')
2019-08-20 23:47:41 (14288): VM state change detected. (old = 'paused', new = 'running')
2019-08-21 00:14:25 (14288): VM state change detected. (old = 'running', new = 'paused')
2019-08-21 10:22:17 (14288): VM state change detected. (old = 'paused', new = 'running')
2019-08-21 10:59:27 (14288): Status Report: Elapsed Time: '42022.217112'
2019-08-21 10:59:27 (14288): Status Report: CPU Time: '82494.265625'
2019-08-21 12:36:42 (14288): Guest Log: The last 10 lines of the pilot log.

2019-08-21 12:36:42 (14288): Guest Log: -rwx------  1 atlas01 atlas01 20628 Aug 19 08:00 pilot.py

2019-08-21 12:36:42 (14288): Guest Log: -rw-------  1 atlas01 atlas01     9 Aug 19 08:00 PILOTVERSION

2019-08-21 12:36:42 (14288): Guest Log: -rw-------  1 atlas01 atlas01  2251 Jul 25 10:38 README.md

2019-08-21 12:36:42 (14288): Guest Log: -rw-------  1 atlas01 atlas01   221 Jul 25 10:38 TODO.md

2019-08-21 12:36:42 (14288): Guest Log: 2019-08-21 16:36:38 UTC [wrapper] Test setup, not cleaning

2019-08-21 12:36:42 (14288): Guest Log: 2019-08-21 16:36:38 UTC [wrapper] ==== wrapper stdout END ====

2019-08-21 12:36:42 (14288): Guest Log: 2019-08-21 16:36:38 UTC [wrapper] ==== wrapper stderr END ====

2019-08-21 12:36:42 (14288): Guest Log: 2019-08-21 16:36:38 UTC [wrapper] wrapper wrapperexiting ec=0, duration=88931

2019-08-21 12:36:42 (14288): Guest Log: 2019-08-21 16:36:38 UTC [wrapper] apfmon messages muted

2019-08-21 12:36:42 (14288): Guest Log: Listing of results directory

2019-08-21 12:36:42 (14288): Guest Log: total 334484

2019-08-21 12:36:42 (14288): Guest Log: drwx------ 3 atlas01 atlas01      4096 Aug 19 08:00 pilot2

2019-08-21 12:36:42 (14288): Guest Log: -rw-r--r-- 1 atlas01 atlas01    255033 Aug 19 23:25 pilot2.tar.gz

2019-08-21 12:36:42 (14288): Guest Log: -rwx------ 1 atlas01 atlas01     12641 Aug 20 00:18 runpilot2-wrapper.sh

2019-08-21 12:36:42 (14288): Guest Log: -rw-r--r-- 1 atlas01 atlas01      4468 Aug 20 00:18 queuedata.json

2019-08-21 12:36:42 (14288): Guest Log: -rw------- 1 atlas01 atlas01      2957 Aug 20 00:18 pandaJobData.out

2019-08-21 12:36:42 (14288): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 201292372 Aug 20 11:54 EVNT.18605773._000273.pool.root.1

2019-08-21 12:36:42 (14288): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8628 Aug 20 11:54 init_data.xml

2019-08-21 12:36:42 (14288): Guest Log: -rwxr-xr-x 1 atlas01 atlas01    263686 Aug 20 11:54 input.tar.gz

2019-08-21 12:36:42 (14288): Guest Log: -rwxr-xr-x 1 atlas01 atlas01       815 Aug 20 11:54 RTE.tar.gz

2019-08-21 12:36:42 (14288): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8659 Aug 20 11:54 start_atlas.sh

2019-08-21 12:36:42 (14288): Guest Log: drwxr-xr-x 3 atlas01 atlas01      4096 Aug 20 11:54 APPS

2019-08-21 12:36:42 (14288): Guest Log: -rw------- 1 atlas01 atlas01   4339674 Aug 20 11:55 agis_schedconf.cvmfs.json

2019-08-21 12:36:42 (14288): Guest Log: -rw------- 1 atlas01 atlas01   7474631 Aug 20 11:55 agis_ddmendpoints.json

2019-08-21 12:36:42 (14288): Guest Log: -rw------- 1 atlas01 atlas01 120777073 Aug 21 12:32 HITS.18722355._013746.pool.root.1

2019-08-21 12:36:42 (14288): Guest Log: -rw------- 1 atlas01 atlas01    672684 Aug 21 12:35 log.18722355._013746.job.log.tgz.1

2019-08-21 12:36:42 (14288): Guest Log: -rw------- 1 atlas01 atlas01     62194 Aug 21 12:36 heartbeat.json

2019-08-21 12:36:42 (14288): Guest Log: -rw------- 1 atlas01 atlas01     11177 Aug 21 12:36 pilotlog.txt

2019-08-21 12:36:42 (14288): Guest Log: -rw------- 1 atlas01 atlas01   2164410 Aug 21 12:36 log.18722355._013746.job.log.1

2019-08-21 12:36:42 (14288): Guest Log: -rw------- 1 atlas01 atlas01       501 Aug 21 12:36 ijWNDmGmAKvnShfckohDCDFpABFKDmABFKDmy9hMDmABFKDmSGuNJm.diag

2019-08-21 12:36:42 (14288): Guest Log: -rw------- 1 atlas01 atlas01       391 Aug 21 12:36 output.list

2019-08-21 12:36:42 (14288): Guest Log: -rw-r--r-- 1 atlas01 atlas01      6787 Aug 21 12:36 runtime_log.err

2019-08-21 12:36:42 (14288): Guest Log: -rw-r--r-- 1 atlas01 atlas01       692 Aug 21 12:36 runtime_log

2019-08-21 12:36:42 (14288): Guest Log: -rw------- 1 atlas01 atlas01   5079040 Aug 21 12:36 result.tar.gz

2019-08-21 12:36:42 (14288): Guest Log: HITS file was successfully produced

2019-08-21 12:36:42 (14288): Guest Log: -rw------- 1 atlas01 atlas01 120777073 Aug 21 12:32 /home/atlas01/RunAtlas/HITS.18722355._013746.pool.root.1

2019-08-21 12:36:42 (14288): Guest Log: Successfully finished the ATLAS job!

2019-08-21 12:36:42 (14288): Guest Log: Copying the results back to the shared directory!

2019-08-21 12:36:42 (14288): Guest Log: Copied the result file back to the shared directory and created atlas_done file!

2019-08-21 12:36:42 (14288): Guest Log: Success! Shutting down the machine.

2019-08-21 12:36:42 (14288): VM Completion File Detected.
2019-08-21 12:36:42 (14288): Powering off VM.
2019-08-21 12:36:44 (14288): Successfully stopped VM.
2019-08-21 12:36:49 (14288): Deregistering VM. (boinc_9f158f77209da153, slot#9)
2019-08-21 12:36:49 (14288): Removing virtual disk drive(s) from VM.
2019-08-21 12:36:49 (14288): Removing network bandwidth throttle group from VM.
2019-08-21 12:36:49 (14288): Removing storage controller(s) from VM.
2019-08-21 12:36:49 (14288): Removing VM from VirtualBox.
12:36:54 (14288): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN