Name emuNDmpVXD3n7Olcko1bjSoqABFKDmABFKDm7AsVDmJaIKDmh7Ewmm_0
Workunit 2303870
Created 1 May 2023, 1:28:06 UTC
Sent 1 May 2023, 1:29:39 UTC
Report deadline 8 May 2023, 1:29:39 UTC
Received 1 May 2023, 2:02:43 UTC
Server state Over
Outcome Success
Client state Done
Exit status 0 (0x00000000)
Computer ID 1498
Run time 30 min 47 sec
CPU time 15 min 57 sec
Validate state Valid
Credit 17.97
Device peak FLOPS 9.74 GFLOPS
Application version ATLAS Simulation v3.01 (native_mt)
x86_64-pc-linux-gnu
Peak working set size 1.52 GB
Peak swap size 2.12 GB
Peak disk usage 81.38 MB

Stderr output

<core_client_version>7.4.25</core_client_version>
<![CDATA[
<stderr_txt>
02:30:43 (30702): wrapper (7.7.26015): starting
02:30:43 (30702): wrapper: running run_atlas (--nthreads 3)
[2023-05-01 02:30:43] Arguments: --nthreads 3
[2023-05-01 02:30:43] Threads: 3
[2023-05-01 02:30:43] Checking for CVMFS
[2023-05-01 02:30:43] Probing /cvmfs/atlas.cern.ch... OK
[2023-05-01 02:30:44] Probing /cvmfs/atlas-condb.cern.ch... OK
[2023-05-01 02:30:44] Running cvmfs_config stat atlas.cern.ch
[2023-05-01 02:30:44] 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
[2023-05-01 02:30:44] 2.9.0.0 2870 64 44020 118477 3 58 3641344 4194305 0 130560 0 91564 98.203 217931 441 http://s1ral-cvmfs.openhtc.io/cvmfs/atlas.cern.ch http://192.168.100.152:3128 1
[2023-05-01 02:30:44] CVMFS is ok
[2023-05-01 02:30:44] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7
[2023-05-01 02:30:44] Checking for apptainer binary...
[2023-05-01 02:30:44] Using apptainer found in PATH at /usr/bin/apptainer
[2023-05-01 02:30:44] Running /usr/bin/apptainer --version
[2023-05-01 02:30:44] apptainer version 1.0.3
[2023-05-01 02:30:44] Checking apptainer works with /usr/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname
[2023-05-01 02:30:45] TeeC16
[2023-05-01 02:30:45] apptainer works
[2023-05-01 02:30:45] Set ATHENA_PROC_NUMBER=3
[2023-05-01 02:30:45] Set ATHENA_CORE_NUMBER=3
[2023-05-01 02:30:45] Starting ATLAS job with PandaID=5836329109
[2023-05-01 02:30:45] Running command: /usr/bin/apptainer exec -B /cvmfs,/home/m/BOINC/slots/2 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 sh start_atlas.sh
[2023-05-01 03:01:27]  *** The last 200 lines of the pilot log: ***
[2023-05-01 03:01:27] 2023-05-01 02:01:13,433 | DEBUG    | pilot.user.atlas.utilities       | get_memory_monitor_info   | summary_dictionary={'Avg': {'nprocs': 4.857, 'nthreads': 5.761, 'pss': 1307075.0, 'rchar': 49517964.
[2023-05-01 03:01:27] 2023-05-01 02:01:13,433 | INFO     | pilot.user.atlas.utilities       | get_memory_monitor_info   | extracted standard info from prmon json
[2023-05-01 03:01:27] 2023-05-01 02:01:13,433 | INFO     | pilot.user.atlas.utilities       | get_memory_monitor_info   | extracted standard memory fields from prmon json
[2023-05-01 03:01:27] 2023-05-01 02:01:13,433 | INFO     | pilot.util.timing                | timing_report             | ..............................
[2023-05-01 03:01:27] 2023-05-01 02:01:13,433 | INFO     | pilot.util.timing                | timing_report             | . Timing measurements:
[2023-05-01 03:01:27] 2023-05-01 02:01:13,433 | INFO     | pilot.util.timing                | timing_report             | . get job = 63 s
[2023-05-01 03:01:27] 2023-05-01 02:01:13,433 | INFO     | pilot.util.timing                | timing_report             | . initial setup = 45 s
[2023-05-01 03:01:27] 2023-05-01 02:01:13,433 | INFO     | pilot.util.timing                | timing_report             | . payload setup = 45 s
[2023-05-01 03:01:27] 2023-05-01 02:01:13,434 | INFO     | pilot.util.timing                | timing_report             | . stage-in = 43 s
[2023-05-01 03:01:27] 2023-05-01 02:01:13,434 | INFO     | pilot.util.timing                | timing_report             | . payload execution = 1227 s
[2023-05-01 03:01:27] 2023-05-01 02:01:13,434 | INFO     | pilot.util.timing                | timing_report             | . stage-out = 361 s
[2023-05-01 03:01:27] 2023-05-01 02:01:13,434 | INFO     | pilot.util.timing                | timing_report             | ..............................
[2023-05-01 03:01:27] 2023-05-01 02:01:13,434 | DEBUG    | pilot.control.job                | send_state                | data={'jobId': '5836329109', 'state': 'finished', 'timestamp': '2023-05-01T03:01:04+00:00', 'siteNam
[2023-05-01 03:01:27] 2023-05-01 02:01:13,435 | DEBUG    | pilot.control.job                | write_heartbeat_to_file   | heartbeat dictionary: {'jobId': '5836329109', 'state': 'finished', 'timestamp': '2023-05-01T03:01:04
[2023-05-01 03:01:27] 2023-05-01 02:01:13,435 | DEBUG    | pilot.control.job                | write_heartbeat_to_file   | wrote heartbeat to file: /home/m/BOINC/slots/2/heartbeat.json
[2023-05-01 03:01:27] 2023-05-01 02:01:13,527 | INFO     | pilot.control.job                | make_job_report           | 
[2023-05-01 03:01:27] 2023-05-01 02:01:13,527 | INFO     | pilot.control.job                | make_job_report           | job summary report
[2023-05-01 03:01:27] 2023-05-01 02:01:13,527 | INFO     | pilot.control.job                | make_job_report           | --------------------------------------------------
[2023-05-01 03:01:27] 2023-05-01 02:01:13,528 | INFO     | pilot.control.job                | make_job_report           | PanDA job id: 5836329109
[2023-05-01 03:01:27] 2023-05-01 02:01:13,528 | INFO     | pilot.control.job                | make_job_report           | task id: NULL
[2023-05-01 03:01:27] 2023-05-01 02:01:13,528 | INFO     | pilot.control.job                | make_job_report           | errors: (none)
[2023-05-01 03:01:27] 2023-05-01 02:01:13,528 | INFO     | pilot.control.job                | make_job_report           | status: LOG_TRANSFER = DONE 
[2023-05-01 03:01:27] 2023-05-01 02:01:13,528 | INFO     | pilot.control.job                | make_job_report           | pilot state: finished 
[2023-05-01 03:01:27] 2023-05-01 02:01:13,528 | INFO     | pilot.control.job                | make_job_report           | transexitcode: 0
[2023-05-01 03:01:27] 2023-05-01 02:01:13,528 | INFO     | pilot.control.job                | make_job_report           | exeerrorcode: 0
[2023-05-01 03:01:27] 2023-05-01 02:01:13,528 | INFO     | pilot.control.job                | make_job_report           | exeerrordiag: 
[2023-05-01 03:01:27] 2023-05-01 02:01:13,528 | INFO     | pilot.control.job                | make_job_report           | exitcode: 0
[2023-05-01 03:01:27] 2023-05-01 02:01:13,528 | INFO     | pilot.control.job                | make_job_report           | exitmsg: OK
[2023-05-01 03:01:27] 2023-05-01 02:01:13,528 | INFO     | pilot.control.job                | make_job_report           | cpuconsumptiontime: 960 s
[2023-05-01 03:01:27] 2023-05-01 02:01:13,528 | INFO     | pilot.control.job                | make_job_report           | nevents: 2
[2023-05-01 03:01:27] 2023-05-01 02:01:13,528 | INFO     | pilot.control.job                | make_job_report           | neventsw: 0
[2023-05-01 03:01:27] 2023-05-01 02:01:13,528 | INFO     | pilot.control.job                | make_job_report           | pid: 9538
[2023-05-01 03:01:27] 2023-05-01 02:01:13,529 | INFO     | pilot.control.job                | make_job_report           | pgrp: 9538
[2023-05-01 03:01:27] 2023-05-01 02:01:13,529 | INFO     | pilot.control.job                | make_job_report           | corecount: 3
[2023-05-01 03:01:27] 2023-05-01 02:01:13,529 | INFO     | pilot.control.job                | make_job_report           | event service: False
[2023-05-01 03:01:27] 2023-05-01 02:01:13,529 | INFO     | pilot.control.job                | make_job_report           | sizes: {0: 2578677, 1: 2578876, 11: 2579022, 22: 2579050, 33: 2579078, 43: 2579106, 48: 2579322, 54:
[2023-05-01 03:01:27] 2023-05-01 02:01:13,529 | INFO     | pilot.control.job                | make_job_report           | --------------------------------------------------
[2023-05-01 03:01:27] 2023-05-01 02:01:13,529 | INFO     | pilot.control.job                | make_job_report           | 
[2023-05-01 03:01:27] 2023-05-01 02:01:13,529 | DEBUG    | pilot.control.job                | has_job_completed         | ls -lF /home/m/BOINC/slots/2:
[2023-05-01 03:01:27] 
[2023-05-01 03:01:27] 2023-05-01 02:01:13,529 | INFO     | pilot.util.container             | print_executable          | executing command: ls -lF /home/m/BOINC/slots/2
[2023-05-01 03:01:27] 2023-05-01 02:01:13,544 | DEBUG    | pilot.control.job                | has_job_completed         | total 44044
[2023-05-01 03:01:27] -rw-r--r-- 2 m m 38217911 May  1 02:30 EVNT.04972714._000030.pool.root.1
[2023-05-01 03:01:27] drwxrwx--- 2 m m     4096 May  1 03:01 PanDA_Pilot-5836329109/
[2023-05-01 03:01:27] -rw------- 1 m m  1013424 May  1 02:30 agis_schedconf.cvmfs.json
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m        0 May  1 02:30 boinc_lockfile
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m     8192 May  1 03:00 boinc_mmap_file
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m      533 May  1 02:55 boinc_task_state.xml
[2023-05-01 03:01:27] -rw------- 1 m m  1446236 May  1 02:31 cric_ddmendpoints.json
[2023-05-01 03:01:27] -rw------- 1 m m   294984 May  1 03:01 e8c31bee-87ba-43db-b151-c60132f7bfed_80890.1.job.log
[2023-05-01 03:01:27] -rw------- 1 m m   169690 May  1 02:57 e8c31bee-87ba-43db-b151-c60132f7bfed_80890.1.job.log.tgz
[2023-05-01 03:01:27] -rw------- 1 m m      129 May  1 02:30 emuNDmpVXD3n7Olcko1bjSoqABFKDmABFKDm7AsVDmJaIKDmh7Ewmm.diag
[2023-05-01 03:01:27] -rw------- 1 m m     7345 May  1 03:01 heartbeat.json
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m     5708 May  1 02:30 init_data.xml
[2023-05-01 03:01:27] -rw-r--r-- 2 m m   425368 May  1 02:30 input.tar.gz
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m      112 May  1 02:30 job.xml
[2023-05-01 03:01:27] -rw------- 1 m m     1019 May  1 02:54 memory_monitor_summary.json
[2023-05-01 03:01:27] -rw------- 1 m m  2662060 May  1 02:54 output.1.e8c31bee-87ba-43db-b151-c60132f7bfed_80890.pool.root
[2023-05-01 03:01:27] -rw------- 1 m m      464 May  1 03:01 output.list
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m     2662 May  1 02:30 pandaJob.out
[2023-05-01 03:01:27] drwx------ 4 m m     4096 May  1 02:32 pilot3/
[2023-05-01 03:01:27] -rw------- 1 m m   413524 May  1 02:28 pilot3.tar.gz
[2023-05-01 03:01:27] -rw------- 1 m m   281293 May  1 03:01 pilotlog.txt
[2023-05-01 03:01:27] -rw-r--r-- 1 m m     4388 May  1 02:26 queuedata.json
[2023-05-01 03:01:27] -rwxr-xr-x 1 m m     7986 May  1 02:30 run_atlas*
[2023-05-01 03:01:27] -rwx------ 1 m m    27540 May  1 02:28 runpilot2-wrapper.sh*
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m      407 May  1 02:30 runtime_log
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m     7699 May  1 02:30 runtime_log.err
[2023-05-01 03:01:27] -rw------- 1 m m      424 May  1 02:30 setup.sh.local
[2023-05-01 03:01:27] drwxrwx--x 2 m m     4096 May  1 02:30 shared/
[2023-05-01 03:01:27] -rw-r--r-- 2 m m    17632 May  1 02:30 start_atlas.sh
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m     1727 May  1 02:30 stderr.txt
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m      107 May  1 02:30 wrapper_26015_x86_64-pc-linux-gnu
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m       25 May  1 03:00 wrapper_checkpoint.txt
[2023-05-01 03:01:27] 2023-05-01 02:01:13,544 | INFO     | pilot.util.queuehandling         | queue_report              | queue jobs had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,545 | INFO     | pilot.util.queuehandling         | queue_report              | queue payloads had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,545 | INFO     | pilot.util.queuehandling         | queue_report              | queue data_in had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,545 | INFO     | pilot.util.queuehandling         | queue_report              | queue data_out had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,545 | INFO     | pilot.util.queuehandling         | queue_report              | queue current_data_in had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,545 | INFO     | pilot.util.queuehandling         | queue_report              | queue validated_jobs had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,545 | INFO     | pilot.util.queuehandling         | queue_report              | queue validated_payloads had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,545 | INFO     | pilot.util.queuehandling         | queue_report              | queue monitored_payloads had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,545 | INFO     | pilot.util.queuehandling         | queue_report              | queue finished_jobs had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,545 | INFO     | pilot.util.queuehandling         | queue_report              | queue finished_payloads had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,546 | INFO     | pilot.util.queuehandling         | queue_report              | queue finished_data_in had 1 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,546 | INFO     | pilot.util.queuehandling         | queue_report              | queue finished_data_out had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,546 | INFO     | pilot.util.queuehandling         | queue_report              | queue failed_jobs had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,546 | INFO     | pilot.util.queuehandling         | queue_report              | queue failed_payloads had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,546 | INFO     | pilot.util.queuehandling         | queue_report              | queue failed_data_in had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,546 | INFO     | pilot.util.queuehandling         | queue_report              | queue failed_data_out had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,546 | INFO     | pilot.util.queuehandling         | queue_report              | queue completed_jobs had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,546 | INFO     | pilot.util.queuehandling         | queue_report              | queue completed_jobids has 1 job(s)
[2023-05-01 03:01:27] 2023-05-01 02:01:13,546 | INFO     | pilot.util.queuehandling         | queue_report              | queue realtimelog_payloads had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,546 | INFO     | pilot.util.queuehandling         | queue_report              | queue messages had 0 job(s) [purged]
[2023-05-01 03:01:27] 2023-05-01 02:01:13,547 | INFO     | pilot.control.job                | has_job_completed         | job 5836329109 has completed (purged errors)
[2023-05-01 03:01:27] 2023-05-01 02:01:13,547 | DEBUG    | pilot.util.realtimelogger        | cleanup                   | attempting real-time logger cleanup
[2023-05-01 03:01:27] 2023-05-01 02:01:13,547 | INFO     | pilot.util.processes             | cleanup                   | overall cleanup function is called
[2023-05-01 03:01:27] 2023-05-01 02:01:13,549 | DEBUG    | pilot.util.processes             | cleanup                   | work directory was removed: /home/m/BOINC/slots/2/PanDA_Pilot-5836329109
[2023-05-01 03:01:27] 2023-05-01 02:01:13,556 | WARNING  | pilot.control.job                | check_job_monitor_waiting_time | no jobs in monitored_payloads queue (waited for 62 s)
[2023-05-01 03:01:27] 2023-05-01 02:01:14,553 | INFO     | pilot.info.jobdata               | collect_zombies           | --- collectZombieJob: --- 10, [9538]
[2023-05-01 03:01:27] 2023-05-01 02:01:14,554 | INFO     | pilot.info.jobdata               | collect_zombies           | zombie collector trying to kill pid 9538
[2023-05-01 03:01:27] 2023-05-01 02:01:14,554 | INFO     | pilot.info.jobdata               | collect_zombies           | harmless exception when collecting zombies: [Errno 10] No child processes
[2023-05-01 03:01:27] 2023-05-01 02:01:15,556 | INFO     | pilot.util.processes             | cleanup                   | collected zombie processes
[2023-05-01 03:01:27] 2023-05-01 02:01:15,557 | INFO     | pilot.util.processes             | cleanup                   | will now attempt to kill all subprocesses of pid=9538
[2023-05-01 03:01:27] 2023-05-01 02:01:15,597 | INFO     | pilot.util.processes             | kill_processes            | process IDs to be killed: [9538] (in reverse order)
[2023-05-01 03:01:27] 2023-05-01 02:01:15,634 | WARNING  | pilot.util.processes             | kill_processes            | found no corresponding commands to process id(s)
[2023-05-01 03:01:27] 2023-05-01 02:01:15,634 | INFO     | pilot.util.processes             | kill_orphans              | Do not look for orphan processes in BOINC jobs
[2023-05-01 03:01:27] 2023-05-01 02:01:15,635 | DEBUG    | pilot.util.queuehandling         | purge_queue               | queue purged
[2023-05-01 03:01:27] 2023-05-01 02:01:15,635 | INFO     | pilot.control.job                | retrieve                  | ready for new job
[2023-05-01 03:01:27] 2023-05-01 02:01:15,635 | INFO     | root                             | retrieve                  | pilot has finished with previous job - re-establishing logging
[2023-05-01 03:01:27] 2023-05-01 02:01:15,636 | INFO     | pilot.util.auxiliary             | pilot_version_banner      | ****************************************
[2023-05-01 03:01:27] 2023-05-01 02:01:15,636 | INFO     | pilot.util.auxiliary             | pilot_version_banner      | ***  PanDA Pilot version 3.6.0 (68)  ***
[2023-05-01 03:01:27] 2023-05-01 02:01:15,636 | INFO     | pilot.util.auxiliary             | pilot_version_banner      | ****************************************
[2023-05-01 03:01:27] 2023-05-01 02:01:15,636 | INFO     | pilot.util.auxiliary             | pilot_version_banner      | 
[2023-05-01 03:01:27] 2023-05-01 02:01:15,656 | INFO     | pilot.util.auxiliary             | display_architecture_info | architecture information:
[2023-05-01 03:01:27] 2023-05-01 02:01:15,657 | INFO     | pilot.util.container             | print_executable          | executing command: cat /etc/os-release
[2023-05-01 03:01:27] 2023-05-01 02:01:15,669 | INFO     | pilot.util.filehandling          | dump                      | cat /etc/os-release:
[2023-05-01 03:01:27] NAME="CentOS Linux"
[2023-05-01 03:01:27] VERSION="7 (Core)"
[2023-05-01 03:01:27] ID="centos"
[2023-05-01 03:01:27] ID_LIKE="rhel fedora"
[2023-05-01 03:01:27] VERSION_ID="7"
[2023-05-01 03:01:27] PRETTY_NAME="CentOS Linux 7 (Core)"
[2023-05-01 03:01:27] ANSI_COLOR="0;31"
[2023-05-01 03:01:27] CPE_NAME="cpe:/o:centos:centos:7"
[2023-05-01 03:01:27] HOME_URL="https://www.centos.org/"
[2023-05-01 03:01:27] BUG_REPORT_URL="https://bugs.centos.org/"
[2023-05-01 03:01:27] 
[2023-05-01 03:01:27] CENTOS_MANTISBT_PROJECT="CentOS-7"
[2023-05-01 03:01:27] CENTOS_MANTISBT_PROJECT_VERSION="7"
[2023-05-01 03:01:27] REDHAT_SUPPORT_PRODUCT="centos"
[2023-05-01 03:01:27] REDHAT_SUPPORT_PRODUCT_VERSION="7"
[2023-05-01 03:01:27] 
[2023-05-01 03:01:27] 2023-05-01 02:01:15,669 | INFO     | pilot.util.auxiliary             | pilot_version_banner      | ****************************************
[2023-05-01 03:01:27] 2023-05-01 02:01:16,172 | DEBUG    | pilot.util.monitoring            | check_local_space         | checking local space on /home/m/BOINC/slots/2
[2023-05-01 03:01:27] 2023-05-01 02:01:16,172 | INFO     | pilot.util.container             | print_executable          | executing command: df -mP /home/m/BOINC/slots/2
[2023-05-01 03:01:27] 2023-05-01 02:01:16,185 | DEBUG    | pilot.util.workernode            | get_local_disk_space      | stdout=Filesystem     1048576-blocks  Used Available Capacity Mounted on
[2023-05-01 03:01:27] /dev/sda1              455417 28132    404130       7% /home/m/BOINC/slots/2
[2023-05-01 03:01:27] 2023-05-01 02:01:16,185 | DEBUG    | pilot.util.workernode            | get_local_disk_space      | stderr=
[2023-05-01 03:01:27] 2023-05-01 02:01:16,185 | INFO     | pilot.util.monitoring            | check_local_space         | sufficient remaining disk space (423761018880 B)
[2023-05-01 03:01:27] 2023-05-01 02:01:16,186 | WARNING  | pilot.control.job                | proceed_with_getjob       | since timefloor is set to 0, pilot was only allowed to run one job
[2023-05-01 03:01:27] 2023-05-01 02:01:16,186 | DEBUG    | pilot.control.job                | retrieve                  | will not set job_aborted yet
[2023-05-01 03:01:27] 2023-05-01 02:01:16,186 | WARNING  | pilot.util.common                | should_abort              | data:copytool_out:received graceful stop - abort after this iteration
[2023-05-01 03:01:27] 2023-05-01 02:01:16,186 | INFO     | pilot.control.job                | retrieve                  | [job] retrieve thread has finished
[2023-05-01 03:01:27] 2023-05-01 02:01:16,389 | DEBUG    | pilot.control.job                | control                   | job control ending since graceful_stop has been set
[2023-05-01 03:01:27] 2023-05-01 02:01:16,389 | DEBUG    | pilot.control.job                | control                   | will not set job_aborted yet
[2023-05-01 03:01:27] 2023-05-01 02:01:16,389 | INFO     | pilot.control.job                | control                   | [job] control thread has finished
[2023-05-01 03:01:27] 2023-05-01 02:01:16,398 | DEBUG    | pilot.control.job                | validate                  | will not set job_aborted yet
[2023-05-01 03:01:27] 2023-05-01 02:01:16,398 | INFO     | pilot.control.job                | validate                  | [job] validate thread has finished
[2023-05-01 03:01:27] 2023-05-01 02:01:16,523 | DEBUG    | pilot.control.data               | control                   | data control ending since graceful_stop has been set
[2023-05-01 03:01:27] 2023-05-01 02:01:16,523 | DEBUG    | pilot.control.data               | control                   | will not set job_aborted yet
[2023-05-01 03:01:27] 2023-05-01 02:01:16,523 | INFO     | pilot.control.data               | control                   | [data] control thread has finished
[2023-05-01 03:01:27] 2023-05-01 02:01:16,527 | DEBUG    | pilot.control.data               | copytool_in               | will not set job_aborted yet
[2023-05-01 03:01:27] 2023-05-01 02:01:16,527 | INFO     | pilot.control.data               | copytool_in               | [data] copytool_in thread has finished
[2023-05-01 03:01:27] 2023-05-01 02:01:16,546 | DEBUG    | pilot.control.job                | create_data_payload       | will not set job_aborted yet
[2023-05-01 03:01:27] 2023-05-01 02:01:16,547 | INFO     | pilot.control.job                | create_data_payload       | [job] create_data_payload thread has finished
[2023-05-01 03:01:27] 2023-05-01 02:01:16,564 | WARNING  | pilot.util.common                | should_abort              | job:job_monitor:received graceful stop - abort after this iteration
[2023-05-01 03:01:27] 2023-05-01 02:01:16,648 | DEBUG    | pilot.control.payload            | control                   | payload control ending since graceful_stop has been set
[2023-05-01 03:01:27] 2023-05-01 02:01:16,648 | DEBUG    | pilot.control.payload            | control                   | will not set job_aborted yet
[2023-05-01 03:01:27] 2023-05-01 02:01:16,648 | INFO     | pilot.control.payload            | control                   | [payload] control thread has finished
[2023-05-01 03:01:27] 2023-05-01 02:01:16,684 | INFO     | pilot.control.monitor            | control                   | [monitor] control thread has ended
[2023-05-01 03:01:27] 2023-05-01 02:01:16,776 | DEBUG    | pilot.control.payload            | validate_post             | will not set job_aborted yet
[2023-05-01 03:01:27] 2023-05-01 02:01:16,777 | INFO     | pilot.control.payload            | validate_post             | [payload] validate_post thread has finished
[2023-05-01 03:01:27] 2023-05-01 02:01:16,798 | DEBUG    | pilot.control.payload            | validate_pre              | will not set job_aborted yet
[2023-05-01 03:01:27] 2023-05-01 02:01:16,798 | INFO     | pilot.control.payload            | validate_pre              | [payload] validate_pre thread has finished
[2023-05-01 03:01:27] 2023-05-01 02:01:17,105 | DEBUG    | pilot.control.payload            | execute_payloads          | will not set job_aborted yet
[2023-05-01 03:01:27] 2023-05-01 02:01:17,105 | INFO     | pilot.control.payload            | execute_payloads          | [payload] execute_payloads thread has finished
[2023-05-01 03:01:27] 2023-05-01 02:01:17,186 | DEBUG    | pilot.control.data               | copytool_out              | will not set job_aborted yet
[2023-05-01 03:01:27] 2023-05-01 02:01:17,186 | INFO     | pilot.control.data               | copytool_out              | [data] copytool_out thread has finished
[2023-05-01 03:01:27] 2023-05-01 02:01:17,251 | DEBUG    | pilot.control.payload            | failed_post               | will not set job_aborted yet
[2023-05-01 03:01:27] 2023-05-01 02:01:17,252 | INFO     | pilot.control.payload            | failed_post               | [payload] failed_post thread has finished
[2023-05-01 03:01:27] 2023-05-01 02:01:17,569 | DEBUG    | pilot.control.job                | job_monitor               | will not set job_aborted yet
[2023-05-01 03:01:27] 2023-05-01 02:01:17,570 | INFO     | pilot.control.job                | job_monitor               | [job] job monitor thread has finished
[2023-05-01 03:01:27] 2023-05-01 02:01:17,607 | WARNING  | pilot.util.common                | should_abort              | data:queue_monitoring:received graceful stop - abort after this iteration
[2023-05-01 03:01:27] 2023-05-01 02:01:18,536 | WARNING  | pilot.util.common                | should_abort              | job:queue_monitor:received graceful stop - abort after this iteration
[2023-05-01 03:01:27] 2023-05-01 02:01:18,536 | DEBUG    | pilot.control.job                | queue_monitor             | will not set job_aborted yet
[2023-05-01 03:01:27] 2023-05-01 02:01:18,536 | INFO     | pilot.control.job                | queue_monitor             | [job] queue monitor thread has finished
[2023-05-01 03:01:27] 2023-05-01 02:01:20,608 | DEBUG    | pilot.control.data               | queue_monitoring          | will not set job_aborted yet
[2023-05-01 03:01:27] 2023-05-01 02:01:20,608 | INFO     | pilot.control.data               | queue_monitoring          | [data] queue_monitor thread has finished
[2023-05-01 03:01:27] 2023-05-01 02:01:26,422 | INFO     | pilot.control.payload            | get_logging_info          | job.realtimelogging is not enabled
[2023-05-01 03:01:27] 2023-05-01 02:01:26,422 | DEBUG    | pilot.control.payload            | run_realtimelog           | real-time logging not needed at this point
[2023-05-01 03:01:27] 2023-05-01 02:01:26,422 | DEBUG    | pilot.control.payload            | run_realtimelog           | realtime logger was not found, waiting ..
[2023-05-01 03:01:27] 2023-05-01 02:01:26,422 | DEBUG    | pilot.control.payload            | run_realtimelog           | will not set job_aborted yet
[2023-05-01 03:01:27] 2023-05-01 02:01:26,423 | INFO     | pilot.control.payload            | run_realtimelog           | [payload] run_realtimelog thread has finished
[2023-05-01 03:01:27] 2023-05-01 02:01:26,904 | DEBUG    | pilot.util.processes             | threads_aborted           | aborting since only the main Pilot thread is still running (total thread count=1 with 0 daemon threa
[2023-05-01 03:01:27] 2023-05-01 02:01:26,904 | DEBUG    | pilot.workflow.generic           | run                       | all relevant threads have aborted (thread count=1)
[2023-05-01 03:01:27] 2023-05-01 02:01:26,904 | INFO     | pilot.workflow.generic           | run                       | end of generic workflow (traces error code: 0)
[2023-05-01 03:01:27] 2023-05-01 02:01:26,905 | INFO     | root                             | wrap_up                   | traces error code: 0
[2023-05-01 03:01:27] 2023-05-01 02:01:26,905 | INFO     | root                             | wrap_up                   | pilot has finished (exit code=0, shell exit code=0)
[2023-05-01 03:01:27] 2023-05-01 02:01:26,959 [wrapper] ==== pilot stdout END ====
[2023-05-01 03:01:27] 2023-05-01 02:01:26,962 [wrapper] ==== wrapper stdout RESUME ====
[2023-05-01 03:01:27] 2023-05-01 02:01:26,965 [wrapper] pilotpid: 2036
[2023-05-01 03:01:27] 2023-05-01 02:01:26,968 [wrapper] Pilot exit status: 0
[2023-05-01 03:01:27] 2023-05-01 02:01:26,978 [wrapper] pandaids: 5836329109
[2023-05-01 03:01:27] 2023-05-01 02:01:26,985 [wrapper] apfmon messages muted
[2023-05-01 03:01:27] 2023-05-01 02:01:26,989 [wrapper] Test setup, not cleaning
[2023-05-01 03:01:27] 2023-05-01 02:01:26,992 [wrapper] ==== wrapper stdout END ====
[2023-05-01 03:01:27] 2023-05-01 02:01:26,996 [wrapper] ==== wrapper stderr END ====
[2023-05-01 03:01:27] 2023-05-01 02:01:27,002 [wrapper] wrapperexiting ec=0, duration=1841
[2023-05-01 03:01:27] 2023-05-01 02:01:27,005 [wrapper] apfmon messages muted
[2023-05-01 03:01:27]  *** Error codes and diagnostics ***
[2023-05-01 03:01:27]     "exeErrorCode": 0,
[2023-05-01 03:01:27]     "exeErrorDiag": "",
[2023-05-01 03:01:27]     "pilotErrorCode": 0,
[2023-05-01 03:01:27]     "pilotErrorDiag": "",
[2023-05-01 03:01:27]  *** Listing of results directory ***
[2023-05-01 03:01:27] total 46912
[2023-05-01 03:01:27] -rw-r--r-- 1 m m     4388 May  1 02:26 queuedata.json
[2023-05-01 03:01:27] -rwx------ 1 m m    27540 May  1 02:28 runpilot2-wrapper.sh
[2023-05-01 03:01:27] -rw------- 1 m m   413524 May  1 02:28 pilot3.tar.gz
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m      107 May  1 02:30 wrapper_26015_x86_64-pc-linux-gnu
[2023-05-01 03:01:27] -rwxr-xr-x 1 m m     7986 May  1 02:30 run_atlas
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m      112 May  1 02:30 job.xml
[2023-05-01 03:01:27] -rw-r--r-- 2 m m    17632 May  1 02:30 start_atlas.sh
[2023-05-01 03:01:27] drwxrwx--x 2 m m     4096 May  1 02:30 shared
[2023-05-01 03:01:27] -rw-r--r-- 2 m m   425368 May  1 02:30 input.tar.gz
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m     5708 May  1 02:30 init_data.xml
[2023-05-01 03:01:27] -rw-r--r-- 2 m m 38217911 May  1 02:30 EVNT.04972714._000030.pool.root.1
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m        0 May  1 02:30 boinc_lockfile
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m     2662 May  1 02:30 pandaJob.out
[2023-05-01 03:01:27] -rw------- 1 m m      424 May  1 02:30 setup.sh.local
[2023-05-01 03:01:27] -rw------- 1 m m  1013424 May  1 02:30 agis_schedconf.cvmfs.json
[2023-05-01 03:01:27] -rw------- 1 m m  1446236 May  1 02:31 cric_ddmendpoints.json
[2023-05-01 03:01:27] drwx------ 4 m m     4096 May  1 02:32 pilot3
[2023-05-01 03:01:27] -rw------- 1 m m  2662060 May  1 02:54 output.1.e8c31bee-87ba-43db-b151-c60132f7bfed_80890.pool.root
[2023-05-01 03:01:27] -rw------- 1 m m     1019 May  1 02:54 memory_monitor_summary.json
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m      533 May  1 02:55 boinc_task_state.xml
[2023-05-01 03:01:27] -rw------- 1 m m   169690 May  1 02:57 e8c31bee-87ba-43db-b151-c60132f7bfed_80890.1.job.log.tgz
[2023-05-01 03:01:27] -rw------- 1 m m     7345 May  1 03:01 heartbeat.json
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m       25 May  1 03:01 wrapper_checkpoint.txt
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m     8192 May  1 03:01 boinc_mmap_file
[2023-05-01 03:01:27] -rw------- 1 m m     9193 May  1 03:01 pilotlog.txt
[2023-05-01 03:01:27] -rw------- 1 m m   312062 May  1 03:01 e8c31bee-87ba-43db-b151-c60132f7bfed_80890.1.job.log
[2023-05-01 03:01:27] -rw------- 1 m m      464 May  1 03:01 output.list
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m      748 May  1 03:01 runtime_log
[2023-05-01 03:01:27] -rw------- 1 m m  3164160 May  1 03:01 result.tar.gz
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m    11913 May  1 03:01 runtime_log.err
[2023-05-01 03:01:27] -rw------- 1 m m      627 May  1 03:01 emuNDmpVXD3n7Olcko1bjSoqABFKDmABFKDm7AsVDmJaIKDmh7Ewmm.diag
[2023-05-01 03:01:27] -rw-rw-r-- 1 m m    28828 May  1 03:01 stderr.txt
[2023-05-01 03:01:27] HITS file was successfully produced:
[2023-05-01 03:01:27] -rw------- 1 m m 2662060 May  1 02:54 shared/HITS.pool.root.1
[2023-05-01 03:01:27]  *** Contents of shared directory: ***
[2023-05-01 03:01:27] total 43452
[2023-05-01 03:01:27] -rw-r--r-- 2 m m    17632 May  1 02:30 start_atlas.sh
[2023-05-01 03:01:27] -rw-r--r-- 2 m m   425368 May  1 02:30 input.tar.gz
[2023-05-01 03:01:27] -rw-r--r-- 2 m m 38217911 May  1 02:30 ATLAS.root_0
[2023-05-01 03:01:27] -rw------- 1 m m  2662060 May  1 02:54 HITS.pool.root.1
[2023-05-01 03:01:27] -rw------- 1 m m  3164160 May  1 03:01 result.tar.gz
03:01:29 (30702): run_atlas exited; CPU time 957.720061
03:01:29 (30702): called boinc_finish(0)

</stderr_txt>
]]>


©2024 CERN