Name | 2B9KDm4MWR5n9Rq4apoT9bVoABFKDmABFKDmlqFKDmpaJKDmWO11un_0 |
Workunit | 223032268 |
Created | 15 May 2024, 16:09:42 UTC |
Sent | 15 May 2024, 20:17:31 UTC |
Report deadline | 23 May 2024, 20:17:31 UTC |
Received | 16 May 2024, 9:23:30 UTC |
Server state | Over |
Outcome | Success |
Client state | Done |
Exit status | 0 (0x00000000) |
Computer ID | 10804962 |
Run time | 8 hours 59 min 51 sec |
CPU time | 14 hours 37 min 34 sec |
Validate state | Valid |
Credit | 2,509.31 |
Device peak FLOPS | 40.68 GFLOPS |
Application version | ATLAS Simulation v3.01 (native_mt) x86_64-pc-linux-gnu |
Peak working set size | 2.56 GB |
Peak swap size | 31.65 GB |
Peak disk usage | 1.91 GB |
<core_client_version>7.7.0</core_client_version> <![CDATA[ <stderr_txt> 16:18:18 (16257): wrapper (7.7.26015): starting 16:18:18 (16257): wrapper: running run_atlas (--nthreads 8) [2024-05-15 16:18:18] Arguments: --nthreads 8 [2024-05-15 16:18:18] Threads: 8 [2024-05-15 16:18:18] Checking for CVMFS [2024-05-15 16:18:19] Probing /cvmfs/atlas.cern.ch... OK [2024-05-15 16:18:19] Probing /cvmfs/atlas-condb.cern.ch... OK [2024-05-15 16:18:19] Running cvmfs_config stat atlas.cern.ch [2024-05-15 16:18:20] 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 [2024-05-15 16:18:20] 2.11.2.0 17826 2975 113064 132772 2 277 4438786 18432000 28055 130560 0 42436 87.184 1721384 56925 http://cvmfs-s1fnal.opensciencegrid.org:8000/cvmfs/atlas.cern.ch http://192.41.237.109:6081 1 [2024-05-15 16:18:20] CVMFS is ok [2024-05-15 16:18:20] Efficiency of ATLAS tasks can be improved by the following measure(s): [2024-05-15 16:18:20] The CVMFS client on this computer should be configured to use Cloudflare's openhtc.io. [2024-05-15 16:18:20] Further information can be found at the LHC@home message board. [2024-05-15 16:18:20] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 [2024-05-15 16:18:20] Checking for apptainer binary... [2024-05-15 16:18:20] Using apptainer found in PATH at /usr/bin/apptainer [2024-05-15 16:18:20] Running /usr/bin/apptainer --version [2024-05-15 16:18:20] apptainer version 1.3.0-1.el7 [2024-05-15 16:18:20] Checking apptainer works with /usr/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname [2024-05-15 16:18:32] c-212-6.aglt2.org [2024-05-15 16:18:32] apptainer works [2024-05-15 16:18:32] Set ATHENA_PROC_NUMBER=8 [2024-05-15 16:18:32] Set ATHENA_CORE_NUMBER=8 [2024-05-15 16:18:32] Starting ATLAS job with PandaID=6206854443 [2024-05-15 16:18:32] Running command: /usr/bin/apptainer exec -B /cvmfs,/tmp/boinchome/slots/0 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 sh start_atlas.sh 00:48:49 (16257): BOINC client no longer exists - exiting 00:48:49 (16257): timer handler: client dead, exiting 01:12:40 (17491): wrapper (7.7.26015): starting 01:12:40 (17491): wrapper: running run_atlas (--nthreads 8) [2024-05-16 01:12:40] Arguments: --nthreads 8 [2024-05-16 01:12:40] Threads: 8 [2024-05-16 01:12:40] This job has been restarted, cleaning up previous attempt [2024-05-16 01:12:40] Checking for CVMFS [2024-05-16 01:12:40] Probing /cvmfs/atlas.cern.ch... OK [2024-05-16 01:12:40] Probing /cvmfs/atlas-condb.cern.ch... OK [2024-05-16 01:12:40] Running cvmfs_config stat atlas.cern.ch [2024-05-16 01:12:40] 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 [2024-05-16 01:12:40] 2.11.2.0 46351 506 72372 132785 2 227 18277067 18432001 16404 130560 0 2936514 98.244 10907556 53023 http://cvmfs-s1fnal.opensciencegrid.org:8000/cvmfs/atlas.cern.ch http://192.41.237.109:6081 1 [2024-05-16 01:12:40] CVMFS is ok [2024-05-16 01:12:40] Efficiency of ATLAS tasks can be improved by the following measure(s): [2024-05-16 01:12:40] The CVMFS client on this computer should be configured to use Cloudflare's openhtc.io. [2024-05-16 01:12:40] Further information can be found at the LHC@home message board. [2024-05-16 01:12:40] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 [2024-05-16 01:12:40] Checking for apptainer binary... [2024-05-16 01:12:40] Using apptainer found in PATH at /usr/bin/apptainer [2024-05-16 01:12:40] Running /usr/bin/apptainer --version [2024-05-16 01:12:41] apptainer version 1.3.0-1.el7 [2024-05-16 01:12:41] Checking apptainer works with /usr/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname [2024-05-16 01:12:42] c-212-6.aglt2.org [2024-05-16 01:12:42] apptainer works [2024-05-16 01:12:42] Set ATHENA_PROC_NUMBER=8 [2024-05-16 01:12:42] Set ATHENA_CORE_NUMBER=8 [2024-05-16 01:12:42] Starting ATLAS job with PandaID=6206854443 [2024-05-16 01:12:42] Running command: /usr/bin/apptainer exec -B /cvmfs,/tmp/boinchome/slots/0 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 sh start_atlas.sh [2024-05-16 01:15:58] Job failed [2024-05-16 01:15:58] ++ pwd [2024-05-16 01:15:58] + export RUNTIME_CONFIG_DIR=/tmp/boinchome/slots/0/ [2024-05-16 01:15:58] + RUNTIME_CONFIG_DIR=/tmp/boinchome/slots/0/ [2024-05-16 01:15:58] + mv ATLAS.root_0 EVNT.38776100._000280.pool.root.1 [2024-05-16 01:15:58] mv: ‘ATLAS.root_0’ and ‘EVNT.38776100._000280.pool.root.1’ are the same file [2024-05-16 01:15:58] + tar --strip-components=5 -xvf input.tar.gz [2024-05-16 01:15:58] + umask 077 [2024-05-16 01:15:58] ++ date +%s [2024-05-16 01:15:58] + ACCOUNTING_STARTTIME=1715836363 [2024-05-16 01:15:58] + echo 'Detecting resource accounting method available for the job.' [2024-05-16 01:15:58] Detecting resource accounting method available for the job. [2024-05-16 01:15:58] + JOB_ACCOUNTING= [2024-05-16 01:15:58] + command -v arc-job-cgroup [2024-05-16 01:15:58] + '[' -z '' ']' [2024-05-16 01:15:58] + GNU_TIME=/usr/bin/time [2024-05-16 01:15:58] + echo 'Looking for /usr/bin/time tool for accounting measurements' [2024-05-16 01:15:58] Looking for /usr/bin/time tool for accounting measurements [2024-05-16 01:15:58] + '[' '!' -z /usr/bin/time ']' [2024-05-16 01:15:58] + /usr/bin/time --version [2024-05-16 01:15:58] + echo 'GNU time found and will be used for job accounting.' [2024-05-16 01:15:58] GNU time found and will be used for job accounting. [2024-05-16 01:15:58] + JOB_ACCOUNTING=gnutime [2024-05-16 01:15:58] + '[' -z gnutime ']' [2024-05-16 01:15:58] + export PANDA_JSID=harvester-CERN_central_ACTA [2024-05-16 01:15:58] + PANDA_JSID=harvester-CERN_central_ACTA [2024-05-16 01:15:58] + export GTAG=http://aipanda404.cern.ch/data/jobs/2024-05-15/BOINC_MCORE/6206854443.out [2024-05-16 01:15:58] + GTAG=http://aipanda404.cern.ch/data/jobs/2024-05-15/BOINC_MCORE/6206854443.out [2024-05-16 01:15:58] + export PILOT_NOKILL=YES [2024-05-16 01:15:58] + PILOT_NOKILL=YES [2024-05-16 01:15:58] + export GRID_GLOBAL_JOBID=2B9KDm4MWR5n9Rq4apoT9bVoABFKDmABFKDmlqFKDmpaJKDmWO11un [2024-05-16 01:15:58] + GRID_GLOBAL_JOBID=2B9KDm4MWR5n9Rq4apoT9bVoABFKDmABFKDmlqFKDmpaJKDmWO11un [2024-05-16 01:15:58] + export GRID_GLOBAL_JOBURL=https://arc-boinc-03.cern.ch:443/arex/2B9KDm4MWR5n9Rq4apoT9bVoABFKDmABFKDmlqFKDmpaJKDmWO11un [2024-05-16 01:15:58] + GRID_GLOBAL_JOBURL=https://arc-boinc-03.cern.ch:443/arex/2B9KDm4MWR5n9Rq4apoT9bVoABFKDmABFKDmlqFKDmpaJKDmWO11un [2024-05-16 01:15:58] + export GRID_GLOBAL_JOBINTERFACE=org.nordugrid.arcrest [2024-05-16 01:15:58] + GRID_GLOBAL_JOBINTERFACE=org.nordugrid.arcrest [2024-05-16 01:15:58] + export GRID_GLOBAL_JOBHOST=arc-boinc-03.cern.ch [2024-05-16 01:15:58] + GRID_GLOBAL_JOBHOST=arc-boinc-03.cern.ch [2024-05-16 01:15:58] ++ pwd [2024-05-16 01:15:58] + RUNTIME_JOB_DIR=/tmp/boinchome/slots/0 [2024-05-16 01:15:58] + RUNTIME_JOB_STDIN=/dev/null [2024-05-16 01:15:58] ++ pwd [2024-05-16 01:15:58] + RUNTIME_JOB_STDOUT=/tmp/boinchome/slots/0/log.38776104._006921.job.log.1 [2024-05-16 01:15:58] ++ pwd [2024-05-16 01:15:58] + RUNTIME_JOB_STDERR=/tmp/boinchome/slots/0/log.38776104._006921.job.log.1 [2024-05-16 01:15:58] ++ pwd [2024-05-16 01:15:58] + RUNTIME_JOB_DIAG=/tmp/boinchome/slots/0.diag [2024-05-16 01:15:58] + '[' '!' -z '' ']' [2024-05-16 01:15:58] + RUNTIME_LOCAL_SCRATCH_DIR= [2024-05-16 01:15:58] + RUNTIME_LOCAL_SCRATCH_MOVE_TOOL=mv [2024-05-16 01:15:58] + RUNTIME_FRONTEND_SEES_NODE= [2024-05-16 01:15:58] + RUNTIME_NODE_SEES_FRONTEND=yes [2024-05-16 01:15:58] + '[' '!' -z '' ']' [2024-05-16 01:15:58] + '[' -z yes ']' [2024-05-16 01:15:58] ++ pwd [2024-05-16 01:15:58] + RUNTIME_JOB_DIAG=/tmp/boinchome/slots/0/2B9KDm4MWR5n9Rq4apoT9bVoABFKDmABFKDmlqFKDmpaJKDmWO11un.diag [2024-05-16 01:15:58] + runtimeenvironments= [2024-05-16 01:15:58] + runtimeenvironments='APPS/HEP/ATLAS-SITE;' [2024-05-16 01:15:58] + RTE_function_0 1 [2024-05-16 01:15:58] + export PROJECT_ROOT=/boincdata/boinc/project/lhcathome [2024-05-16 01:15:58] + PROJECT_ROOT=/boincdata/boinc/project/lhcathome [2024-05-16 01:15:58] + export BOINC_APP=ATLAS [2024-05-16 01:15:58] + BOINC_APP=ATLAS [2024-05-16 01:15:58] + export WU_TEMPLATE=templates/ATLAS_IN_DYNAMIC [2024-05-16 01:15:58] + WU_TEMPLATE=templates/ATLAS_IN_DYNAMIC [2024-05-16 01:15:58] + export RESULT_TEMPLATE=templates/ATLAS_OUT_2 [2024-05-16 01:15:58] + RESULT_TEMPLATE=templates/ATLAS_OUT_2 [2024-05-16 01:15:58] + '[' -z 1 ']' [2024-05-16 01:15:58] + '[' 1 == 1 ']' [2024-05-16 01:15:58] + shift [2024-05-16 01:15:58] + FRONTIER_SQUID_NDGF='(proxyurl=http://db-atlas-squid.ndgf.org:3128)(proxyurl=http://squid.vega.izum.si:3128)(proxyurl=http://grid-proxy.ijs.si:3128)(proxyurl=http://atlas-db-squid1.grid.uiocloud.no:3128)' [2024-05-16 01:15:58] + FRONTIER_BACKUP='(backupproxyurl=http://atlasbpfrontier.cern.ch:3127)(backupproxyurl=http://atlasbpfrontier.fnal.gov:3127)' [2024-05-16 01:15:58] + FRONTIER_WPAD='(proxyconfigurl=http://grid-wpad/wpad.dat)' [2024-05-16 01:15:58] + export ATLAS_LOCAL_AREA=/tmp/boinchome/slots/0/ [2024-05-16 01:15:58] + ATLAS_LOCAL_AREA=/tmp/boinchome/slots/0/ [2024-05-16 01:15:58] + '[' -e /tmp/boinchome/slots/0/init_data.xml ']' [2024-05-16 01:15:58] + grep '<use_http_proxy/>' /tmp/boinchome/slots/0/init_data.xml [2024-05-16 01:15:58] + '[' -e /tmp/boinchome/slots/0/init_data.xml ']' [2024-05-16 01:15:58] + grep '.cern.ch</domain_name>' /tmp/boinchome/slots/0/init_data.xml [2024-05-16 01:15:58] + FRONTIER_SERVER_URLS='(serverurl=http://atlascern-frontier.openhtc.io:8080/atlr)(serverurl=http://atlasfrontier-ai.cern.ch:8000/atlr)' [2024-05-16 01:15:58] + FRONTIER_PROXY_URLS='(proxyurl=http://db-atlas-squid.ndgf.org:3128)(proxyurl=http://squid.vega.izum.si:3128)(proxyurl=http://grid-proxy.ijs.si:3128)(proxyurl=http://atlas-db-squid1.grid.uiocloud.no:3128)(backupproxyurl=http://atlasbpfrontier.cern.ch:3127)(backupproxyurl=http://atlasbpfrontier.fnal.gov:3127)' [2024-05-16 01:15:58] + echo 'export FRONTIER_SERVER="(serverurl=http://atlascern-frontier.openhtc.io:8080/atlr)(serverurl=http://atlasfrontier-ai.cern.ch:8000/atlr)(proxyurl=http://db-atlas-squid.ndgf.org:3128)(proxyurl=http://squid.vega.izum.si:3128)(proxyurl=http://grid-proxy.ijs.si:3128)(proxyurl=http://atlas-db-squid1.grid.uiocloud.no:3128)(backupproxyurl=http://atlasbpfrontier.cern.ch:3127)(backupproxyurl=http://atlasbpfrontier.fnal.gov:3127)"' [2024-05-16 01:15:58] + export 'FRONTIER_SERVER=(serverurl=http://atlascern-frontier.openhtc.io:8080/atlr)(serverurl=http://atlasfrontier-ai.cern.ch:8000/atlr)(proxyurl=http://db-atlas-squid.ndgf.org:3128)(proxyurl=http://squid.vega.izum.si:3128)(proxyurl=http://grid-proxy.ijs.si:3128)(proxyurl=http://atlas-db-squid1.grid.uiocloud.no:3128)(backupproxyurl=http://atlasbpfrontier.cern.ch:3127)(backupproxyurl=http://atlasbpfrontier.fnal.gov:3127)' [2024-05-16 01:15:58] + FRONTIER_SERVER='(serverurl=http://atlascern-frontier.openhtc.io:8080/atlr)(serverurl=http://atlasfrontier-ai.cern.ch:8000/atlr)(proxyurl=http://db-atlas-squid.ndgf.org:3128)(proxyurl=http://squid.vega.izum.si:3128)(proxyurl=http://grid-proxy.ijs.si:3128)(proxyurl=http://atlas-db-squid1.grid.uiocloud.no:3128)(backupproxyurl=http://atlasbpfrontier.cern.ch:3127)(backupproxyurl=http://atlasbpfrontier.fnal.gov:3127)' [2024-05-16 01:15:58] + '[' 0 -ne 0 ']' [2024-05-16 01:15:58] + echo 'runtimeenvironments=APPS/HEP/ATLAS-SITE;' [2024-05-16 01:15:58] + '[' -f /etc/os-release ']' [2024-05-16 01:15:58] +++ cat /etc/os-release [2024-05-16 01:15:58] ++ 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"' [2024-05-16 01:15:58] +++ NAME='CentOS Linux' [2024-05-16 01:15:58] +++ VERSION='7 (Core)' [2024-05-16 01:15:58] +++ ID=centos [2024-05-16 01:15:58] +++ ID_LIKE='rhel fedora' [2024-05-16 01:15:58] +++ VERSION_ID=7 [2024-05-16 01:15:58] +++ PRETTY_NAME='CentOS Linux 7 (Core)' [2024-05-16 01:15:58] +++ ANSI_COLOR='0;31' [2024-05-16 01:15:58] +++ CPE_NAME=cpe:/o:centos:centos:7 [2024-05-16 01:15:58] +++ HOME_URL=https://www.centos.org/ [2024-05-16 01:15:58] +++ BUG_REPORT_URL=https://bugs.centos.org/ [2024-05-16 01:15:58] +++ CENTOS_MANTISBT_PROJECT=CentOS-7 [2024-05-16 01:15:58] +++ CENTOS_MANTISBT_PROJECT_VERSION=7 [2024-05-16 01:15:58] +++ REDHAT_SUPPORT_PRODUCT=centos [2024-05-16 01:15:58] +++ REDHAT_SUPPORT_PRODUCT_VERSION=7 [2024-05-16 01:15:58] ++ echo 'CentOS Linux 7 (Core)' [2024-05-16 01:15:58] + SYSTEM_SOFTWARE='CentOS Linux 7 (Core)' [2024-05-16 01:15:58] + '[' -n 'CentOS Linux 7 (Core)' ']' [2024-05-16 01:15:58] + echo 'systemsoftware=CentOS Linux 7 (Core)' [2024-05-16 01:15:58] + HOME=/tmp/boinchome/slots/0 [2024-05-16 01:15:58] + export HOME [2024-05-16 01:15:58] + cd /tmp/boinchome/slots/0 [2024-05-16 01:15:58] + '[' '!' -z '' ']' [2024-05-16 01:15:58] + '[' -z '' ']' [2024-05-16 01:15:58] ++ /bin/hostname -f [2024-05-16 01:15:58] + nodename=c-212-6.aglt2.org [2024-05-16 01:15:58] + echo nodename=c-212-6.aglt2.org [2024-05-16 01:15:58] + echo Processors=1 [2024-05-16 01:15:58] + echo Benchmark=HEPSPEC:1.0 [2024-05-16 01:15:58] + executable=./runpilot2-wrapper.sh [2024-05-16 01:15:58] + '[' '!' -f ./runpilot2-wrapper.sh ']' [2024-05-16 01:15:58] ++ dd if=./runpilot2-wrapper.sh count=1 [2024-05-16 01:15:58] ++ tr -d '\0' [2024-05-16 01:15:58] ++ head -n 1 [2024-05-16 01:15:58] + line1='#!/bin/bash' [2024-05-16 01:15:58] ++ echo '#!/bin/bash' [2024-05-16 01:15:58] ++ sed -n 's/^#! *//p' [2024-05-16 01:15:58] + shebang=/bin/bash [2024-05-16 01:15:58] ++ echo /bin/bash [2024-05-16 01:15:58] ++ awk '{print $1}' [2024-05-16 01:15:58] + interpreter=/bin/bash [2024-05-16 01:15:58] + '[' /bin/bash = /usr/bin/env ']' [2024-05-16 01:15:58] + '[' x/bin/bash = x ']' [2024-05-16 01:15:58] + type /bin/bash [2024-05-16 01:15:58] + '[' xgnutime = xgnutime ']' [2024-05-16 01:15:58] + /usr/bin/time -o /tmp/boinchome/slots/0/2B9KDm4MWR5n9Rq4apoT9bVoABFKDmABFKDmlqFKDmpaJKDmWO11un.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 SCORE_HIMEM --pilotversion 3.7.5.4 -z -t --piloturl local --mute --container [2024-05-16 01:15:58] + RESULT=2 [2024-05-16 01:15:58] + sed -i -e /nodename=/d /tmp/boinchome/slots/0/2B9KDm4MWR5n9Rq4apoT9bVoABFKDmABFKDmlqFKDmpaJKDmWO11un.diag [2024-05-16 01:15:58] ++ awk -F '>' '{print $2}' [2024-05-16 01:15:58] ++ awk -F '<' '{print $1}' [2024-05-16 01:15:58] ++ sed -e 's# #_#g' [2024-05-16 01:15:58] ++ grep domain_name init_data.xml [2024-05-16 01:15:58] + hostname=c-212-6.aglt2.org [2024-05-16 01:15:58] ++ grep user_name init_data.xml [2024-05-16 01:15:58] ++ awk -F '>' '{print $2}' [2024-05-16 01:15:58] ++ awk -F '<' '{print $1}' [2024-05-16 01:15:58] ++ sed -e 's# #_#g' [2024-05-16 01:15:58] + username=AGLT2 [2024-05-16 01:15:58] + nodename=AGLT2@c-212-6.aglt2.org [2024-05-16 01:15:58] + echo nodename=AGLT2@c-212-6.aglt2.org [2024-05-16 01:15:58] + '[' -n 8 ']' [2024-05-16 01:15:58] + sed -i -e s/Processors=1/Processors=8/ /tmp/boinchome/slots/0/2B9KDm4MWR5n9Rq4apoT9bVoABFKDmABFKDmlqFKDmpaJKDmWO11un.diag [2024-05-16 01:15:58] + echo exitcode=2 [2024-05-16 01:15:58] + runtimeenvironments= [2024-05-16 01:15:58] + runtimeenvironments='APPS/HEP/ATLAS-SITE;' [2024-05-16 01:15:58] + RTE_function_0 2 [2024-05-16 01:15:58] + export PROJECT_ROOT=/boincdata/boinc/project/lhcathome [2024-05-16 01:15:58] + PROJECT_ROOT=/boincdata/boinc/project/lhcathome [2024-05-16 01:15:58] + export BOINC_APP=ATLAS [2024-05-16 01:15:58] + BOINC_APP=ATLAS [2024-05-16 01:15:58] + export WU_TEMPLATE=templates/ATLAS_IN_DYNAMIC [2024-05-16 01:15:58] + WU_TEMPLATE=templates/ATLAS_IN_DYNAMIC [2024-05-16 01:15:58] + export RESULT_TEMPLATE=templates/ATLAS_OUT_2 [2024-05-16 01:15:58] + RESULT_TEMPLATE=templates/ATLAS_OUT_2 [2024-05-16 01:15:58] + '[' -z 2 ']' [2024-05-16 01:15:58] + '[' 2 == 1 ']' [2024-05-16 01:15:58] + '[' 0 -ne 0 ']' [2024-05-16 01:15:58] + echo 'zip all output files' [2024-05-16 01:15:58] + flist='*.diag ' [2024-05-16 01:15:58] + for f in ./heartbeat.json ./output.list ./log.38776104._006921.job.log.1 ./log.38776104._006921.job.log.1 ./gmlog/ [2024-05-16 01:15:58] + '[' -e ./heartbeat.json ']' [2024-05-16 01:15:58] + flist='*.diag ./heartbeat.json' [2024-05-16 01:15:58] + for f in ./heartbeat.json ./output.list ./log.38776104._006921.job.log.1 ./log.38776104._006921.job.log.1 ./gmlog/ [2024-05-16 01:15:58] + '[' -e ./output.list ']' [2024-05-16 01:15:58] + for f in ./heartbeat.json ./output.list ./log.38776104._006921.job.log.1 ./log.38776104._006921.job.log.1 ./gmlog/ [2024-05-16 01:15:58] + '[' -e ./log.38776104._006921.job.log.1 ']' [2024-05-16 01:15:58] + flist='*.diag ./heartbeat.json ./log.38776104._006921.job.log.1' [2024-05-16 01:15:58] + for f in ./heartbeat.json ./output.list ./log.38776104._006921.job.log.1 ./log.38776104._006921.job.log.1 ./gmlog/ [2024-05-16 01:15:58] + '[' -e ./log.38776104._006921.job.log.1 ']' [2024-05-16 01:15:58] + flist='*.diag ./heartbeat.json ./log.38776104._006921.job.log.1 ./log.38776104._006921.job.log.1' [2024-05-16 01:15:58] + for f in ./heartbeat.json ./output.list ./log.38776104._006921.job.log.1 ./log.38776104._006921.job.log.1 ./gmlog/ [2024-05-16 01:15:58] + '[' -e ./gmlog/ ']' [2024-05-16 01:15:58] + '[' -f output.list ']' [2024-05-16 01:15:58] + tar cvf result.tar.gz 2B9KDm4MWR5n9Rq4apoT9bVoABFKDmABFKDmlqFKDmpaJKDmWO11un.diag ./heartbeat.json ./log.38776104._006921.job.log.1 ./log.38776104._006921.job.log.1 [2024-05-16 01:15:58] + '[' xgnutime = xcgroup ']' [2024-05-16 01:15:58] + '[' -n '' ']' [2024-05-16 01:15:58] + '[' -n '' ']' [2024-05-16 01:15:58] ++ date +%s [2024-05-16 01:15:58] + ACCOUNTING_ENDTIME=1715836557 [2024-05-16 01:15:58] ++ date -d '1970-01-01 UTC 1715836363 seconds' +%Y%m%d%H%M%SZ [2024-05-16 01:15:58] + echo LRMSStartTime=20240516011243Z [2024-05-16 01:15:58] ++ date -d '1970-01-01 UTC 1715836557 seconds' +%Y%m%d%H%M%SZ [2024-05-16 01:15:58] + echo LRMSEndTime=20240516011557Z [2024-05-16 01:15:58] + echo walltime=194 [2024-05-16 01:15:58] + echo exitcode=2 [2024-05-16 01:15:58] + exit 2 [2024-05-16 01:15:58] INFO: Cleanup error: while stopping driver for /var/lib/apptainer/mnt/session/final: fuse-overlayfs exited [2024-05-16 01:15:58] ./log.38776104._006921.job.log.1 [2024-05-16 01:15:58] ./pilot3/pilot/util/realtimelogger.py [2024-05-16 01:15:58] ./pilot3/pilot/util/loggingsupport.py [2024-05-16 01:15:58] ./pilot3/pilot/util/__pycache__/realtimelogger.cpython-39.pyc [2024-05-16 01:15:58] ./pilot3/pilot/util/__pycache__/loggingsupport.cpython-39.pyc [2024-05-16 01:15:58] ./PanDA_Pilot-6206854443/prmon.log [2024-05-16 01:15:58] ./PanDA_Pilot-6206854443/pilotlog.txt [2024-05-16 01:15:58] ./PanDA_Pilot-6206854443/log.EVNTtoHITS [2024-05-16 01:15:58] ./PanDA_Pilot-6206854443/PoolFileCatalog.xml [2024-05-16 01:15:58] ./runtime_log [2024-05-16 01:15:58] ./runtime_log.err [2024-05-16 01:15:58] ./pilotlog.txt 01:31:43 (59428): wrapper (7.7.26015): starting 01:31:43 (59428): wrapper: running run_atlas (--nthreads 8) [2024-05-16 01:31:43] Arguments: --nthreads 8 [2024-05-16 01:31:43] Threads: 8 [2024-05-16 01:31:43] This job has been restarted, cleaning up previous attempt [2024-05-16 01:31:43] Checking for CVMFS [2024-05-16 01:31:43] Probing /cvmfs/atlas.cern.ch... OK [2024-05-16 01:31:43] Probing /cvmfs/atlas-condb.cern.ch... OK [2024-05-16 01:31:43] Running cvmfs_config stat atlas.cern.ch [2024-05-16 01:31:43] 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 [2024-05-16 01:31:43] 2.11.2.0 46351 525 79312 132786 3 64 10762414 18432001 12498 130560 0 3303940 98.394 11003885 53040 http://cvmfs-s1fnal.opensciencegrid.org:8000/cvmfs/atlas.cern.ch http://192.41.237.109:6081 1 [2024-05-16 01:31:43] CVMFS is ok [2024-05-16 01:31:43] Efficiency of ATLAS tasks can be improved by the following measure(s): [2024-05-16 01:31:43] The CVMFS client on this computer should be configured to use Cloudflare's openhtc.io. [2024-05-16 01:31:43] Further information can be found at the LHC@home message board. [2024-05-16 01:31:43] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 [2024-05-16 01:31:43] Checking for apptainer binary... [2024-05-16 01:31:43] Using apptainer found in PATH at /usr/bin/apptainer [2024-05-16 01:31:43] Running /usr/bin/apptainer --version [2024-05-16 01:31:43] apptainer version 1.3.0-1.el7 [2024-05-16 01:31:43] Checking apptainer works with /usr/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname [2024-05-16 01:31:45] c-212-6.aglt2.org [2024-05-16 01:31:45] apptainer works [2024-05-16 01:31:45] Set ATHENA_PROC_NUMBER=8 [2024-05-16 01:31:45] Set ATHENA_CORE_NUMBER=8 [2024-05-16 01:31:45] Starting ATLAS job with PandaID=6206854443 [2024-05-16 01:31:45] Running command: /usr/bin/apptainer exec -B /cvmfs,/tmp/boinchome/slots/0 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 sh start_atlas.sh [2024-05-16 05:17:13] *** The last 200 lines of the pilot log: *** [2024-05-16 05:17:13] 2024-05-16 09:16:53,870 | INFO | intersect: -63507351929.77978 [2024-05-16 05:17:13] 2024-05-16 09:16:53,870 | INFO | chi2: 6.350583590862555 [2024-05-16 05:17:13] 2024-05-16 09:16:53,870 | INFO | model: linear, x: [1715837544.0, 1715837605.0, 1715837666.0, 1715837727.0, 1715837788.0, 1715837849.0, 1715837910.0, 1715837971.0, 1715838032.0, 1715838093.0, 1715 [2024-05-16 05:17:13] 2024-05-16 09:16:53,870 | INFO | sum of square deviations: 3081679259.702326 [2024-05-16 05:17:13] 2024-05-16 09:16:53,871 | INFO | sum of deviations: 134173197169.41878 [2024-05-16 05:17:13] 2024-05-16 09:16:53,871 | INFO | mean x: 1715844071.0372093 [2024-05-16 05:17:13] 2024-05-16 09:16:53,871 | INFO | mean y: 2445345.697674419 [2024-05-16 05:17:13] 2024-05-16 09:16:53,872 | INFO | -- intersect: -74703669552.6749 [2024-05-16 05:17:13] 2024-05-16 09:16:53,872 | INFO | intersect: -74703669552.6749 [2024-05-16 05:17:13] 2024-05-16 09:16:53,872 | INFO | chi2: 5.5725532175402135 [2024-05-16 05:17:13] 2024-05-16 09:16:53,872 | INFO | current chi2=5.5725532175402135 (change=12.251320877687508 %) [2024-05-16 05:17:13] 2024-05-16 09:16:53,872 | INFO | right removable region: 214 [2024-05-16 05:17:13] 2024-05-16 09:16:53,872 | INFO | model: linear, x: [1715837849.0, 1715837910.0, 1715837971.0, 1715838032.0, 1715838093.0, 1715838154.0, 1715838215.0, 1715838276.0, 1715838337.0, 1715838398.0, 1715 [2024-05-16 05:17:13] 2024-05-16 09:16:53,872 | INFO | sum of square deviations: 3081686946.623267 [2024-05-16 05:17:13] 2024-05-16 09:16:53,873 | INFO | sum of deviations: 62821026603.76281 [2024-05-16 05:17:13] 2024-05-16 09:16:53,873 | INFO | mean x: 1715844376.0418606 [2024-05-16 05:17:13] 2024-05-16 09:16:53,873 | INFO | mean y: 2478326.693023256 [2024-05-16 05:17:13] 2024-05-16 09:16:53,873 | INFO | -- intersect: -34975475976.34017 [2024-05-16 05:17:13] 2024-05-16 09:16:53,873 | INFO | intersect: -34975475976.34017 [2024-05-16 05:17:13] 2024-05-16 09:16:53,874 | INFO | chi2: 3.3952207118514366 [2024-05-16 05:17:13] 2024-05-16 09:16:53,874 | INFO | current chi2=3.3952207118514366 (change=46.53687077300107 %) [2024-05-16 05:17:13] 2024-05-16 09:16:53,874 | INFO | model: linear, x: [1715838154.0, 1715838215.0, 1715838276.0, 1715838337.0, 1715838398.0, 1715838459.0, 1715838520.0, 1715838581.0, 1715838642.0, 1715838703.0, 1715 [2024-05-16 05:17:13] 2024-05-16 09:16:53,874 | INFO | sum of square deviations: 2871643054.114293 [2024-05-16 05:17:13] 2024-05-16 09:16:53,875 | INFO | sum of deviations: 14947669650.085718 [2024-05-16 05:17:13] 2024-05-16 09:16:53,875 | INFO | mean x: 1715844528.5428572 [2024-05-16 05:17:13] 2024-05-16 09:16:53,875 | INFO | mean y: 2513089.157142857 [2024-05-16 05:17:13] 2024-05-16 09:16:53,875 | INFO | -- intersect: -8928916305.181818 [2024-05-16 05:17:13] 2024-05-16 09:16:53,875 | INFO | intersect: -8928916305.181818 [2024-05-16 05:17:13] 2024-05-16 09:16:53,875 | INFO | chi2: 1.6096157656867929 [2024-05-16 05:17:13] 2024-05-16 09:16:53,875 | INFO | current chi2=1.6096157656867929 (change=52.59171929329276 %) [2024-05-16 05:17:13] 2024-05-16 09:16:53,876 | INFO | model: linear, x: [1715838459.0, 1715838520.0, 1715838581.0, 1715838642.0, 1715838703.0, 1715838764.0, 1715838825.0, 1715838886.0, 1715838947.0, 1715839008.0, 1715 [2024-05-16 05:17:13] 2024-05-16 09:16:53,876 | INFO | sum of square deviations: 2671366786.60487 [2024-05-16 05:17:13] 2024-05-16 09:16:53,877 | INFO | sum of deviations: -11536345239.804878 [2024-05-16 05:17:13] 2024-05-16 09:16:53,877 | INFO | mean x: 1715844681.0439024 [2024-05-16 05:17:13] 2024-05-16 09:16:53,877 | INFO | mean y: 2533083.7560975607 [2024-05-16 05:17:13] 2024-05-16 09:16:53,877 | INFO | -- intersect: 7412439023.165839 [2024-05-16 05:17:13] 2024-05-16 09:16:53,877 | INFO | intersect: 7412439023.165839 [2024-05-16 05:17:13] 2024-05-16 09:16:53,877 | INFO | chi2: 0.8289627075737857 [2024-05-16 05:17:13] 2024-05-16 09:16:53,877 | INFO | current chi2=0.8289627075737857 (change=48.49934218803561 %) [2024-05-16 05:17:13] 2024-05-16 09:16:53,877 | INFO | model: linear, x: [1715838764.0, 1715838825.0, 1715838886.0, 1715838947.0, 1715839008.0, 1715839069.0, 1715839130.0, 1715839191.0, 1715839252.0, 1715839313.0, 1715 [2024-05-16 05:17:13] 2024-05-16 09:16:53,878 | INFO | sum of square deviations: 2480625581.59499 [2024-05-16 05:17:13] 2024-05-16 09:16:53,878 | INFO | sum of deviations: -11623882429.235 [2024-05-16 05:17:13] 2024-05-16 09:16:53,879 | INFO | mean x: 1715844833.545 [2024-05-16 05:17:13] 2024-05-16 09:16:53,879 | INFO | mean y: 2533147.415 [2024-05-16 05:17:13] 2024-05-16 09:16:53,879 | INFO | -- intersect: 8042754436.721107 [2024-05-16 05:17:13] 2024-05-16 09:16:53,879 | INFO | intersect: 8042754436.721107 [2024-05-16 05:17:13] 2024-05-16 09:16:53,879 | INFO | chi2: 0.8288943844856976 [2024-05-16 05:17:13] 2024-05-16 09:16:53,879 | INFO | current chi2=0.8288943844856976 (change=0.008241997796015053 %) [2024-05-16 05:17:13] 2024-05-16 09:16:53,879 | INFO | left removable region: 40 [2024-05-16 05:17:13] 2024-05-16 09:16:53,879 | INFO | model: linear, x: [1715839984.0, 1715840045.0, 1715840106.0, 1715840167.0, 1715840228.0, 1715840289.0, 1715840350.0, 1715840411.0, 1715840472.0, 1715840533.0, 1715 [2024-05-16 05:17:13] 2024-05-16 09:16:53,880 | INFO | sum of square deviations: 1633474935.1321867 [2024-05-16 05:17:13] 2024-05-16 09:16:53,880 | INFO | sum of deviations: 3498393480.9770107 [2024-05-16 05:17:13] 2024-05-16 09:16:53,880 | INFO | mean x: 1715845260.545977 [2024-05-16 05:17:13] 2024-05-16 09:16:53,880 | INFO | mean y: 2543285.252873563 [2024-05-16 05:17:13] 2024-05-16 09:16:53,880 | INFO | -- intersect: -3672261723.844855 [2024-05-16 05:17:13] 2024-05-16 09:16:53,880 | INFO | intersect: -3672261723.844855 [2024-05-16 05:17:13] 2024-05-16 09:16:53,881 | INFO | chi2: 0.00039821823128578305 [2024-05-16 05:17:13] 2024-05-16 09:16:53,881 | INFO | -- intersect: -3672261723.844855 [2024-05-16 05:17:13] 2024-05-16 09:16:53,881 | INFO | current memory leak: 2.14 B/s (using 174 data points, chi2=0.00) [2024-05-16 05:17:13] 2024-05-16 09:16:53,881 | INFO | .............................. [2024-05-16 05:17:13] 2024-05-16 09:16:53,881 | INFO | . Timing measurements: [2024-05-16 05:17:13] 2024-05-16 09:16:53,881 | INFO | . get job = 0 s [2024-05-16 05:17:13] 2024-05-16 09:16:53,882 | INFO | . initial setup = 0 s [2024-05-16 05:17:13] 2024-05-16 09:16:53,882 | INFO | . payload setup = 6 s [2024-05-16 05:17:13] 2024-05-16 09:16:53,882 | INFO | . stage-in = 0 s [2024-05-16 05:17:13] 2024-05-16 09:16:53,882 | INFO | . payload execution = 13416 s [2024-05-16 05:17:13] 2024-05-16 09:16:53,882 | INFO | . stage-out = 7 s [2024-05-16 05:17:13] 2024-05-16 09:16:53,882 | INFO | . log creation = 1 s [2024-05-16 05:17:13] 2024-05-16 09:16:53,882 | INFO | .............................. [2024-05-16 05:17:13] 2024-05-16 09:16:53,952 | INFO | [2024-05-16 05:17:13] 2024-05-16 09:16:53,954 | INFO | job summary report [2024-05-16 05:17:13] 2024-05-16 09:16:53,954 | INFO | -------------------------------------------------- [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | PanDA job id: 6206854443 [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | task id: 38776104 [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | errors: (none) [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | status: LOG_TRANSFER = DONE [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | pilot state: finished [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | transexitcode: 0 [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | exeerrorcode: 0 [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | exeerrordiag: [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | exitcode: 0 [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | exitmsg: OK [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | cpuconsumptiontime: 37571 s [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | nevents: 400 [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | neventsw: 0 [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | pid: 41449 [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | pgrp: 41449 [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | corecount: 8 [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | event service: False [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | sizes: {0: 2392202, 4: 2392408, 11: 2392436, 13425: 2422291, 13433: 2431307, 13483: 2431421} [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | -------------------------------------------------- [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | [2024-05-16 05:17:13] 2024-05-16 09:16:53,955 | INFO | executing command: ls -lF /tmp/boinchome/slots/0 [2024-05-16 05:17:13] 2024-05-16 09:16:54,317 | INFO | queue jobs had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,318 | INFO | queue payloads had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,318 | INFO | queue data_in had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,318 | INFO | queue data_out had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,318 | INFO | queue current_data_in had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,318 | INFO | queue validated_jobs had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,318 | INFO | queue validated_payloads had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,318 | INFO | queue monitored_payloads had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,318 | INFO | queue finished_jobs had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,318 | INFO | queue finished_payloads had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,318 | INFO | queue finished_data_in had 1 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,318 | INFO | queue finished_data_out had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,318 | INFO | queue failed_jobs had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,318 | INFO | queue failed_payloads had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,319 | INFO | queue failed_data_in had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,319 | INFO | queue failed_data_out had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,319 | INFO | queue completed_jobs had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,319 | INFO | queue completed_jobids has 1 job(s) [2024-05-16 05:17:13] 2024-05-16 09:16:54,319 | INFO | queue realtimelog_payloads had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,319 | INFO | queue messages had 0 job(s) [purged] [2024-05-16 05:17:13] 2024-05-16 09:16:54,319 | INFO | job 6206854443 has completed (purged errors) [2024-05-16 05:17:13] 2024-05-16 09:16:54,319 | INFO | overall cleanup function is called [2024-05-16 05:17:13] 2024-05-16 09:16:55,344 | INFO | --- collectZombieJob: --- 10, [41449] [2024-05-16 05:17:13] 2024-05-16 09:16:55,345 | INFO | zombie collector waiting for pid 41449 [2024-05-16 05:17:13] 2024-05-16 09:16:55,345 | INFO | harmless exception when collecting zombies: [Errno 10] No child processes [2024-05-16 05:17:13] 2024-05-16 09:16:56,350 | INFO | collected zombie processes [2024-05-16 05:17:13] 2024-05-16 09:16:56,351 | INFO | will now attempt to kill all subprocesses of pid=41449 [2024-05-16 05:17:13] 2024-05-16 09:16:56,586 | INFO | process IDs to be killed: [41449] (in reverse order) [2024-05-16 05:17:13] 2024-05-16 09:16:56,695 | WARNING | found no corresponding commands to process id(s) [2024-05-16 05:17:13] 2024-05-16 09:16:56,696 | INFO | Do not look for orphan processes in BOINC jobs [2024-05-16 05:17:13] 2024-05-16 09:16:56,705 | INFO | did not find any defunct processes belonging to 41449 [2024-05-16 05:17:13] 2024-05-16 09:16:56,713 | INFO | did not find any defunct processes belonging to 41449 [2024-05-16 05:17:13] 2024-05-16 09:16:56,713 | INFO | ready for new job [2024-05-16 05:17:13] 2024-05-16 09:16:56,713 | INFO | pilot has finished with previous job - re-establishing logging [2024-05-16 05:17:13] 2024-05-16 09:16:56,715 | INFO | ************************************* [2024-05-16 05:17:13] 2024-05-16 09:16:56,715 | INFO | *** PanDA Pilot version 3.7.5.4 *** [2024-05-16 05:17:13] 2024-05-16 09:16:56,715 | INFO | ************************************* [2024-05-16 05:17:13] 2024-05-16 09:16:56,715 | INFO | [2024-05-16 05:17:13] 2024-05-16 09:16:56,719 | INFO | architecture information: [2024-05-16 05:17:13] 2024-05-16 09:16:56,720 | INFO | executing command: cat /etc/os-release [2024-05-16 05:17:13] 2024-05-16 09:16:56,736 | INFO | cat /etc/os-release: [2024-05-16 05:17:13] NAME="CentOS Linux" [2024-05-16 05:17:13] VERSION="7 (Core)" [2024-05-16 05:17:13] ID="centos" [2024-05-16 05:17:13] ID_LIKE="rhel fedora" [2024-05-16 05:17:13] VERSION_ID="7" [2024-05-16 05:17:13] PRETTY_NAME="CentOS Linux 7 (Core)" [2024-05-16 05:17:13] ANSI_COLOR="0;31" [2024-05-16 05:17:13] CPE_NAME="cpe:/o:centos:centos:7" [2024-05-16 05:17:13] HOME_URL="https://www.centos.org/" [2024-05-16 05:17:13] BUG_REPORT_URL="https://bugs.centos.org/" [2024-05-16 05:17:13] [2024-05-16 05:17:13] CENTOS_MANTISBT_PROJECT="CentOS-7" [2024-05-16 05:17:13] CENTOS_MANTISBT_PROJECT_VERSION="7" [2024-05-16 05:17:13] REDHAT_SUPPORT_PRODUCT="centos" [2024-05-16 05:17:13] REDHAT_SUPPORT_PRODUCT_VERSION="7" [2024-05-16 05:17:13] [2024-05-16 05:17:13] 2024-05-16 09:16:56,736 | INFO | ************************************* [2024-05-16 05:17:13] 2024-05-16 09:16:57,240 | INFO | executing command: df -mP /tmp/boinchome/slots/0 [2024-05-16 05:17:13] 2024-05-16 09:16:57,324 | INFO | sufficient remaining disk space (99326361600 B) [2024-05-16 05:17:13] 2024-05-16 09:16:57,324 | WARNING | since timefloor is set to 0, pilot was only allowed to run one job [2024-05-16 05:17:13] 2024-05-16 09:16:57,325 | WARNING | setting graceful_stop since proceed_with_getjob() returned False (pilot will end) [2024-05-16 05:17:13] 2024-05-16 09:16:57,325 | WARNING | data:copytool_out:received graceful stop - abort after this iteration [2024-05-16 05:17:13] 2024-05-16 09:16:57,325 | WARNING | job:job_monitor:received graceful stop - abort after this iteration [2024-05-16 05:17:13] 2024-05-16 09:16:57,325 | INFO | aborting loop [2024-05-16 05:17:13] 2024-05-16 09:16:57,325 | WARNING | aborting monitor loop since graceful_stop has been set (timing out remaining threads) [2024-05-16 05:17:13] 2024-05-16 09:16:57,326 | INFO | found 0 job(s) in 20 queues [2024-05-16 05:17:13] 2024-05-16 09:16:57,326 | WARNING | pilot monitor received instruction that args.graceful_stop has been set [2024-05-16 05:17:13] 2024-05-16 09:16:57,326 | WARNING | will wait for a maximum of 300 s for threads to finish [2024-05-16 05:17:13] 2024-05-16 09:16:57,451 | INFO | all data control threads have been joined [2024-05-16 05:17:13] 2024-05-16 09:16:57,758 | INFO | all job control threads have been joined [2024-05-16 05:17:13] 2024-05-16 09:16:58,331 | INFO | [job] retrieve thread has finished [2024-05-16 05:17:13] 2024-05-16 09:16:58,331 | INFO | [job] job monitor thread has finished [2024-05-16 05:17:13] 2024-05-16 09:16:58,455 | INFO | all payload control threads have been joined [2024-05-16 05:17:13] 2024-05-16 09:16:58,457 | INFO | [data] control thread has finished [2024-05-16 05:17:13] 2024-05-16 09:16:58,600 | INFO | [job] create_data_payload thread has finished [2024-05-16 05:17:13] 2024-05-16 09:16:58,662 | INFO | [payload] execute_payloads thread has finished [2024-05-16 05:17:13] 2024-05-16 09:16:58,765 | INFO | [job] control thread has finished [2024-05-16 05:17:13] 2024-05-16 09:16:58,796 | INFO | [payload] validate_pre thread has finished [2024-05-16 05:17:13] 2024-05-16 09:16:58,796 | INFO | [data] copytool_in thread has finished [2024-05-16 05:17:13] 2024-05-16 09:16:58,967 | WARNING | job:queue_monitor:received graceful stop - abort after this iteration [2024-05-16 05:17:13] 2024-05-16 09:16:59,101 | INFO | [payload] run_realtimelog thread has finished [2024-05-16 05:17:13] 2024-05-16 09:16:59,106 | INFO | [job] validate thread has finished [2024-05-16 05:17:13] 2024-05-16 09:16:59,334 | INFO | [data] copytool_out thread has finished [2024-05-16 05:17:13] 2024-05-16 09:16:59,441 | WARNING | data:queue_monitoring:received graceful stop - abort after this iteration [2024-05-16 05:17:13] 2024-05-16 09:16:59,461 | INFO | [payload] control thread has finished [2024-05-16 05:17:13] 2024-05-16 09:16:59,864 | INFO | [payload] validate_post thread has finished [2024-05-16 05:17:13] 2024-05-16 09:16:59,877 | INFO | [payload] failed_post thread has finished [2024-05-16 05:17:13] 2024-05-16 09:16:59,973 | INFO | [job] queue monitor thread has finished [2024-05-16 05:17:13] 2024-05-16 09:17:03,450 | INFO | [data] queue_monitor thread has finished [2024-05-16 05:17:13] 2024-05-16 09:17:05,332 | INFO | only monitor.control thread still running - safe to abort: ['<_MainThread(MainThread, started 140353299396416)>', '<ExcThread(monitor, started 140352728057600)>'] [2024-05-16 05:17:13] 2024-05-16 09:17:05,376 | WARNING | job_aborted has been set - aborting pilot monitoring [2024-05-16 05:17:13] 2024-05-16 09:17:05,376 | INFO | [monitor] control thread has ended [2024-05-16 05:17:13] 2024-05-16 09:17:10,362 | INFO | all workflow threads have been joined [2024-05-16 05:17:13] 2024-05-16 09:17:10,362 | INFO | end of generic workflow (traces error code: 0) [2024-05-16 05:17:13] 2024-05-16 09:17:10,365 | INFO | traces error code: 0 [2024-05-16 05:17:13] 2024-05-16 09:17:10,366 | INFO | pilot has finished (exit code=0, shell exit code=0) [2024-05-16 05:17:13] 2024-05-16 09:17:11,103 [wrapper] ==== pilot stdout END ==== [2024-05-16 05:17:13] 2024-05-16 09:17:11,144 [wrapper] ==== wrapper stdout RESUME ==== [2024-05-16 05:17:13] 2024-05-16 09:17:11,180 [wrapper] pilotpid: 86369 [2024-05-16 05:17:13] 2024-05-16 09:17:11,222 [wrapper] Pilot exit status: 0 [2024-05-16 05:17:13] 2024-05-16 09:17:11,427 [wrapper] pandaids: 6206854443 6206854443 6206854443 [2024-05-16 05:17:13] 2024-05-16 09:17:11,708 [wrapper] cleanup: SIGTERM to supervisor_pilot 64722 86370 [2024-05-16 05:17:13] 2024-05-16 09:17:11,710 [wrapper] Test setup, not cleaning [2024-05-16 05:17:13] 2024-05-16 09:17:11,712 [wrapper] ==== wrapper stdout END ==== [2024-05-16 05:17:13] 2024-05-16 09:17:11,714 [wrapper] ==== wrapper stderr END ==== [2024-05-16 05:17:13] 2024-05-16 09:17:11,717 [wrapper] apfmon messages muted [2024-05-16 05:17:13] *** Error codes and diagnostics *** [2024-05-16 05:17:13] "exeErrorCode": 0, [2024-05-16 05:17:13] "exeErrorDiag": "", [2024-05-16 05:17:13] "pilotErrorCode": 0, [2024-05-16 05:17:13] "pilotErrorDiag": "", [2024-05-16 05:17:13] *** Listing of results directory *** [2024-05-16 05:17:13] total 1410980 [2024-05-16 05:17:13] drwx------ 4 boincer umatlas 4096 May 8 08:24 pilot3 [2024-05-16 05:17:13] -rw-r--r-- 1 boincer umatlas 467845 May 15 11:49 pilot3.tar.gz [2024-05-16 05:17:13] -rwx------ 1 boincer umatlas 32251 May 15 12:09 runpilot2-wrapper.sh [2024-05-16 05:17:13] -rw-r--r-- 1 boincer umatlas 5633 May 15 12:09 queuedata.json [2024-05-16 05:17:13] -rw-r--r-- 1 boincer umatlas 100 May 15 16:18 wrapper_26015_x86_64-pc-linux-gnu [2024-05-16 05:17:13] -rwxr-xr-x 1 boincer umatlas 7986 May 15 16:18 run_atlas [2024-05-16 05:17:13] -rw-r--r-- 1 boincer umatlas 105 May 15 16:18 job.xml [2024-05-16 05:17:13] -rw-r--r-- 3 boincer umatlas 602978389 May 15 16:18 EVNT.38776100._000280.pool.root.1 [2024-05-16 05:17:13] -rw-r--r-- 3 boincer umatlas 602978389 May 15 16:18 ATLAS.root_0 [2024-05-16 05:17:13] -rw-r--r-- 2 boincer umatlas 17537 May 15 16:18 start_atlas.sh [2024-05-16 05:17:13] -rw-r--r-- 2 boincer umatlas 480004 May 15 16:18 input.tar.gz [2024-05-16 05:17:13] -rw-r--r-- 1 boincer umatlas 0 May 15 16:18 boinc_lockfile [2024-05-16 05:17:13] -rw------- 1 boincer umatlas 1324701 May 15 16:19 cric_ddmendpoints.json [2024-05-16 05:17:13] drwxrwx--x 2 boincer umatlas 4096 May 16 01:15 shared [2024-05-16 05:17:13] -rw-r--r-- 1 boincer umatlas 2641 May 16 01:31 pandaJob.out [2024-05-16 05:17:13] -rw------- 1 boincer umatlas 424 May 16 01:31 setup.sh.local [2024-05-16 05:17:13] -rw------- 1 boincer umatlas 1010627 May 16 01:31 agis_schedconf.cvmfs.json [2024-05-16 05:17:13] -rw-r--r-- 1 boincer umatlas 6302 May 16 05:13 init_data.xml [2024-05-16 05:17:13] -rw------- 1 boincer umatlas 231918180 May 16 05:14 HITS.38776104._006921.pool.root.1 [2024-05-16 05:17:13] -rw-r--r-- 1 boincer umatlas 532 May 16 05:14 boinc_task_state.xml [2024-05-16 05:17:13] -rw------- 1 boincer umatlas 1086 May 16 05:15 memory_monitor_summary.json [2024-05-16 05:17:13] -rw------- 1 boincer umatlas 0 May 16 05:15 agis_ddmendpoints.agis.ALL.json [2024-05-16 05:17:13] -rw------- 1 boincer umatlas 404250 May 16 05:16 log.38776104._006921.job.log.tgz.1 [2024-05-16 05:17:13] -rw------- 1 boincer umatlas 98 May 16 05:16 pilot_heartbeat.json [2024-05-16 05:17:13] -rw------- 1 boincer umatlas 7756 May 16 05:16 heartbeat.json [2024-05-16 05:17:13] -rw-r--r-- 1 boincer umatlas 8192 May 16 05:17 boinc_mmap_file [2024-05-16 05:17:13] -rw-r--r-- 1 boincer umatlas 28 May 16 05:17 wrapper_checkpoint.txt [2024-05-16 05:17:13] -rw------- 1 boincer umatlas 4334 May 16 05:17 pilotlog.txt [2024-05-16 05:17:13] -rw------- 1 boincer umatlas 1301602 May 16 05:17 log.38776104._006921.job.log.1 [2024-05-16 05:17:13] -rw------- 1 boincer umatlas 357 May 16 05:17 output.list [2024-05-16 05:17:13] -rw-r--r-- 1 boincer umatlas 620 May 16 05:17 runtime_log [2024-05-16 05:17:13] -rw-r--r-- 2 boincer umatlas 1730560 May 16 05:17 result.tar.gz [2024-05-16 05:17:13] -rw-r--r-- 1 boincer umatlas 11080 May 16 05:17 runtime_log.err [2024-05-16 05:17:13] -rw------- 1 boincer umatlas 1359 May 16 05:17 2B9KDm4MWR5n9Rq4apoT9bVoABFKDmABFKDmlqFKDmpaJKDmWO11un.diag [2024-05-16 05:17:13] -rw-r--r-- 1 boincer umatlas 41515 May 16 05:17 stderr.txt [2024-05-16 05:17:13] HITS file was successfully produced: [2024-05-16 05:17:13] -rw------- 1 boincer umatlas 231918180 May 16 05:14 shared/HITS.pool.root.1 [2024-05-16 05:17:13] *** Contents of shared directory: *** [2024-05-16 05:17:13] total 817524 [2024-05-16 05:17:13] -rw-r--r-- 3 boincer umatlas 602978389 May 15 16:18 ATLAS.root_0 [2024-05-16 05:17:13] -rw-r--r-- 2 boincer umatlas 17537 May 15 16:18 start_atlas.sh [2024-05-16 05:17:13] -rw-r--r-- 2 boincer umatlas 480004 May 15 16:18 input.tar.gz [2024-05-16 05:17:13] -rw------- 1 boincer umatlas 231918180 May 16 05:14 HITS.pool.root.1 [2024-05-16 05:17:13] -rw-r--r-- 2 boincer umatlas 1730560 May 16 05:17 result.tar.gz 05:17:14 (59428): run_atlas exited; CPU time 36438.095178 05:17:14 (59428): called boinc_finish(0) </stderr_txt> ]]>
©2024 CERN