Stderr output

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

2019-04-10 11:25:27 (1349236): Guest Log: CPUID EDX: 0x178bfbff

2019-04-10 11:25:27 (1349236): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-04-10 11:25:27 (1349236): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-04-10 11:25:27 (1349236): Guest Log: BIOS: Booting from Hard Disk...

2019-04-10 11:25:27 (1349236): Successfully started VM. (PID = '1349344')
2019-04-10 11:25:27 (1349236): Reporting VM Process ID to BOINC.
2019-04-10 11:25:32 (1349236): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-04-10 11:25:32 (1349236): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-04-10 11:25:32 (1349236): VM state change detected. (old = 'poweroff', new = 'running')
2019-04-10 11:25:37 (1349236): Detected: Web Application Enabled (http://localhost:17439)
2019-04-10 11:25:37 (1349236): Detected: Remote Desktop Enabled (localhost:17440)
2019-04-10 11:25:42 (1349236): Stopping VM.
2019-04-10 11:25:46 (1349236): Successfully stopped VM.
2019-04-10 11:35:29 (6620): vboxwrapper (7.9.26200): starting
2019-04-10 11:35:30 (6620): Feature: Checkpoint interval offset (396 seconds)
2019-04-10 11:35:30 (6620): Detected: VirtualBox COM Interface (Version: 5.2.18)
2019-04-10 11:35:30 (6620): Detected: Minimum checkpoint interval (900.000000 seconds)
2019-04-10 11:35:30 (6620): Guest Log: BIOS: VirtualBox 5.2.18

2019-04-10 11:35:30 (6620): Guest Log: CPUID EDX: 0x178bfbff

2019-04-10 11:35:30 (6620): Guest Log: BIOS: ata0-0: PCHS=16383/16/63 LCHS=1024/255/63

2019-04-10 11:35:30 (6620): Guest Log: BIOS: Boot : bseqnr=1, bootseq=0032

2019-04-10 11:35:30 (6620): Guest Log: BIOS: Booting from Hard Disk...

2019-04-10 11:35:30 (6620): Guest Log: BIOS: KBD: unsupported int 16h function 03

2019-04-10 11:35:30 (6620): Guest Log: BIOS: AX=0305 BX=0000 CX=0000 DX=0000 

2019-04-10 11:35:30 (6620): Starting VM. (boinc_4bd102b2c4dc40ea, slot#1)
2019-04-10 11:35:42 (6620): Successfully started VM. (PID = '8052')
2019-04-10 11:35:42 (6620): Reporting VM Process ID to BOINC.
2019-04-10 11:35:47 (6620): Guest Log: vboxguest: major 0, IRQ 20, I/O port d020, MMIO at 00000000f0400000 (size 0x400000)

2019-04-10 11:35:47 (6620): VM state change detected. (old = 'poweroff', new = 'running')
2019-04-10 11:35:52 (6620): Detected: Web Application Enabled (http://localhost:17439)
2019-04-10 11:35:52 (6620): Detected: Remote Desktop Enabled (localhost:17440)
2019-04-10 11:35:57 (6620): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801ac328c10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-04-10 11:35:57 (6620): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801ac328010), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-04-10 11:35:57 (6620): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801ae588e10), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-04-10 11:35:57 (6620): Guest Log: VBoxGuest: VBoxGuestCommonGuestCapsAcquire: pSession(0xffff8801ac329610), OR(0x0), NOT(0xffffffff), flags(0x0)

2019-04-10 11:35:57 (6620): Preference change detected
2019-04-10 11:35:57 (6620): Setting CPU throttle for VM. (100%)
2019-04-10 11:35:57 (6620): Setting checkpoint interval to 900 seconds. (Higher value of (Preference: 600 seconds) or (Vbox_job.xml: 900 seconds))
2019-04-10 11:37:17 (6620): Guest Log: Copying input files into RunAtlas.

2019-04-10 11:37:27 (6620): Guest Log: Copied input files into RunAtlas.

2019-04-10 11:37:42 (6620): Guest Log: copied the webapp to /var/www

2019-04-10 11:37:42 (6620): Guest Log: set up http_proxy http://192.168.200.1:0

2019-04-10 11:37:42 (6620): Guest Log: ATHENA_PROC_NUMBER=4

2019-04-10 11:37:42 (6620): Guest Log: Starting ATLAS job. ( )

2019-04-10 12:50:35 (6620): Guest Log: log_extracts:

2019-04-10 12:50:35 (6620): Guest Log: - Last 10 lines from /home/atlas01/RunAtlas/Panda_Pilot_7836_1554889128/PandaJob/athena_stdout.txt -

2019-04-10 12:50:35 (6620): Guest Log: PyJobTransforms.trfExe.preExecute 2019-04-10 11:40:55,019 INFO Batch/grid running - command outputs will not be echoed. Logs for EVNTtoHITS are in log.EVNTtoHITS

2019-04-10 12:50:35 (6620): Guest Log: PyJobTransforms.trfExe.preExecute 2019-04-10 11:40:55,019 INFO Now writing wrapper for substep executor EVNTtoHITS

2019-04-10 12:50:35 (6620): Guest Log: PyJobTransforms.trfExe._writeAthenaWrapper 2019-04-10 11:40:55,019 INFO Valgrind not engaged

2019-04-10 12:50:35 (6620): Guest Log: PyJobTransforms.trfExe.preExecute 2019-04-10 11:40:55,020 INFO Athena will be executed in a subshell via ['./runwrapper.EVNTtoHITS.sh']

2019-04-10 12:50:35 (6620): Guest Log: PyJobTransforms.trfExe.execute 2019-04-10 11:40:55,020 INFO Starting execution of EVNTtoHITS (['./runwrapper.EVNTtoHITS.sh'])

2019-04-10 12:50:35 (6620): Guest Log: PyJobTransforms.trfExe.execute 2019-04-10 12:48:08,624 INFO EVNTtoHITS executor returns 33

2019-04-10 12:50:35 (6620): Guest Log: PyJobTransforms.trfExe.validate 2019-04-10 12:48:09,700 ERROR Validation of return code failed: Non-zero return code from EVNTtoHITS (33) (Error code 65)

2019-04-10 12:50:35 (6620): Guest Log: PyJobTransforms.trfExe.validate 2019-04-10 12:48:09,784 INFO Scanning logfile log.EVNTtoHITS for errors

2019-04-10 12:50:35 (6620): Guest Log: PyJobTransforms.transform.execute 2019-04-10 12:48:09,832 CRITICAL Transform executor raised TransformValidationException: Non-zero return code from EVNTtoHITS (33); Logfile error in log.EVNTtoHITS: "IOVDbSvc            FATAL Conditions database connection COOLOFL_TRT/OFLP200 cannot be opened - STOP"

2019-04-10 12:50:35 (6620): Guest Log: PyJobTransforms.transform.execute 2019-04-10 12:48:13,293 WARNING Transform now exiting early with exit code 65 (Non-zero return code from EVNTtoHITS (33); Logfile error in log.EVNTtoHITS: "IOVDbSvc            FATAL Conditions database connection COOLOFL_TRT/OFLP200 cannot be opened - STOP")

2019-04-10 12:50:35 (6620): Guest Log: - Walltime -

2019-04-10 12:50:35 (6620): Guest Log: JobRetrival=1, StageIn=12, Execution=4119, StageOut=0, CleanUp=21

2019-04-10 12:50:35 (6620): Guest Log: The last 10 lines of the pilot log.

2019-04-10 12:50:35 (6620): Guest Log:     <metadata att_name="surl" att_value="srm://srm.ndgf.org:8443/srm/managerv2?SFN=/atlas/disk/atlasdatadisk/rucio/mc16_13TeV/a4/6d/log.17602216._037650.job.log.tgz.1"/>

2019-04-10 12:50:35 (6620): Guest Log:     <metadata att_name="fsize" att_value="79299"/>

2019-04-10 12:50:35 (6620): Guest Log:     <metadata att_name="adler32" att_value="de93cd77"/>

2019-04-10 12:50:35 (6620): Guest Log:   </File>

2019-04-10 12:50:35 (6620): Guest Log: </POOLFILECATALOG>

2019-04-10 12:50:35 (6620): Guest Log: 2019-04-10 10:50:31 UTC [wrapper] ---------

2019-04-10 12:50:35 (6620): Guest Log: 2019-04-10 10:50:31 UTC [wrapper] output list

2019-04-10 12:50:35 (6620): Guest Log: log.17602216._037650.job.log.tgz.1 srm://srm.ndgf.org:8443;autodir=no;spacetoken=ATLASDATADISK/srm/managerv2?SFN=/atlas/disk/atlasdatadisk/rucio/mc16_13TeV/a4/6d/log.17602216._037650.job.log.tgz.1:checksumtype=adler32:checksumvalue=de93cd77

2019-04-10 12:50:35 (6620): Guest Log: 2019-04-10 10:50:31 UTC [wrapper] ==== wrapper stdout END ====

2019-04-10 12:50:35 (6620): Guest Log: 2019-04-10 10:50:31 UTC [wrapper] ==== wrapper stderr END ====

2019-04-10 12:50:35 (6620): Guest Log: Listing of results directory

2019-04-10 12:50:35 (6620): Guest Log: total 380944

2019-04-10 12:50:35 (6620): Guest Log: -rw-r--r-- 1 atlas01 atlas01   1089918 Apr  3 05:59 pilotcode.tar.gz

2019-04-10 12:50:35 (6620): Guest Log: -rwx------ 1 atlas01 atlas01     15214 Apr  9 00:38 runpilot3-wrapper.sh

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01      2886 Apr  9 00:38 pandaJobData.out

2019-04-10 12:50:35 (6620): Guest Log: -rwxr-xr-x 1 atlas01 atlas01 374359475 Apr 10 11:37 EVNT.16926542._000752.pool.root.1

2019-04-10 12:50:35 (6620): Guest Log: -rwxr-xr-x 1 atlas01 atlas01     10353 Apr 10 11:37 init_data.xml

2019-04-10 12:50:35 (6620): Guest Log: -rwxr-xr-x 1 atlas01 atlas01   1098427 Apr 10 11:37 input.tar.gz

2019-04-10 12:50:35 (6620): Guest Log: -rwxr-xr-x 1 atlas01 atlas01       786 Apr 10 11:37 RTE.tar.gz

2019-04-10 12:50:35 (6620): Guest Log: -rwxr-xr-x 1 atlas01 atlas01      8644 Apr 10 11:37 start_atlas.sh

2019-04-10 12:50:35 (6620): Guest Log: drwxr-xr-x 3 atlas01 atlas01      4096 Apr 10 11:37 APPS

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01       243 Apr 10 11:37 setup.sh.local

2019-04-10 12:50:35 (6620): Guest Log: lrwxrwxrwx 1 atlas01 atlas01        16 Apr 10 11:38 RunJob.py -> pilot3/RunJob.py

2019-04-10 12:50:35 (6620): Guest Log: lrwxrwxrwx 1 atlas01 atlas01        21 Apr 10 11:38 RunJobEvent.py -> pilot3/RunJobEvent.py

2019-04-10 12:50:35 (6620): Guest Log: lrwxrwxrwx 1 atlas01 atlas01        16 Apr 10 11:38 VmPeak.py -> pilot3/VmPeak.py

2019-04-10 12:50:35 (6620): Guest Log: lrwxrwxrwx 1 atlas01 atlas01        19 Apr 10 11:38 PILOTVERSION -> pilot3/PILOTVERSION

2019-04-10 12:50:35 (6620): Guest Log: lrwxrwxrwx 1 atlas01 atlas01        15 Apr 10 11:38 pilot.py -> pilot3/pilot.py

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01        22 Apr 10 11:38 PILOT_INITDIR

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01        50 Apr 10 11:38 CURRENT_SITEWORKDIR

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01     11294 Apr 10 11:38 pilotlog.txt

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01         1 Apr 10 11:38 STATUSCODE

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01        11 Apr 10 11:38 pandaIDs.out

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01        19 Apr 10 11:38 START_TIME_4302798401

2019-04-10 12:50:35 (6620): Guest Log: -rw-r--r-- 1 atlas01 atlas01      3803 Apr 10 11:38 queuedata.json

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01      2886 Apr 10 11:38 pandaJobData_1.out

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01   7210745 Apr 10 11:39 agis_ddmendpoints.cvmfs.json

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01   4842551 Apr 10 11:39 agis_schedconf.cvmfs.json

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01       297 Apr 10 12:48 memory_monitor_summary.json

2019-04-10 12:50:35 (6620): Guest Log: drwx------ 6 atlas01 atlas01     12288 Apr 10 12:48 pilot3

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01       203 Apr 10 12:48 pilot_error_report.json

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01       166 Apr 10 12:48 workdir_size-4302798401.json

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01     79299 Apr 10 12:48 log.17602216._037650.job.log.tgz.1

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01      1757 Apr 10 12:49 log_extracts.txt

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01     10477 Apr 10 12:49 panda_node_struct.pickle

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01       137 Apr 10 12:50 pilotlog-last.txt

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01       598 Apr 10 12:50 metadata-surl.xml

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01      3872 Apr 10 12:50 jobSmallFiles.tgz

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01    364760 Apr 10 12:50 log.17602216._037650.job.log.1

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01       489 Apr 10 12:50 8N5NDmD5rXunShfckohDCDFpABFKDmABFKDmtWeaDmABFKDmPZtGEm.diag

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01       241 Apr 10 12:50 output.list

2019-04-10 12:50:35 (6620): Guest Log: -rw-r--r-- 1 atlas01 atlas01      7869 Apr 10 12:50 runtime_log.err

2019-04-10 12:50:35 (6620): Guest Log: -rw-r--r-- 1 atlas01 atlas01       712 Apr 10 12:50 runtime_log

2019-04-10 12:50:35 (6620): Guest Log: -rw------- 1 atlas01 atlas01    829440 Apr 10 12:50 result.tar.gz

2019-04-10 12:50:35 (6620): Guest Log: No HITS file was produced

2019-04-10 12:50:35 (6620): Guest Log: Successfully finished the ATLAS job!

2019-04-10 12:50:35 (6620): Guest Log: Copying the results back to the shared directory!

2019-04-10 12:50:35 (6620): Guest Log: Copied the result file back to the shared directory and created atlas_done file!

2019-04-10 12:50:35 (6620): Guest Log: Success! Shutting down the machine.

2019-04-10 12:50:35 (6620): VM Completion File Detected.
2019-04-10 12:50:35 (6620): Powering off VM.
2019-04-10 12:50:38 (6620): Successfully stopped VM.
2019-04-10 12:50:43 (6620): Deregistering VM. (boinc_4bd102b2c4dc40ea, slot#1)
2019-04-10 12:50:43 (6620): Removing virtual disk drive(s) from VM.
2019-04-10 12:50:43 (6620): Removing network bandwidth throttle group from VM.
2019-04-10 12:50:43 (6620): Removing storage controller(s) from VM.
2019-04-10 12:50:44 (6620): Removing VM from VirtualBox.
12:50:49 (6620): called boinc_finish(0)

</stderr_txt>
]]>

©2024 CERN