Stderr output

<core_client_version>8.1.0</core_client_version>
<![CDATA[
<stderr_txt>
19:51:12 (225468): wrapper (7.7.26015): starting
19:51:12 (225468): wrapper: running run_atlas (--nthreads 12)
[2025-05-29 19:51:12] Arguments: --nthreads 12
[2025-05-29 19:51:12] Threads: 12
[2025-05-29 19:51:12] Checking for CVMFS
[2025-05-29 19:51:12] Probing /cvmfs/atlas.cern.ch... OK
[2025-05-29 19:51:13] Probing /cvmfs/atlas-condb.cern.ch... OK
[2025-05-29 19:51:13] Running cvmfs_config stat atlas.cern.ch
[2025-05-29 19:51:13] 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-05-29 19:51:13] 2.12.6.0 34032 23482 406188 146580 3 383 15861701 29414400 12256 16776704 0 67066037 99.451 93317978 32230 http://cvmfs-s1fnal.opensciencegrid.org:8000/cvmfs/atlas.cern.ch http://192.41.231.237:6081 1
[2025-05-29 19:51:13] CVMFS is ok
[2025-05-29 19:51:13] Efficiency of ATLAS tasks can be improved by the following measure(s):
[2025-05-29 19:51:13] The CVMFS client on this computer should be configured to use Cloudflare's openhtc.io.
[2025-05-29 19:51:13] Further information can be found at the LHC@home message board.
[2025-05-29 19:51:13] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7
[2025-05-29 19:51:13] Checking for apptainer binary...
[2025-05-29 19:51:13] Using apptainer found in PATH at /usr/bin/apptainer
[2025-05-29 19:51:13] Running /usr/bin/apptainer --version
[2025-05-29 19:51:13] apptainer version 1.4.1-1.el9
[2025-05-29 19:51:13] Checking apptainer works with /usr/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname
[2025-05-29 19:51:13] c6-5-37-4.aglt2.org
[2025-05-29 19:51:13] apptainer works
[2025-05-29 19:51:13] Set ATHENA_PROC_NUMBER=12
[2025-05-29 19:51:13] Set ATHENA_CORE_NUMBER=12
[2025-05-29 19:51:13] Starting ATLAS job with PandaID=6670182775
[2025-05-29 19:51:13] 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
[2025-05-29 22:03:09]  *** The last 200 lines of the pilot log: ***
[2025-05-29 22:03:09] 2025-05-30 02:02:43,344 | INFO     | executing command: export ATLAS_LOCAL_ROOT_BASE=/cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase;source ${ATLAS_LOCAL_ROOT_BASE}/user/atlasLocalSetup.sh --quiet;lsetup
[2025-05-29 22:03:09] 2025-05-30 02:02:44,216 | INFO     | PID=229134 has CPU usage=10.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-05-29 22:03:09] 2025-05-30 02:02:44,217 | INFO     | .. there are 14 such processes running
[2025-05-29 22:03:09] 2025-05-30 02:02:45,649 | INFO     | monitor loop #526: job 0:6670182775 is in state 'finished'
[2025-05-29 22:03:09] 2025-05-30 02:02:45,649 | INFO     | will abort job monitoring soon since job state=finished (job is still in queue)
[2025-05-29 22:03:09] 2025-05-30 02:02:48,153 | INFO     | monitor loop #527: job 0:6670182775 is in state 'finished'
[2025-05-29 22:03:09] 2025-05-30 02:02:48,153 | INFO     | will abort job monitoring soon since job state=finished (job is still in queue)
[2025-05-29 22:03:09] 2025-05-30 02:02:49,549 | INFO     | CPU arch script returned: x86-64-v4
[2025-05-29 22:03:09] 2025-05-30 02:02:49,549 | INFO     | using path: /tmp/boinchome/slots/0/PanDA_Pilot-6670182775/memory_monitor_summary.json (trf name=prmon)
[2025-05-29 22:03:09] 2025-05-30 02:02:49,550 | INFO     | extracted standard info from prmon json
[2025-05-29 22:03:09] 2025-05-30 02:02:49,550 | INFO     | extracted standard memory fields from prmon json
[2025-05-29 22:03:09] 2025-05-30 02:02:49,550 | WARNING  | GPU info not found in prmon json: 'gpu'
[2025-05-29 22:03:09] 2025-05-30 02:02:49,551 | WARNING  | format EVNTtoHITS has no such key: dbData
[2025-05-29 22:03:09] 2025-05-30 02:02:49,551 | WARNING  | format EVNTtoHITS has no such key: dbTime
[2025-05-29 22:03:09] 2025-05-30 02:02:49,553 | INFO     | fitting pss+swap vs Time
[2025-05-29 22:03:09] 2025-05-30 02:02:49,554 | INFO     | sum of square deviations: 665516253.2248075
[2025-05-29 22:03:09] 2025-05-30 02:02:49,554 | INFO     | sum of deviations: -7832506232.922475
[2025-05-29 22:03:09] 2025-05-30 02:02:49,554 | INFO     | mean x: 1748566615.4108527
[2025-05-29 22:03:09] 2025-05-30 02:02:49,554 | INFO     | mean y: 2396848.6589147286
[2025-05-29 22:03:09] 2025-05-30 02:02:49,554 | INFO     | -- intersect: 20581396756.658676
[2025-05-29 22:03:09] 2025-05-30 02:02:49,554 | INFO     | intersect: 20581396756.658676
[2025-05-29 22:03:09] 2025-05-30 02:02:49,555 | INFO     | chi2: 1.7782172689004319
[2025-05-29 22:03:09] 2025-05-30 02:02:49,555 | INFO     | sum of square deviations: 591086187.1935494
[2025-05-29 22:03:09] 2025-05-30 02:02:49,555 | INFO     | sum of deviations: -6419811589.870967
[2025-05-29 22:03:09] 2025-05-30 02:02:49,555 | INFO     | mean x: 1748566462.919355
[2025-05-29 22:03:09] 2025-05-30 02:02:49,556 | INFO     | mean y: 2399697.6129032257
[2025-05-29 22:03:09] 2025-05-30 02:02:49,556 | INFO     | -- intersect: 18993652559.75027
[2025-05-29 22:03:09] 2025-05-30 02:02:49,556 | INFO     | intersect: 18993652559.75027
[2025-05-29 22:03:09] 2025-05-30 02:02:49,556 | INFO     | chi2: 1.7742238046191432
[2025-05-29 22:03:09] 2025-05-30 02:02:49,556 | INFO     | current chi2=1.7742238046191432 (change=0.22457684733643582 %)
[2025-05-29 22:03:09] 2025-05-30 02:02:49,556 | INFO     | right removable region: 123
[2025-05-29 22:03:09] 2025-05-30 02:02:49,556 | INFO     | sum of square deviations: 591061482.3467727
[2025-05-29 22:03:09] 2025-05-30 02:02:49,557 | INFO     | sum of deviations: -27549852951.669357
[2025-05-29 22:03:09] 2025-05-30 02:02:49,557 | INFO     | mean x: 1748566767.9274194
[2025-05-29 22:03:09] 2025-05-30 02:02:49,557 | INFO     | mean y: 2436624.814516129
[2025-05-29 22:03:09] 2025-05-30 02:02:49,557 | INFO     | -- intersect: 81504545578.53831
[2025-05-29 22:03:09] 2025-05-30 02:02:49,557 | INFO     | intersect: 81504545578.53831
[2025-05-29 22:03:09] 2025-05-30 02:02:49,557 | INFO     | chi2: 0.21578674956170474
[2025-05-29 22:03:09] 2025-05-30 02:02:49,557 | INFO     | current chi2=0.21578674956170474 (change=87.86499527725667 %)
[2025-05-29 22:03:09] 2025-05-30 02:02:49,557 | INFO     | sum of square deviations: 522381703.2773101
[2025-05-29 22:03:09] 2025-05-30 02:02:49,558 | INFO     | sum of deviations: -23072472757.403362
[2025-05-29 22:03:09] 2025-05-30 02:02:49,558 | INFO     | mean x: 1748566920.4369748
[2025-05-29 22:03:09] 2025-05-30 02:02:49,558 | INFO     | mean y: 2426650.5462184874
[2025-05-29 22:03:09] 2025-05-30 02:02:49,558 | INFO     | -- intersect: 77232854866.48628
[2025-05-29 22:03:09] 2025-05-30 02:02:49,558 | INFO     | intersect: 77232854866.48628
[2025-05-29 22:03:09] 2025-05-30 02:02:49,558 | INFO     | chi2: 0.2106570547795213
[2025-05-29 22:03:09] 2025-05-30 02:02:49,558 | INFO     | current chi2=0.2106570547795213 (change=2.3772056405699735 %)
[2025-05-29 22:03:09] 2025-05-30 02:02:49,559 | INFO     | left removable region: 20
[2025-05-29 22:03:09] 2025-05-30 02:02:49,559 | INFO     | sum of square deviations: 338698499.2038824
[2025-05-29 22:03:09] 2025-05-30 02:02:49,559 | INFO     | sum of deviations: -10384424221.504852
[2025-05-29 22:03:09] 2025-05-30 02:02:49,559 | INFO     | mean x: 1748567042.4271846
[2025-05-29 22:03:09] 2025-05-30 02:02:49,559 | INFO     | mean y: 2401057.4660194176
[2025-05-29 22:03:09] 2025-05-30 02:02:49,559 | INFO     | -- intersect: 53613096088.5523
[2025-05-29 22:03:09] 2025-05-30 02:02:49,559 | INFO     | intersect: 53613096088.5523
[2025-05-29 22:03:09] 2025-05-30 02:02:49,560 | INFO     | chi2: 0.13844750137925207
[2025-05-29 22:03:09] 2025-05-30 02:02:49,560 | INFO     | -- intersect: 53613096088.5523
[2025-05-29 22:03:09] 2025-05-30 02:02:49,560 | INFO     | current memory leak: -30.66 B/s (using 103 data points, chi2=0.14)
[2025-05-29 22:03:09] 2025-05-30 02:02:49,560 | INFO     | ..............................
[2025-05-29 22:03:09] 2025-05-30 02:02:49,560 | INFO     | . Timing measurements:
[2025-05-29 22:03:09] 2025-05-30 02:02:49,560 | INFO     | . get job = 0 s
[2025-05-29 22:03:09] 2025-05-30 02:02:49,561 | INFO     | . initial setup = 3 s
[2025-05-29 22:03:09] 2025-05-30 02:02:49,561 | INFO     | . payload setup = 6 s
[2025-05-29 22:03:09] 2025-05-30 02:02:49,561 | INFO     | . stage-in = 0 s
[2025-05-29 22:03:09] 2025-05-30 02:02:49,561 | INFO     | . payload execution = 7845 s
[2025-05-29 22:03:09] 2025-05-30 02:02:49,561 | INFO     | . stage-out = 1 s
[2025-05-29 22:03:09] 2025-05-30 02:02:49,561 | INFO     | . log creation = 0 s
[2025-05-29 22:03:09] 2025-05-30 02:02:49,561 | INFO     | ..............................
[2025-05-29 22:03:09] 2025-05-30 02:02:49,663 | INFO     | 
[2025-05-29 22:03:09] 2025-05-30 02:02:49,664 | INFO     | job summary report
[2025-05-29 22:03:09] 2025-05-30 02:02:49,665 | INFO     | --------------------------------------------------
[2025-05-29 22:03:09] 2025-05-30 02:02:49,665 | INFO     | PanDA job id: 6670182775
[2025-05-29 22:03:09] 2025-05-30 02:02:49,665 | INFO     | task id: 44871837
[2025-05-29 22:03:09] 2025-05-30 02:02:49,665 | INFO     | errors: (none)
[2025-05-29 22:03:09] 2025-05-30 02:02:49,665 | INFO     | status: LOG_TRANSFER = DONE 
[2025-05-29 22:03:09] 2025-05-30 02:02:49,665 | INFO     | pilot state: finished 
[2025-05-29 22:03:09] 2025-05-30 02:02:49,665 | INFO     | transexitcode: 0
[2025-05-29 22:03:09] 2025-05-30 02:02:49,665 | INFO     | exeerrorcode: 0
[2025-05-29 22:03:09] 2025-05-30 02:02:49,665 | INFO     | exeerrordiag: 
[2025-05-29 22:03:09] 2025-05-30 02:02:49,665 | INFO     | exitcode: 0
[2025-05-29 22:03:09] 2025-05-30 02:02:49,665 | INFO     | exitmsg: OK
[2025-05-29 22:03:09] 2025-05-30 02:02:49,666 | INFO     | cpuconsumptiontime: 49907 s
[2025-05-29 22:03:09] 2025-05-30 02:02:49,666 | INFO     | nevents: 400
[2025-05-29 22:03:09] 2025-05-30 02:02:49,666 | INFO     | neventsw: 0
[2025-05-29 22:03:09] 2025-05-30 02:02:49,666 | INFO     | pid: 245778
[2025-05-29 22:03:09] 2025-05-30 02:02:49,666 | INFO     | pgrp: 245778
[2025-05-29 22:03:09] 2025-05-30 02:02:49,666 | INFO     | corecount: 12
[2025-05-29 22:03:09] 2025-05-30 02:02:49,666 | INFO     | event service: False
[2025-05-29 22:03:09] 2025-05-30 02:02:49,666 | INFO     | sizes: {0: 2404356, 1: 2405212, 5: 2405418, 12: 2405474, 7856: 2433153, 7859: 2442094, 7861: 2442278, 7870: 2442448}
[2025-05-29 22:03:09] 2025-05-30 02:02:49,666 | INFO     | --------------------------------------------------
[2025-05-29 22:03:09] 2025-05-30 02:02:49,666 | INFO     | 
[2025-05-29 22:03:09] 2025-05-30 02:02:49,667 | INFO     | executing command: ls -lF /tmp/boinchome/slots/0
[2025-05-29 22:03:09] 2025-05-30 02:02:49,688 | INFO     | queue jobs had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,688 | INFO     | queue payloads had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,688 | INFO     | queue data_in had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,688 | INFO     | queue data_out had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,688 | INFO     | queue current_data_in had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,688 | INFO     | queue validated_jobs had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,688 | INFO     | queue validated_payloads had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,688 | INFO     | queue monitored_payloads had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,689 | INFO     | queue finished_jobs had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,689 | INFO     | queue finished_payloads had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,689 | INFO     | queue finished_data_in had 1 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,689 | INFO     | queue finished_data_out had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,689 | INFO     | queue failed_jobs had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,689 | INFO     | queue failed_payloads had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,689 | INFO     | queue failed_data_in had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,689 | INFO     | queue failed_data_out had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,689 | INFO     | queue completed_jobs had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,689 | INFO     | queue completed_jobids has 1 job(s)
[2025-05-29 22:03:09] 2025-05-30 02:02:49,689 | INFO     | queue realtimelog_payloads had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,689 | INFO     | queue messages had 0 job(s) [purged]
[2025-05-29 22:03:09] 2025-05-30 02:02:49,689 | INFO     | job 6670182775 has completed (purged errors)
[2025-05-29 22:03:09] 2025-05-30 02:02:49,690 | INFO     | overall cleanup function is called
[2025-05-29 22:03:09] 2025-05-30 02:02:50,697 | INFO     | --- collectZombieJob: --- 10, [245778]
[2025-05-29 22:03:09] 2025-05-30 02:02:50,697 | INFO     | zombie collector waiting for pid 245778
[2025-05-29 22:03:09] 2025-05-30 02:02:50,698 | INFO     | harmless exception when collecting zombies: [Errno 10] No child processes
[2025-05-29 22:03:09] 2025-05-30 02:02:50,698 | INFO     | collected zombie processes
[2025-05-29 22:03:09] 2025-05-30 02:02:50,698 | INFO     | will attempt to kill all subprocesses of pid=245778
[2025-05-29 22:03:09] 2025-05-30 02:02:51,016 | INFO     | process IDs to be killed: [245778] (in reverse order)
[2025-05-29 22:03:09] 2025-05-30 02:02:51,108 | WARNING  | found no corresponding commands to process id(s)
[2025-05-29 22:03:09] 2025-05-30 02:02:51,108 | INFO     | Do not look for orphan processes in BOINC jobs
[2025-05-29 22:03:09] 2025-05-30 02:02:51,120 | INFO     | did not find any defunct processes belonging to 245778
[2025-05-29 22:03:09] 2025-05-30 02:02:51,132 | INFO     | did not find any defunct processes belonging to 245778
[2025-05-29 22:03:09] 2025-05-30 02:02:51,132 | INFO     | ready for new job
[2025-05-29 22:03:09] 2025-05-30 02:02:51,132 | INFO     | pilot has finished with previous job - re-establishing logging
[2025-05-29 22:03:09] 2025-05-30 02:02:51,135 | INFO     | **************************************
[2025-05-29 22:03:09] 2025-05-30 02:02:51,135 | INFO     | ***  PanDA Pilot version 3.10.2.2  ***
[2025-05-29 22:03:09] 2025-05-30 02:02:51,135 | INFO     | **************************************
[2025-05-29 22:03:09] 2025-05-30 02:02:51,135 | INFO     | 
[2025-05-29 22:03:09] 2025-05-30 02:02:51,150 | INFO     | architecture information:
[2025-05-29 22:03:09] 2025-05-30 02:02:51,151 | INFO     | executing command: cat /etc/os-release
[2025-05-29 22:03:09] 2025-05-30 02:02:51,168 | INFO     | cat /etc/os-release:
[2025-05-29 22:03:09] NAME="CentOS Linux"
[2025-05-29 22:03:09] VERSION="7 (Core)"
[2025-05-29 22:03:09] ID="centos"
[2025-05-29 22:03:09] ID_LIKE="rhel fedora"
[2025-05-29 22:03:09] VERSION_ID="7"
[2025-05-29 22:03:09] PRETTY_NAME="CentOS Linux 7 (Core)"
[2025-05-29 22:03:09] ANSI_COLOR="0;31"
[2025-05-29 22:03:09] CPE_NAME="cpe:/o:centos:centos:7"
[2025-05-29 22:03:09] HOME_URL="https://www.centos.org/"
[2025-05-29 22:03:09] BUG_REPORT_URL="https://bugs.centos.org/"
[2025-05-29 22:03:09] 
[2025-05-29 22:03:09] CENTOS_MANTISBT_PROJECT="CentOS-7"
[2025-05-29 22:03:09] CENTOS_MANTISBT_PROJECT_VERSION="7"
[2025-05-29 22:03:09] REDHAT_SUPPORT_PRODUCT="centos"
[2025-05-29 22:03:09] REDHAT_SUPPORT_PRODUCT_VERSION="7"
[2025-05-29 22:03:09] 
[2025-05-29 22:03:09] 2025-05-30 02:02:51,168 | INFO     | **************************************
[2025-05-29 22:03:09] 2025-05-30 02:02:51,672 | INFO     | executing command: df -mP /tmp/boinchome/slots/0
[2025-05-29 22:03:09] 2025-05-30 02:02:51,690 | INFO     | sufficient remaining disk space (75520540672 B)
[2025-05-29 22:03:09] 2025-05-30 02:02:51,690 | WARNING  | since timefloor is set to 0, pilot was only allowed to run one job
[2025-05-29 22:03:09] 2025-05-30 02:02:51,691 | INFO     | current server update state: UPDATING_FINAL
[2025-05-29 22:03:09] 2025-05-30 02:02:51,691 | INFO     | update_server=False
[2025-05-29 22:03:09] 2025-05-30 02:02:51,691 | WARNING  | setting graceful_stop since proceed_with_getjob() returned False (pilot will end)
[2025-05-29 22:03:09] 2025-05-30 02:02:51,691 | WARNING  | data:queue_monitoring:received graceful stop - abort after this iteration
[2025-05-29 22:03:09] 2025-05-30 02:02:51,691 | WARNING  | job:job_monitor:received graceful stop - abort after this iteration
[2025-05-29 22:03:09] 2025-05-30 02:02:51,691 | INFO     | aborting loop
[2025-05-29 22:03:09] 2025-05-30 02:02:51,727 | INFO     | all job control threads have been joined
[2025-05-29 22:03:09] 2025-05-30 02:02:51,759 | INFO     | all data control threads have been joined
[2025-05-29 22:03:09] 2025-05-30 02:02:51,811 | WARNING  | data:copytool_out:received graceful stop - abort after this iteration
[2025-05-29 22:03:09] 2025-05-30 02:02:52,241 | INFO     | found 0 job(s) in 20 queues
[2025-05-29 22:03:09] 2025-05-30 02:02:52,241 | WARNING  | pilot monitor received instruction that args.graceful_stop has been set
[2025-05-29 22:03:09] 2025-05-30 02:02:52,241 | WARNING  | will wait for a maximum of 300 s for threads to finish
[2025-05-29 22:03:09] 2025-05-30 02:02:52,696 | INFO     | [job] retrieve thread has finished
[2025-05-29 22:03:09] 2025-05-30 02:02:52,697 | INFO     | [job] job monitor thread has finished
[2025-05-29 22:03:09] 2025-05-30 02:02:52,732 | INFO     | [job] control thread has finished
[2025-05-29 22:03:09] 2025-05-30 02:02:52,764 | INFO     | [data] control thread has finished
[2025-05-29 22:03:09] 2025-05-30 02:02:52,985 | INFO     | all payload control threads have been joined
[2025-05-29 22:03:09] 2025-05-30 02:02:53,187 | INFO     | [payload] validate_post thread has finished
[2025-05-29 22:03:09] 2025-05-30 02:02:53,336 | INFO     | [job] create_data_payload thread has finished
[2025-05-29 22:03:09] 2025-05-30 02:02:53,559 | INFO     | [payload] execute_payloads thread has finished
[2025-05-29 22:03:09] 2025-05-30 02:02:53,620 | INFO     | [payload] failed_post thread has finished
[2025-05-29 22:03:09] 2025-05-30 02:02:53,631 | INFO     | [payload] validate_pre thread has finished
[2025-05-29 22:03:09] 2025-05-30 02:02:53,817 | INFO     | [data] copytool_out thread has finished
[2025-05-29 22:03:09] 2025-05-30 02:02:53,991 | INFO     | [payload] control thread has finished
[2025-05-29 22:03:09] 2025-05-30 02:02:54,062 | INFO     | [data] copytool_in thread has finished
[2025-05-29 22:03:09] 2025-05-30 02:02:54,186 | INFO     | [job] validate thread has finished
[2025-05-29 22:03:09] 2025-05-30 02:02:54,675 | WARNING  | job:queue_monitor:received graceful stop - abort after this iteration
[2025-05-29 22:03:09] 2025-05-30 02:02:55,680 | INFO     | [job] queue monitor thread has finished
[2025-05-29 22:03:09] 2025-05-30 02:02:55,699 | INFO     | [data] queue_monitor thread has finished
[2025-05-29 22:03:09] 2025-05-30 02:03:02,239 | INFO     | job.realtimelogging is not enabled
[2025-05-29 22:03:09] 2025-05-30 02:03:03,245 | INFO     | [payload] run_realtimelog thread has finished
[2025-05-29 22:03:09] 2025-05-30 02:03:04,172 | INFO     | only monitor.control thread still running - safe to abort: ['<_MainThread(MainThread, started 140256980576064)>', '<ExcThread(monitor, started 140255965468416)>']
[2025-05-29 22:03:09] 2025-05-30 02:03:04,307 | WARNING  | job_aborted has been set - aborting pilot monitoring
[2025-05-29 22:03:09] 2025-05-30 02:03:04,307 | INFO     | [monitor] control thread has ended
[2025-05-29 22:03:09] 2025-05-30 02:03:09,198 | INFO     | all workflow threads have been joined
[2025-05-29 22:03:09] 2025-05-30 02:03:09,198 | INFO     | end of generic workflow (traces error code: 0)
[2025-05-29 22:03:09] 2025-05-30 02:03:09,198 | INFO     | traces error code: 0
[2025-05-29 22:03:09] 2025-05-30 02:03:09,199 | INFO     | pilot has finished (exit code=0, shell exit code=0)
[2025-05-29 22:03:09] 2025-05-30 02:03:09,398 [wrapper] ==== pilot stdout END ====
[2025-05-29 22:03:09] 2025-05-30 02:03:09,402 [wrapper] ==== wrapper stdout RESUME ====
[2025-05-29 22:03:09] 2025-05-30 02:03:09,405 [wrapper] pilotpid: 229134
[2025-05-29 22:03:09] 2025-05-30 02:03:09,408 [wrapper] Pilot exit status: 0
[2025-05-29 22:03:09] 2025-05-30 02:03:09,423 [wrapper] pandaids: 6670182775
[2025-05-29 22:03:09] 2025-05-30 02:03:09,498 [wrapper] cleanup supervisor_pilot  642614 229135
[2025-05-29 22:03:09] 2025-05-30 02:03:09,502 [wrapper] Test setup, not cleaning
[2025-05-29 22:03:09] 2025-05-30 02:03:09,505 [wrapper] apfmon messages muted
[2025-05-29 22:03:09] 2025-05-30 02:03:09,508 [wrapper] ==== wrapper stdout END ====
[2025-05-29 22:03:09] 2025-05-30 02:03:09,512 [wrapper] ==== wrapper stderr END ====
[2025-05-29 22:03:09]  *** Error codes and diagnostics ***
[2025-05-29 22:03:09]     "exeErrorCode": 0,
[2025-05-29 22:03:09]     "exeErrorDiag": "",
[2025-05-29 22:03:09]     "pilotErrorCode": 0,
[2025-05-29 22:03:09]     "pilotErrorDiag": "",
[2025-05-29 22:03:09]  *** Listing of results directory ***
[2025-05-29 22:03:09] total 417224
[2025-05-29 22:03:09] -rw-r--r--. 1 boincer umatlas    495897 May 29 05:39 pilot3.tar.gz
[2025-05-29 22:03:09] -rwx------. 1 boincer umatlas     37140 May 29 06:01 runpilot2-wrapper.sh
[2025-05-29 22:03:09] -rw-r--r--. 1 boincer umatlas      5111 May 29 06:01 queuedata.json
[2025-05-29 22:03:09] -rw-r--r--. 1 boincer umatlas       100 May 29 19:51 wrapper_26015_x86_64-pc-linux-gnu
[2025-05-29 22:03:09] -rwxr-xr-x. 1 boincer umatlas      7986 May 29 19:51 run_atlas
[2025-05-29 22:03:09] -rw-r--r--. 1 boincer umatlas       105 May 29 19:51 job.xml
[2025-05-29 22:03:09] -rw-r--r--. 2 boincer umatlas 224333274 May 29 19:51 EVNT.44871834._002258.pool.root.1
[2025-05-29 22:03:09] -rw-r--r--. 2 boincer umatlas     15093 May 29 19:51 start_atlas.sh
[2025-05-29 22:03:09] drwxrwx--x. 2 boincer umatlas      4096 May 29 19:51 shared
[2025-05-29 22:03:09] -rw-r--r--. 2 boincer umatlas    508888 May 29 19:51 input.tar.gz
[2025-05-29 22:03:09] -rw-r--r--. 1 boincer umatlas         0 May 29 19:51 boinc_lockfile
[2025-05-29 22:03:09] -rw-r--r--. 1 boincer umatlas      2559 May 29 19:51 pandaJob.out
[2025-05-29 22:03:09] -rw-------. 1 boincer umatlas    992366 May 29 19:51 agis_schedconf.cvmfs.json
[2025-05-29 22:03:09] -rw-------. 1 boincer umatlas   1603667 May 29 19:51 agis_ddmendpoints.agis.ALL.json
[2025-05-29 22:03:09] drwx------. 4 boincer umatlas      4096 May 29 19:51 pilot3
[2025-05-29 22:03:09] -rw-r--r--. 1 boincer umatlas      6841 May 29 21:58 init_data.xml
[2025-05-29 22:03:09] -rw-------. 1 boincer umatlas 196831131 May 29 22:01 HITS.44871837._098463.pool.root.1
[2025-05-29 22:03:09] -rw-r--r--. 1 boincer umatlas       530 May 29 22:02 boinc_task_state.xml
[2025-05-29 22:03:09] -rw-------. 1 boincer umatlas        95 May 29 22:02 pilot_heartbeat.json
[2025-05-29 22:03:09] -rw-------. 1 boincer umatlas      1042 May 29 22:02 memory_monitor_summary.json
[2025-05-29 22:03:09] -rw-------. 1 boincer umatlas    407378 May 29 22:02 log.44871837._098463.job.log.tgz.1
[2025-05-29 22:03:09] -rw-r--r--. 1 boincer umatlas      8192 May 29 22:02 boinc_mmap_file
[2025-05-29 22:03:09] -rw-r--r--. 1 boincer umatlas        27 May 29 22:02 wrapper_checkpoint.txt
[2025-05-29 22:03:09] -rw-------. 1 boincer umatlas      7723 May 29 22:02 heartbeat.json
[2025-05-29 22:03:09] -rw-------. 1 boincer umatlas      4425 May 29 22:03 pilotlog.txt
[2025-05-29 22:03:09] -rw-------. 1 boincer umatlas    707565 May 29 22:03 log.44871837._098463.job.log.1
[2025-05-29 22:03:09] -rw-------. 1 boincer umatlas       357 May 29 22:03 output.list
[2025-05-29 22:03:09] -rw-r--r--. 1 boincer umatlas       620 May 29 22:03 runtime_log
[2025-05-29 22:03:09] -rw-------. 1 boincer umatlas   1136640 May 29 22:03 result.tar.gz
[2025-05-29 22:03:09] -rw-r--r--. 1 boincer umatlas      8666 May 29 22:03 runtime_log.err
[2025-05-29 22:03:09] -rw-------. 1 boincer umatlas       654 May 29 22:03 e1JKDmOGpe7n9Rq4apOajLDm4fhM0noT9bVof3QYDmj1UKDm7Rzbim.diag
[2025-05-29 22:03:09] -rw-r--r--. 1 boincer umatlas     21284 May 29 22:03 stderr.txt
[2025-05-29 22:03:09] HITS file was successfully produced:
[2025-05-29 22:03:09] -rw-------. 1 boincer umatlas 196831131 May 29 22:01 shared/HITS.pool.root.1
[2025-05-29 22:03:09]  *** Contents of shared directory: ***
[2025-05-29 22:03:09] total 412928
[2025-05-29 22:03:09] -rw-r--r--. 2 boincer umatlas 224333274 May 29 19:51 ATLAS.root_0
[2025-05-29 22:03:09] -rw-r--r--. 2 boincer umatlas     15093 May 29 19:51 start_atlas.sh
[2025-05-29 22:03:09] -rw-r--r--. 2 boincer umatlas    508888 May 29 19:51 input.tar.gz
[2025-05-29 22:03:09] -rw-------. 1 boincer umatlas 196831131 May 29 22:01 HITS.pool.root.1
[2025-05-29 22:03:09] -rw-------. 1 boincer umatlas   1136640 May 29 22:03 result.tar.gz
22:03:10 (225468): run_atlas exited; CPU time 50056.058986
22:03:10 (225468): called boinc_finish(0)

</stderr_txt>
]]>

©2025 CERN