| Name | 7NvLDmLxWP7nsSi4ap6QjLDmwznN0nGgGQJmkKkKDmU8bLDmHdteNm_0 |
| Workunit | 231672718 |
| Created | 17 Apr 2025, 5:27:22 UTC |
| Sent | 17 Apr 2025, 8:13:00 UTC |
| Report deadline | 25 Apr 2025, 8:13:00 UTC |
| Received | 17 Apr 2025, 11:07:42 UTC |
| Server state | Over |
| Outcome | Validate error |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 10696208 |
| Run time | 15 min 9 sec |
| CPU time | 2 min 7 sec |
| Validate state | Invalid |
| Credit | 0.00 |
| Device peak FLOPS | 34.73 GFLOPS |
| Application version | ATLAS Simulation v3.01 (native_mt) x86_64-pc-linux-gnu |
| Peak working set size | 2.05 GB |
| Peak swap size | 2.70 GB |
| Peak disk usage | 438.72 MB |
Stderr output
<core_client_version>8.0.4</core_client_version>
<![CDATA[
<stderr_txt>
12:06:43 (161493): wrapper (7.7.26015): starting
12:06:43 (161493): wrapper: running run_atlas (--nthreads 4)
[2025-04-17 12:06:43] Arguments: --nthreads 4
[2025-04-17 12:06:43] Threads: 4
[2025-04-17 12:06:43] Checking for CVMFS
[2025-04-17 12:06:43] Probing /cvmfs/atlas.cern.ch... OK
[2025-04-17 12:06:43] Probing /cvmfs/atlas-condb.cern.ch... OK
[2025-04-17 12:06:43] Running cvmfs_config stat atlas.cern.ch
[2025-04-17 12:06: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
[2025-04-17 12:06:43] 2.9.2.0 3028 301 76436 144958 3 177 34000169 36864001 0 130560 0 428340 99.995 79959 12293 http://cvmfs-stratum-one.cern.ch:8000/cvmfs/atlas.cern.ch http://130.183.36.13:3128 1
[2025-04-17 12:06:43] CVMFS is ok
[2025-04-17 12:06:43] Efficiency of ATLAS tasks can be improved by the following measure(s):
[2025-04-17 12:06:43] The CVMFS client on this computer should be configured to use Cloudflare's openhtc.io.
[2025-04-17 12:06:43] Further information can be found at the LHC@home message board.
[2025-04-17 12:06:43] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7
[2025-04-17 12:06:43] Checking for apptainer binary...
[2025-04-17 12:06:43] Using apptainer found in PATH at /usr/bin/apptainer
[2025-04-17 12:06:43] Running /usr/bin/apptainer --version
[2025-04-17 12:06:43] apptainer version 1.3.6-6.1
[2025-04-17 12:06:43] Checking apptainer works with /usr/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname
[2025-04-17 12:06:44] belleA253b
[2025-04-17 12:06:44] apptainer works
[2025-04-17 12:06:44] Set ATHENA_PROC_NUMBER=4
[2025-04-17 12:06:44] Set ATHENA_CORE_NUMBER=4
[2025-04-17 12:06:44] Starting ATLAS job with PandaID=6604984211
[2025-04-17 12:06:44] Running command: /usr/bin/apptainer exec -B /cvmfs,/local/data/boinc/slots/0 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 sh start_atlas.sh
12:36:13 (2771): wrapper (7.7.26015): starting
12:36:13 (2771): wrapper: running run_atlas (--nthreads 4)
[2025-04-17 12:36:13] Arguments: --nthreads 4
[2025-04-17 12:36:13] Threads: 4
[2025-04-17 12:36:13] This job has been restarted, cleaning up previous attempt
[2025-04-17 12:36:13] Checking for CVMFS
[2025-04-17 12:36:16] Probing /cvmfs/atlas.cern.ch... OK
[2025-04-17 12:36:17] Probing /cvmfs/atlas-condb.cern.ch... OK
[2025-04-17 12:36:17] Running cvmfs_config stat atlas.cern.ch
[2025-04-17 12:36:17] 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-04-17 12:36:17] 2.9.2.0 2969 0 28856 144962 3 1 34013074 36864001 0 130560 0 0 0.000 1170 1363 http://cvmfs-stratum-one.cern.ch:8000/cvmfs/atlas.cern.ch http://130.183.36.13:3128 1
[2025-04-17 12:36:17] CVMFS is ok
[2025-04-17 12:36:17] Efficiency of ATLAS tasks can be improved by the following measure(s):
[2025-04-17 12:36:17] The CVMFS client on this computer should be configured to use Cloudflare's openhtc.io.
[2025-04-17 12:36:17] Further information can be found at the LHC@home message board.
[2025-04-17 12:36:17] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7
[2025-04-17 12:36:17] Checking for apptainer binary...
[2025-04-17 12:36:17] Using apptainer found in PATH at /usr/bin/apptainer
[2025-04-17 12:36:17] Running /usr/bin/apptainer --version
[2025-04-17 12:36:17] apptainer version 1.3.6-6.1
[2025-04-17 12:36:17] Checking apptainer works with /usr/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname
[2025-04-17 12:36:17] belleA253b
[2025-04-17 12:36:17] apptainer works
[2025-04-17 12:36:17] Set ATHENA_PROC_NUMBER=4
[2025-04-17 12:36:17] Set ATHENA_CORE_NUMBER=4
[2025-04-17 12:36:17] Starting ATLAS job with PandaID=6604984211
[2025-04-17 12:36:17] Running command: /usr/bin/apptainer exec -B /cvmfs,/local/data/boinc/slots/0 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 sh start_atlas.sh
[2025-04-17 12:40:04] *** The last 200 lines of the pilot log: ***
[2025-04-17 12:40:04] 2025-04-17 10:37:33,346 | INFO | PID=7076 has CPU usage=0.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 PR -
[2025-04-17 12:40:04] 2025-04-17 10:37:36,809 | INFO | system load is normal
[2025-04-17 12:40:04] 2025-04-17 10:37:36,809 | INFO | CPU consumption time changed by a factor of 2.142857142857143 (below the limit of 5)
[2025-04-17 12:40:04] 2025-04-17 10:37:36,809 | INFO | (instant) CPU consumption time for pid=13641: 15)
[2025-04-17 12:40:04] 2025-04-17 10:37:36,809 | INFO | using path: /local/data/boinc/slots/0/PanDA_Pilot-6604984211/memory_monitor_output.txt (trf name=prmon)
[2025-04-17 12:40:04] 2025-04-17 10:37:39,312 | INFO | monitor loop #10: job 0:6604984211 is in state 'running'
[2025-04-17 12:40:04] 2025-04-17 10:37:49,874 | INFO | system load is normal
[2025-04-17 12:40:04] 2025-04-17 10:37:49,875 | INFO | CPU consumption time changed by a factor of 1.2666666666666666 (below the limit of 5)
[2025-04-17 12:40:04] 2025-04-17 10:37:49,875 | INFO | (instant) CPU consumption time for pid=13641: 19)
[2025-04-17 12:40:04] 2025-04-17 10:37:49,875 | INFO | using path: /local/data/boinc/slots/0/PanDA_Pilot-6604984211/memory_monitor_output.txt (trf name=prmon)
[2025-04-17 12:40:04] 2025-04-17 10:37:49,875 | INFO | using path: /local/data/boinc/slots/0/PanDA_Pilot-6604984211/memory_monitor_output.txt (trf name=prmon)
[2025-04-17 12:40:04] 2025-04-17 10:37:49,876 | INFO | max memory (maxPSS) used by the payload is within the allowed limit: 1715 B (2.0 (queuedata.pilot_rss_grace) * 8000 (queuedata.maxrss) * 2.0 (scale) = 32768000.0 B
[2025-04-17 12:40:04] 2025-04-17 10:37:49,876 | INFO | payload log (log.EVNTtoHITS) within allowed size limit (2147483648 B): 20995 B
[2025-04-17 12:40:04] 2025-04-17 10:37:49,876 | INFO | payload log (payload.stdout) within allowed size limit (2147483648 B): 9351 B
[2025-04-17 12:40:04] 2025-04-17 10:37:49,876 | INFO | executing command: df -mP /local/data/boinc/slots/0
[2025-04-17 12:40:04] 2025-04-17 10:37:49,894 | INFO | sufficient remaining disk space (322347991040 B)
[2025-04-17 12:40:04] 2025-04-17 10:37:49,894 | INFO | work directory size check will use 2537553920 B as a max limit (10% grace limit added)
[2025-04-17 12:40:04] 2025-04-17 10:37:49,894 | INFO | size of work directory /local/data/boinc/slots/0/PanDA_Pilot-6604984211: 238139 B (within 2537553920 B limit)
[2025-04-17 12:40:04] 2025-04-17 10:37:49,895 | INFO | pfn file=/local/data/boinc/slots/0/PanDA_Pilot-6604984211/HITS.44075483._051005.pool.root.1 does not exist (skip from workdir size calculation)
[2025-04-17 12:40:04] 2025-04-17 10:37:49,895 | INFO | total size of present files: 0 B (workdir size: 238139 B)
[2025-04-17 12:40:04] 2025-04-17 10:37:49,895 | INFO | output file size check: skipping output file /local/data/boinc/slots/0/PanDA_Pilot-6604984211/HITS.44075483._051005.pool.root.1 since it does not exist
[2025-04-17 12:40:04] 2025-04-17 10:37:52,399 | INFO | monitor loop #11: job 0:6604984211 is in state 'running'
[2025-04-17 12:40:04] 2025-04-17 10:37:55,469 | INFO | PID=7076 has CPU usage=1.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 PR -
[2025-04-17 12:40:04] 2025-04-17 10:38:02,999 | INFO | system load is normal
[2025-04-17 12:40:04] 2025-04-17 10:38:03,000 | INFO | CPU consumption time changed by a factor of 1.0 (below the limit of 5)
[2025-04-17 12:40:04] 2025-04-17 10:38:03,000 | INFO | (instant) CPU consumption time for pid=13641: 19)
[2025-04-17 12:40:04] 2025-04-17 10:38:03,000 | INFO | using path: /local/data/boinc/slots/0/PanDA_Pilot-6604984211/memory_monitor_output.txt (trf name=prmon)
[2025-04-17 12:40:04] 2025-04-17 10:38:05,504 | INFO | monitor loop #12: job 0:6604984211 is in state 'running'
[2025-04-17 12:40:04] 2025-04-17 10:38:16,082 | INFO | system load is normal
[2025-04-17 12:40:04] 2025-04-17 10:38:16,083 | INFO | CPU consumption time changed by a factor of 1.0 (below the limit of 5)
[2025-04-17 12:40:04] 2025-04-17 10:38:16,083 | INFO | (instant) CPU consumption time for pid=13641: 19)
[2025-04-17 12:40:04] 2025-04-17 10:38:16,083 | INFO | using path: /local/data/boinc/slots/0/PanDA_Pilot-6604984211/memory_monitor_output.txt (trf name=prmon)
[2025-04-17 12:40:04] 2025-04-17 10:38:17,593 | INFO | PID=7076 has CPU usage=1.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 PR -
[2025-04-17 12:40:04] 2025-04-17 10:38:18,587 | INFO | monitor loop #13: job 0:6604984211 is in state 'running'
[2025-04-17 12:40:04] 2025-04-17 10:38:28,625 | INFO | 122s have passed since pilot start - server update state is 'RUNNING'
[2025-04-17 12:40:04] 2025-04-17 10:38:29,188 | INFO | system load is normal
[2025-04-17 12:40:04] 2025-04-17 10:38:29,189 | INFO | CPU consumption time changed by a factor of 1.0 (below the limit of 5)
[2025-04-17 12:40:04] 2025-04-17 10:38:29,189 | INFO | (instant) CPU consumption time for pid=13641: 19)
[2025-04-17 12:40:04] 2025-04-17 10:38:29,189 | INFO | using path: /local/data/boinc/slots/0/PanDA_Pilot-6604984211/memory_monitor_output.txt (trf name=prmon)
[2025-04-17 12:40:04] 2025-04-17 10:38:31,693 | INFO | monitor loop #14: job 0:6604984211 is in state 'running'
[2025-04-17 12:40:04] 2025-04-17 10:38:39,718 | INFO | PID=7076 has CPU usage=1.0% 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 PR -
[2025-04-17 12:40:04] 2025-04-17 10:38:42,258 | INFO | system load is normal
[2025-04-17 12:40:04] 2025-04-17 10:38:42,258 | INFO | CPU consumption time changed by a factor of 1.0 (below the limit of 5)
[2025-04-17 12:40:04] 2025-04-17 10:38:42,258 | INFO | (instant) CPU consumption time for pid=13641: 19)
[2025-04-17 12:40:04] 2025-04-17 10:38:42,258 | INFO | using path: /local/data/boinc/slots/0/PanDA_Pilot-6604984211/memory_monitor_output.txt (trf name=prmon)
[2025-04-17 12:40:04] 2025-04-17 10:38:44,763 | INFO | monitor loop #15: job 0:6604984211 is in state 'running'
[2025-04-17 12:40:04] 2025-04-17 10:38:55,362 | INFO | system load is normal
[2025-04-17 12:40:04] 2025-04-17 10:38:55,362 | INFO | CPU consumption time changed by a factor of 1.0 (below the limit of 5)
[2025-04-17 12:40:04] 2025-04-17 10:38:55,362 | INFO | (instant) CPU consumption time for pid=13641: 19)
[2025-04-17 12:40:04] 2025-04-17 10:38:55,363 | INFO | using path: /local/data/boinc/slots/0/PanDA_Pilot-6604984211/memory_monitor_output.txt (trf name=prmon)
[2025-04-17 12:40:04] 2025-04-17 10:38:57,867 | INFO | monitor loop #16: job 0:6604984211 is in state 'running'
[2025-04-17 12:40:04] 2025-04-17 10:39:01,842 | INFO | PID=7076 has CPU usage=1.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 PR -
[2025-04-17 12:40:04] 2025-04-17 10:39:08,466 | INFO | system load is normal
[2025-04-17 12:40:04] 2025-04-17 10:39:08,466 | INFO | CPU consumption time changed by a factor of 1.0 (below the limit of 5)
[2025-04-17 12:40:04] 2025-04-17 10:39:08,467 | INFO | (instant) CPU consumption time for pid=13641: 19)
[2025-04-17 12:40:04] 2025-04-17 10:39:08,467 | INFO | using path: /local/data/boinc/slots/0/PanDA_Pilot-6604984211/memory_monitor_output.txt (trf name=prmon)
[2025-04-17 12:40:04] 2025-04-17 10:39:08,467 | INFO | using path: /local/data/boinc/slots/0/PanDA_Pilot-6604984211/memory_monitor_output.txt (trf name=prmon)
[2025-04-17 12:40:04] 2025-04-17 10:39:08,467 | INFO | max memory (maxPSS) used by the payload is within the allowed limit: 161486 B (2.0 (queuedata.pilot_rss_grace) * 8000 (queuedata.maxrss) * 2.0 (scale) = 32768000.0
[2025-04-17 12:40:04] 2025-04-17 10:39:08,468 | INFO | payload log (log.EVNTtoHITS) within allowed size limit (2147483648 B): 20995 B
[2025-04-17 12:40:04] 2025-04-17 10:39:08,468 | INFO | payload log (payload.stdout) within allowed size limit (2147483648 B): 10113 B
[2025-04-17 12:40:04] 2025-04-17 10:39:08,468 | INFO | executing command: df -mP /local/data/boinc/slots/0
[2025-04-17 12:40:04] 2025-04-17 10:39:08,488 | INFO | sufficient remaining disk space (322347991040 B)
[2025-04-17 12:40:04] 2025-04-17 10:39:08,488 | INFO | work directory size check will use 2537553920 B as a max limit (10% grace limit added)
[2025-04-17 12:40:04] 2025-04-17 10:39:08,488 | INFO | size of work directory /local/data/boinc/slots/0/PanDA_Pilot-6604984211: 240146 B (within 2537553920 B limit)
[2025-04-17 12:40:04] 2025-04-17 10:39:08,489 | INFO | pfn file=/local/data/boinc/slots/0/PanDA_Pilot-6604984211/HITS.44075483._051005.pool.root.1 does not exist (skip from workdir size calculation)
[2025-04-17 12:40:04] 2025-04-17 10:39:08,489 | INFO | total size of present files: 0 B (workdir size: 240146 B)
[2025-04-17 12:40:04] 2025-04-17 10:39:08,489 | INFO | output file size check: skipping output file /local/data/boinc/slots/0/PanDA_Pilot-6604984211/HITS.44075483._051005.pool.root.1 since it does not exist
[2025-04-17 12:40:04] 2025-04-17 10:39:10,993 | INFO | monitor loop #17: job 0:6604984211 is in state 'running'
[2025-04-17 12:40:04] 2025-04-17 10:39:21,571 | INFO | system load is normal
[2025-04-17 12:40:04] 2025-04-17 10:39:21,571 | INFO | CPU consumption time changed by a factor of 1.0 (below the limit of 5)
[2025-04-17 12:40:04] 2025-04-17 10:39:21,571 | INFO | (instant) CPU consumption time for pid=13641: 19)
[2025-04-17 12:40:04] 2025-04-17 10:39:21,571 | INFO | using path: /local/data/boinc/slots/0/PanDA_Pilot-6604984211/memory_monitor_output.txt (trf name=prmon)
[2025-04-17 12:40:04] File "/local/data/boinc/slots/0/pilot3/pilot/common/exception.py", line 466, in run
[2025-04-17 12:40:04] self._target(**self.kwargs)
[2025-04-17 12:40:04] File "/local/data/boinc/slots/0/pilot3/pilot/control/monitor.py", line 208, in control
[2025-04-17 12:40:04] raise PilotException(error) from error
[2025-04-17 12:40:04] monitor: exception caught: memory_info() takes 1 positional argument but 2 were given
[2025-04-17 12:40:04] unexpected exception caught by thread run() function: (<class 'pilot.common.exception.PilotException'>, PilotException(TypeError('memory_info() takes 1 positional argument but 2 were given')), <traceb
[2025-04-17 12:40:04] Traceback (most recent call last):
[2025-04-17 12:40:04] File "/local/data/boinc/slots/0/pilot3/pilot/control/monitor.py", line 183, in control
[2025-04-17 12:40:04] processes = get_process_info('python3 pilot3/pilot.py', pid=getpid())
[2025-04-17 12:40:04] File "/local/data/boinc/slots/0/pilot3/pilot/util/psutils.py", line 398, in get_process_info
[2025-04-17 12:40:04] for proc in psutil.process_iter(['pid', 'username', 'cpu_percent', 'memory_percent', 'cmdline']):
[2025-04-17 12:40:04] File "/cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase/x86_64/psutil/6.0.0-x86_64-centos7/lib/python3.9/site-packages/psutil/__init__.py", line 1516, in process_iter
[2025-04-17 12:40:04] proc.info = proc.as_dict(attrs=attrs, ad_value=ad_value)
[2025-04-17 12:40:04] File "/cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase/x86_64/psutil/6.0.0-x86_64-centos7/lib/python3.9/site-packages/psutil/__init__.py", line 558, in as_dict
[2025-04-17 12:40:04] ret = meth()
[2025-04-17 12:40:04] File "/cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase/x86_64/psutil/6.0.0-x86_64-centos7/lib/python3.9/site-packages/psutil/__init__.py", line 1151, in memory_percent
[2025-04-17 12:40:04] metrics = fun()
[2025-04-17 12:40:04] File "/cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase/x86_64/psutil/6.0.0-x86_64-centos7/lib/python3.9/site-packages/psutil/_common.py", line 515, in wrapper
[2025-04-17 12:40:04] raise raise_from(err, None)
[2025-04-17 12:40:04] File "<string>", line 3, in raise_from
[2025-04-17 12:40:04] File "/cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase/x86_64/psutil/6.0.0-x86_64-centos7/lib/python3.9/site-packages/psutil/_common.py", line 513, in wrapper
[2025-04-17 12:40:04] ret = fun(self)
[2025-04-17 12:40:04] File "/cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase/x86_64/psutil/6.0.0-x86_64-centos7/lib/python3.9/site-packages/psutil/__init__.py", line 1107, in memory_info
[2025-04-17 12:40:04] return self._proc.memory_info()
[2025-04-17 12:40:04] File "/cvmfs/atlas.cern.ch/repo/ATLASLocalRootBase/x86_64/psutil/6.0.0-x86_64-centos7/lib/python3.9/site-packages/psutil/_pslinux.py", line 1717, in wrapper
[2025-04-17 12:40:04] return fun(self, *args, **kwargs)
[2025-04-17 12:40:04] TypeError: memory_info() takes 1 positional argument but 2 were given
[2025-04-17 12:40:04]
[2025-04-17 12:40:04] The above exception was the direct cause of the following exception:
[2025-04-17 12:40:04]
[2025-04-17 12:40:04] Traceback (most recent call last):
[2025-04-17 12:40:04] File "/local/data/boinc/slots/0/pilot3/pilot/common/exception.py", line 466, in run
[2025-04-17 12:40:04] self._target(**self.kwargs)
[2025-04-17 12:40:04] File "/local/data/boinc/slots/0/pilot3/pilot/control/monitor.py", line 208, in control
[2025-04-17 12:40:04] raise PilotException(error) from error
[2025-04-17 12:40:04] pilot.common.exception.PilotException: error code: 1301, message: An unknown pilot exception has occurred
[2025-04-17 12:40:04] details: memory_info() takes 1 positional argument but 2 were given
[2025-04-17 12:40:04]
[2025-04-17 12:40:04] None
[2025-04-17 12:40:04] exception has been put in bucket queue belonging to thread 'monitor'
[2025-04-17 12:40:04] setting graceful stop in 10 s since there is no point in continuing
[2025-04-17 12:40:04] 2025-04-17 10:39:24,076 | INFO | monitor loop #18: job 0:6604984211 is in state 'running'
[2025-04-17 12:40:04] received exception from bucket queue in generic workflow: error code: 1301, message: An unknown pilot exception has occurred
[2025-04-17 12:40:04] details: memory_info() takes 1 positional argument but 2 were given
[2025-04-17 12:40:04] 2025-04-17 10:39:34,183 | INFO | all data control threads have been joined
[2025-04-17 12:40:04] 2025-04-17 10:39:34,268 | INFO | all job control threads have been joined
[2025-04-17 12:40:04] 2025-04-17 10:39:34,351 | WARNING | data:copytool_out:received graceful stop - abort after this iteration
[2025-04-17 12:40:04] 2025-04-17 10:39:34,676 | INFO | system load is normal
[2025-04-17 12:40:04] 2025-04-17 10:39:34,676 | INFO | CPU consumption time changed by a factor of 1.0 (below the limit of 5)
[2025-04-17 12:40:04] 2025-04-17 10:39:34,676 | INFO | (instant) CPU consumption time for pid=13641: 19)
[2025-04-17 12:40:04] 2025-04-17 10:39:34,676 | INFO | using path: /local/data/boinc/slots/0/PanDA_Pilot-6604984211/memory_monitor_output.txt (trf name=prmon)
[2025-04-17 12:40:04] 2025-04-17 10:39:34,677 | WARNING | job:job_monitor:received graceful stop - abort after this iteration
[2025-04-17 12:40:04] 2025-04-17 10:39:34,677 | INFO | will abort loop
[2025-04-17 12:40:04] 2025-04-17 10:39:34,698 | INFO | all payload control threads have been joined
[2025-04-17 12:40:04] 2025-04-17 10:39:34,904 | INFO | breaking -- sending SIGTERM to pid=13641
[2025-04-17 12:40:04] 2025-04-17 10:39:34,904 | INFO | breaking -- sleep 10 s before sending SIGKILL pid=13641
[2025-04-17 12:40:04] 2025-04-17 10:39:35,189 | INFO | [data] control thread has finished
[2025-04-17 12:40:04] 2025-04-17 10:39:35,274 | INFO | [job] control thread has finished
[2025-04-17 12:40:04] 2025-04-17 10:39:35,597 | INFO | [job] retrieve thread has finished
[2025-04-17 12:40:04] 2025-04-17 10:39:35,651 | INFO | [job] validate thread has finished
[2025-04-17 12:40:04] 2025-04-17 10:39:35,683 | INFO | [job] job monitor thread has finished
[2025-04-17 12:40:04] 2025-04-17 10:39:35,704 | INFO | [payload] control thread has finished
[2025-04-17 12:40:04] 2025-04-17 10:39:35,765 | INFO | [job] create_data_payload thread has finished
[2025-04-17 12:40:04] 2025-04-17 10:39:35,771 | WARNING | data:queue_monitoring:received graceful stop - abort after this iteration
[2025-04-17 12:40:04] 2025-04-17 10:39:35,833 | INFO | [payload] validate_pre thread has finished
[2025-04-17 12:40:04] 2025-04-17 10:39:36,227 | WARNING | job:queue_monitor:received graceful stop - abort after this iteration
[2025-04-17 12:40:04] 2025-04-17 10:39:36,227 | WARNING | since job:queue_monitor is responsible for sending job updates, we sleep for 20 s
[2025-04-17 12:40:04] 2025-04-17 10:39:36,352 | INFO | [payload] validate_post thread has finished
[2025-04-17 12:40:04] 2025-04-17 10:39:36,352 | INFO | [payload] failed_post thread has finished
[2025-04-17 12:40:04] 2025-04-17 10:39:36,357 | INFO | [data] copytool_out thread has finished
[2025-04-17 12:40:04] 2025-04-17 10:39:36,447 | INFO | [data] copytool_in thread has finished
[2025-04-17 12:40:04] 2025-04-17 10:39:36,586 | INFO | job.realtimelogging is not enabled
[2025-04-17 12:40:04] 2025-04-17 10:39:37,591 | INFO | [payload] run_realtimelog thread has finished
[2025-04-17 12:40:04] 2025-04-17 10:39:39,778 | INFO | [data] queue_monitor thread has finished
[2025-04-17 12:40:04] 2025-04-17 10:39:44,922 | INFO |
[2025-04-17 12:40:04]
[2025-04-17 12:40:04] finished pid=13641 exit_code=None state=failed
[2025-04-17 12:40:04]
[2025-04-17 12:40:04] 2025-04-17 10:39:44,922 | WARNING | detected unset exit_code from wait_graceful - reset to -1
[2025-04-17 12:40:04] 2025-04-17 10:39:44,923 | INFO | using pid=19682 to kill prmon
[2025-04-17 12:40:04] 2025-04-17 10:39:44,923 | INFO | stopping utility process 'MemoryMonitor' with signal 10
[2025-04-17 12:40:04] 2025-04-17 10:39:44,923 | INFO | process 19682 no longer exists
[2025-04-17 12:40:04] 2025-04-17 10:39:44,923 | INFO | utility process 13642 cleanup finished with status=True
[2025-04-17 12:40:04] 2025-04-17 10:39:44,924 | INFO | taking a short nap (3 s) to allow the memory monitor to finish writing to the summary file (#0/#20)
[2025-04-17 12:40:04] 2025-04-17 10:39:47,930 | INFO | copied /local/data/boinc/slots/0/PanDA_Pilot-6604984211/memory_monitor_summary.json to /local/data/boinc/slots/0
[2025-04-17 12:40:04] 2025-04-17 10:39:47,939 | WARNING | found lingering processes - will now be removed
[2025-04-17 12:40:04] 2025-04-17 10:39:47,939 | INFO | 1: 20257
[2025-04-17 12:40:04] 2025-04-17 10:39:47,939 | INFO | killed process 20257 with signal=9
[2025-04-17 12:40:04] 2025-04-17 10:39:47,939 | INFO | CPU consumption time: 11.719999999999999 s (rounded to 12 s)
[2025-04-17 12:40:04] 2025-04-17 10:39:47,939 | WARNING | main payload execution returned non-zero exit code: -1
[2025-04-17 12:40:04] 2025-04-17 10:39:47,939 | INFO | scanning dmesg message for subprocess=20257 for memory errors
[2025-04-17 12:40:04] 2025-04-17 10:39:47,939 | INFO | executing command: dmesg|grep 20257
[2025-04-17 12:40:04] 2025-04-17 10:39:47,960 | INFO | scanning dmesg message for subprocess=20249 for memory errors
[2025-04-17 12:40:04] 2025-04-17 10:39:47,960 | INFO | executing command: dmesg|grep 20249
[2025-04-17 12:40:04] 2025-04-17 10:39:47,978 | INFO | scanning dmesg message for subprocess=20250 for memory errors
[2025-04-17 12:40:04] 2025-04-17 10:39:47,978 | INFO | executing command: dmesg|grep 20250
[2025-04-17 12:40:04] 2025-04-17 10:39:47,996 | INFO | scanning dmesg message for subprocess=20251 for memory errors
[2025-04-17 12:40:04] 2025-04-17 10:39:47,996 | INFO | executing command: dmesg|grep 20251
[2025-04-17 12:40:04] 2025-04-17 10:39:48,014 | INFO | scanning dmesg message for subprocess=20223 for memory errors
[2025-04-17 12:40:04] 2025-04-17 10:39:48,014 | INFO | executing command: dmesg|grep 20223
[2025-04-17 12:40:04] 2025-04-17 10:39:48,034 | WARNING | job report does not exist: /local/data/boinc/slots/0/PanDA_Pilot-6604984211/jobReport.json
[2025-04-17 12:40:04] 2025-04-17 10:39:48,034 | WARNING | metadata does not exist: /local/data/boinc/slots/0/PanDA_Pilot-6604984211/metadata.xml
[2025-04-17 12:40:04] 2025-04-17 10:39:48,034 | WARNING | file does not exist: /local/data/boinc/slots/0/PanDA_Pilot-6604984211/metadata.xml
[2025-04-17 12:40:04] 2025-04-17 10:39:48,034 | INFO | generated guid for lfn=HITS.44075483._051005.pool.root.1: 34205E10-3654-4306-93D1-2210AAC57718
[2025-04-17 12:40:04] 2025-04-17 10:39:48,034 | WARNING | aborting payload error diagnosis since an error has already been set: [1315, 1187]
[2025-04-17 12:40:04] 2025-04-17 10:39:49,079 | INFO | [payload] execute_payloads thread has finished
[2025-04-17 12:40:04] 2025-04-17 10:39:49,617 | INFO | waiting for thread to finish: ['<_MainThread(MainThread, started 140513157760832)>', '<ExcThread(queue_monitor, started 140512002569984)>']
[2025-04-17 12:40:04] 2025-04-17 10:39:51,628 | INFO | waiting for thread to finish: ['<_MainThread(MainThread, started 140513157760832)>', '<ExcThread(queue_monitor, started 140512002569984)>']
[2025-04-17 12:40:04] 2025-04-17 10:39:53,639 | INFO | waiting for thread to finish: ['<_MainThread(MainThread, started 140513157760832)>', '<ExcThread(queue_monitor, started 140512002569984)>']
[2025-04-17 12:40:04] 2025-04-17 10:39:55,651 | INFO | waiting for thread to finish: ['<_MainThread(MainThread, started 140513157760832)>', '<ExcThread(queue_monitor, started 140512002569984)>']
[2025-04-17 12:40:04] 2025-04-17 10:39:57,662 | INFO | waiting for thread to finish: ['<_MainThread(MainThread, started 140513157760832)>', '<ExcThread(queue_monitor, started 140512002569984)>']
[2025-04-17 12:40:04] 2025-04-17 10:39:59,304 | INFO | waiting for thread to finish: ['<_MainThread(MainThread, started 140513157760832)>', '<ExcThread(queue_monitor, started 140512002569984)>']
[2025-04-17 12:40:04] 2025-04-17 10:39:59,304 | INFO | [job] queue monitor thread has finished
[2025-04-17 12:40:04] 2025-04-17 10:39:59,673 | INFO | caller=run is remaining thread - safe to abort (names=['<_MainThread(MainThread, started 140513157760832)>'])
[2025-04-17 12:40:04] 2025-04-17 10:40:04,699 | INFO | all workflow threads have been joined
[2025-04-17 12:40:04] 2025-04-17 10:40:04,699 | INFO | end of generic workflow (traces error code: 0)
[2025-04-17 12:40:04] 2025-04-17 10:40:04,699 | INFO | traces error code: 0
[2025-04-17 12:40:04] 2025-04-17 10:40:04,699 | INFO | pilot has finished (exit code=0, shell exit code=0)
[2025-04-17 12:40:04] 2025-04-17 10:40:04,769 [wrapper] ==== pilot stdout END ====
[2025-04-17 12:40:04] 2025-04-17 10:40:04,774 [wrapper] ==== wrapper stdout RESUME ====
[2025-04-17 12:40:04] 2025-04-17 10:40:04,778 [wrapper] pilotpid: 7076
[2025-04-17 12:40:04] 2025-04-17 10:40:04,782 [wrapper] Pilot exit status: 0
[2025-04-17 12:40:04] 2025-04-17 10:40:04,795 [wrapper] pandaids: 6604984211 6604984211
[2025-04-17 12:40:04] 2025-04-17 10:40:04,819 [wrapper] cleanup supervisor_pilot 7082 7077
[2025-04-17 12:40:04] 2025-04-17 10:40:04,823 [wrapper] Test setup, not cleaning
[2025-04-17 12:40:04] 2025-04-17 10:40:04,828 [wrapper] apfmon messages muted
[2025-04-17 12:40:04] 2025-04-17 10:40:04,832 [wrapper] ==== wrapper stdout END ====
[2025-04-17 12:40:04] 2025-04-17 10:40:04,837 [wrapper] ==== wrapper stderr END ====
[2025-04-17 12:40:04] *** Error codes and diagnostics ***
[2025-04-17 12:40:04] *** Listing of results directory ***
[2025-04-17 12:40:04] total 445588
[2025-04-17 12:40:04] drwx------ 4 boinc boinc 4096 Apr 3 10:00 pilot3
[2025-04-17 12:40:04] -rw-r--r-- 1 boinc boinc 495897 Apr 17 07:26 pilot3.tar.gz
[2025-04-17 12:40:04] -rwx------ 1 boinc boinc 37140 Apr 17 07:27 runpilot2-wrapper.sh
[2025-04-17 12:40:04] -rw-r--r-- 1 boinc boinc 5111 Apr 17 07:27 queuedata.json
[2025-04-17 12:40:04] -rw-r--r-- 1 boinc boinc 100 Apr 17 12:06 wrapper_26015_x86_64-pc-linux-gnu
[2025-04-17 12:40:04] -rwxr-xr-x 1 boinc boinc 7986 Apr 17 12:06 run_atlas
[2025-04-17 12:40:04] -rw-r--r-- 1 boinc boinc 105 Apr 17 12:06 job.xml
[2025-04-17 12:40:04] -rw-r--r-- 3 boinc boinc 226059661 Apr 17 12:06 EVNT.44075481._001005.pool.root.1
[2025-04-17 12:40:04] -rw-r--r-- 3 boinc boinc 226059661 Apr 17 12:06 ATLAS.root_0
[2025-04-17 12:40:04] drwxrwx--x 2 boinc boinc 4096 Apr 17 12:06 shared
[2025-04-17 12:40:04] -rw-r--r-- 2 boinc boinc 508885 Apr 17 12:06 input.tar.gz
[2025-04-17 12:40:04] -rw-r--r-- 2 boinc boinc 17569 Apr 17 12:06 start_atlas.sh
[2025-04-17 12:40:04] -rw-r--r-- 1 boinc boinc 0 Apr 17 12:06 boinc_lockfile
[2025-04-17 12:40:04] -rw------- 1 boinc boinc 1618536 Apr 17 12:06 agis_ddmendpoints.agis.ALL.json
[2025-04-17 12:40:04] -rw------- 1 boinc boinc 140786 Apr 17 12:18 log.44075483._051005.job.log.tgz.1
[2025-04-17 12:40:04] -rw-r--r-- 1 boinc boinc 6703 Apr 17 12:36 init_data.xml
[2025-04-17 12:40:04] -rw-r--r-- 1 boinc boinc 2579 Apr 17 12:36 pandaJob.out
[2025-04-17 12:40:04] -rw------- 1 boinc boinc 424 Apr 17 12:36 setup.sh.local
[2025-04-17 12:40:04] -rw------- 1 boinc boinc 990271 Apr 17 12:36 agis_schedconf.cvmfs.json
[2025-04-17 12:40:04] -rw------- 1 boinc boinc 551 Apr 17 12:36 heartbeat.json
[2025-04-17 12:40:04] -rw------- 1 boinc boinc 96 Apr 17 12:38 pilot_heartbeat.json
[2025-04-17 12:40:04] -rw------- 1 boinc boinc 996 Apr 17 12:39 memory_monitor_summary.json
[2025-04-17 12:40:04] drwxrwx--- 2 boinc boinc 4096 Apr 17 12:39 PanDA_Pilot-6604984211
[2025-04-17 12:40:04] -rw-r--r-- 1 boinc boinc 8192 Apr 17 12:39 boinc_mmap_file
[2025-04-17 12:40:04] -rw-r--r-- 1 boinc boinc 24 Apr 17 12:39 wrapper_checkpoint.txt
[2025-04-17 12:40:04] -rw-r--r-- 1 boinc boinc 526 Apr 17 12:39 boinc_task_state.xml
[2025-04-17 12:40:04] -rw------- 1 boinc boinc 43896 Apr 17 12:40 pilotlog.txt
[2025-04-17 12:40:04] -rw------- 1 boinc boinc 59403 Apr 17 12:40 log.44075483._051005.job.log.1
[2025-04-17 12:40:04] -rw-r--r-- 1 boinc boinc 571 Apr 17 12:40 runtime_log
[2025-04-17 12:40:04] -rw------- 1 boinc boinc 71680 Apr 17 12:40 result.tar.gz
[2025-04-17 12:40:04] -rw-r--r-- 1 boinc boinc 10530 Apr 17 12:40 runtime_log.err
[2025-04-17 12:40:04] -rw------- 1 boinc boinc 778 Apr 17 12:40 7NvLDmLxWP7nsSi4ap6QjLDmwznN0nGgGQJmkKkKDmU8bLDmHdteNm.diag
[2025-04-17 12:40:04] -rw-r--r-- 1 boinc boinc 28007 Apr 17 12:40 stderr.txt
[2025-04-17 12:40:04] No HITS result produced
[2025-04-17 12:40:04] *** Contents of shared directory: ***
[2025-04-17 12:40:04] total 221360
[2025-04-17 12:40:04] -rw-r--r-- 3 boinc boinc 226059661 Apr 17 12:06 ATLAS.root_0
[2025-04-17 12:40:04] -rw-r--r-- 2 boinc boinc 508885 Apr 17 12:06 input.tar.gz
[2025-04-17 12:40:04] -rw-r--r-- 2 boinc boinc 17569 Apr 17 12:06 start_atlas.sh
[2025-04-17 12:40:04] -rw------- 1 boinc boinc 71680 Apr 17 12:40 result.tar.gz
12:40:06 (2771): run_atlas exited; CPU time 9.456902
12:40:06 (2771): called boinc_finish(0)
</stderr_txt>
]]>
©2025 CERN