| Name | qPSMDmRJoY4n7Olcko1bjSoqABFKDmABFKDmyeZQDmX0HKDmkSF9tm_0 |
| Workunit | 2370206 |
| Created | 16 Dec 2023, 18:11:33 UTC |
| Sent | 16 Dec 2023, 18:17:46 UTC |
| Report deadline | 23 Dec 2023, 18:17:46 UTC |
| Received | 16 Dec 2023, 18:46:47 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 4008 |
| Run time | 28 min 14 sec |
| CPU time | 21 min 6 sec |
| Validate state | Valid |
| Credit | 23.13 |
| Device peak FLOPS | 3.24 GFLOPS |
| Application version | ATLAS Simulation v3.01 (native_mt) x86_64-pc-linux-gnu |
| Peak working set size | 1.42 GB |
| Peak swap size | 2.14 GB |
| Peak disk usage | 80.49 MB |
Stderr output
<core_client_version>7.16.1</core_client_version>
<![CDATA[
<stderr_txt>
19:18:19 (24774): wrapper (7.7.26015): starting
19:18:19 (24774): wrapper: running run_atlas (--nthreads 1)
[2023-12-16 19:18:19] Arguments: --nthreads 1
[2023-12-16 19:18:19] Threads: 1
[2023-12-16 19:18:19] Checking for CVMFS
[2023-12-16 19:18:22] Probing /cvmfs/atlas.cern.ch... OK
[2023-12-16 19:18:28] Probing /cvmfs/atlas-condb.cern.ch... OK
[2023-12-16 19:18:28] Running cvmfs_config stat atlas.cern.ch
[2023-12-16 19:18:28] 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-12-16 19:18:28] 2.6.3.0 25018 0 28448 127114 3 1 2324449 2816000 0 65024 0 0 n/a 962 583 http://s1cern-cvmfs.openhtc.io/cvmfs/atlas.cern.ch http://10.116.178.201:3128 1
[2023-12-16 19:18:28] CVMFS is ok
[2023-12-16 19:18:28] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7
[2023-12-16 19:18:28] Checking for apptainer binary...
[2023-12-16 19:18:28] which: no apptainer in (/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin)
[2023-12-16 19:18:28] apptainer is not installed, using version from CVMFS
[2023-12-16 19:18:28] Checking apptainer works with /cvmfs/atlas.cern.ch/repo/containers/sw/apptainer/x86_64-el7/current/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname
[2023-12-16 19:18:29] HPi7COS7
[2023-12-16 19:18:29] apptainer works
[2023-12-16 19:18:29] Starting ATLAS job with PandaID=6052516889
[2023-12-16 19:18:29] Running command: /cvmfs/atlas.cern.ch/repo/containers/sw/apptainer/x86_64-el7/current/bin/apptainer exec -B /cvmfs,/var/lib/boinc/slots/0 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 sh start_atlas.sh
[2023-12-16 19:46:31] *** The last 200 lines of the pilot log: ***
[2023-12-16 19:46:31] 2023-12-16 18:46:08,961 | DEBUG | pilot.control.job | send_state | data={'jobId': '6052516889', 'state': 'finished', 'timestamp': '2023-12-16T19:45:53+01:00', 'siteNam
[2023-12-16 19:46:31] 2023-12-16 18:46:08,962 | DEBUG | pilot.control.job | write_heartbeat_to_file | heartbeat dictionary: {'jobId': '6052516889', 'state': 'finished', 'timestamp': '2023-12-16T19:45:53
[2023-12-16 19:46:31] 2023-12-16 18:46:08,962 | DEBUG | pilot.control.job | write_heartbeat_to_file | wrote heartbeat to file: /var/lib/boinc/slots/0/heartbeat.json
[2023-12-16 19:46:31] 2023-12-16 18:46:08,962 | DEBUG | pilot.control.job | queue_monitor | job.completed=False
[2023-12-16 19:46:31] 2023-12-16 18:46:09,138 | INFO | pilot.control.job | make_job_report |
[2023-12-16 19:46:31] 2023-12-16 18:46:09,139 | INFO | pilot.control.job | make_job_report | job summary report
[2023-12-16 19:46:31] 2023-12-16 18:46:09,139 | INFO | pilot.control.job | make_job_report | --------------------------------------------------
[2023-12-16 19:46:31] 2023-12-16 18:46:09,139 | INFO | pilot.control.job | make_job_report | PanDA job id: 6052516889
[2023-12-16 19:46:31] 2023-12-16 18:46:09,139 | INFO | pilot.control.job | make_job_report | task id: NULL
[2023-12-16 19:46:31] 2023-12-16 18:46:09,139 | INFO | pilot.control.job | make_job_report | errors: (none)
[2023-12-16 19:46:31] 2023-12-16 18:46:09,139 | INFO | pilot.control.job | make_job_report | status: LOG_TRANSFER = DONE
[2023-12-16 19:46:31] 2023-12-16 18:46:09,139 | INFO | pilot.control.job | make_job_report | pilot state: finished
[2023-12-16 19:46:31] 2023-12-16 18:46:09,139 | INFO | pilot.control.job | make_job_report | transexitcode: 0
[2023-12-16 19:46:31] 2023-12-16 18:46:09,139 | INFO | pilot.control.job | make_job_report | exeerrorcode: 0
[2023-12-16 19:46:31] 2023-12-16 18:46:09,140 | INFO | pilot.control.job | make_job_report | exeerrordiag:
[2023-12-16 19:46:31] 2023-12-16 18:46:09,140 | INFO | pilot.control.job | make_job_report | exitcode: 0
[2023-12-16 19:46:31] 2023-12-16 18:46:09,140 | INFO | pilot.control.job | make_job_report | exitmsg: OK
[2023-12-16 19:46:31] 2023-12-16 18:46:09,140 | INFO | pilot.control.job | make_job_report | cpuconsumptiontime: 1321 s
[2023-12-16 19:46:31] 2023-12-16 18:46:09,140 | INFO | pilot.control.job | make_job_report | nevents: 2
[2023-12-16 19:46:31] 2023-12-16 18:46:09,140 | INFO | pilot.control.job | make_job_report | neventsw: 0
[2023-12-16 19:46:31] 2023-12-16 18:46:09,140 | INFO | pilot.control.job | make_job_report | pid: 3726
[2023-12-16 19:46:31] 2023-12-16 18:46:09,140 | INFO | pilot.control.job | make_job_report | pgrp: 3726
[2023-12-16 19:46:31] 2023-12-16 18:46:09,140 | INFO | pilot.control.job | make_job_report | corecount: 1
[2023-12-16 19:46:31] 2023-12-16 18:46:09,140 | INFO | pilot.control.job | make_job_report | event service: False
[2023-12-16 19:46:31] 2023-12-16 18:46:09,140 | INFO | pilot.control.job | make_job_report | sizes: {0: 2429989, 1: 2430188, 10: 2430394, 12: 2430422, 1567: 2453343, 1568: 2453342, 1569: 246257
[2023-12-16 19:46:31] 2023-12-16 18:46:09,141 | INFO | pilot.control.job | make_job_report | --------------------------------------------------
[2023-12-16 19:46:31] 2023-12-16 18:46:09,141 | INFO | pilot.control.job | make_job_report |
[2023-12-16 19:46:31] 2023-12-16 18:46:09,141 | DEBUG | pilot.control.job | has_job_completed | ls -lF /var/lib/boinc/slots/0:
[2023-12-16 19:46:31]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,141 | INFO | pilot.util.container | print_executable | executing command: ls -lF /var/lib/boinc/slots/0
[2023-12-16 19:46:31] 2023-12-16 18:46:09,167 | DEBUG | pilot.util.container | execute | subprocess.communicate() will use timeout 864000 s
[2023-12-16 19:46:31] 2023-12-16 18:46:09,196 | WARNING | pilot.control.monitor | run_checks | too much time has passed since last successful pilot heartbeat (1611.6825158596039 s) - must update
[2023-12-16 19:46:31] 2023-12-16 18:46:09,216 | DEBUG | pilot.control.job | has_job_completed | total 44544
[2023-12-16 19:46:31] -rw-r--r--. 2 boinc boinc 37965264 Dec 16 19:18 EVNT.04972714._000027.pool.root.1
[2023-12-16 19:46:31] drwxrwx---. 2 boinc boinc 4096 Dec 16 19:45 PanDA_Pilot-6052516889/
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 0 Dec 16 19:19 agis_ddmendpoints.agis.ALL.json
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 979478 Dec 16 19:19 agis_schedconf.cvmfs.json
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 0 Dec 16 19:18 boinc_lockfile
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 8192 Dec 16 19:45 boinc_mmap_file
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 534 Dec 16 19:45 boinc_task_state.xml
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 1358245 Dec 16 19:19 cric_ddmendpoints.json
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 718595 Dec 16 19:46 dbcb9225-ea5f-4663-b19d-a1a33838f335_17039.1.job.log
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 199445 Dec 16 19:45 dbcb9225-ea5f-4663-b19d-a1a33838f335_17039.1.job.log.tgz
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 8795 Dec 16 19:46 heartbeat.json
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 6046 Dec 16 19:18 init_data.xml
[2023-12-16 19:46:31] -rw-r--r--. 2 boinc boinc 488892 Dec 16 19:18 input.tar.gz
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 112 Dec 16 19:18 job.xml
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 1018 Dec 16 19:45 memory_monitor_summary.json
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 1808605 Dec 16 19:45 output.1.dbcb9225-ea5f-4663-b19d-a1a33838f335_17039.pool.root
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 464 Dec 16 19:45 output.list
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 2662 Dec 16 19:18 pandaJob.out
[2023-12-16 19:46:31] drwx------. 5 boinc boinc 4096 Dec 16 19:19 pilot3/
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 477391 Dec 16 19:11 pilot3.tar.gz
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 47 Dec 16 19:19 pilot_heartbeat.json
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 688991 Dec 16 19:46 pilotlog.txt
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 123 Dec 16 19:18 qPSMDmRJoY4n7Olcko1bjSoqABFKDmABFKDmyeZQDmX0HKDmkSF9tm.diag
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 4388 Dec 16 19:09 queuedata.json
[2023-12-16 19:46:31] -rwxr-xr-x. 1 boinc boinc 7986 Dec 16 19:18 run_atlas*
[2023-12-16 19:46:31] -rwx------. 1 boinc boinc 27437 Dec 16 19:11 runpilot2-wrapper.sh*
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 407 Dec 16 19:18 runtime_log
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 7753 Dec 16 19:18 runtime_log.err
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 424 Dec 16 19:18 setup.sh.local
[2023-12-16 19:46:31] drwxrwx--x. 2 boinc boinc 68 Dec 16 19:18 shared/
[2023-12-16 19:46:31] -rw-r--r--. 2 boinc boinc 17646 Dec 16 19:18 start_atlas.sh
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 1745 Dec 16 19:18 stderr.txt
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 107 Dec 16 19:18 wrapper_26015_x86_64-pc-linux-gnu
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 26 Dec 16 19:45 wrapper_checkpoint.txt
[2023-12-16 19:46:31] 2023-12-16 18:46:09,217 | INFO | pilot.util.queuehandling | queue_report | queue jobs had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,217 | INFO | pilot.util.queuehandling | queue_report | queue payloads had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,217 | INFO | pilot.util.queuehandling | queue_report | queue data_in had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,217 | INFO | pilot.util.queuehandling | queue_report | queue data_out had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,217 | INFO | pilot.util.queuehandling | queue_report | queue current_data_in had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,218 | INFO | pilot.util.queuehandling | queue_report | queue validated_jobs had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,218 | INFO | pilot.util.queuehandling | queue_report | queue validated_payloads had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,218 | INFO | pilot.util.queuehandling | queue_report | queue monitored_payloads had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,218 | INFO | pilot.util.queuehandling | queue_report | queue finished_jobs had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,218 | INFO | pilot.util.queuehandling | queue_report | queue finished_payloads had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,218 | INFO | pilot.util.queuehandling | queue_report | queue finished_data_in had 1 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,218 | INFO | pilot.util.queuehandling | queue_report | queue finished_data_out had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,219 | INFO | pilot.util.queuehandling | queue_report | queue failed_jobs had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,219 | INFO | pilot.util.queuehandling | queue_report | queue failed_payloads had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,219 | INFO | pilot.util.queuehandling | queue_report | queue failed_data_in had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,219 | INFO | pilot.util.queuehandling | queue_report | queue failed_data_out had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,219 | INFO | pilot.util.queuehandling | queue_report | queue completed_jobs had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,219 | INFO | pilot.util.queuehandling | queue_report | queue completed_jobids has 1 job(s)
[2023-12-16 19:46:31] 2023-12-16 18:46:09,219 | INFO | pilot.util.queuehandling | queue_report | queue realtimelog_payloads had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,220 | INFO | pilot.util.queuehandling | queue_report | queue messages had 0 job(s) [purged]
[2023-12-16 19:46:31] 2023-12-16 18:46:09,220 | INFO | pilot.control.job | has_job_completed | job 6052516889 has completed (purged errors)
[2023-12-16 19:46:31] 2023-12-16 18:46:09,220 | DEBUG | pilot.util.realtimelogger | cleanup | attempting real-time logger cleanup
[2023-12-16 19:46:31] 2023-12-16 18:46:09,220 | INFO | pilot.util.processes | cleanup | overall cleanup function is called
[2023-12-16 19:46:31] 2023-12-16 18:46:09,223 | DEBUG | pilot.util.processes | cleanup | work directory was removed: /var/lib/boinc/slots/0/PanDA_Pilot-6052516889
[2023-12-16 19:46:31] 2023-12-16 18:46:10,260 | INFO | pilot.info.jobdata | collect_zombies | --- collectZombieJob: --- 10, [3726]
[2023-12-16 19:46:31] 2023-12-16 18:46:10,261 | INFO | pilot.info.jobdata | collect_zombies | zombie collector waiting for pid 3726
[2023-12-16 19:46:31] 2023-12-16 18:46:10,261 | INFO | pilot.info.jobdata | collect_zombies | harmless exception when collecting zombies: [Errno 10] No child processes
[2023-12-16 19:46:31] 2023-12-16 18:46:11,205 | WARNING | pilot.control.monitor | run_checks | too much time has passed since last successful pilot heartbeat (1613.6925621032715 s) - must update
[2023-12-16 19:46:31] 2023-12-16 18:46:11,271 | INFO | pilot.util.processes | cleanup | collected zombie processes
[2023-12-16 19:46:31] 2023-12-16 18:46:11,272 | INFO | pilot.util.processes | cleanup | will now attempt to kill all subprocesses of pid=3726
[2023-12-16 19:46:31] 2023-12-16 18:46:11,280 | DEBUG | pilot.util.container | execute | subprocess.communicate() will use timeout 864000 s
[2023-12-16 19:46:31] 2023-12-16 18:46:11,364 | INFO | pilot.util.processes | kill_processes | process IDs to be killed: [3726] (in reverse order)
[2023-12-16 19:46:31] 2023-12-16 18:46:11,376 | DEBUG | pilot.util.container | execute | subprocess.communicate() will use timeout 864000 s
[2023-12-16 19:46:31] 2023-12-16 18:46:11,416 | WARNING | pilot.util.processes | kill_processes | found no corresponding commands to process id(s)
[2023-12-16 19:46:31] 2023-12-16 18:46:11,417 | INFO | pilot.util.processes | kill_orphans | Do not look for orphan processes in BOINC jobs
[2023-12-16 19:46:31] 2023-12-16 18:46:11,419 | INFO | pilot.util.processes | kill_defunct_children | did not find any defunct processes belonging to 3726
[2023-12-16 19:46:31] 2023-12-16 18:46:11,420 | INFO | pilot.util.processes | kill_defunct_children | did not find any defunct processes belonging to 3726
[2023-12-16 19:46:31] 2023-12-16 18:46:11,421 | DEBUG | pilot.util.queuehandling | purge_queue | queue purged
[2023-12-16 19:46:31] 2023-12-16 18:46:11,421 | INFO | pilot.control.job | retrieve | ready for new job
[2023-12-16 19:46:31] 2023-12-16 18:46:11,421 | INFO | root | retrieve | pilot has finished with previous job - re-establishing logging
[2023-12-16 19:46:31] 2023-12-16 18:46:11,422 | INFO | pilot.util.auxiliary | pilot_version_banner | **************************************
[2023-12-16 19:46:31] 2023-12-16 18:46:11,422 | INFO | pilot.util.auxiliary | pilot_version_banner | *** PanDA Pilot version 3.7.1.22 ***
[2023-12-16 19:46:31] 2023-12-16 18:46:11,423 | INFO | pilot.util.auxiliary | pilot_version_banner | **************************************
[2023-12-16 19:46:31] 2023-12-16 18:46:11,423 | INFO | pilot.util.auxiliary | pilot_version_banner |
[2023-12-16 19:46:31] 2023-12-16 18:46:11,423 | INFO | pilot.util.auxiliary | pilot_version_banner | pilot is running in a VM
[2023-12-16 19:46:31] 2023-12-16 18:46:11,423 | INFO | pilot.util.auxiliary | display_architecture_info | architecture information:
[2023-12-16 19:46:31] 2023-12-16 18:46:11,423 | INFO | pilot.util.container | print_executable | executing command: cat /etc/os-release
[2023-12-16 19:46:31] 2023-12-16 18:46:11,430 | DEBUG | pilot.util.container | execute | subprocess.communicate() will use timeout 864000 s
[2023-12-16 19:46:31] 2023-12-16 18:46:11,450 | INFO | pilot.util.filehandling | dump | cat /etc/os-release:
[2023-12-16 19:46:31] NAME="CentOS Linux"
[2023-12-16 19:46:31] VERSION="7 (Core)"
[2023-12-16 19:46:31] ID="centos"
[2023-12-16 19:46:31] ID_LIKE="rhel fedora"
[2023-12-16 19:46:31] VERSION_ID="7"
[2023-12-16 19:46:31] PRETTY_NAME="CentOS Linux 7 (Core)"
[2023-12-16 19:46:31] ANSI_COLOR="0;31"
[2023-12-16 19:46:31] CPE_NAME="cpe:/o:centos:centos:7"
[2023-12-16 19:46:31] HOME_URL="https://www.centos.org/"
[2023-12-16 19:46:31] BUG_REPORT_URL="https://bugs.centos.org/"
[2023-12-16 19:46:31]
[2023-12-16 19:46:31] CENTOS_MANTISBT_PROJECT="CentOS-7"
[2023-12-16 19:46:31] CENTOS_MANTISBT_PROJECT_VERSION="7"
[2023-12-16 19:46:31] REDHAT_SUPPORT_PRODUCT="centos"
[2023-12-16 19:46:31] REDHAT_SUPPORT_PRODUCT_VERSION="7"
[2023-12-16 19:46:31]
[2023-12-16 19:46:31] 2023-12-16 18:46:11,450 | INFO | pilot.util.auxiliary | pilot_version_banner | **************************************
[2023-12-16 19:46:31] 2023-12-16 18:46:11,952 | DEBUG | pilot.util.monitoring | check_local_space | checking local space on /var/lib/boinc/slots/0
[2023-12-16 19:46:31] 2023-12-16 18:46:11,952 | INFO | pilot.util.container | print_executable | executing command: df -mP /var/lib/boinc/slots/0
[2023-12-16 19:46:31] 2023-12-16 18:46:11,961 | DEBUG | pilot.util.container | execute | subprocess.communicate() will use timeout 864000 s
[2023-12-16 19:46:31] 2023-12-16 18:46:11,977 | DEBUG | pilot.util.workernode | get_local_disk_space | stdout=Filesystem 1048576-blocks Used Available Capacity Mounted on
[2023-12-16 19:46:31] /dev/mapper/centos-root 22002 11481 10522 53% /var/lib/boinc/slots/0
[2023-12-16 19:46:31] 2023-12-16 18:46:11,977 | DEBUG | pilot.util.workernode | get_local_disk_space | stderr=
[2023-12-16 19:46:31] 2023-12-16 18:46:11,978 | INFO | pilot.util.monitoring | check_local_space | sufficient remaining disk space (11033116672 B)
[2023-12-16 19:46:31] 2023-12-16 18:46:11,978 | WARNING | pilot.control.job | proceed_with_getjob | since timefloor is set to 0, pilot was only allowed to run one job
[2023-12-16 19:46:31] 2023-12-16 18:46:11,978 | WARNING | pilot.control.job | retrieve | setting graceful_stop since proceed_with_getjob() returned False (pilot will end)
[2023-12-16 19:46:31] 2023-12-16 18:46:11,978 | WARNING | pilot.control.monitor | control | aborting monitor loop since graceful_stop has been set (timing out remaining threads)
[2023-12-16 19:46:31] 2023-12-16 18:46:11,978 | WARNING | pilot.control.monitor | run_checks | too much time has passed since last successful pilot heartbeat (1614.466413974762 s) - must update ?
[2023-12-16 19:46:31] 2023-12-16 18:46:11,979 | INFO | pilot.util.queuehandling | abort_jobs_in_queues | found 0 job(s) in 20 queues
[2023-12-16 19:46:31] 2023-12-16 18:46:11,979 | WARNING | pilot.control.monitor | run_checks | pilot monitor received instruction that args.graceful_stop has been set
[2023-12-16 19:46:31] 2023-12-16 18:46:11,979 | WARNING | pilot.control.monitor | run_checks | will wait for a maximum of 300 s for threads to finish
[2023-12-16 19:46:31] 2023-12-16 18:46:12,089 | WARNING | pilot.util.common | should_abort | data:queue_monitoring:received graceful stop - abort after this iteration
[2023-12-16 19:46:31] 2023-12-16 18:46:12,169 | DEBUG | pilot.control.data | control | data control ending since graceful_stop has been set
[2023-12-16 19:46:31] 2023-12-16 18:46:12,228 | DEBUG | pilot.control.payload | control | payload control ending since graceful_stop has been set
[2023-12-16 19:46:31] 2023-12-16 18:46:12,857 | WARNING | pilot.util.common | should_abort | job:job_monitor:received graceful stop - abort after this iteration
[2023-12-16 19:46:31] 2023-12-16 18:46:12,857 | INFO | pilot.control.job | job_monitor | aborting loop
[2023-12-16 19:46:31] 2023-12-16 18:46:12,984 | INFO | pilot.control.job | retrieve | [job] retrieve thread has finished
[2023-12-16 19:46:31] 2023-12-16 18:46:13,175 | INFO | pilot.control.data | control | [data] control thread has finished
[2023-12-16 19:46:31] 2023-12-16 18:46:13,204 | WARNING | pilot.util.common | should_abort | data:copytool_out:received graceful stop - abort after this iteration
[2023-12-16 19:46:31] 2023-12-16 18:46:13,234 | INFO | pilot.control.payload | control | [payload] control thread has finished
[2023-12-16 19:46:31] 2023-12-16 18:46:13,455 | DEBUG | pilot.control.job | control | job control ending since graceful_stop has been set
[2023-12-16 19:46:31] 2023-12-16 18:46:13,619 | INFO | pilot.control.job | create_data_payload | [job] create_data_payload thread has finished
[2023-12-16 19:46:31] 2023-12-16 18:46:13,652 | INFO | pilot.control.data | copytool_in | [data] copytool_in thread has finished
[2023-12-16 19:46:31] 2023-12-16 18:46:13,682 | INFO | pilot.control.payload | validate_pre | [payload] validate_pre thread has finished
[2023-12-16 19:46:31] 2023-12-16 18:46:13,701 | INFO | pilot.control.payload | execute_payloads | [payload] execute_payloads thread has finished
[2023-12-16 19:46:31] 2023-12-16 18:46:13,863 | INFO | pilot.control.job | job_monitor | [job] job monitor thread has finished
[2023-12-16 19:46:31] 2023-12-16 18:46:13,985 | INFO | pilot.control.payload | validate_post | [payload] validate_post thread has finished
[2023-12-16 19:46:31] 2023-12-16 18:46:14,153 | WARNING | pilot.util.common | should_abort | job:queue_monitor:received graceful stop - abort after this iteration
[2023-12-16 19:46:31] 2023-12-16 18:46:14,367 | INFO | pilot.control.job | validate | [job] validate thread has finished
[2023-12-16 19:46:31] 2023-12-16 18:46:14,461 | INFO | pilot.control.job | control | [job] control thread has finished
[2023-12-16 19:46:31] 2023-12-16 18:46:14,476 | INFO | pilot.control.payload | failed_post | [payload] failed_post thread has finished
[2023-12-16 19:46:31] 2023-12-16 18:46:15,156 | INFO | pilot.control.job | queue_monitor | [job] queue monitor thread has finished
[2023-12-16 19:46:31] 2023-12-16 18:46:15,231 | INFO | pilot.control.data | copytool_out | [data] copytool_out thread has finished
[2023-12-16 19:46:31] 2023-12-16 18:46:16,099 | INFO | pilot.control.data | queue_monitoring | [data] queue_monitor thread has finished
[2023-12-16 19:46:31] 2023-12-16 18:46:22,565 | INFO | pilot.control.payload | get_logging_info | job.realtimelogging is not enabled
[2023-12-16 19:46:31] 2023-12-16 18:46:22,565 | DEBUG | pilot.control.payload | run_realtimelog | real-time logging not needed at this point
[2023-12-16 19:46:31] 2023-12-16 18:46:22,565 | DEBUG | pilot.control.payload | run_realtimelog | realtime logger was not found, waiting ..
[2023-12-16 19:46:31] 2023-12-16 18:46:23,567 | INFO | pilot.control.payload | run_realtimelog | [payload] run_realtimelog thread has finished
[2023-12-16 19:46:31] 2023-12-16 18:46:24,900 | INFO | pilot.util.processes | threads_aborted | only monitor.control thread still running - safe to abort: ['<_MainThread(MainThread, started 140512
[2023-12-16 19:46:31] 2023-12-16 18:46:24,901 | DEBUG | pilot.workflow.generic | run | will proceed to set job_aborted
[2023-12-16 19:46:31] 2023-12-16 18:46:25,089 | WARNING | pilot.control.monitor | run_checks | job_aborted has been set - aborting pilot monitoring
[2023-12-16 19:46:31] 2023-12-16 18:46:25,091 | INFO | pilot.control.monitor | control | [monitor] control thread has ended
[2023-12-16 19:46:31] 2023-12-16 18:46:29,931 | DEBUG | pilot.workflow.generic | run | all relevant threads have aborted (thread count=1)
[2023-12-16 19:46:31] 2023-12-16 18:46:29,932 | INFO | pilot.workflow.generic | run | end of generic workflow (traces error code: 0)
[2023-12-16 19:46:31] 2023-12-16 18:46:29,976 | DEBUG | pilot.util.processgroups | get_all_child_pids | python3(29021)---pstree(13530)
[2023-12-16 19:46:31]
[2023-12-16 19:46:31] 2023-12-16 18:46:29,979 | INFO | pilot.util.processgroups | find_defunct_subprocesses | child pids=[29021, 13530]
[2023-12-16 19:46:31] 2023-12-16 18:46:30,054 | DEBUG | pilot.util.processgroups | is_defunct | 29021: return code=0, stdout=SN
[2023-12-16 19:46:31] , stderr=
[2023-12-16 19:46:31] 2023-12-16 18:46:30,094 | DEBUG | pilot.util.processgroups | is_defunct | 13530: return code=1, stdout=, stderr=
[2023-12-16 19:46:31] 2023-12-16 18:46:30,094 | INFO | root | list_zombies | no defunct processes were found
[2023-12-16 19:46:31] 2023-12-16 18:46:30,094 | INFO | root | wrap_up | traces error code: 0
[2023-12-16 19:46:31] 2023-12-16 18:46:30,095 | INFO | root | wrap_up | pilot has finished (exit code=0, shell exit code=0)
[2023-12-16 19:46:31] 2023-12-16 18:46:30,238 [wrapper] ==== pilot stdout END ====
[2023-12-16 19:46:31] 2023-12-16 18:46:30,243 [wrapper] ==== wrapper stdout RESUME ====
[2023-12-16 19:46:31] 2023-12-16 18:46:30,247 [wrapper] pilotpid: 29021
[2023-12-16 19:46:31] 2023-12-16 18:46:30,259 [wrapper] Pilot exit status: 0
[2023-12-16 19:46:31] 2023-12-16 18:46:30,293 [wrapper] pandaids: 6052516889
[2023-12-16 19:46:31] 2023-12-16 18:46:30,328 [wrapper] apfmon messages muted
[2023-12-16 19:46:31] 2023-12-16 18:46:30,339 [wrapper] Test setup, not cleaning
[2023-12-16 19:46:31] 2023-12-16 18:46:30,344 [wrapper] ==== wrapper stdout END ====
[2023-12-16 19:46:31] 2023-12-16 18:46:30,352 [wrapper] ==== wrapper stderr END ====
[2023-12-16 19:46:31] 2023-12-16 18:46:30,365 [wrapper] wrapperexiting ec=0, duration=1680
[2023-12-16 19:46:31] 2023-12-16 18:46:30,374 [wrapper] apfmon messages muted
[2023-12-16 19:46:31] *** Error codes and diagnostics ***
[2023-12-16 19:46:31] "exeErrorCode": 0,
[2023-12-16 19:46:31] "exeErrorDiag": "",
[2023-12-16 19:46:31] "pilotErrorCode": 0,
[2023-12-16 19:46:31] "pilotErrorDiag": "",
[2023-12-16 19:46:31] *** Listing of results directory ***
[2023-12-16 19:46:31] insgesamt 45896
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 4388 16. Dez 19:09 queuedata.json
[2023-12-16 19:46:31] -rwx------. 1 boinc boinc 27437 16. Dez 19:11 runpilot2-wrapper.sh
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 477391 16. Dez 19:11 pilot3.tar.gz
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 107 16. Dez 19:18 wrapper_26015_x86_64-pc-linux-gnu
[2023-12-16 19:46:31] -rwxr-xr-x. 1 boinc boinc 7986 16. Dez 19:18 run_atlas
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 112 16. Dez 19:18 job.xml
[2023-12-16 19:46:31] -rw-r--r--. 2 boinc boinc 17646 16. Dez 19:18 start_atlas.sh
[2023-12-16 19:46:31] drwxrwx--x. 2 boinc boinc 68 16. Dez 19:18 shared
[2023-12-16 19:46:31] -rw-r--r--. 2 boinc boinc 488892 16. Dez 19:18 input.tar.gz
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 6046 16. Dez 19:18 init_data.xml
[2023-12-16 19:46:31] -rw-r--r--. 2 boinc boinc 37965264 16. Dez 19:18 EVNT.04972714._000027.pool.root.1
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 0 16. Dez 19:18 boinc_lockfile
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 2662 16. Dez 19:18 pandaJob.out
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 424 16. Dez 19:18 setup.sh.local
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 979478 16. Dez 19:19 agis_schedconf.cvmfs.json
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 0 16. Dez 19:19 agis_ddmendpoints.agis.ALL.json
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 1358245 16. Dez 19:19 cric_ddmendpoints.json
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 47 16. Dez 19:19 pilot_heartbeat.json
[2023-12-16 19:46:31] drwx------. 5 boinc boinc 4096 16. Dez 19:19 pilot3
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 1808605 16. Dez 19:45 output.1.dbcb9225-ea5f-4663-b19d-a1a33838f335_17039.pool.root
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 534 16. Dez 19:45 boinc_task_state.xml
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 1018 16. Dez 19:45 memory_monitor_summary.json
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 199445 16. Dez 19:45 dbcb9225-ea5f-4663-b19d-a1a33838f335_17039.1.job.log.tgz
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 8795 16. Dez 19:46 heartbeat.json
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 8192 16. Dez 19:46 boinc_mmap_file
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 26 16. Dez 19:46 wrapper_checkpoint.txt
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 9722 16. Dez 19:46 pilotlog.txt
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 737291 16. Dez 19:46 dbcb9225-ea5f-4663-b19d-a1a33838f335_17039.1.job.log
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 464 16. Dez 19:46 output.list
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 748 16. Dez 19:46 runtime_log
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 2764800 16. Dez 19:46 result.tar.gz
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 11864 16. Dez 19:46 runtime_log.err
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 634 16. Dez 19:46 qPSMDmRJoY4n7Olcko1bjSoqABFKDmABFKDmyeZQDmX0HKDmkSF9tm.diag
[2023-12-16 19:46:31] -rw-r--r--. 1 boinc boinc 29272 16. Dez 19:46 stderr.txt
[2023-12-16 19:46:31] HITS file was successfully produced:
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 1808605 16. Dez 19:45 shared/HITS.pool.root.1
[2023-12-16 19:46:31] *** Contents of shared directory: ***
[2023-12-16 19:46:31] insgesamt 42044
[2023-12-16 19:46:31] -rw-r--r--. 2 boinc boinc 17646 16. Dez 19:18 start_atlas.sh
[2023-12-16 19:46:31] -rw-r--r--. 2 boinc boinc 488892 16. Dez 19:18 input.tar.gz
[2023-12-16 19:46:31] -rw-r--r--. 2 boinc boinc 37965264 16. Dez 19:18 ATLAS.root_0
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 1808605 16. Dez 19:45 HITS.pool.root.1
[2023-12-16 19:46:31] -rw-------. 1 boinc boinc 2764800 16. Dez 19:46 result.tar.gz
19:46:32 (24774): run_atlas exited; CPU time 1266.274881
19:46:32 (24774): called boinc_finish(0)
</stderr_txt>
]]>
©2024 CERN