Stderr output

<core_client_version>8.1.0</core_client_version>
<![CDATA[
<stderr_txt>
00:02:16 (2210021): wrapper (7.7.26015): starting
00:02:16 (2210021): wrapper: running run_atlas (--nthreads 9)
[2025-11-26 00:02:16] Arguments: --nthreads 9
[2025-11-26 00:02:16] Threads: 9
[2025-11-26 00:02:16] Checking for CVMFS
[2025-11-26 00:02:17] Probing /cvmfs/atlas.cern.ch... OK
[2025-11-26 00:02:17] Probing /cvmfs/atlas-condb.cern.ch... OK
[2025-11-26 00:02:17] Running cvmfs_config stat atlas.cern.ch
[2025-11-26 00:02: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
[2025-11-26 00:02:18] 2.13.3.0 13172 29605 380996 153309 0 176 23871876 32935936 39125 16776704 0 153653434 99.538 169366918 34968 http://cvmfs-s1fnal.opensciencegrid.org:8000/cvmfs/atlas.cern.ch http://192.41.231.239:6081 1
[2025-11-26 00:02:18] CVMFS is ok
[2025-11-26 00:02:18] Efficiency of ATLAS tasks can be improved by the following measure(s):
[2025-11-26 00:02:18] The CVMFS client on this computer should be configured to use Cloudflare's openhtc.io.
[2025-11-26 00:02:18] Further information can be found at the LHC@home message board.
[2025-11-26 00:02:18] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7
[2025-11-26 00:02:18] Checking for apptainer binary...
[2025-11-26 00:02:18] Using apptainer found in PATH at /usr/bin/apptainer
[2025-11-26 00:02:18] Running /usr/bin/apptainer --version
[2025-11-26 00:02:18] apptainer version 1.4.4-1.el9
[2025-11-26 00:02:18] Checking apptainer works with /usr/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname
[2025-11-26 00:02:19] c6-6-8-3.aglt2.org
[2025-11-26 00:02:19] apptainer works
[2025-11-26 00:02:19] Set ATHENA_PROC_NUMBER=9
[2025-11-26 00:02:19] Set ATHENA_CORE_NUMBER=9
[2025-11-26 00:02:19] Starting ATLAS job with PandaID=6891551927
[2025-11-26 00:02:19] Running command: /usr/bin/apptainer exec -B /cvmfs,/tmp/boinchome/slots/7 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 sh start_atlas.sh
13:20:08 (2210021): BOINC client no longer exists - exiting
13:20:08 (2210021): timer handler: client dead, exiting
13:20:45 (1728670): wrapper (7.7.26015): starting
13:20:45 (1728670): wrapper: running run_atlas (--nthreads 8)
[2025-11-26 13:20:45] Arguments: --nthreads 8
[2025-11-26 13:20:45] Threads: 8
[2025-11-26 13:20:45] This job has been restarted, cleaning up previous attempt
[2025-11-26 13:20:45] Checking for CVMFS
[2025-11-26 13:20:45] Probing /cvmfs/atlas.cern.ch... OK
[2025-11-26 13:20:45] Probing /cvmfs/atlas-condb.cern.ch... OK
[2025-11-26 13:20:45] Running cvmfs_config stat atlas.cern.ch
[2025-11-26 13:20:45] 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-11-26 13:20:45] 2.13.3.0 13172 30403 374404 153331 3 439 24192179 32935937 35383 16776704 0 159168469 99.541 174462204 34908 http://cvmfs-s1fnal.opensciencegrid.org:8000/cvmfs/atlas.cern.ch http://192.41.231.239:6081 1
[2025-11-26 13:20:45] CVMFS is ok
[2025-11-26 13:20:45] Efficiency of ATLAS tasks can be improved by the following measure(s):
[2025-11-26 13:20:45] The CVMFS client on this computer should be configured to use Cloudflare's openhtc.io.
[2025-11-26 13:20:45] Further information can be found at the LHC@home message board.
[2025-11-26 13:20:45] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7
[2025-11-26 13:20:45] Checking for apptainer binary...
[2025-11-26 13:20:45] Using apptainer found in PATH at /usr/bin/apptainer
[2025-11-26 13:20:45] Running /usr/bin/apptainer --version
[2025-11-26 13:20:45] apptainer version 1.4.4-1.el9
[2025-11-26 13:20:45] Checking apptainer works with /usr/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname
[2025-11-26 13:20:46] c6-6-8-3.aglt2.org
[2025-11-26 13:20:46] apptainer works
[2025-11-26 13:20:46] Set ATHENA_PROC_NUMBER=8
[2025-11-26 13:20:46] Set ATHENA_CORE_NUMBER=8
[2025-11-26 13:20:46] Starting ATLAS job with PandaID=6891551927
[2025-11-26 13:20:46] Running command: /usr/bin/apptainer exec -B /cvmfs,/tmp/boinchome/slots/7 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 sh start_atlas.sh
[2025-11-27 16:29:13]  *** The last 200 lines of the pilot log: ***
[2025-11-27 16:29:13] 2025-11-27 21:28:28,714 | INFO     | chi2: 14.560884138396712
[2025-11-27 16:29:13] 2025-11-27 21:28:28,717 | INFO     | sum of square deviations: 1258207843986.046
[2025-11-27 16:29:13] 2025-11-27 21:28:28,927 | INFO     | monitor loop #5016: job 0:6891551927 is in state 'finished'
[2025-11-27 16:29:13] 2025-11-27 21:28:28,928 | INFO     | will abort job monitoring soon since job state=finished (job is still in queue)
[2025-11-27 16:29:13] 2025-11-27 21:28:29,192 | INFO     | sum of deviations: 5490857069234.194
[2025-11-27 16:29:13] 2025-11-27 21:28:29,192 | INFO     | mean x: 1764229909.6244514
[2025-11-27 16:29:13] 2025-11-27 21:28:29,193 | INFO     | mean y: 2938242.6413793103
[2025-11-27 16:29:13] 2025-11-27 21:28:29,193 | INFO     | intersect: -7696214419.073593
[2025-11-27 16:29:13] 2025-11-27 21:28:29,216 | INFO     | chi2: 10.785519011210262
[2025-11-27 16:29:13] 2025-11-27 21:28:29,216 | INFO     | current chi2=10.785519011210262 (change=25.92813108945013 %)
[2025-11-27 16:29:13] 2025-11-27 21:28:29,242 | INFO     | sum of square deviations: 1246413280909.0005
[2025-11-27 16:29:13] 2025-11-27 21:28:30,196 | INFO     | sum of deviations: 6034186905215.206
[2025-11-27 16:29:13] 2025-11-27 21:28:30,196 | INFO     | mean x: 1764229757.1327045
[2025-11-27 16:29:13] 2025-11-27 21:28:30,196 | INFO     | mean y: 2945268.73836478
[2025-11-27 16:29:13] 2025-11-27 21:28:30,197 | INFO     | intersect: -8538115919.66404
[2025-11-27 16:29:13] 2025-11-27 21:28:30,221 | INFO     | chi2: 7.594380957089113
[2025-11-27 16:29:13] 2025-11-27 21:28:30,221 | INFO     | current chi2=7.594380957089113 (change=29.587246110310883 %)
[2025-11-27 16:29:13] 2025-11-27 21:28:30,246 | INFO     | sum of square deviations: 1234692672706.7349
[2025-11-27 16:29:13] 2025-11-27 21:28:31,091 | INFO     | sum of deviations: 6565624894547.2295
[2025-11-27 16:29:13] 2025-11-27 21:28:31,091 | INFO     | mean x: 1764229604.6410096
[2025-11-27 16:29:13] 2025-11-27 21:28:31,092 | INFO     | mean y: 2952181.7350157727
[2025-11-27 16:29:13] 2025-11-27 21:28:31,092 | INFO     | intersect: -9378548225.597113
[2025-11-27 16:29:13] 2025-11-27 21:28:31,111 | INFO     | chi2: 4.510893140951362
[2025-11-27 16:29:13] 2025-11-27 21:28:31,111 | INFO     | current chi2=4.510893140951362 (change=40.602227272512756 %)
[2025-11-27 16:29:13] 2025-11-27 21:28:31,132 | INFO     | sum of square deviations: 1223045786816.7495
[2025-11-27 16:29:13] 2025-11-27 21:28:31,628 | INFO     | monitor loop #5017: job 0:6891551927 is in state 'finished'
[2025-11-27 16:29:13] 2025-11-27 21:28:31,629 | INFO     | will abort job monitoring soon since job state=finished (job is still in queue)
[2025-11-27 16:29:13] 2025-11-27 21:28:31,820 | INFO     | sum of deviations: 6813271861059.291
[2025-11-27 16:29:13] 2025-11-27 21:28:31,839 | INFO     | mean x: 1764229452.149367
[2025-11-27 16:29:13] 2025-11-27 21:28:31,839 | INFO     | mean y: 2955423.846202532
[2025-11-27 16:29:13] 2025-11-27 21:28:31,839 | INFO     | intersect: -9825110714.271593
[2025-11-27 16:29:13] 2025-11-27 21:28:31,841 | INFO     | chi2: 3.691332579419567
[2025-11-27 16:29:13] 2025-11-27 21:28:31,842 | INFO     | current chi2=3.691332579419567 (change=18.168476528329975 %)
[2025-11-27 16:29:13] 2025-11-27 21:28:31,842 | INFO     | right removable region: 1579
[2025-11-27 16:29:13] 2025-11-27 21:28:31,864 | INFO     | sum of square deviations: 1258205777307.273
[2025-11-27 16:29:13] 2025-11-27 21:28:32,701 | INFO     | sum of deviations: 4442096150711.758
[2025-11-27 16:29:13] 2025-11-27 21:28:32,988 | INFO     | mean x: 1764230214.6213167
[2025-11-27 16:29:13] 2025-11-27 21:28:32,988 | INFO     | mean y: 2936514.0012539183
[2025-11-27 16:29:13] 2025-11-27 21:28:32,988 | INFO     | intersect: -6225679175.644275
[2025-11-27 16:29:13] 2025-11-27 21:28:32,990 | INFO     | chi2: 12.343843777528818
[2025-11-27 16:29:13] 2025-11-27 21:28:32,990 | INFO     | current chi2=12.343843777528818 (change=15.226000974910656 %)
[2025-11-27 16:29:13] 2025-11-27 21:28:32,991 | INFO     | left removable region: 10
[2025-11-27 16:29:13] 2025-11-27 21:28:32,999 | INFO     | sum of square deviations: 1197677041811.2097
[2025-11-27 16:29:13] 2025-11-27 21:28:33,481 | INFO     | sum of deviations: 6242895013146.914
[2025-11-27 16:29:13] 2025-11-27 21:28:33,481 | INFO     | mean x: 1764229726.6577437
[2025-11-27 16:29:13] 2025-11-27 21:28:33,481 | INFO     | mean y: 2964211.089228808
[2025-11-27 16:29:13] 2025-11-27 21:28:33,481 | INFO     | intersect: -9193088295.637619
[2025-11-27 16:29:13] 2025-11-27 21:28:33,534 | INFO     | chi2: 1.1639000429245114
[2025-11-27 16:29:13] 2025-11-27 21:28:33,534 | INFO     | current memory leak: 5.21 B/s (using 1569 data points, chi2=1.16)
[2025-11-27 16:29:13] 2025-11-27 21:28:33,536 | INFO     | could have reported an average CPU frequency of 2656 MHz (57 samples)
[2025-11-27 16:29:13] 2025-11-27 21:28:33,536 | INFO     | ..............................
[2025-11-27 16:29:13] 2025-11-27 21:28:33,536 | INFO     | . Timing measurements:
[2025-11-27 16:29:13] 2025-11-27 21:28:33,536 | INFO     | . get job = 0 s
[2025-11-27 16:29:13] 2025-11-27 21:28:33,536 | INFO     | . initial setup = 2 s
[2025-11-27 16:29:13] 2025-11-27 21:28:33,537 | INFO     | . payload setup = 7 s
[2025-11-27 16:29:13] 2025-11-27 21:28:33,537 | INFO     | . stage-in = 0 s
[2025-11-27 16:29:13] 2025-11-27 21:28:33,537 | INFO     | . payload execution = 97563 s
[2025-11-27 16:29:13] 2025-11-27 21:28:33,537 | INFO     | . stage-out = 13 s
[2025-11-27 16:29:13] 2025-11-27 21:28:33,537 | INFO     | . log creation = 1 s
[2025-11-27 16:29:13] 2025-11-27 21:28:33,537 | INFO     | ..............................
[2025-11-27 16:29:13] 2025-11-27 21:28:34,688 | INFO     | 
[2025-11-27 16:29:13] 2025-11-27 21:28:34,689 | INFO     | job summary report
[2025-11-27 16:29:13] 2025-11-27 21:28:34,689 | INFO     | --------------------------------------------------
[2025-11-27 16:29:13] 2025-11-27 21:28:34,689 | INFO     | PanDA job id: 6891551927
[2025-11-27 16:29:13] 2025-11-27 21:28:34,689 | INFO     | task id: 47493068
[2025-11-27 16:29:13] 2025-11-27 21:28:34,689 | INFO     | errors: (none)
[2025-11-27 16:29:13] 2025-11-27 21:28:34,689 | INFO     | status: LOG_TRANSFER = DONE 
[2025-11-27 16:29:13] 2025-11-27 21:28:34,689 | INFO     | pilot state: finished 
[2025-11-27 16:29:13] 2025-11-27 21:28:34,689 | INFO     | transexitcode: 0
[2025-11-27 16:29:13] 2025-11-27 21:28:34,689 | INFO     | exeerrorcode: 0
[2025-11-27 16:29:13] 2025-11-27 21:28:34,689 | INFO     | exeerrordiag: 
[2025-11-27 16:29:13] 2025-11-27 21:28:34,689 | INFO     | exitcode: 0
[2025-11-27 16:29:13] 2025-11-27 21:28:34,689 | INFO     | exitmsg: OK
[2025-11-27 16:29:13] 2025-11-27 21:28:34,707 | INFO     | cpuconsumptiontime: 279222 s
[2025-11-27 16:29:13] 2025-11-27 21:28:34,707 | INFO     | nevents: 1000
[2025-11-27 16:29:13] 2025-11-27 21:28:34,707 | INFO     | neventsw: 0
[2025-11-27 16:29:13] 2025-11-27 21:28:34,707 | INFO     | pid: 1790365
[2025-11-27 16:29:13] 2025-11-27 21:28:34,707 | INFO     | pgrp: 1790365
[2025-11-27 16:29:13] 2025-11-27 21:28:34,707 | INFO     | corecount: 8
[2025-11-27 16:29:13] 2025-11-27 21:28:34,707 | INFO     | event service: False
[2025-11-27 16:29:13] 2025-11-27 21:28:34,707 | INFO     | sizes: {0: 2284294, 5: 2284556, 11: 2284612, 97577: 2353063, 97578: 2353062, 97592: 2362112, 97595: 2362296, 97638: 2362542}
[2025-11-27 16:29:13] 2025-11-27 21:28:34,707 | INFO     | --------------------------------------------------
[2025-11-27 16:29:13] 2025-11-27 21:28:34,707 | INFO     | 
[2025-11-27 16:29:13] 2025-11-27 21:28:34,707 | INFO     | executing command: ls -lF /tmp/boinchome/slots/7
[2025-11-27 16:29:13] 2025-11-27 21:28:34,821 | INFO     | queue jobs had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,821 | INFO     | queue payloads had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,821 | INFO     | queue data_in had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,821 | INFO     | queue data_out had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,821 | INFO     | queue current_data_in had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,821 | INFO     | queue validated_jobs had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,821 | INFO     | queue validated_payloads had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,822 | INFO     | queue monitored_payloads had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,822 | INFO     | queue finished_jobs had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,822 | INFO     | queue finished_payloads had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,822 | INFO     | queue finished_data_in had 1 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,822 | INFO     | queue finished_data_out had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,822 | INFO     | queue failed_jobs had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,822 | INFO     | queue failed_payloads had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,822 | INFO     | queue failed_data_in had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,822 | INFO     | queue failed_data_out had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,822 | INFO     | queue completed_jobs had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,823 | INFO     | queue completed_jobids has 1 job(s)
[2025-11-27 16:29:13] 2025-11-27 21:28:34,823 | INFO     | queue realtimelog_payloads had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,823 | INFO     | queue messages had 0 job(s) [purged]
[2025-11-27 16:29:13] 2025-11-27 21:28:34,823 | INFO     | job 6891551927 has completed (purged errors)
[2025-11-27 16:29:13] 2025-11-27 21:28:34,829 | INFO     | overall cleanup function is called
[2025-11-27 16:29:13] 2025-11-27 21:28:35,917 | INFO     | --- collectZombieJob: --- 10, [1790365]
[2025-11-27 16:29:13] 2025-11-27 21:28:35,918 | INFO     | zombie collector waiting for pid 1790365
[2025-11-27 16:29:13] 2025-11-27 21:28:35,918 | INFO     | harmless exception when collecting zombies: [Errno 10] No child processes
[2025-11-27 16:29:13] 2025-11-27 21:28:35,918 | INFO     | collected zombie processes
[2025-11-27 16:29:13] 2025-11-27 21:28:35,918 | INFO     | will attempt to kill all subprocesses of pid=1790365
[2025-11-27 16:29:13] 2025-11-27 21:28:36,693 | INFO     | PID=1750908 has CPU usage=5.9% 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-11-27 16:29:13] 2025-11-27 21:28:36,694 | INFO     | .. there are 53 such processes running
[2025-11-27 16:29:13] 2025-11-27 21:28:39,757 | INFO     | process IDs to be killed: [1790365] (in reverse order)
[2025-11-27 16:29:13] 2025-11-27 21:28:40,684 | WARNING  | found no corresponding commands to process id(s)
[2025-11-27 16:29:13] 2025-11-27 21:28:40,701 | INFO     | Do not look for orphan processes in BOINC jobs
[2025-11-27 16:29:13] 2025-11-27 21:28:40,924 | INFO     | did not find any defunct processes belonging to 1790365
[2025-11-27 16:29:13] 2025-11-27 21:28:41,002 | INFO     | did not find any defunct processes belonging to 1790365
[2025-11-27 16:29:13] 2025-11-27 21:28:41,048 | WARNING  | condor_chirp not found. condor_config_val not found. | If you're on a worker node/container, HTCondor may not expose configs to your env. Try: export CONDOR_CONFIG
[2025-11-27 16:29:13] 2025-11-27 21:28:41,048 | INFO     | ready for new job
[2025-11-27 16:29:13] 2025-11-27 21:28:41,048 | INFO     | pilot has finished with previous job - re-establishing logging
[2025-11-27 16:29:13] 2025-11-27 21:28:41,064 | INFO     | ***************************************
[2025-11-27 16:29:13] 2025-11-27 21:28:41,065 | INFO     | ***  PanDA Pilot version 3.11.1.15  ***
[2025-11-27 16:29:13] 2025-11-27 21:28:41,065 | INFO     | ***************************************
[2025-11-27 16:29:13] 2025-11-27 21:28:41,065 | INFO     | 
[2025-11-27 16:29:13] 2025-11-27 21:28:41,079 | INFO     | architecture information:
[2025-11-27 16:29:13] 2025-11-27 21:28:41,098 | INFO     | executing command: cat /etc/os-release
[2025-11-27 16:29:13] 2025-11-27 21:28:41,206 | INFO     | cat /etc/os-release:
[2025-11-27 16:29:13] NAME="CentOS Linux"
[2025-11-27 16:29:13] VERSION="7 (Core)"
[2025-11-27 16:29:13] ID="centos"
[2025-11-27 16:29:13] ID_LIKE="rhel fedora"
[2025-11-27 16:29:13] VERSION_ID="7"
[2025-11-27 16:29:13] PRETTY_NAME="CentOS Linux 7 (Core)"
[2025-11-27 16:29:13] ANSI_COLOR="0;31"
[2025-11-27 16:29:13] CPE_NAME="cpe:/o:centos:centos:7"
[2025-11-27 16:29:13] HOME_URL="https://www.centos.org/"
[2025-11-27 16:29:13] BUG_REPORT_URL="https://bugs.centos.org/"
[2025-11-27 16:29:13] 
[2025-11-27 16:29:13] CENTOS_MANTISBT_PROJECT="CentOS-7"
[2025-11-27 16:29:13] CENTOS_MANTISBT_PROJECT_VERSION="7"
[2025-11-27 16:29:13] REDHAT_SUPPORT_PRODUCT="centos"
[2025-11-27 16:29:13] REDHAT_SUPPORT_PRODUCT_VERSION="7"
[2025-11-27 16:29:13] 
[2025-11-27 16:29:13] 2025-11-27 21:28:41,206 | INFO     | ***************************************
[2025-11-27 16:29:13] 2025-11-27 21:28:41,709 | INFO     | executing command: df -mP /tmp/boinchome/slots/7
[2025-11-27 16:29:13] 2025-11-27 21:28:41,826 | INFO     | sufficient remaining disk space (88661295104 B)
[2025-11-27 16:29:13] 2025-11-27 21:28:41,826 | WARNING  | since timefloor is set to 0, pilot was only allowed to run one job
[2025-11-27 16:29:13] 2025-11-27 21:28:41,826 | INFO     | current server update state: UPDATING_FINAL
[2025-11-27 16:29:13] 2025-11-27 21:28:41,827 | INFO     | update_server=False
[2025-11-27 16:29:13] 2025-11-27 21:28:41,827 | WARNING  | setting graceful_stop since proceed_with_getjob() returned False (pilot will end)
[2025-11-27 16:29:13] 2025-11-27 21:28:41,850 | INFO     | all payload control threads have been joined
[2025-11-27 16:29:13] 2025-11-27 21:28:42,016 | WARNING  | job monitor detected an abort_job request (signal=args.signal)
[2025-11-27 16:29:13] 2025-11-27 21:28:42,017 | WARNING  | cannot recover job monitoring - aborting pilot
[2025-11-27 16:29:13] 2025-11-27 21:28:42,017 | WARNING  | job:job_monitor:received graceful stop - abort after this iteration
[2025-11-27 16:29:13] 2025-11-27 21:28:42,017 | INFO     | will abort loop
[2025-11-27 16:29:13] 2025-11-27 21:28:42,488 | WARNING  | data:copytool_out:received graceful stop - abort after this iteration
[2025-11-27 16:29:13] 2025-11-27 21:28:42,668 | INFO     | all data control threads have been joined
[2025-11-27 16:29:13] 2025-11-27 21:28:42,790 | INFO     | all job control threads have been joined
[2025-11-27 16:29:13] 2025-11-27 21:28:42,832 | INFO     | [job] retrieve thread has finished
[2025-11-27 16:29:13] 2025-11-27 21:28:42,856 | INFO     | [payload] control thread has finished
[2025-11-27 16:29:13] 2025-11-27 21:28:42,914 | INFO     | [data] copytool_in thread has finished
[2025-11-27 16:29:13] 2025-11-27 21:28:42,953 | INFO     | [job] validate thread has finished
[2025-11-27 16:29:13] 2025-11-27 21:28:42,954 | INFO     | [job] create_data_payload thread has finished
[2025-11-27 16:29:13] 2025-11-27 21:28:42,970 | WARNING  | data:queue_monitoring:received graceful stop - abort after this iteration
[2025-11-27 16:29:13] 2025-11-27 21:28:42,980 | INFO     | [payload] failed_post thread has finished
[2025-11-27 16:29:13] 2025-11-27 21:28:43,042 | INFO     | [job] job monitor thread has finished
[2025-11-27 16:29:13] 2025-11-27 21:28:43,333 | INFO     | [payload] validate_post thread has finished
[2025-11-27 16:29:13] 2025-11-27 21:28:43,436 | INFO     | [payload] execute_payloads thread has finished
[2025-11-27 16:29:13] 2025-11-27 21:28:43,609 | WARNING  | job:queue_monitor:received graceful stop - abort after this iteration
[2025-11-27 16:29:13] 2025-11-27 21:28:43,674 | INFO     | [data] control thread has finished
[2025-11-27 16:29:13] 2025-11-27 21:28:43,795 | INFO     | [job] control thread has finished
[2025-11-27 16:29:13] 2025-11-27 21:28:44,076 | INFO     | [payload] validate_pre thread has finished
[2025-11-27 16:29:13] 2025-11-27 21:28:44,110 | INFO     | [payload] run_realtimelog thread has finished
[2025-11-27 16:29:13] 2025-11-27 21:28:44,507 | INFO     | [data] copytool_out thread has finished
[2025-11-27 16:29:13] 2025-11-27 21:28:44,615 | INFO     | [job] queue monitor thread has finished
[2025-11-27 16:29:13] 2025-11-27 21:28:47,002 | INFO     | [data] queue_monitor thread has finished
[2025-11-27 16:29:13] 2025-11-27 21:28:59,034 | INFO     | [monitor] cgroup control has ended
[2025-11-27 16:29:13] 2025-11-27 21:28:59,916 | INFO     | only monitor.control thread still running - safe to abort: ['<_MainThread(MainThread, started 139826155374400)>', '<ExcThread(monitor, started 139825655039744)>']
[2025-11-27 16:29:13] 2025-11-27 21:29:04,945 | INFO     | all workflow threads have been joined
[2025-11-27 16:29:13] 2025-11-27 21:29:04,974 | INFO     | end of generic workflow (traces error code: 0)
[2025-11-27 16:29:13] 2025-11-27 21:29:04,975 | INFO     | traces error code: 0
[2025-11-27 16:29:13] 2025-11-27 21:29:04,975 | INFO     | pilot has finished (exit code=0, shell exit code=0)
[2025-11-27 16:29:13] 2025-11-27 21:29:11,274 | INFO     | PID=1750908 has CPU usage=5.2% 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-11-27 16:29:13] 2025-11-27 21:29:11,275 | INFO     | .. there are 53 such processes running
[2025-11-27 16:29:13] 2025-11-27 21:29:11,275 | INFO     | found 0 job(s) in 20 queues
[2025-11-27 16:29:13] 2025-11-27 21:29:11,275 | WARNING  | pilot monitor received instruction that args.graceful_stop has been set
[2025-11-27 16:29:13] 2025-11-27 21:29:11,275 | WARNING  | will wait for a maximum of 300 s for threads to finish
[2025-11-27 16:29:13] 2025-11-27 21:29:11,275 | WARNING  | job_aborted has been set - aborting pilot monitoring
[2025-11-27 16:29:13] 2025-11-27 21:29:11,275 | INFO     | [monitor] control thread has ended
[2025-11-27 16:29:13] 2025-11-27 21:29:11,683 [wrapper] ==== pilot stdout END ====
[2025-11-27 16:29:13] 2025-11-27 21:29:11,702 [wrapper] ==== wrapper stdout RESUME ====
[2025-11-27 16:29:13] 2025-11-27 21:29:11,726 [wrapper] pilotpid: 1750908
[2025-11-27 16:29:13] 2025-11-27 21:29:11,759 [wrapper] Pilot exit status: 0
[2025-11-27 16:29:13] 2025-11-27 21:29:11,851 [wrapper] pandaids: 6891551927 6891551927
[2025-11-27 16:29:13] 2025-11-27 21:29:12,773 [wrapper] cleanup supervisor_pilot 1075310 1750909
[2025-11-27 16:29:13] 2025-11-27 21:29:12,826 [wrapper] Test setup, not cleaning
[2025-11-27 16:29:13] 2025-11-27 21:29:12,840 [wrapper] apfmon messages muted
[2025-11-27 16:29:13] 2025-11-27 21:29:12,867 [wrapper] ==== wrapper stdout END ====
[2025-11-27 16:29:13] 2025-11-27 21:29:12,885 [wrapper] ==== wrapper stderr END ====
[2025-11-27 16:29:13]  *** Error codes and diagnostics ***
[2025-11-27 16:29:13]     "exeErrorCode": 0,
[2025-11-27 16:29:13]     "exeErrorDiag": "",
[2025-11-27 16:29:13]     "pilotErrorCode": 0,
[2025-11-27 16:29:13]     "pilotErrorDiag": "",
[2025-11-27 16:29:13]  *** Listing of results directory ***
[2025-11-27 16:29:13] total 1719176
[2025-11-27 16:29:13] drwx------. 5 boincer umatlas      4096 Nov  5 12:16 pilot3
[2025-11-27 16:29:13] -rwx------. 1 boincer umatlas     36292 Nov 18 10:15 runpilot2-wrapper.sh
[2025-11-27 16:29:13] -rw-r--r--. 1 boincer umatlas    576529 Nov 18 10:15 pilot3.tar.gz
[2025-11-27 16:29:13] -rw-r--r--. 1 boincer umatlas      5112 Nov 18 10:15 queuedata.json
[2025-11-27 16:29:13] -rw-r--r--. 1 boincer umatlas       100 Nov 26 00:02 wrapper_26015_x86_64-pc-linux-gnu
[2025-11-27 16:29:13] -rwxr-xr-x. 1 boincer umatlas      7986 Nov 26 00:02 run_atlas
[2025-11-27 16:29:13] -rw-r--r--. 1 boincer umatlas       105 Nov 26 00:02 job.xml
[2025-11-27 16:29:13] -rw-r--r--. 3 boincer umatlas 407884073 Nov 26 00:02 EVNT.47493065._000018.pool.root.1
[2025-11-27 16:29:13] -rw-r--r--. 3 boincer umatlas 407884073 Nov 26 00:02 ATLAS.root_0
[2025-11-27 16:29:13] -rw-r--r--. 2 boincer umatlas     15120 Nov 26 00:02 start_atlas.sh
[2025-11-27 16:29:13] drwxrwx--x. 2 boincer umatlas      4096 Nov 26 00:02 shared
[2025-11-27 16:29:13] -rw-r--r--. 2 boincer umatlas    590086 Nov 26 00:02 input.tar.gz
[2025-11-27 16:29:13] -rw-r--r--. 1 boincer umatlas         0 Nov 26 00:02 boinc_lockfile
[2025-11-27 16:29:13] -rw-r--r--. 1 boincer umatlas      2768 Nov 26 13:20 pandaJob.out
[2025-11-27 16:29:13] -rw-------. 1 boincer umatlas    991522 Nov 26 13:20 agis_schedconf.cvmfs.json
[2025-11-27 16:29:13] -rw-r--r--. 1 boincer umatlas       534 Nov 27 15:55 boinc_task_state.xml
[2025-11-27 16:29:13] -rw-r--r--. 1 boincer umatlas      6880 Nov 27 16:20 init_data.xml
[2025-11-27 16:29:13] -rw-------. 1 boincer umatlas 913418787 Nov 27 16:24 HITS.47493068._000171.pool.root.1
[2025-11-27 16:29:13] -rw-------. 1 boincer umatlas      1070 Nov 27 16:27 memory_monitor_summary.json
[2025-11-27 16:29:13] -rw-------. 1 boincer umatlas   1516240 Nov 27 16:27 agis_ddmendpoints.agis.ALL.json
[2025-11-27 16:29:13] -rw-------. 1 boincer umatlas   2499543 Nov 27 16:27 log.47493068._000171.job.log.tgz.1
[2025-11-27 16:29:13] -rw-------. 1 boincer umatlas        95 Nov 27 16:28 pilot_heartbeat.json
[2025-11-27 16:29:13] -rw-------. 1 boincer umatlas      8635 Nov 27 16:28 heartbeat.json
[2025-11-27 16:29:13] -rw-r--r--. 1 boincer umatlas      8192 Nov 27 16:28 boinc_mmap_file
[2025-11-27 16:29:13] -rw-r--r--. 1 boincer umatlas        30 Nov 27 16:28 wrapper_checkpoint.txt
[2025-11-27 16:29:13] -rw-------. 1 boincer umatlas       823 Nov 27 16:29 pilotlog.txt
[2025-11-27 16:29:13] -rw-------. 1 boincer umatlas  11164685 Nov 27 16:29 log.47493068._000171.job.log.1
[2025-11-27 16:29:13] -rw-------. 1 boincer umatlas       353 Nov 27 16:29 output.list
[2025-11-27 16:29:13] -rw-r--r--. 1 boincer umatlas       620 Nov 27 16:29 runtime_log
[2025-11-27 16:29:13] -rw-------. 1 boincer umatlas  13680640 Nov 27 16:29 result.tar.gz
[2025-11-27 16:29:13] -rw-------. 1 boincer umatlas       790 Nov 27 16:29 snjLDmLbcf8n9Rq4apOajLDm4fhM0noT9bVo0NGKDmDBFKDmrgU4lm.diag
[2025-11-27 16:29:13] -rw-r--r--. 1 boincer umatlas      8789 Nov 27 16:29 runtime_log.err
[2025-11-27 16:29:13] -rw-r--r--. 1 boincer umatlas     23736 Nov 27 16:29 stderr.txt
[2025-11-27 16:29:13] HITS file was successfully produced:
[2025-11-27 16:29:13] -rw-------. 1 boincer umatlas 913418787 Nov 27 16:24 shared/HITS.pool.root.1
[2025-11-27 16:29:13]  *** Contents of shared directory: ***
[2025-11-27 16:29:13] total 1304304
[2025-11-27 16:29:13] -rw-r--r--. 3 boincer umatlas 407884073 Nov 26 00:02 ATLAS.root_0
[2025-11-27 16:29:13] -rw-r--r--. 2 boincer umatlas     15120 Nov 26 00:02 start_atlas.sh
[2025-11-27 16:29:13] -rw-r--r--. 2 boincer umatlas    590086 Nov 26 00:02 input.tar.gz
[2025-11-27 16:29:13] -rw-------. 1 boincer umatlas 913418787 Nov 27 16:24 HITS.pool.root.1
[2025-11-27 16:29:13] -rw-------. 1 boincer umatlas  13680640 Nov 27 16:29 result.tar.gz
16:29:15 (1728670): run_atlas exited; CPU time 283703.523921
16:29:15 (1728670): called boinc_finish(0)

</stderr_txt>
]]>

©2025 CERN