| Name | He2NDmyQKz7nsSi4ap6QjLDmwznN0nGgGQJmUXzaDmGAOLDmAZ14Mn_1 |
| Workunit | 233779286 |
| Created | 25 Jul 2025, 2:23:48 UTC |
| Sent | 25 Jul 2025, 5:32:20 UTC |
| Report deadline | 2 Aug 2025, 5:32:20 UTC |
| Received | 25 Jul 2025, 19:21:38 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10877468 |
| Run time | 47 min 24 sec |
| CPU time | 1 hours 26 min 45 sec |
| Validate state | Valid |
| Credit | 52.29 |
| Device peak FLOPS | 16.09 GFLOPS |
| Application version | ATLAS Simulation v3.01 (native_mt) x86_64-pc-linux-gnu |
| Peak working set size | 2.46 GB |
| Peak swap size | 2.72 GB |
| Peak disk usage | 433.69 MB |
Stderr output
<core_client_version>8.0.3</core_client_version>
<![CDATA[
<stderr_txt>
22:33:08 (2988301): wrapper (7.7.26015): starting
22:33:08 (2988301): wrapper: running run_atlas (--nthreads 2)
[2025-07-24 22:33:08] Arguments: --nthreads 2
[2025-07-24 22:33:08] Threads: 2
[2025-07-24 22:33:08] Checking for CVMFS
[2025-07-24 22:33:08] Probing /cvmfs/atlas.cern.ch... OK
[2025-07-24 22:33:08] Probing /cvmfs/atlas-condb.cern.ch... OK
[2025-07-24 22:33:08] Running cvmfs_config stat atlas.cern.ch
[2025-07-24 22:33:09] VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
[2025-07-24 22:33:09] 2.13.1.0 2681522 253 53812 148728 3 188 3349155 10240000 2888 130560 0 712159 99.997 10638 710 http://s1fnal-cvmfs.openhtc.io:8080/cvmfs/atlas.cern.ch DIRECT 1
[2025-07-24 22:33:09] CVMFS is ok
[2025-07-24 22:33:09] Efficiency of ATLAS tasks can be improved by the following measure(s):
[2025-07-24 22:33:09] Small home clusters do not require a local http proxy but it is suggested if
[2025-07-24 22:33:09] more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
[2025-07-24 22:33:09] Further information can be found at the LHC@home message board.
[2025-07-24 22:33:09] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7
[2025-07-24 22:33:09] Checking for apptainer binary...
[2025-07-24 22:33:09] Using apptainer found in PATH at /usr/bin/apptainer
[2025-07-24 22:33:09] Running /usr/bin/apptainer --version
[2025-07-24 22:33:09] apptainer version 1.4.1
[2025-07-24 22:33:09] Checking apptainer works with /usr/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname
[2025-07-24 22:33:09] robot
[2025-07-24 22:33:09] apptainer works
[2025-07-24 22:33:09] Set ATHENA_PROC_NUMBER=2
[2025-07-24 22:33:09] Set ATHENA_CORE_NUMBER=2
[2025-07-24 22:33:09] Starting ATLAS job with PandaID=6744938872
[2025-07-24 22:33:09] Running command: /usr/bin/apptainer exec -B /cvmfs,/var/lib/boinc/slots/5 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 sh start_atlas.sh
10:44:35 (3045971): wrapper (7.7.26015): starting
10:44:35 (3045971): wrapper: running run_atlas (--nthreads 2)
[2025-07-25 10:44:35] Arguments: --nthreads 2
[2025-07-25 10:44:35] Threads: 2
[2025-07-25 10:44:35] This job has been restarted, cleaning up previous attempt
[2025-07-25 10:44:35] Checking for CVMFS
[2025-07-25 10:44:36] Probing /cvmfs/atlas.cern.ch... OK
[2025-07-25 10:44:36] Probing /cvmfs/atlas-condb.cern.ch... OK
[2025-07-25 10:44:36] Running cvmfs_config stat atlas.cern.ch
[2025-07-25 10:44:37] VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
[2025-07-25 10:44:37] 2.13.1.0 3046303 0 30848 148748 3 1 3428061 10240000 0 130560 0 0 0.000 1291 1623 http://s1fnal-cvmfs.openhtc.io:8080/cvmfs/atlas.cern.ch DIRECT 1
[2025-07-25 10:44:37] CVMFS is ok
[2025-07-25 10:44:37] Efficiency of ATLAS tasks can be improved by the following measure(s):
[2025-07-25 10:44:37] Small home clusters do not require a local http proxy but it is suggested if
[2025-07-25 10:44:37] more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
[2025-07-25 10:44:37] Further information can be found at the LHC@home message board.
[2025-07-25 10:44:37] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7
[2025-07-25 10:44:37] Checking for apptainer binary...
[2025-07-25 10:44:37] Using apptainer found in PATH at /usr/bin/apptainer
[2025-07-25 10:44:37] Running /usr/bin/apptainer --version
[2025-07-25 10:44:37] apptainer version 1.4.1
[2025-07-25 10:44:37] Checking apptainer works with /usr/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname
[2025-07-25 10:44:37] robot
[2025-07-25 10:44:37] apptainer works
[2025-07-25 10:44:37] Set ATHENA_PROC_NUMBER=2
[2025-07-25 10:44:37] Set ATHENA_CORE_NUMBER=2
[2025-07-25 10:44:37] Starting ATLAS job with PandaID=6744938872
[2025-07-25 10:44:37] Running command: /usr/bin/apptainer exec -B /cvmfs,/var/lib/boinc/slots/5 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 sh start_atlas.sh
[2025-07-25 10:47:45] Job failed
[2025-07-25 10:47:45] ++ pwd
[2025-07-25 10:47:45] + export RUNTIME_CONFIG_DIR=/var/lib/boinc/slots/5/
[2025-07-25 10:47:45] + RUNTIME_CONFIG_DIR=/var/lib/boinc/slots/5/
[2025-07-25 10:47:45] + mv ATLAS.root_0 EVNT.45743347._000675.pool.root.1
[2025-07-25 10:47:45] mv: ‘ATLAS.root_0’ and ‘EVNT.45743347._000675.pool.root.1’ are the same file
[2025-07-25 10:47:45] + tar --strip-components=5 -xvf input.tar.gz
[2025-07-25 10:47:45] + umask 077
[2025-07-25 10:47:45] ++ date +%s
[2025-07-25 10:47:45] + ACCOUNTING_STARTTIME=1753465477
[2025-07-25 10:47:45] + echo 'Detecting resource accounting method available for the job.'
[2025-07-25 10:47:45] Detecting resource accounting method available for the job.
[2025-07-25 10:47:45] + JOB_ACCOUNTING=
[2025-07-25 10:47:45] + command -v arc-job-cgroup
[2025-07-25 10:47:45] + '[' -z '' ']'
[2025-07-25 10:47:45] + GNU_TIME=/usr/bin/time
[2025-07-25 10:47:45] + echo 'Looking for /usr/bin/time tool for accounting measurements'
[2025-07-25 10:47:45] Looking for /usr/bin/time tool for accounting measurements
[2025-07-25 10:47:45] + '[' '!' -z /usr/bin/time ']'
[2025-07-25 10:47:45] + /usr/bin/time --version
[2025-07-25 10:47:45] + echo 'GNU time found and will be used for job accounting.'
[2025-07-25 10:47:45] GNU time found and will be used for job accounting.
[2025-07-25 10:47:45] + JOB_ACCOUNTING=gnutime
[2025-07-25 10:47:45] + '[' -z gnutime ']'
[2025-07-25 10:47:45] + export PANDA_JSID=harvester-CERN_central_ACTA
[2025-07-25 10:47:45] + PANDA_JSID=harvester-CERN_central_ACTA
[2025-07-25 10:47:45] + export GTAG=http://aipanda404.cern.ch/data/jobs/2025-07-24/BOINC_MCORE/6744938872.out
[2025-07-25 10:47:45] + GTAG=http://aipanda404.cern.ch/data/jobs/2025-07-24/BOINC_MCORE/6744938872.out
[2025-07-25 10:47:45] + export PILOT_NOKILL=YES
[2025-07-25 10:47:45] + PILOT_NOKILL=YES
[2025-07-25 10:47:45] + export GRID_GLOBAL_JOBID=He2NDmyQKz7nsSi4ap6QjLDmwznN0nGgGQJmUXzaDmGAOLDmAZ14Mn
[2025-07-25 10:47:45] + GRID_GLOBAL_JOBID=He2NDmyQKz7nsSi4ap6QjLDmwznN0nGgGQJmUXzaDmGAOLDmAZ14Mn
[2025-07-25 10:47:45] + export GRID_GLOBAL_JOBURL=https://arc-boinc-01.cern.ch:443/arex/He2NDmyQKz7nsSi4ap6QjLDmwznN0nGgGQJmUXzaDmGAOLDmAZ14Mn
[2025-07-25 10:47:45] + GRID_GLOBAL_JOBURL=https://arc-boinc-01.cern.ch:443/arex/He2NDmyQKz7nsSi4ap6QjLDmwznN0nGgGQJmUXzaDmGAOLDmAZ14Mn
[2025-07-25 10:47:45] + export GRID_GLOBAL_JOBINTERFACE=org.nordugrid.arcrest
[2025-07-25 10:47:45] + GRID_GLOBAL_JOBINTERFACE=org.nordugrid.arcrest
[2025-07-25 10:47:45] + export GRID_GLOBAL_JOBHOST=arc-boinc-01.cern.ch
[2025-07-25 10:47:45] + GRID_GLOBAL_JOBHOST=arc-boinc-01.cern.ch
[2025-07-25 10:47:45] ++ pwd
[2025-07-25 10:47:45] + RUNTIME_JOB_DIR=/var/lib/boinc/slots/5
[2025-07-25 10:47:45] + RUNTIME_JOB_STDIN=/dev/null
[2025-07-25 10:47:45] ++ pwd
[2025-07-25 10:47:45] + RUNTIME_JOB_STDOUT=/var/lib/boinc/slots/5/log.45743349._012589.job.log.1
[2025-07-25 10:47:45] ++ pwd
[2025-07-25 10:47:45] + RUNTIME_JOB_STDERR=/var/lib/boinc/slots/5/log.45743349._012589.job.log.1
[2025-07-25 10:47:45] ++ pwd
[2025-07-25 10:47:45] + RUNTIME_JOB_DIAG=/var/lib/boinc/slots/5.diag
[2025-07-25 10:47:45] + '[' '!' -z '' ']'
[2025-07-25 10:47:45] + RUNTIME_LOCAL_SCRATCH_DIR=
[2025-07-25 10:47:45] + RUNTIME_LOCAL_SCRATCH_MOVE_TOOL=mv
[2025-07-25 10:47:45] + RUNTIME_FRONTEND_SEES_NODE=
[2025-07-25 10:47:45] + RUNTIME_NODE_SEES_FRONTEND=yes
[2025-07-25 10:47:45] + '[' '!' -z '' ']'
[2025-07-25 10:47:45] + '[' -z yes ']'
[2025-07-25 10:47:45] ++ pwd
[2025-07-25 10:47:45] + RUNTIME_JOB_DIAG=/var/lib/boinc/slots/5/He2NDmyQKz7nsSi4ap6QjLDmwznN0nGgGQJmUXzaDmGAOLDmAZ14Mn.diag
[2025-07-25 10:47:45] + runtimeenvironments=
[2025-07-25 10:47:45] + runtimeenvironments='APPS/HEP/ATLAS-SITE;'
[2025-07-25 10:47:45] + RTE_function_0 1
[2025-07-25 10:47:45] + export PROJECT_ROOT=/boincdata/boinc/project/lhcathome
[2025-07-25 10:47:45] + PROJECT_ROOT=/boincdata/boinc/project/lhcathome
[2025-07-25 10:47:45] + export BOINC_APP=ATLAS
[2025-07-25 10:47:45] + BOINC_APP=ATLAS
[2025-07-25 10:47:45] + export WU_TEMPLATE=templates/ATLAS_IN_DYNAMIC
[2025-07-25 10:47:45] + WU_TEMPLATE=templates/ATLAS_IN_DYNAMIC
[2025-07-25 10:47:45] + export RESULT_TEMPLATE=templates/ATLAS_OUT_2
[2025-07-25 10:47:45] + RESULT_TEMPLATE=templates/ATLAS_OUT_2
[2025-07-25 10:47:45] + '[' -z 1 ']'
[2025-07-25 10:47:45] + '[' 1 == 1 ']'
[2025-07-25 10:47:45] + shift
[2025-07-25 10:47:45] + export ATLAS_LOCAL_AREA=/var/lib/boinc/slots/5/
[2025-07-25 10:47:45] + ATLAS_LOCAL_AREA=/var/lib/boinc/slots/5/
[2025-07-25 10:47:45] + '[' 0 -ne 0 ']'
[2025-07-25 10:47:45] + echo 'runtimeenvironments=APPS/HEP/ATLAS-SITE;'
[2025-07-25 10:47:45] + '[' -f /etc/os-release ']'
[2025-07-25 10:47:45] +++ cat /etc/os-release
[2025-07-25 10:47:45] ++ eval 'NAME="CentOS' 'Linux"' 'VERSION="7' '(Core)"' 'ID="centos"' 'ID_LIKE="rhel' 'fedora"' 'VERSION_ID="7"' 'PRETTY_NAME="CentOS' Linux 7 '(Core)"' 'ANSI_COLOR="0;31"' 'CPE_NAME="cpe:/o:centos:centos:7"' 'HOME_URL="https://www.centos.org/"' 'BUG_REPORT_URL="https://bugs.centos.org/"' 'CENTOS_MANTISBT_PROJECT="CentOS-7"' 'CENTOS_MANTISBT_PROJECT_VERSION="7"' 'REDHAT_SUPPORT_PRODUCT="centos"' 'REDHAT_SUPPORT_PRODUCT_VERSION="7"'
[2025-07-25 10:47:45] +++ NAME='CentOS Linux'
[2025-07-25 10:47:45] +++ VERSION='7 (Core)'
[2025-07-25 10:47:45] +++ ID=centos
[2025-07-25 10:47:45] +++ ID_LIKE='rhel fedora'
[2025-07-25 10:47:45] +++ VERSION_ID=7
[2025-07-25 10:47:45] +++ PRETTY_NAME='CentOS Linux 7 (Core)'
[2025-07-25 10:47:45] +++ ANSI_COLOR='0;31'
[2025-07-25 10:47:45] +++ CPE_NAME=cpe:/o:centos:centos:7
[2025-07-25 10:47:45] +++ HOME_URL=https://www.centos.org/
[2025-07-25 10:47:45] +++ BUG_REPORT_URL=https://bugs.centos.org/
[2025-07-25 10:47:45] +++ CENTOS_MANTISBT_PROJECT=CentOS-7
[2025-07-25 10:47:45] +++ CENTOS_MANTISBT_PROJECT_VERSION=7
[2025-07-25 10:47:45] +++ REDHAT_SUPPORT_PRODUCT=centos
[2025-07-25 10:47:45] +++ REDHAT_SUPPORT_PRODUCT_VERSION=7
[2025-07-25 10:47:45] ++ echo 'CentOS Linux 7 (Core)'
[2025-07-25 10:47:45] + SYSTEM_SOFTWARE='CentOS Linux 7 (Core)'
[2025-07-25 10:47:45] + '[' -n 'CentOS Linux 7 (Core)' ']'
[2025-07-25 10:47:45] + echo 'systemsoftware=CentOS Linux 7 (Core)'
[2025-07-25 10:47:45] + HOME=/var/lib/boinc/slots/5
[2025-07-25 10:47:45] + export HOME
[2025-07-25 10:47:45] + cd /var/lib/boinc/slots/5
[2025-07-25 10:47:45] + '[' '!' -z '' ']'
[2025-07-25 10:47:45] + '[' -z '' ']'
[2025-07-25 10:47:45] ++ /bin/hostname -f
[2025-07-25 10:47:45] + nodename=robot
[2025-07-25 10:47:45] + echo nodename=robot
[2025-07-25 10:47:45] + echo Processors=2
[2025-07-25 10:47:45] + echo Nodecount=1
[2025-07-25 10:47:45] + echo Benchmark=HEPSPEC:1.0
[2025-07-25 10:47:45] + executable=./runpilot2-wrapper.sh
[2025-07-25 10:47:45] + '[' '!' -f ./runpilot2-wrapper.sh ']'
[2025-07-25 10:47:45] ++ dd if=./runpilot2-wrapper.sh count=1
[2025-07-25 10:47:45] ++ head -n 1
[2025-07-25 10:47:45] ++ tr -d '\0'
[2025-07-25 10:47:45] + line1='#!/bin/bash'
[2025-07-25 10:47:45] ++ echo '#!/bin/bash'
[2025-07-25 10:47:45] ++ sed -n 's/^#! *//p'
[2025-07-25 10:47:45] + shebang=/bin/bash
[2025-07-25 10:47:45] ++ echo /bin/bash
[2025-07-25 10:47:45] ++ awk '{print $1}'
[2025-07-25 10:47:45] + interpreter=/bin/bash
[2025-07-25 10:47:45] + '[' /bin/bash = /usr/bin/env ']'
[2025-07-25 10:47:45] + '[' x/bin/bash = x ']'
[2025-07-25 10:47:45] + type /bin/bash
[2025-07-25 10:47:45] + '[' xgnutime = xgnutime ']'
[2025-07-25 10:47:45] + /usr/bin/time -o /var/lib/boinc/slots/5/He2NDmyQKz7nsSi4ap6QjLDmwznN0nGgGQJmUXzaDmGAOLDmAZ14Mn.diag -a -f 'WallTime=%es\nKernelTime=%Ss\nUserTime=%Us\nCPUUsage=%P\nMaxResidentMemory=%MkB\nAverageResidentMemory=%tkB\nAverageTotalMemory=%KkB\nAverageUnsharedMemory=%DkB\nAverageUnsharedStack=%pkB\nAverageSharedMemory=%XkB\nPageSize=%ZB\nMajorPageFaults=%F\nMinorPageFaults=%R\nSwaps=%W\nForcedSwitches=%c\nWaitSwitches=%w\nInputs=%I\nOutputs=%O\nSocketReceived=%r\nSocketSent=%s\nSignals=%k\n' ./runpilot2-wrapper.sh -q BOINC_MCORE -j managed --pilot-user ATLAS --harvester-submit-mode PUSH -w generic --job-type managed --resource-type MCORE --pilotversion 3.10.4.12 -z -t --piloturl local --mute --container
[2025-07-25 10:47:45] + RESULT=2
[2025-07-25 10:47:45] + sed -i -e /nodename=/d /var/lib/boinc/slots/5/He2NDmyQKz7nsSi4ap6QjLDmwznN0nGgGQJmUXzaDmGAOLDmAZ14Mn.diag
[2025-07-25 10:47:45] ++ grep domain_name init_data.xml
[2025-07-25 10:47:45] ++ awk -F '>' '{print $2}'
[2025-07-25 10:47:45] ++ awk -F '<' '{print $1}'
[2025-07-25 10:47:45] ++ sed -e 's# #_#g'
[2025-07-25 10:47:45] + hostname=robot
[2025-07-25 10:47:45] ++ grep user_name init_data.xml
[2025-07-25 10:47:45] ++ awk -F '>' '{print $2}'
[2025-07-25 10:47:45] ++ awk -F '<' '{print $1}'
[2025-07-25 10:47:45] ++ sed -e 's# #_#g'
[2025-07-25 10:47:45] + username=Cobra
[2025-07-25 10:47:45] + nodename=Cobra@robot
[2025-07-25 10:47:45] + echo nodename=Cobra@robot
[2025-07-25 10:47:45] + '[' -n 2 ']'
[2025-07-25 10:47:45] + sed -i -e s/Processors=1/Processors=2/ /var/lib/boinc/slots/5/He2NDmyQKz7nsSi4ap6QjLDmwznN0nGgGQJmUXzaDmGAOLDmAZ14Mn.diag
[2025-07-25 10:47:45] + echo exitcode=2
[2025-07-25 10:47:45] + runtimeenvironments=
[2025-07-25 10:47:45] + runtimeenvironments='APPS/HEP/ATLAS-SITE;'
[2025-07-25 10:47:45] + RTE_function_0 2
[2025-07-25 10:47:45] + export PROJECT_ROOT=/boincdata/boinc/project/lhcathome
[2025-07-25 10:47:45] + PROJECT_ROOT=/boincdata/boinc/project/lhcathome
[2025-07-25 10:47:45] + export BOINC_APP=ATLAS
[2025-07-25 10:47:45] + BOINC_APP=ATLAS
[2025-07-25 10:47:45] + export WU_TEMPLATE=templates/ATLAS_IN_DYNAMIC
[2025-07-25 10:47:45] + WU_TEMPLATE=templates/ATLAS_IN_DYNAMIC
[2025-07-25 10:47:45] + export RESULT_TEMPLATE=templates/ATLAS_OUT_2
[2025-07-25 10:47:45] + RESULT_TEMPLATE=templates/ATLAS_OUT_2
[2025-07-25 10:47:45] + '[' -z 2 ']'
[2025-07-25 10:47:45] + '[' 2 == 1 ']'
[2025-07-25 10:47:45] + '[' 0 -ne 0 ']'
[2025-07-25 10:47:45] + echo 'zip all output files'
[2025-07-25 10:47:45] + flist='*.diag '
[2025-07-25 10:47:45] + for f in ./heartbeat.json ./output.list ./log.45743349._012589.job.log.1 ./log.45743349._012589.job.log.1 ./gmlog/
[2025-07-25 10:47:45] + '[' -e ./heartbeat.json ']'
[2025-07-25 10:47:45] + flist='*.diag ./heartbeat.json'
[2025-07-25 10:47:45] + for f in ./heartbeat.json ./output.list ./log.45743349._012589.job.log.1 ./log.45743349._012589.job.log.1 ./gmlog/
[2025-07-25 10:47:45] + '[' -e ./output.list ']'
[2025-07-25 10:47:45] + for f in ./heartbeat.json ./output.list ./log.45743349._012589.job.log.1 ./log.45743349._012589.job.log.1 ./gmlog/
[2025-07-25 10:47:45] + '[' -e ./log.45743349._012589.job.log.1 ']'
[2025-07-25 10:47:45] + flist='*.diag ./heartbeat.json ./log.45743349._012589.job.log.1'
[2025-07-25 10:47:45] + for f in ./heartbeat.json ./output.list ./log.45743349._012589.job.log.1 ./log.45743349._012589.job.log.1 ./gmlog/
[2025-07-25 10:47:45] + '[' -e ./log.45743349._012589.job.log.1 ']'
[2025-07-25 10:47:45] + flist='*.diag ./heartbeat.json ./log.45743349._012589.job.log.1 ./log.45743349._012589.job.log.1'
[2025-07-25 10:47:45] + for f in ./heartbeat.json ./output.list ./log.45743349._012589.job.log.1 ./log.45743349._012589.job.log.1 ./gmlog/
[2025-07-25 10:47:45] + '[' -e ./gmlog/ ']'
[2025-07-25 10:47:45] + '[' -f output.list ']'
[2025-07-25 10:47:45] + tar cvf result.tar.gz He2NDmyQKz7nsSi4ap6QjLDmwznN0nGgGQJmUXzaDmGAOLDmAZ14Mn.diag ./heartbeat.json ./log.45743349._012589.job.log.1 ./log.45743349._012589.job.log.1
[2025-07-25 10:47:45] + '[' xgnutime = xcgroup ']'
[2025-07-25 10:47:45] + '[' -n '' ']'
[2025-07-25 10:47:45] + '[' -n '' ']'
[2025-07-25 10:47:45] ++ date +%s
[2025-07-25 10:47:45] + ACCOUNTING_ENDTIME=1753465665
[2025-07-25 10:47:45] ++ date -d '1970-01-01 UTC 1753465477 seconds' +%Y%m%d%H%M%SZ
[2025-07-25 10:47:45] + echo LRMSStartTime=20250725104437Z
[2025-07-25 10:47:45] ++ date -d '1970-01-01 UTC 1753465665 seconds' +%Y%m%d%H%M%SZ
[2025-07-25 10:47:45] + echo LRMSEndTime=20250725104745Z
[2025-07-25 10:47:45] + echo walltime=188
[2025-07-25 10:47:45] + echo exitcode=2
[2025-07-25 10:47:45] + exit 2
[2025-07-25 10:47:45] ./pilotlog.txt
[2025-07-25 10:47:45] ./runtime_log
[2025-07-25 10:47:45] ./PanDA_Pilot-6744938872/prmon.log
[2025-07-25 10:47:45] ./PanDA_Pilot-6744938872/pilotlog.txt
[2025-07-25 10:47:45] ./PanDA_Pilot-6744938872/PoolFileCatalog.xml
[2025-07-25 10:47:45] ./PanDA_Pilot-6744938872/log.EVNTtoHITS
[2025-07-25 10:47:45] ./runtime_log.err
[2025-07-25 10:47:45] ./log.45743349._012589.job.log.1
[2025-07-25 10:47:45] ./pilot3/pilot/util/__pycache__/lokirealtimelogger.cpython-39.pyc
[2025-07-25 10:47:45] ./pilot3/pilot/util/__pycache__/realtimelogger.cpython-39.pyc
[2025-07-25 10:47:45] ./pilot3/pilot/util/__pycache__/loggingsupport.cpython-39.pyc
[2025-07-25 10:47:45] ./pilot3/pilot/util/realtimelogger.py
[2025-07-25 10:47:45] ./pilot3/pilot/util/lokirealtimelogger.py
[2025-07-25 10:47:45] ./pilot3/pilot/util/loggingsupport.py
10:48:30 (3097642): wrapper (7.7.26015): starting
10:48:30 (3097642): wrapper: running run_atlas (--nthreads 2)
[2025-07-25 10:48:30] Arguments: --nthreads 2
[2025-07-25 10:48:30] Threads: 2
[2025-07-25 10:48:30] This job has been restarted, cleaning up previous attempt
[2025-07-25 10:48:30] Checking for CVMFS
[2025-07-25 10:48:30] Probing /cvmfs/atlas.cern.ch... OK
[2025-07-25 10:48:30] Probing /cvmfs/atlas-condb.cern.ch... OK
[2025-07-25 10:48:30] Running cvmfs_config stat atlas.cern.ch
[2025-07-25 10:48:31] VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
[2025-07-25 10:48:31] 2.13.1.0 3046303 3 54800 148748 0 188 3434903 10240001 2484 130560 0 152644 99.989 2789 761 http://s1fnal-cvmfs.openhtc.io:8080/cvmfs/atlas.cern.ch DIRECT 1
[2025-07-25 10:48:31] CVMFS is ok
[2025-07-25 10:48:31] Efficiency of ATLAS tasks can be improved by the following measure(s):
[2025-07-25 10:48:31] Small home clusters do not require a local http proxy but it is suggested if
[2025-07-25 10:48:31] more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
[2025-07-25 10:48:31] Further information can be found at the LHC@home message board.
[2025-07-25 10:48:31] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7
[2025-07-25 10:48:31] Checking for apptainer binary...
[2025-07-25 10:48:31] Using apptainer found in PATH at /usr/bin/apptainer
[2025-07-25 10:48:31] Running /usr/bin/apptainer --version
[2025-07-25 10:48:31] apptainer version 1.4.1
[2025-07-25 10:48:31] Checking apptainer works with /usr/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname
[2025-07-25 10:48:31] robot
[2025-07-25 10:48:31] apptainer works
[2025-07-25 10:48:31] Set ATHENA_PROC_NUMBER=2
[2025-07-25 10:48:31] Set ATHENA_CORE_NUMBER=2
[2025-07-25 10:48:31] Starting ATLAS job with PandaID=6744938872
[2025-07-25 10:48:31] Running command: /usr/bin/apptainer exec -B /cvmfs,/var/lib/boinc/slots/5 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 sh start_atlas.sh
11:17:58 (3151441): wrapper (7.7.26015): starting
11:17:58 (3151441): wrapper: running run_atlas (--nthreads 2)
[2025-07-25 11:17:58] Arguments: --nthreads 2
[2025-07-25 11:17:58] Threads: 2
[2025-07-25 11:17:58] This job has been restarted, cleaning up previous attempt
[2025-07-25 11:17:58] Checking for CVMFS
[2025-07-25 11:17:58] Probing /cvmfs/atlas.cern.ch... OK
[2025-07-25 11:17:58] Probing /cvmfs/atlas-condb.cern.ch... OK
[2025-07-25 11:17:58] Running cvmfs_config stat atlas.cern.ch
[2025-07-25 11:17:59] VERSION PID UPTIME(M) MEM(K) REVISION EXPIRES(M) NOCATALOGS CACHEUSE(K) CACHEMAX(K) NOFDUSE NOFDMAX NOIOERR NOOPEN HITRATE(%) RX(K) SPEED(K/S) HOST PROXY ONLINE
[2025-07-25 11:17:59] 2.13.1.0 3046303 33 55232 148748 -21 188 3434903 10240001 0 130560 0 317633 99.995 2790 700 http://s1fnal-cvmfs.openhtc.io:8080/cvmfs/atlas.cern.ch DIRECT 0
[2025-07-25 11:17:59] CVMFS is ok
[2025-07-25 11:17:59] Efficiency of ATLAS tasks can be improved by the following measure(s):
[2025-07-25 11:17:59] Small home clusters do not require a local http proxy but it is suggested if
[2025-07-25 11:17:59] more than 10 cores throughout the same LAN segment are regularly running ATLAS like tasks.
[2025-07-25 11:17:59] Further information can be found at the LHC@home message board.
[2025-07-25 11:17:59] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7
[2025-07-25 11:17:59] Checking for apptainer binary...
[2025-07-25 11:17:59] Using apptainer found in PATH at /usr/bin/apptainer
[2025-07-25 11:17:59] Running /usr/bin/apptainer --version
[2025-07-25 11:17:59] apptainer version 1.4.1
[2025-07-25 11:17:59] Checking apptainer works with /usr/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname
[2025-07-25 11:17:59] robot
[2025-07-25 11:17:59] apptainer works
[2025-07-25 11:17:59] Set ATHENA_PROC_NUMBER=2
[2025-07-25 11:17:59] Set ATHENA_CORE_NUMBER=2
[2025-07-25 11:17:59] Starting ATLAS job with PandaID=6744938872
[2025-07-25 11:17:59] Running command: /usr/bin/apptainer exec -B /cvmfs,/var/lib/boinc/slots/5 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 sh start_atlas.sh
[2025-07-25 11:21:08] *** The last 200 lines of the pilot log: ***
[2025-07-25 11:21:08] 2025-07-25 18:20:36,322 | WARNING | found no stored workdir sizes
[2025-07-25 11:21:08] 2025-07-25 18:20:36,322 | INFO | will not add max space = 0 B to job metrics
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | WARNING | wrong length of table data, x=[1753467501.0], y=[1162.0] (must be same and length>=4)
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | could have reported an average CPU frequency of 4032 MHz (4 samples)
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | ..............................
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . Timing measurements:
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . get job = 0 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . initial setup = 0 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . payload setup = 2 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . stage-in = 0 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . payload execution = 11 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . stage-out = 0 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . log creation = 0 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | ..............................
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | building log extracts (sent to the server as 'pilotLog')
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | executing command: tail -n 20 /var/lib/boinc/slots/5/PanDA_Pilot-6744938872/pilotlog.txt
[2025-07-25 11:21:08] 2025-07-25 18:20:36,335 | WARNING | detected the following tail of warning/fatal messages in the pilot log:
[2025-07-25 11:21:08] - Log from pilotlog.txt -
[2025-07-25 11:21:08] 2025-07-25 18:20:36,321 | INFO | extracted standard memory fields from prmon json
[2025-07-25 11:21:08] 2025-07-25 18:20:36,321 | WARNING | GPU info not found in prmon json: 'gpu'
[2025-07-25 11:21:08] 2025-07-25 18:20:36,322 | WARNING | format EVNTtoHITS has no such key: dbData
[2025-07-25 11:21:08] 2025-07-25 18:20:36,322 | WARNING | format EVNTtoHITS has no such key: dbTime
[2025-07-25 11:21:08] 2025-07-25 18:20:36,322 | WARNING | found no stored workdir sizes
[2025-07-25 11:21:08] 2025-07-25 18:20:36,322 | INFO | will not add max space = 0 B to job metrics
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | WARNING | wrong length of table data, x=[1753467501.0], y=[1162.0] (must be same and length>=4)
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | could have reported an average CPU frequency of 4032 MHz (4 samples)
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | ..............................
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . Timing measurements:
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . get job = 0 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . initial setup = 0 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . payload setup = 2 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . stage-in = 0 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . payload execution = 11 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . stage-out = 0 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . log creation = 0 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | ..............................
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | building log extracts (sent to the server as 'pilotLog')
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | executing command: tail -n 20 /var/lib/boinc/slots/5/PanDA_Pilot-6744938872/pilotlog.txt
[2025-07-25 11:21:08] 2025-07-25 18:20:36,336 | WARNING |
[2025-07-25 11:21:08] [begin log extracts]
[2025-07-25 11:21:08] - Log from pilotlog.txt -
[2025-07-25 11:21:08] 2025-07-25 18:20:36,321 | INFO | extracted standard memory fields from prmon json
[2025-07-25 11:21:08] 2025-07-25 18:20:36,321 | WARNING | GPU info not found in prmon json: 'gpu'
[2025-07-25 11:21:08] 2025-07-25 18:20:36,322 | WARNING | format EVNTtoHITS has no such key: dbData
[2025-07-25 11:21:08] 2025-07-25 18:20:36,322 | WARNING | format EVNTtoHITS has no such key: dbTime
[2025-07-25 11:21:08] 2025-07-25 18:20:36,322 | WARNING | found no stored workdir sizes
[2025-07-25 11:21:08] 2025-07-25 18:20:36,322 | INFO | will not add max space = 0 B to job metrics
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | WARNING | wrong length of table data, x=[1753467501.0], y=[1162.0] (must be same and length>=4)
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | could have reported an average CPU frequency of 4032 MHz (4 samples)
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | ..............................
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . Timing measurements:
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . get job = 0 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . initial setup = 0 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . payload setup = 2 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . stage-in = 0 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . payload execution = 11 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . stage-out = 0 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | . log creation = 0 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | ..............................
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | building log extracts (sent to the server as 'pilotLog')
[2025-07-25 11:21:08] 2025-07-25 18:20:36,323 | INFO | executing command: tail -n 20 /var/lib/boinc/slots/5/PanDA_Pilot-6744938872/pilotlog.txt
[2025-07-25 11:21:08] [end log extracts]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,336 | WARNING | pilotErrorCodes = [1305] (will report primary/first error code)
[2025-07-25 11:21:08] 2025-07-25 18:20:36,337 | WARNING | pilotErrorDiags = ['Failed to execute payload:PyJobTransforms.transform.execute CRITICAL Transform executor raised TransformValidationException: Non-zero return c
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO |
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | job summary report
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | --------------------------------------------------
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | PanDA job id: 6744938872
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | task id: 45743349
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | error 1/1: 1305: Failed to execute payload:PyJobTransforms.transform.execute 2025-07-25 18:18:25,788 CRITICAL Transform executor raised TransformValidationExceptio
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | status: LOG_TRANSFER = DONE
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | pilot state: failed
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | transexitcode: 65
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | exeerrorcode: 65
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | exeerrordiag: Non-zero return code from EVNTtoHITS (1)
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | exitcode: 65
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | exitmsg: Non-zero return code from EVNTtoHITS (1)
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | cpuconsumptiontime: 9 s
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | nevents: 0
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | neventsw: 0
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | pid: 3182834
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | pgrp: 3182834
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | corecount: 2
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | event service: False
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | sizes: {0: 2397473, 2: 2397735, 11: 2397763, 17: 2425125, 139: 2425263}
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO | --------------------------------------------------
[2025-07-25 11:21:08] 2025-07-25 18:20:36,401 | INFO |
[2025-07-25 11:21:08] 2025-07-25 18:20:36,402 | INFO | executing command: ls -lF /var/lib/boinc/slots/5
[2025-07-25 11:21:08] 2025-07-25 18:20:36,416 | INFO | queue jobs had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,416 | INFO | queue payloads had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,416 | INFO | queue data_in had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,416 | INFO | queue data_out had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,416 | INFO | queue current_data_in had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,416 | INFO | queue validated_jobs had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,416 | INFO | queue validated_payloads had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,416 | INFO | queue monitored_payloads had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,416 | INFO | queue finished_jobs had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,416 | INFO | queue finished_payloads had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,416 | INFO | queue finished_data_in had 1 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,416 | INFO | queue finished_data_out had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,417 | INFO | queue failed_jobs had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,417 | INFO | queue failed_payloads had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,417 | INFO | queue failed_data_in had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,417 | INFO | queue failed_data_out had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,417 | INFO | queue completed_jobs had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,417 | INFO | queue completed_jobids has 1 job(s)
[2025-07-25 11:21:08] 2025-07-25 18:20:36,417 | INFO | queue realtimelog_payloads had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,417 | INFO | queue messages had 0 job(s) [purged]
[2025-07-25 11:21:08] 2025-07-25 18:20:36,417 | INFO | job 6744938872 has completed (purged errors)
[2025-07-25 11:21:08] 2025-07-25 18:20:36,417 | INFO | overall cleanup function is called
[2025-07-25 11:21:08] 2025-07-25 18:20:37,422 | INFO | --- collectZombieJob: --- 10, [3182834]
[2025-07-25 11:21:08] 2025-07-25 18:20:37,422 | INFO | zombie collector waiting for pid 3182834
[2025-07-25 11:21:08] 2025-07-25 18:20:37,422 | INFO | harmless exception when collecting zombies: [Errno 10] No child processes
[2025-07-25 11:21:08] 2025-07-25 18:20:37,422 | INFO | collected zombie processes
[2025-07-25 11:21:08] 2025-07-25 18:20:37,422 | INFO | will attempt to kill all subprocesses of pid=3182834
[2025-07-25 11:21:08] 2025-07-25 18:20:37,498 | INFO | process IDs to be killed: [3182834] (in reverse order)
[2025-07-25 11:21:08] 2025-07-25 18:20:37,540 | WARNING | found no corresponding commands to process id(s)
[2025-07-25 11:21:08] 2025-07-25 18:20:37,541 | INFO | Do not look for orphan processes in BOINC jobs
[2025-07-25 11:21:08] 2025-07-25 18:20:37,544 | INFO | did not find any defunct processes belonging to 3182834
[2025-07-25 11:21:08] 2025-07-25 18:20:37,547 | INFO | did not find any defunct processes belonging to 3182834
[2025-07-25 11:21:08] 2025-07-25 18:20:37,547 | INFO | ready for new job
[2025-07-25 11:21:08] 2025-07-25 18:20:37,547 | INFO | pilot has finished with previous job - re-establishing logging
[2025-07-25 11:21:08] 2025-07-25 18:20:37,548 | INFO | ***************************************
[2025-07-25 11:21:08] 2025-07-25 18:20:37,548 | INFO | *** PanDA Pilot version 3.10.4.12 ***
[2025-07-25 11:21:08] 2025-07-25 18:20:37,548 | INFO | ***************************************
[2025-07-25 11:21:08] 2025-07-25 18:20:37,548 | INFO |
[2025-07-25 11:21:08] 2025-07-25 18:20:37,549 | INFO | architecture information:
[2025-07-25 11:21:08] 2025-07-25 18:20:37,549 | INFO | executing command: cat /etc/os-release
[2025-07-25 11:21:08] 2025-07-25 18:20:37,561 | INFO | cat /etc/os-release:
[2025-07-25 11:21:08] NAME="CentOS Linux"
[2025-07-25 11:21:08] VERSION="7 (Core)"
[2025-07-25 11:21:08] ID="centos"
[2025-07-25 11:21:08] ID_LIKE="rhel fedora"
[2025-07-25 11:21:08] VERSION_ID="7"
[2025-07-25 11:21:08] PRETTY_NAME="CentOS Linux 7 (Core)"
[2025-07-25 11:21:08] ANSI_COLOR="0;31"
[2025-07-25 11:21:08] CPE_NAME="cpe:/o:centos:centos:7"
[2025-07-25 11:21:08] HOME_URL="https://www.centos.org/"
[2025-07-25 11:21:08] BUG_REPORT_URL="https://bugs.centos.org/"
[2025-07-25 11:21:08]
[2025-07-25 11:21:08] CENTOS_MANTISBT_PROJECT="CentOS-7"
[2025-07-25 11:21:08] CENTOS_MANTISBT_PROJECT_VERSION="7"
[2025-07-25 11:21:08] REDHAT_SUPPORT_PRODUCT="centos"
[2025-07-25 11:21:08] REDHAT_SUPPORT_PRODUCT_VERSION="7"
[2025-07-25 11:21:08]
[2025-07-25 11:21:08] 2025-07-25 18:20:37,561 | INFO | ***************************************
[2025-07-25 11:21:08] 2025-07-25 18:20:38,063 | INFO | executing command: df -mP /var/lib/boinc/slots/5
[2025-07-25 11:21:08] 2025-07-25 18:20:38,078 | INFO | sufficient remaining disk space (604042690560 B)
[2025-07-25 11:21:08] 2025-07-25 18:20:38,079 | WARNING | since timefloor is set to 0, pilot was only allowed to run one job
[2025-07-25 11:21:08] 2025-07-25 18:20:38,079 | INFO | current server update state: UPDATING_FINAL
[2025-07-25 11:21:08] 2025-07-25 18:20:38,079 | INFO | update_server=False
[2025-07-25 11:21:08] 2025-07-25 18:20:38,079 | WARNING | setting graceful_stop since proceed_with_getjob() returned False (pilot will end)
[2025-07-25 11:21:08] 2025-07-25 18:20:38,079 | WARNING | job:queue_monitor:received graceful stop - abort after this iteration
[2025-07-25 11:21:08] 2025-07-25 18:20:38,079 | WARNING | data:copytool_out:received graceful stop - abort after this iteration
[2025-07-25 11:21:08] 2025-07-25 18:20:38,079 | WARNING | data:queue_monitoring:received graceful stop - abort after this iteration
[2025-07-25 11:21:08] 2025-07-25 18:20:38,176 | WARNING | job monitor detected an abort_job request (signal=args.signal)
[2025-07-25 11:21:08] 2025-07-25 18:20:38,176 | WARNING | cannot recover job monitoring - aborting pilot
[2025-07-25 11:21:08] 2025-07-25 18:20:38,176 | WARNING | job:job_monitor:received graceful stop - abort after this iteration
[2025-07-25 11:21:08] 2025-07-25 18:20:38,176 | INFO | will abort loop
[2025-07-25 11:21:08] 2025-07-25 18:20:38,270 | INFO | all data control threads have been joined
[2025-07-25 11:21:08] 2025-07-25 18:20:38,359 | INFO | all payload control threads have been joined
[2025-07-25 11:21:08] 2025-07-25 18:20:38,385 | INFO | all job control threads have been joined
[2025-07-25 11:21:08] 2025-07-25 18:20:39,083 | INFO | [job] retrieve thread has finished
[2025-07-25 11:21:08] 2025-07-25 18:20:39,084 | INFO | [job] queue monitor thread has finished
[2025-07-25 11:21:08] 2025-07-25 18:20:39,181 | INFO | [job] job monitor thread has finished
[2025-07-25 11:21:08] 2025-07-25 18:20:39,275 | INFO | [data] control thread has finished
[2025-07-25 11:21:08] 2025-07-25 18:20:39,357 | INFO | [data] copytool_in thread has finished
[2025-07-25 11:21:08] 2025-07-25 18:20:39,363 | INFO | [payload] control thread has finished
[2025-07-25 11:21:08] 2025-07-25 18:20:39,391 | INFO | [job] control thread has finished
[2025-07-25 11:21:08] 2025-07-25 18:20:39,668 | INFO | [payload] validate_post thread has finished
[2025-07-25 11:21:08] 2025-07-25 18:20:39,695 | INFO | [job] validate thread has finished
[2025-07-25 11:21:08] 2025-07-25 18:20:39,796 | INFO | [job] create_data_payload thread has finished
[2025-07-25 11:21:08] 2025-07-25 18:20:39,884 | INFO | [payload] validate_pre thread has finished
[2025-07-25 11:21:08] 2025-07-25 18:20:39,976 | INFO | [payload] execute_payloads thread has finished
[2025-07-25 11:21:08] 2025-07-25 18:20:40,084 | INFO | [data] copytool_out thread has finished
[2025-07-25 11:21:08] 2025-07-25 18:20:40,364 | INFO | [payload] failed_post thread has finished
[2025-07-25 11:21:08] 2025-07-25 18:20:40,446 | INFO | [payload] run_realtimelog thread has finished
[2025-07-25 11:21:08] 2025-07-25 18:20:42,084 | INFO | [data] queue_monitor thread has finished
[2025-07-25 11:21:08] 2025-07-25 18:20:42,490 | INFO | only monitor.control thread still running - safe to abort: ['<_MainThread(MainThread, started 140139819472704)>', '<ExcThread(monitor, started 140139196045056)>']
[2025-07-25 11:21:08] 2025-07-25 18:20:47,508 | INFO | all workflow threads have been joined
[2025-07-25 11:21:08] 2025-07-25 18:20:47,509 | INFO | end of generic workflow (traces error code: 0)
[2025-07-25 11:21:08] 2025-07-25 18:20:47,509 | INFO | traces error code: 0
[2025-07-25 11:21:08] 2025-07-25 18:20:47,509 | INFO | pilot has finished (exit code=0, shell exit code=0)
[2025-07-25 11:21:08] 2025-07-25 18:21:08,388 | INFO | PID=3162982 has CPU usage=0.8% CMD=/cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase/x86_64/python/3.9.20-x86_64-centos7/bin/python3 pilot3/pilot.py -q BOINC_MCORE -i P
[2025-07-25 11:21:08] 2025-07-25 18:21:08,388 | INFO | .. there are 3 such processes running
[2025-07-25 11:21:08] 2025-07-25 18:21:08,389 | INFO | found 0 job(s) in 20 queues
[2025-07-25 11:21:08] 2025-07-25 18:21:08,389 | WARNING | pilot monitor received instruction that args.graceful_stop has been set
[2025-07-25 11:21:08] 2025-07-25 18:21:08,389 | WARNING | will wait for a maximum of 300 s for threads to finish
[2025-07-25 11:21:08] 2025-07-25 18:21:08,389 | WARNING | job_aborted has been set - aborting pilot monitoring
[2025-07-25 11:21:08] 2025-07-25 18:21:08,389 | INFO | [monitor] control thread has ended
[2025-07-25 11:21:08] 2025-07-25 18:21:08,482 [wrapper] ==== pilot stdout END ====
[2025-07-25 11:21:08] 2025-07-25 18:21:08,484 [wrapper] ==== wrapper stdout RESUME ====
[2025-07-25 11:21:08] 2025-07-25 18:21:08,485 [wrapper] pilotpid: 3162982
[2025-07-25 11:21:08] 2025-07-25 18:21:08,487 [wrapper] Pilot exit status: 0
[2025-07-25 11:21:08] 2025-07-25 18:21:08,492 [wrapper] pandaids: 6744938872 6744938872 6744938872 6744938872
[2025-07-25 11:21:08] 2025-07-25 18:21:08,518 [wrapper] cleanup supervisor_pilot 3162999 3162983
[2025-07-25 11:21:08] 2025-07-25 18:21:08,519 [wrapper] Test setup, not cleaning
[2025-07-25 11:21:08] 2025-07-25 18:21:08,521 [wrapper] apfmon messages muted
[2025-07-25 11:21:08] 2025-07-25 18:21:08,523 [wrapper] ==== wrapper stdout END ====
[2025-07-25 11:21:08] 2025-07-25 18:21:08,524 [wrapper] ==== wrapper stderr END ====
[2025-07-25 11:21:08] *** Error codes and diagnostics ***
[2025-07-25 11:21:08] "exeErrorCode": 65,
[2025-07-25 11:21:08] "exeErrorDiag": "Non-zero return code from EVNTtoHITS (1)",
[2025-07-25 11:21:08] "pilotErrorCode": 1305,
[2025-07-25 11:21:08] "pilotErrorDiag": "Failed to execute payload:PyJobTransforms.transform.execute CRITICAL Transform executor raised TransformValidationException: Non-zero return code from EVNTtoHITS (1)",
[2025-07-25 11:21:08] *** Listing of results directory ***
[2025-07-25 11:21:08] total 290208
[2025-07-25 11:21:08] drwx------ 4 boinc boinc 4096 Jun 17 03:04 pilot3
[2025-07-25 11:21:08] -rw-r--r-- 1 boinc boinc 530466 Jul 24 16:14 pilot3.tar.gz
[2025-07-25 11:21:08] -rw-r--r-- 1 boinc boinc 5112 Jul 24 16:20 queuedata.json
[2025-07-25 11:21:08] -rwx------ 1 boinc boinc 36292 Jul 24 16:21 runpilot2-wrapper.sh
[2025-07-25 11:21:08] -rw-r--r-- 1 boinc boinc 100 Jul 24 22:33 wrapper_26015_x86_64-pc-linux-gnu
[2025-07-25 11:21:08] -rwxr-xr-x 1 boinc boinc 7986 Jul 24 22:33 run_atlas
[2025-07-25 11:21:08] -rw-r--r-- 1 boinc boinc 105 Jul 24 22:33 job.xml
[2025-07-25 11:21:08] -rw-r--r-- 3 boinc boinc 146539809 Jul 24 22:33 EVNT.45743347._000675.pool.root.1
[2025-07-25 11:21:08] -rw-r--r-- 3 boinc boinc 146539809 Jul 24 22:33 ATLAS.root_0
[2025-07-25 11:21:08] -rw-r--r-- 2 boinc boinc 542896 Jul 24 22:33 input.tar.gz
[2025-07-25 11:21:08] -rw-r--r-- 2 boinc boinc 15095 Jul 24 22:33 start_atlas.sh
[2025-07-25 11:21:08] -rw-r--r-- 1 boinc boinc 0 Jul 24 22:33 boinc_lockfile
[2025-07-25 11:21:08] -rw------- 1 boinc boinc 0 Jul 25 10:44 wrapper_sigint_3057390
[2025-07-25 11:21:08] -rw------- 1 boinc boinc 1593052 Jul 25 10:44 agis_ddmendpoints.agis.ALL.json
[2025-07-25 11:21:08] -rw------- 1 boinc boinc 0 Jul 25 10:47 wrapper_sigkill_3057390
[2025-07-25 11:21:08] drwxrwx--x 2 boinc boinc 4096 Jul 25 10:47 shared
[2025-07-25 11:21:08] -rw-r--r-- 1 boinc boinc 7087 Jul 25 11:17 init_data.xml
[2025-07-25 11:21:08] -rw-r--r-- 1 boinc boinc 2562 Jul 25 11:17 pandaJob.out
[2025-07-25 11:21:08] -rw------- 1 boinc boinc 979309 Jul 25 11:18 agis_schedconf.cvmfs.json
[2025-07-25 11:21:08] -rw------- 1 boinc boinc 967 Jul 25 11:18 memory_monitor_summary.json
[2025-07-25 11:21:08] -rw------- 1 boinc boinc 20360 Jul 25 11:18 log.45743349._012589.job.log.tgz.1
[2025-07-25 11:21:08] -rw------- 1 boinc boinc 8336 Jul 25 11:20 heartbeat.json
[2025-07-25 11:21:08] -rw-r--r-- 1 boinc boinc 528 Jul 25 11:20 boinc_task_state.xml
[2025-07-25 11:21:08] -rw-r--r-- 1 boinc boinc 8192 Jul 25 11:21 boinc_mmap_file
[2025-07-25 11:21:08] -rw-r--r-- 1 boinc boinc 26 Jul 25 11:21 wrapper_checkpoint.txt
[2025-07-25 11:21:08] -rw------- 1 boinc boinc 822 Jul 25 11:21 pilotlog.txt
[2025-07-25 11:21:08] -rw------- 1 boinc boinc 98 Jul 25 11:21 pilot_heartbeat.json
[2025-07-25 11:21:08] -rw------- 1 boinc boinc 74131 Jul 25 11:21 log.45743349._012589.job.log.1
[2025-07-25 11:21:08] -rw------- 1 boinc boinc 188 Jul 25 11:21 output.list
[2025-07-25 11:21:08] -rw-r--r-- 1 boinc boinc 620 Jul 25 11:21 runtime_log
[2025-07-25 11:21:08] -rw-r--r-- 2 boinc boinc 112640 Jul 25 11:21 result.tar.gz
[2025-07-25 11:21:08] -rw-r--r-- 1 boinc boinc 8527 Jul 25 11:21 runtime_log.err
[2025-07-25 11:21:08] -rw------- 1 boinc boinc 1479 Jul 25 11:21 He2NDmyQKz7nsSi4ap6QjLDmwznN0nGgGQJmUXzaDmGAOLDmAZ14Mn.diag
[2025-07-25 11:21:08] -rw-r--r-- 1 boinc boinc 41201 Jul 25 11:21 stderr.txt
[2025-07-25 11:21:08] No HITS result produced
[2025-07-25 11:21:08] *** Contents of shared directory: ***
[2025-07-25 11:21:08] total 143772
[2025-07-25 11:21:08] -rw-r--r-- 3 boinc boinc 146539809 Jul 24 22:33 ATLAS.root_0
[2025-07-25 11:21:08] -rw-r--r-- 2 boinc boinc 542896 Jul 24 22:33 input.tar.gz
[2025-07-25 11:21:08] -rw-r--r-- 2 boinc boinc 15095 Jul 24 22:33 start_atlas.sh
[2025-07-25 11:21:08] -rw-r--r-- 2 boinc boinc 112640 Jul 25 11:21 result.tar.gz
11:21:10 (3151441): run_atlas exited; CPU time 12.716982
11:21:10 (3151441): called boinc_finish(0)
</stderr_txt>
]]>
©2025 CERN