| Name | 1ypKDmlHUd5nsSi4ap6QjLDmwznN0nGgGQJmpmIZDmNNiKDmfKIjon_0 |
| Workunit | 223640904 |
| Created | 17 Jun 2024, 16:21:33 UTC |
| Sent | 17 Jun 2024, 23:21:35 UTC |
| Report deadline | 25 Jun 2024, 23:21:35 UTC |
| Received | 27 Jun 2024, 18:23:47 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10603223 |
| Run time | 2 hours 33 min 34 sec |
| CPU time | 17 hours 11 min 57 sec |
| Validate state | Valid |
| Credit | 1,091.49 |
| Device peak FLOPS | 48.75 GFLOPS |
| Application version | ATLAS Simulation v3.01 (native_mt) x86_64-pc-linux-gnu |
| Peak working set size | 2.77 GB |
| Peak swap size | 3.37 GB |
| Peak disk usage | 1.78 GB |
Stderr output
<core_client_version>7.16.11</core_client_version>
<![CDATA[
<stderr_txt>
15:33:03 (42056): wrapper (7.7.26015): starting
15:33:03 (42056): wrapper: running run_atlas (--nthreads 12)
[2024-06-18 15:33:03] Arguments: --nthreads 12
[2024-06-18 15:33:03] Threads: 12
[2024-06-18 15:33:03] Checking for CVMFS
[2024-06-18 15:33:03] Probing /cvmfs/atlas.cern.ch... OK
[2024-06-18 15:33:03] Probing /cvmfs/atlas-condb.cern.ch... OK
[2024-06-18 15:33:03] Running cvmfs_config stat atlas.cern.ch
[2024-06-18 15:33:03] 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-06-18 15:33:03] 2.10.1.0 79574 66631 110400 134030 1 135 36295028 45568000 17011 130560 0 321663216 99.732 232955212 20154 http://cvmfs-s1bnl.opensciencegrid.org:8000/cvmfs/atlas.cern.ch http://206.12.9.163:3129 1
[2024-06-18 15:33:03] CVMFS is ok
[2024-06-18 15:33:03] Efficiency of ATLAS tasks can be improved by the following measure(s):
[2024-06-18 15:33:03] The CVMFS client on this computer should be configured to use Cloudflare's openhtc.io.
[2024-06-18 15:33:03] Further information can be found at the LHC@home message board.
[2024-06-18 15:33:03] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7
[2024-06-18 15:33:03] Checking for apptainer binary...
[2024-06-18 15:33:03] Using apptainer found in PATH at /usr/bin/apptainer
[2024-06-18 15:33:03] Running /usr/bin/apptainer --version
[2024-06-18 15:33:03] apptainer version 1.1.8-1.el7
[2024-06-18 15:33:03] Checking apptainer works with /usr/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname
[2024-06-18 15:33:04] INFO: /etc/singularity/ exists; cleanup by system administrator is not complete (see https://apptainer.org/docs/admin/latest/singularity_migration.html) WARNING: Environment variable TMPDIR already has value [/home/boinc/slots/0/.apptainertmp], will not forward new value [/home/boinc] from parent process environment wns0040.triumf.lcg
[2024-06-18 15:33:04] apptainer works
[2024-06-18 15:33:04] Set ATHENA_PROC_NUMBER=12
[2024-06-18 15:33:04] Set ATHENA_CORE_NUMBER=12
[2024-06-18 15:33:04] Starting ATLAS job with PandaID=6234469692
[2024-06-18 15:33:04] Running command: /usr/bin/apptainer exec -B /cvmfs,/home/boinc/slots/0 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 sh start_atlas.sh
14:10:28 (2459): wrapper (7.7.26015): starting
14:10:28 (2459): wrapper: running run_atlas (--nthreads 12)
[1998-01-03 14:10:28] Arguments: --nthreads 12
[1998-01-03 14:10:28] Threads: 12
[1998-01-03 14:10:28] This job has been restarted, cleaning up previous attempt
[1998-01-03 14:10:28] Checking for CVMFS
[1998-01-03 14:10:37] Probing /cvmfs/atlas.cern.ch... OK
[1998-01-03 14:11:17] Probing /cvmfs/atlas-condb.cern.ch... OK
[1998-01-03 14:11:17] Running cvmfs_config stat atlas.cern.ch
[1998-01-03 14:11:18] 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
[1998-01-03 14:11:18] 2.10.1.0 2762 0 28436 134353 3 1 39970864 45568000 0 130560 0 0 0.000 1141 3907 http://cvmfs-s1bnl.opensciencegrid.org:8000/cvmfs/atlas.cern.ch http://206.12.9.162:3129 1
[1998-01-03 14:11:18] CVMFS is ok
[1998-01-03 14:11:18] Efficiency of ATLAS tasks can be improved by the following measure(s):
[1998-01-03 14:11:18] The CVMFS client on this computer should be configured to use Cloudflare's openhtc.io.
[1998-01-03 14:11:18] Further information can be found at the LHC@home message board.
[1998-01-03 14:11:18] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7
[1998-01-03 14:11:18] Checking for apptainer binary...
[1998-01-03 14:11:18] Using apptainer found in PATH at /usr/bin/apptainer
[1998-01-03 14:11:18] Running /usr/bin/apptainer --version
[1998-01-03 14:11:18] apptainer version 1.1.8-1.el7
[1998-01-03 14:11:18] Checking apptainer works with /usr/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname
[1998-01-03 14:11:18] INFO: /etc/singularity/ exists; cleanup by system administrator is not complete (see https://apptainer.org/docs/admin/latest/singularity_migration.html) WARNING: Environment variable TMPDIR already has value [/home/boinc/slots/0/.apptainertmp], will not forward new value [/home/boinc] from parent process environment wns0040.triumf.lcg
[1998-01-03 14:11:18] apptainer works
[1998-01-03 14:11:18] Set ATHENA_PROC_NUMBER=12
[1998-01-03 14:11:18] Set ATHENA_CORE_NUMBER=12
[1998-01-03 14:11:19] Starting ATLAS job with PandaID=6234469692
[1998-01-03 14:11:19] Running command: /usr/bin/apptainer exec -B /cvmfs,/home/boinc/slots/0 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 sh start_atlas.sh
[2024-06-27 11:23:42] *** The last 200 lines of the pilot log: ***
[2024-06-27 11:23:42] 1998-01-03 22:23:54,638 | INFO | neither /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_summary.json, nor /home/boinc/slots/0/memory_monitor_summary.json exist
[2024-06-27 11:23:42] 1998-01-03 22:23:54,638 | INFO | using path: /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_output.txt (trf name=prmon)
[2024-06-27 11:23:42] 1998-01-03 22:23:54,639 | INFO | oom_score(pilot) = 0, oom_score(payload) = 0
[2024-06-27 11:23:42] 1998-01-03 22:23:54,774 | INFO | number of running child processes to parent process 24369: 6
[2024-06-27 11:23:42] 1998-01-03 22:23:54,774 | INFO | maximum number of monitored processes: 6
[2024-06-27 11:23:42] 1998-01-03 22:23:57,283 | INFO | monitor loop #63: job 0:6234469692 is in state 'running'
[2024-06-27 11:23:42] 1998-01-03 22:24:07,472 | INFO | (instant) CPU consumption time for pid=24369: 66.95 (rounded to 67)
[2024-06-27 11:23:42] 1998-01-03 22:24:07,473 | INFO | neither /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_summary.json, nor /home/boinc/slots/0/memory_monitor_summary.json exist
[2024-06-27 11:23:42] 1998-01-03 22:24:07,473 | INFO | using path: /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_output.txt (trf name=prmon)
[2024-06-27 11:23:42] 1998-01-03 22:24:07,474 | INFO | oom_score(pilot) = 0, oom_score(payload) = 0
[2024-06-27 11:23:42] 1998-01-03 22:24:07,612 | INFO | number of running child processes to parent process 24369: 6
[2024-06-27 11:23:42] 1998-01-03 22:24:07,612 | INFO | maximum number of monitored processes: 6
[2024-06-27 11:23:42] 1998-01-03 22:24:10,117 | INFO | monitor loop #64: job 0:6234469692 is in state 'running'
[2024-06-27 11:23:42] 1998-01-03 22:24:20,304 | INFO | (instant) CPU consumption time for pid=24369: 79.79 (rounded to 80)
[2024-06-27 11:23:42] 1998-01-03 22:24:20,305 | INFO | neither /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_summary.json, nor /home/boinc/slots/0/memory_monitor_summary.json exist
[2024-06-27 11:23:42] 1998-01-03 22:24:20,305 | INFO | using path: /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_output.txt (trf name=prmon)
[2024-06-27 11:23:42] 1998-01-03 22:24:20,306 | INFO | neither /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_summary.json, nor /home/boinc/slots/0/memory_monitor_summary.json exist
[2024-06-27 11:23:42] 1998-01-03 22:24:20,306 | INFO | using path: /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_output.txt (trf name=prmon)
[2024-06-27 11:23:42] 1998-01-03 22:24:20,307 | INFO | max memory (maxPSS) used by the payload is within the allowed limit: 2015531 B (2 * maxRSS = 786432000 B)
[2024-06-27 11:23:42] 1998-01-03 22:24:20,307 | INFO | oom_score(pilot) = 0, oom_score(payload) = 0
[2024-06-27 11:23:42] 1998-01-03 22:24:20,309 | INFO | payload log (log.EVNTtoHITS) within allowed size limit (2147483648 B): 249404 B
[2024-06-27 11:23:42] 1998-01-03 22:24:20,309 | INFO | payload log (payload.stdout) within allowed size limit (2147483648 B): 9333 B
[2024-06-27 11:23:42] 1998-01-03 22:24:20,309 | INFO | executing command: df -mP /home/boinc/slots/0
[2024-06-27 11:23:42] 1998-01-03 22:24:20,332 | INFO | sufficient remaining disk space (2236945006592 B)
[2024-06-27 11:23:42] 1998-01-03 22:24:20,333 | INFO | work directory size check will use 61362667520 B as a max limit (10% grace limit added)
[2024-06-27 11:23:42] 1998-01-03 22:24:20,334 | INFO | size of work directory /home/boinc/slots/0/PanDA_Pilot-6234469692: 470066 B (within 61362667520 B limit)
[2024-06-27 11:23:42] 1998-01-03 22:24:20,334 | INFO | pfn file=/home/boinc/slots/0/PanDA_Pilot-6234469692/HITS.38920995._054993.pool.root.1 does not exist (skip from workdir size calculation)
[2024-06-27 11:23:42] 1998-01-03 22:24:20,335 | INFO | total size of present files: 0 B (workdir size: 470066 B)
[2024-06-27 11:23:42] 1998-01-03 22:24:20,335 | INFO | output file size check: skipping output file /home/boinc/slots/0/PanDA_Pilot-6234469692/HITS.38920995._054993.pool.root.1 since it does not exist
[2024-06-27 11:23:42] 1998-01-03 22:24:20,458 | INFO | number of running child processes to parent process 24369: 6
[2024-06-27 11:23:42] 1998-01-03 22:24:20,458 | INFO | maximum number of monitored processes: 6
[2024-06-27 11:23:42] 1998-01-03 22:24:22,965 | INFO | monitor loop #65: job 0:6234469692 is in state 'running'
[2024-06-27 11:23:42] 1998-01-03 22:24:33,151 | INFO | (instant) CPU consumption time for pid=24369: 92.14000000000001 (rounded to 92)
[2024-06-27 11:23:42] 1998-01-03 22:24:33,152 | INFO | neither /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_summary.json, nor /home/boinc/slots/0/memory_monitor_summary.json exist
[2024-06-27 11:23:42] 1998-01-03 22:24:33,152 | INFO | using path: /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_output.txt (trf name=prmon)
[2024-06-27 11:23:42] 1998-01-03 22:24:33,153 | INFO | oom_score(pilot) = 0, oom_score(payload) = 0
[2024-06-27 11:23:42] 1998-01-03 22:24:33,288 | INFO | number of running child processes to parent process 24369: 6
[2024-06-27 11:23:42] 1998-01-03 22:24:33,289 | INFO | maximum number of monitored processes: 6
[2024-06-27 11:23:42] 1998-01-03 22:24:35,794 | INFO | monitor loop #66: job 0:6234469692 is in state 'running'
[2024-06-27 11:23:42] 2024-06-27 18:23:09,000 | CRITICAL | max running time (691200s) minus grace time (180s) has been exceeded - time to abort pilot
[2024-06-27 11:23:42] 2024-06-27 18:23:09,001 | INFO | setting REACHED_MAXTIME and graceful stop
[2024-06-27 11:23:42] 2024-06-27 18:23:09,001 | INFO | [monitor] control thread has ended
[2024-06-27 11:23:42] 2024-06-27 18:23:09,164 | INFO | all data control threads have been joined
[2024-06-27 11:23:42] 2024-06-27 18:23:09,273 | INFO | all payload control threads have been joined
[2024-06-27 11:23:42] 2024-06-27 18:23:09,477 | INFO | breaking -- sending SIGTERM to pid=24369
[2024-06-27 11:23:42] 2024-06-27 18:23:09,477 | INFO | breaking -- sleep 10 s before sending SIGKILL pid=24369
[2024-06-27 11:23:42] 2024-06-27 18:23:09,583 | INFO | all job control threads have been joined
[2024-06-27 11:23:42] 2024-06-27 18:23:10,082 | INFO | [job] validate thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:10,111 | INFO | [payload] failed_post thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:10,170 | INFO | [data] control thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:10,171 | INFO | [payload] validate_pre thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:10,200 | INFO | [job] create_data_payload thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:10,280 | INFO | [payload] control thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:10,397 | INFO | [payload] validate_post thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:10,566 | INFO | [data] copytool_in thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:10,589 | INFO | [job] control thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:11,278 | INFO | job.realtimelogging is not enabled
[2024-06-27 11:23:42] 2024-06-27 18:23:11,300 | INFO | [data] copytool_out thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:11,513 | INFO | [job] retrieve thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:12,000 | WARNING | since job:queue_monitor is responsible for sending job updates, we sleep for 20 s
[2024-06-27 11:23:42] 2024-06-27 18:23:12,284 | INFO | [payload] run_realtimelog thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:13,585 | INFO | (instant) CPU consumption time for pid=24369: 4.13 (rounded to 4)
[2024-06-27 11:23:42] 2024-06-27 18:23:13,586 | INFO | neither /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_summary.json, nor /home/boinc/slots/0/memory_monitor_summary.json exist
[2024-06-27 11:23:42] 2024-06-27 18:23:13,586 | INFO | using path: /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_output.txt (trf name=prmon)
[2024-06-27 11:23:42] 2024-06-27 18:23:13,588 | INFO | oom_score(pilot) = 0, oom_score(payload) = 0
[2024-06-27 11:23:42] 2024-06-27 18:23:13,696 | INFO | number of running child processes to parent process 24369: 1
[2024-06-27 11:23:42] 2024-06-27 18:23:13,696 | INFO | maximum number of monitored processes: 6
[2024-06-27 11:23:42] 2024-06-27 18:23:13,697 | INFO | will send heartbeat for job in 'running' state
[2024-06-27 11:23:42] 2024-06-27 18:23:13,697 | WARNING | the max batch system time limit has been reached
[2024-06-27 11:23:42] 2024-06-27 18:23:13,697 | INFO | this job has now completed (state=failed)
[2024-06-27 11:23:42] 2024-06-27 18:23:13,697 | INFO | pilot will not update the server (heartbeat message will be written to file)
[2024-06-27 11:23:42] 2024-06-27 18:23:13,698 | INFO | job 6234469692 has failed - writing final server update
[2024-06-27 11:23:42] 2024-06-27 18:23:13,698 | WARNING | making sure that job.state is set to failed since a pilot error code is set
[2024-06-27 11:23:42] 2024-06-27 18:23:13,698 | INFO | payload/TRF did not report the number of read events
[2024-06-27 11:23:42] 2024-06-27 18:23:13,708 | INFO | executing command: lscpu
[2024-06-27 11:23:42] 2024-06-27 18:23:13,792 | INFO | found 48 cores (24 cores per socket, 2 sockets)
[2024-06-27 11:23:42] 2024-06-27 18:23:13,792 | INFO | executing command: grep -o 'avx2[^ ]*\|AVX2[^ ]*' /proc/cpuinfo
[2024-06-27 11:23:42] 2024-06-27 18:23:13,866 | INFO | executing command: export ATLAS_LOCAL_ROOT_BASE=/cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase;source ${ATLAS_LOCAL_ROOT_BASE}/user/atlasLocalSetup.sh --quiet;lsetup
[2024-06-27 11:23:42] 2024-06-27 18:23:16,523 | INFO | [data] queue_monitor thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:19,313 | INFO | CPU arch script returned: x86-64-v4
[2024-06-27 11:23:42] 2024-06-27 18:23:19,314 | INFO | neither /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_summary.json, nor /home/boinc/slots/0/memory_monitor_summary.json exist
[2024-06-27 11:23:42] 2024-06-27 18:23:19,314 | INFO | using path: /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_output.txt (trf name=prmon)
[2024-06-27 11:23:42] 2024-06-27 18:23:19,315 | INFO | extracted standard info from memory monitor json
[2024-06-27 11:23:42] 2024-06-27 18:23:19,315 | WARNING | standard memory fields were not found in memory monitor json (or json doesn't exist yet)
[2024-06-27 11:23:42] 2024-06-27 18:23:19,317 | WARNING | wrong length of table data, x=[883865827.0, 883865888.0, 883865949.0, 883866010.0, 883866071.0, 883866132.0, 883866193.0], y=[1127832.0, 1131111.0, 1131126.0, 1145
[2024-06-27 11:23:42] 2024-06-27 18:23:19,317 | INFO | ..............................
[2024-06-27 11:23:42] 2024-06-27 18:23:19,317 | INFO | . Timing measurements:
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . get job = 0 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . initial setup = 1 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . payload setup = 6 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . stage-in = 0 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . payload execution = 0 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . stage-out = 0 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . log creation = 0 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | ..............................
[2024-06-27 11:23:42] 2024-06-27 18:23:19,336 | INFO | building log extracts (sent to the server as 'pilotLog')
[2024-06-27 11:23:42] 2024-06-27 18:23:19,336 | INFO | executing command: tail -n 20 /home/boinc/slots/0/PanDA_Pilot-6234469692/pilotlog.txt
[2024-06-27 11:23:42] 2024-06-27 18:23:19,353 | WARNING | detected the following tail of warning/fatal messages in the pilot log:
[2024-06-27 11:23:42] - Log from pilotlog.txt -
[2024-06-27 11:23:42] 2024-06-27 18:23:13,866 | INFO | executing command: export ATLAS_LOCAL_ROOT_BASE=/cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase;source ${ATLAS_LOCAL_ROOT_BASE}/user/atlasLocalSetup.sh --quiet;lsetup
[2024-06-27 11:23:42] 2024-06-27 18:23:16,523 | INFO | [data] queue_monitor thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:19,313 | INFO | CPU arch script returned: x86-64-v4
[2024-06-27 11:23:42] 2024-06-27 18:23:19,314 | INFO | neither /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_summary.json, nor /home/boinc/slots/0/memory_monitor_summary.json exist
[2024-06-27 11:23:42] 2024-06-27 18:23:19,314 | INFO | using path: /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_output.txt (trf name=prmon)
[2024-06-27 11:23:42] 2024-06-27 18:23:19,315 | INFO | extracted standard info from memory monitor json
[2024-06-27 11:23:42] 2024-06-27 18:23:19,315 | WARNING | standard memory fields were not found in memory monitor json (or json doesn't exist yet)
[2024-06-27 11:23:42] 2024-06-27 18:23:19,317 | WARNING | wrong length of table data, x=[883865827.0, 883865888.0, 883865949.0, 883866010.0, 883866071.0, 883866132.0, 883866193.0], y=[1127832.0, 1131111.0, 1131126.0, 1145
[2024-06-27 11:23:42] 2024-06-27 18:23:19,317 | INFO | ..............................
[2024-06-27 11:23:42] 2024-06-27 18:23:19,317 | INFO | . Timing measurements:
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . get job = 0 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . initial setup = 1 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . payload setup = 6 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . stage-in = 0 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . payload execution = 0 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . stage-out = 0 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . log creation = 0 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | ..............................
[2024-06-27 11:23:42] 2024-06-27 18:23:19,336 | INFO | building log extracts (sent to the server as 'pilotLog')
[2024-06-27 11:23:42] 2024-06-27 18:23:19,336 | INFO | executing command: tail -n 20 /home/boinc/slots/0/PanDA_Pilot-6234469692/pilotlog.txt
[2024-06-27 11:23:42] 2024-06-27 18:23:19,354 | WARNING |
[2024-06-27 11:23:42] [begin log extracts]
[2024-06-27 11:23:42] - Log from pilotlog.txt -
[2024-06-27 11:23:42] 2024-06-27 18:23:13,866 | INFO | executing command: export ATLAS_LOCAL_ROOT_BASE=/cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase;source ${ATLAS_LOCAL_ROOT_BASE}/user/atlasLocalSetup.sh --quiet;lsetup
[2024-06-27 11:23:42] 2024-06-27 18:23:16,523 | INFO | [data] queue_monitor thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:19,313 | INFO | CPU arch script returned: x86-64-v4
[2024-06-27 11:23:42] 2024-06-27 18:23:19,314 | INFO | neither /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_summary.json, nor /home/boinc/slots/0/memory_monitor_summary.json exist
[2024-06-27 11:23:42] 2024-06-27 18:23:19,314 | INFO | using path: /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_output.txt (trf name=prmon)
[2024-06-27 11:23:42] 2024-06-27 18:23:19,315 | INFO | extracted standard info from memory monitor json
[2024-06-27 11:23:42] 2024-06-27 18:23:19,315 | WARNING | standard memory fields were not found in memory monitor json (or json doesn't exist yet)
[2024-06-27 11:23:42] 2024-06-27 18:23:19,317 | WARNING | wrong length of table data, x=[883865827.0, 883865888.0, 883865949.0, 883866010.0, 883866071.0, 883866132.0, 883866193.0], y=[1127832.0, 1131111.0, 1131126.0, 1145
[2024-06-27 11:23:42] 2024-06-27 18:23:19,317 | INFO | ..............................
[2024-06-27 11:23:42] 2024-06-27 18:23:19,317 | INFO | . Timing measurements:
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . get job = 0 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . initial setup = 1 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . payload setup = 6 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . stage-in = 0 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . payload execution = 0 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . stage-out = 0 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | . log creation = 0 s
[2024-06-27 11:23:42] 2024-06-27 18:23:19,318 | INFO | ..............................
[2024-06-27 11:23:42] 2024-06-27 18:23:19,336 | INFO | building log extracts (sent to the server as 'pilotLog')
[2024-06-27 11:23:42] 2024-06-27 18:23:19,336 | INFO | executing command: tail -n 20 /home/boinc/slots/0/PanDA_Pilot-6234469692/pilotlog.txt
[2024-06-27 11:23:42] [end log extracts]
[2024-06-27 11:23:42] 2024-06-27 18:23:19,354 | WARNING | pilotErrorCodes = [1213] (will report primary/first error code)
[2024-06-27 11:23:42] 2024-06-27 18:23:19,354 | WARNING | pilotErrorDiags = ['the max batch system time limit has been reached'] (will report primary/first error diag)
[2024-06-27 11:23:42] 2024-06-27 18:23:19,355 | WARNING | job state is 'failed' - order log transfer and abort job_monitor() (2)
[2024-06-27 11:23:42] 2024-06-27 18:23:19,437 | INFO | will abort loop
[2024-06-27 11:23:42] 2024-06-27 18:23:19,528 | INFO |
[2024-06-27 11:23:42]
[2024-06-27 11:23:42] finished pid=24369 exit_code=None state=failed
[2024-06-27 11:23:42]
[2024-06-27 11:23:42] 2024-06-27 18:23:19,529 | WARNING | detected unset exit_code from wait_graceful - reset to -1
[2024-06-27 11:23:42] 2024-06-27 18:23:19,532 | INFO | using pid=35234 to kill prmon
[2024-06-27 11:23:42] 2024-06-27 18:23:19,532 | INFO | stopping utility process 'MemoryMonitor' with signal 10
[2024-06-27 11:23:42] 2024-06-27 18:23:19,532 | INFO | process 35234 no longer exists
[2024-06-27 11:23:42] 2024-06-27 18:23:19,532 | INFO | utility process 24380 cleanup finished with status=True
[2024-06-27 11:23:42] 2024-06-27 18:23:19,532 | INFO | taking a short nap (3 s) to allow the memory monitor to finish writing to the summary file (#0/#20)
[2024-06-27 11:23:42] 2024-06-27 18:23:20,445 | INFO | [job] job monitor thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:22,551 | INFO | copied /home/boinc/slots/0/PanDA_Pilot-6234469692/memory_monitor_summary.json to /home/boinc/slots/0
[2024-06-27 11:23:42] 2024-06-27 18:23:22,552 | INFO | CPU consumption time: 26.32 s (rounded to 26 s)
[2024-06-27 11:23:42] 2024-06-27 18:23:22,552 | WARNING | main payload execution returned non-zero exit code: -1
[2024-06-27 11:23:42] 2024-06-27 18:23:22,552 | INFO | scanning dmesg message for subprocess=36999 for memory errors
[2024-06-27 11:23:42] 2024-06-27 18:23:22,552 | INFO | executing command: dmesg|grep 36999
[2024-06-27 11:23:42] 2024-06-27 18:23:22,728 | INFO | scanning dmesg message for subprocess=37001 for memory errors
[2024-06-27 11:23:42] 2024-06-27 18:23:22,728 | INFO | executing command: dmesg|grep 37001
[2024-06-27 11:23:42] 2024-06-27 18:23:22,750 | INFO | scanning dmesg message for subprocess=37002 for memory errors
[2024-06-27 11:23:42] 2024-06-27 18:23:22,750 | INFO | executing command: dmesg|grep 37002
[2024-06-27 11:23:42] 2024-06-27 18:23:22,773 | INFO | scanning dmesg message for subprocess=36912 for memory errors
[2024-06-27 11:23:42] 2024-06-27 18:23:22,774 | INFO | executing command: dmesg|grep 36912
[2024-06-27 11:23:42] 2024-06-27 18:23:22,792 | INFO | scanning dmesg message for subprocess=37010 for memory errors
[2024-06-27 11:23:42] 2024-06-27 18:23:22,793 | INFO | executing command: dmesg|grep 37010
[2024-06-27 11:23:42] 2024-06-27 18:23:22,812 | WARNING | job report does not exist: /home/boinc/slots/0/PanDA_Pilot-6234469692/jobReport.json
[2024-06-27 11:23:42] 2024-06-27 18:23:22,812 | WARNING | metadata does not exist: /home/boinc/slots/0/PanDA_Pilot-6234469692/metadata.xml
[2024-06-27 11:23:42] 2024-06-27 18:23:22,812 | WARNING | file does not exist: /home/boinc/slots/0/PanDA_Pilot-6234469692/metadata.xml
[2024-06-27 11:23:42] 2024-06-27 18:23:22,813 | INFO | generated guid for lfn=HITS.38920995._054993.pool.root.1: 78DC7E9F-BE7A-4366-8E7C-F833A970D1B8
[2024-06-27 11:23:42] 2024-06-27 18:23:22,813 | WARNING | aborting payload error diagnosis since an error has already been set: [1213, 1315, 1187]
[2024-06-27 11:23:42] 2024-06-27 18:23:23,895 | INFO | [payload] execute_payloads thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:24,299 | INFO | waiting for thread to finish: ['<_MainThread(MainThread, started 140199249471296)>', '<ExcThread(queue_monitor, started 140198427993856)>']
[2024-06-27 11:23:42] 2024-06-27 18:23:26,308 | INFO | waiting for thread to finish: ['<_MainThread(MainThread, started 140199249471296)>', '<ExcThread(queue_monitor, started 140198427993856)>']
[2024-06-27 11:23:42] 2024-06-27 18:23:28,321 | INFO | waiting for thread to finish: ['<_MainThread(MainThread, started 140199249471296)>', '<ExcThread(queue_monitor, started 140198427993856)>']
[2024-06-27 11:23:42] 2024-06-27 18:23:30,334 | INFO | waiting for thread to finish: ['<_MainThread(MainThread, started 140199249471296)>', '<ExcThread(queue_monitor, started 140198427993856)>']
[2024-06-27 11:23:42] 2024-06-27 18:23:32,349 | INFO | waiting for thread to finish: ['<_MainThread(MainThread, started 140199249471296)>', '<ExcThread(queue_monitor, started 140198427993856)>']
[2024-06-27 11:23:42] 2024-06-27 18:23:34,363 | INFO | waiting for thread to finish: ['<_MainThread(MainThread, started 140199249471296)>', '<ExcThread(queue_monitor, started 140198427993856)>']
[2024-06-27 11:23:42] 2024-06-27 18:23:35,119 | INFO | waiting for thread to finish: ['<_MainThread(MainThread, started 140199249471296)>', '<ExcThread(queue_monitor, started 140198427993856)>']
[2024-06-27 11:23:42] 2024-06-27 18:23:35,120 | INFO | [job] queue monitor thread has finished
[2024-06-27 11:23:42] 2024-06-27 18:23:36,376 | INFO | caller=run is remaining thread - safe to abort (names=['<_MainThread(MainThread, started 140199249471296)>'])
[2024-06-27 11:23:42] 2024-06-27 18:23:41,402 | INFO | all workflow threads have been joined
[2024-06-27 11:23:42] 2024-06-27 18:23:41,402 | INFO | end of generic workflow (traces error code: 0)
[2024-06-27 11:23:42] 2024-06-27 18:23:41,403 | INFO | traces error code: 0
[2024-06-27 11:23:42] 2024-06-27 18:23:41,403 | INFO | pilot has finished (exit code=0, shell exit code=0)
[2024-06-27 11:23:42] 2024-06-27 18:23:41,516 [wrapper] ==== pilot stdout END ====
[2024-06-27 11:23:42] 2024-06-27 18:23:41,520 [wrapper] ==== wrapper stdout RESUME ====
[2024-06-27 11:23:42] 2024-06-27 18:23:41,525 [wrapper] pilotpid: 11391
[2024-06-27 11:23:42] 2024-06-27 18:23:41,529 [wrapper] Pilot exit status: 0
[2024-06-27 11:23:42] 2024-06-27 18:23:41,543 [wrapper] pandaids: 6234469692 6234469692
[2024-06-27 11:23:42] 2024-06-27 18:23:41,621 [wrapper] cleanup supervisor_pilot 11402 11392
[2024-06-27 11:23:42] 2024-06-27 18:23:41,625 [wrapper] Test setup, not cleaning
[2024-06-27 11:23:42] 2024-06-27 18:23:41,629 [wrapper] ==== wrapper stdout END ====
[2024-06-27 11:23:42] 2024-06-27 18:23:41,633 [wrapper] ==== wrapper stderr END ====
[2024-06-27 11:23:42] 2024-06-27 18:23:41,641 [wrapper] apfmon messages muted
[2024-06-27 11:23:42] *** Error codes and diagnostics ***
[2024-06-27 11:23:42] "exeErrorCode": 0,
[2024-06-27 11:23:42] "exeErrorDiag": "",
[2024-06-27 11:23:42] "pilotErrorCode": 1213,
[2024-06-27 11:23:42] "pilotErrorDiag": "the max batch system time limit has been reached",
[2024-06-27 11:23:42] *** Listing of results directory ***
[2024-06-27 11:23:42] total 1241116
[2024-06-27 11:23:42] -rw-r--r-- 1 boinc boinc 2612 Jan 3 1998 pandaJob.out
[2024-06-27 11:23:42] -rw------- 1 boinc boinc 424 Jan 3 1998 setup.sh.local
[2024-06-27 11:23:42] -rw------- 1 boinc boinc 94 Jan 3 1998 pilot_heartbeat.json
[2024-06-27 11:23:42] drwx------ 4 boinc boinc 4096 Jun 12 03:00 pilot3
[2024-06-27 11:23:42] -rw-r--r-- 1 boinc boinc 469766 Jun 17 09:10 pilot3.tar.gz
[2024-06-27 11:23:42] -rw-r--r-- 1 boinc boinc 5654 Jun 17 09:20 queuedata.json
[2024-06-27 11:23:42] -rwx------ 1 boinc boinc 32308 Jun 17 09:21 runpilot2-wrapper.sh
[2024-06-27 11:23:42] -rw-r--r-- 1 boinc boinc 100 Jun 18 15:33 wrapper_26015_x86_64-pc-linux-gnu
[2024-06-27 11:23:42] -rwxr-xr-x 1 boinc boinc 7986 Jun 18 15:33 run_atlas
[2024-06-27 11:23:42] -rw-r--r-- 1 boinc boinc 105 Jun 18 15:33 job.xml
[2024-06-27 11:23:42] -rw-r--r-- 3 boinc boinc 633500856 Jun 18 15:33 EVNT.38776180._000991.pool.root.1
[2024-06-27 11:23:42] -rw-r--r-- 3 boinc boinc 633500856 Jun 18 15:33 ATLAS.root_0
[2024-06-27 11:23:42] drwxrwx--x 2 boinc boinc 4096 Jun 18 15:33 shared
[2024-06-27 11:23:42] -rw-r--r-- 2 boinc boinc 482522 Jun 18 15:33 input.tar.gz
[2024-06-27 11:23:42] -rw-r--r-- 2 boinc boinc 17537 Jun 18 15:33 start_atlas.sh
[2024-06-27 11:23:42] -rw-r--r-- 1 boinc boinc 0 Jun 18 15:33 boinc_lockfile
[2024-06-27 11:23:42] -rw------- 1 boinc boinc 1023173 Jun 18 15:33 agis_schedconf.cvmfs.json
[2024-06-27 11:23:42] -rw------- 1 boinc boinc 0 Jun 18 15:33 agis_ddmendpoints.agis.ALL.json
[2024-06-27 11:23:42] -rw------- 1 boinc boinc 1317943 Jun 18 15:33 cric_ddmendpoints.json
[2024-06-27 11:23:42] -rw-r--r-- 1 boinc boinc 6633 Jun 27 11:23 init_data.xml
[2024-06-27 11:23:42] -rw-r--r-- 1 boinc boinc 27 Jun 27 11:23 wrapper_checkpoint.txt
[2024-06-27 11:23:42] -rw-r--r-- 1 boinc boinc 8192 Jun 27 11:23 boinc_mmap_file
[2024-06-27 11:23:42] -rw-r--r-- 1 boinc boinc 530 Jun 27 11:23 boinc_task_state.xml
[2024-06-27 11:23:42] -rw------- 1 boinc boinc 2170 Jun 27 11:23 heartbeat.json
[2024-06-27 11:23:42] drwxrwx--- 2 boinc boinc 4096 Jun 27 11:23 PanDA_Pilot-6234469692
[2024-06-27 11:23:42] -rw------- 1 boinc boinc 1021 Jun 27 11:23 memory_monitor_summary.json
[2024-06-27 11:23:42] -rw------- 1 boinc boinc 97425 Jun 27 11:23 pilotlog.txt
[2024-06-27 11:23:42] -rw------- 1 boinc boinc 140076 Jun 27 11:23 log.38920995._054993.job.log.1
[2024-06-27 11:23:42] -rw-r--r-- 1 boinc boinc 571 Jun 27 11:23 runtime_log
[2024-06-27 11:23:42] -rw------- 1 boinc boinc 153600 Jun 27 11:23 result.tar.gz
[2024-06-27 11:23:42] -rw-r--r-- 1 boinc boinc 11072 Jun 27 11:23 runtime_log.err
[2024-06-27 11:23:42] -rw------- 1 boinc boinc 760 Jun 27 11:23 1ypKDmlHUd5nsSi4ap6QjLDmwznN0nGgGQJmpmIZDmNNiKDmfKIjon.diag
[2024-06-27 11:23:42] -rw-r--r-- 1 boinc boinc 28859 Jun 27 11:23 stderr.txt
[2024-06-27 11:23:42] No HITS result produced
[2024-06-27 11:23:42] *** Contents of shared directory: ***
[2024-06-27 11:23:42] total 619304
[2024-06-27 11:23:42] -rw-r--r-- 3 boinc boinc 633500856 Jun 18 15:33 ATLAS.root_0
[2024-06-27 11:23:42] -rw-r--r-- 2 boinc boinc 482522 Jun 18 15:33 input.tar.gz
[2024-06-27 11:23:42] -rw-r--r-- 2 boinc boinc 17537 Jun 18 15:33 start_atlas.sh
[2024-06-27 11:23:42] -rw------- 1 boinc boinc 153600 Jun 27 11:23 result.tar.gz
11:23:43 (2459): run_atlas exited; CPU time 25.834232
11:23:43 (2459): called boinc_finish(0)
</stderr_txt>
]]>
©2024 CERN