| Name | drSNDmBhZH2n7Olcko1bjSoqABFKDmABFKDmFymXDmHaOKDmTTzHHn_1 |
| Workunit | 2247411 |
| Created | 22 Nov 2022, 5:12:25 UTC |
| Sent | 22 Nov 2022, 5:13:02 UTC |
| Report deadline | 29 Nov 2022, 5:13:02 UTC |
| Received | 22 Nov 2022, 5:23:16 UTC |
| Server state | Over |
| Outcome | Success |
| Client state | Done |
| Exit status | 0 (0x00000000) |
| Computer ID | 4722 |
| Run time | 9 min 15 sec |
| CPU time | 4 min 55 sec |
| Validate state | Valid |
| Credit | 19.77 |
| Device peak FLOPS | 16.19 GFLOPS |
| Application version | ATLAS Simulation v1.25 (native_mt) x86_64-pc-linux-gnu |
| Peak working set size | 1.41 GB |
| Peak swap size | 2.13 GB |
| Peak disk usage | 79.26 MB |
Stderr output
<core_client_version>7.20.2</core_client_version>
<![CDATA[
<stderr_txt>
06:13:17 (2802742): wrapper (7.7.26015): starting
06:13:17 (2802742): wrapper: running run_atlas (--nthreads 2)
[2022-11-22 06:13:17] Arguments: --nthreads 2
[2022-11-22 06:13:17] Threads: 2
[2022-11-22 06:13:17] Checking for CVMFS
[2022-11-22 06:13:17] Probing /cvmfs/atlas.cern.ch... OK
[2022-11-22 06:13:17] Probing /cvmfs/atlas-condb.cern.ch... OK
[2022-11-22 06:13:17] Running cvmfs_config stat atlas.cern.ch
[2022-11-22 06:13: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
[2022-11-22 06:13:17] 2.9.4.0 1406539 48549 122268 112428 2 80 9483146 10240000 2343 130560 2 387533637 99.999 4101020 337 http://s1cern-cvmfs.openhtc.io/cvmfs/atlas.cern.ch http://192.168.101.86:3128 1
[2022-11-22 06:13:17] CVMFS is ok
[2022-11-22 06:13:17] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7
[2022-11-22 06:13:17] Checking for apptainer binary...
[2022-11-22 06:13:17] Using apptainer found in PATH at /usr/bin/apptainer
[2022-11-22 06:13:17] Running /usr/bin/apptainer --version
[2022-11-22 06:13:17] apptainer version 1.1.0-rc.2
[2022-11-22 06:13:17] Checking apptainer works with /usr/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname
[2022-11-22 06:13:17] ellievl
[2022-11-22 06:13:17] apptainer works
[2022-11-22 06:13:17] Set ATHENA_PROC_NUMBER=2
[2022-11-22 06:13:17] Starting ATLAS job with PandaID=5672623273
[2022-11-22 06:13:17] Try creating a directory in /tmp
[2022-11-22 06:13:17] /usr/bin/apptainer exec -B /cvmfs,/var/lib/boinc_data/boinc-01/slots/3 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 mktemp /tmp/tmp.XXXXX
[2022-11-22 06:13:17] /tmp/tmp.4O3Rw
[2022-11-22 06:13:17] Try creating a directory in /var/tmp
[2022-11-22 06:13:17] /usr/bin/apptainer exec -B /cvmfs,/var/lib/boinc_data/boinc-01/slots/3 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 mktemp /var/tmp/tmp.XXXXX
[2022-11-22 06:13:17] /var/tmp/tmp.An3aR
[2022-11-22 06:13:17] Running command: /usr/bin/apptainer exec -B /cvmfs,/var/lib/boinc_data/boinc-01/slots/3 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 sh start_atlas.sh
[2022-11-22 06:22:29] *** The last 200 lines of the pilot log: ***
[2022-11-22 06:22:29] 2022-11-22 05:22:15,120 | INFO | pilot.control.job | is_final_update | job 5672623273 has finished - writing final server update
[2022-11-22 06:22:29] 2022-11-22 05:22:15,120 | DEBUG | pilot.control.job | get_data_structure | state=finished
[2022-11-22 06:22:29] 2022-11-22 05:22:15,121 | DEBUG | pilot.user.atlas.jobmetrics | get_job_metrics_string | job definition core count: 2
[2022-11-22 06:22:29] 2022-11-22 05:22:15,121 | DEBUG | pilot.api.analytics | get_fitted_data | removing tails from data to be fitted
[2022-11-22 06:22:29] 2022-11-22 05:22:15,121 | WARNING | pilot.api.analytics | get_fitted_data | wrong length of table data, x=[1669094370.0], y=[1655370.0] (must be same and length>=4)
[2022-11-22 06:22:29] 2022-11-22 05:22:15,121 | DEBUG | pilot.user.atlas.jobmetrics | get_job_metrics | job metrics="actualCoreCount=0.72 nEvents=2 dbTime=18.57 dbData=4308190 workDirSize=4099776 eventnum
[2022-11-22 06:22:29] 2022-11-22 05:22:15,121 | INFO | pilot.control.job | get_data_structure | total number of processed events: 2 (read)
[2022-11-22 06:22:29] 2022-11-22 05:22:15,122 | INFO | pilot.util.container | print_executable | executing command: grep -o 'avx2[^ ]*\|AVX2[^ ]*' /proc/cpuinfo
[2022-11-22 06:22:29] 2022-11-22 05:22:15,178 | INFO | pilot.user.atlas.utilities | get_memory_values | using path: /var/lib/boinc_data/boinc-01/slots/3/PanDA_Pilot-5672623273/memory_monitor_summary.json
[2022-11-22 06:22:29] 2022-11-22 05:22:15,179 | DEBUG | pilot.user.atlas.utilities | get_memory_monitor_info | summary_dictionary={'Avg': {'nprocs': 5.625, 'nthreads': 6.5, 'pss': 962737.0, 'rchar': 208903693.0,
[2022-11-22 06:22:29] 2022-11-22 05:22:15,179 | INFO | pilot.user.atlas.utilities | get_memory_monitor_info | extracted standard info from prmon json
[2022-11-22 06:22:29] 2022-11-22 05:22:15,179 | INFO | pilot.user.atlas.utilities | get_memory_monitor_info | extracted standard memory fields from prmon json
[2022-11-22 06:22:29] 2022-11-22 05:22:15,179 | INFO | pilot.util.timing | timing_report | ..............................
[2022-11-22 06:22:29] 2022-11-22 05:22:15,179 | INFO | pilot.util.timing | timing_report | . Timing measurements:
[2022-11-22 06:22:29] 2022-11-22 05:22:15,179 | INFO | pilot.util.timing | timing_report | . get job = 0 s
[2022-11-22 06:22:29] 2022-11-22 05:22:15,179 | INFO | pilot.util.timing | timing_report | . initial setup = 3 s
[2022-11-22 06:22:29] 2022-11-22 05:22:15,179 | INFO | pilot.util.timing | timing_report | . payload setup = 9 s
[2022-11-22 06:22:29] 2022-11-22 05:22:15,179 | INFO | pilot.util.timing | timing_report | . stage-in = 0 s
[2022-11-22 06:22:29] 2022-11-22 05:22:15,179 | INFO | pilot.util.timing | timing_report | . payload execution = 478 s
[2022-11-22 06:22:29] 2022-11-22 05:22:15,179 | INFO | pilot.util.timing | timing_report | . stage-out = 0 s
[2022-11-22 06:22:29] 2022-11-22 05:22:15,179 | INFO | pilot.util.timing | timing_report | ..............................
[2022-11-22 06:22:29] 2022-11-22 05:22:15,180 | DEBUG | pilot.control.job | send_state | data={'jobId': '5672623273', 'state': 'finished', 'timestamp': '2022-11-22T06:22:15+01:00', 'siteNam
[2022-11-22 06:22:29] 2022-11-22 05:22:15,180 | DEBUG | pilot.control.job | write_heartbeat_to_file | heartbeat dictionary: {'jobId': '5672623273', 'state': 'finished', 'timestamp': '2022-11-22T06:22:15
[2022-11-22 06:22:29] 2022-11-22 05:22:15,180 | DEBUG | pilot.control.job | write_heartbeat_to_file | wrote heartbeat to file: /var/lib/boinc_data/boinc-01/slots/3/heartbeat.json
[2022-11-22 06:22:29] 2022-11-22 05:22:15,214 | INFO | pilot.control.job | make_job_report |
[2022-11-22 06:22:29] 2022-11-22 05:22:15,214 | INFO | pilot.control.job | make_job_report | job summary report
[2022-11-22 06:22:29] 2022-11-22 05:22:15,214 | INFO | pilot.control.job | make_job_report | --------------------------------------------------
[2022-11-22 06:22:29] 2022-11-22 05:22:15,214 | INFO | pilot.control.job | make_job_report | PanDA job id: 5672623273
[2022-11-22 06:22:29] 2022-11-22 05:22:15,214 | INFO | pilot.control.job | make_job_report | task id: NULL
[2022-11-22 06:22:29] 2022-11-22 05:22:15,214 | INFO | pilot.control.job | make_job_report | errors: (none)
[2022-11-22 06:22:29] 2022-11-22 05:22:15,214 | INFO | pilot.control.job | make_job_report | status: LOG_TRANSFER = DONE
[2022-11-22 06:22:29] 2022-11-22 05:22:15,214 | INFO | pilot.control.job | make_job_report | pilot state: finished
[2022-11-22 06:22:29] 2022-11-22 05:22:15,214 | INFO | pilot.control.job | make_job_report | transexitcode: 0
[2022-11-22 06:22:29] 2022-11-22 05:22:15,214 | INFO | pilot.control.job | make_job_report | exeerrorcode: 0
[2022-11-22 06:22:29] 2022-11-22 05:22:15,214 | INFO | pilot.control.job | make_job_report | exeerrordiag:
[2022-11-22 06:22:29] 2022-11-22 05:22:15,214 | INFO | pilot.control.job | make_job_report | exitcode: 0
[2022-11-22 06:22:29] 2022-11-22 05:22:15,214 | INFO | pilot.control.job | make_job_report | exitmsg: OK
[2022-11-22 06:22:29] 2022-11-22 05:22:15,214 | INFO | pilot.control.job | make_job_report | cpuconsumptiontime: 357 s
[2022-11-22 06:22:29] 2022-11-22 05:22:15,214 | INFO | pilot.control.job | make_job_report | nevents: 2
[2022-11-22 06:22:29] 2022-11-22 05:22:15,214 | INFO | pilot.control.job | make_job_report | neventsw: 0
[2022-11-22 06:22:29] 2022-11-22 05:22:15,215 | INFO | pilot.control.job | make_job_report | pid: 2811846
[2022-11-22 06:22:29] 2022-11-22 05:22:15,215 | INFO | pilot.control.job | make_job_report | pgrp: 2811846
[2022-11-22 06:22:29] 2022-11-22 05:22:15,215 | INFO | pilot.control.job | make_job_report | corecount: 2
[2022-11-22 06:22:29] 2022-11-22 05:22:15,215 | INFO | pilot.control.job | make_job_report | event service: False
[2022-11-22 06:22:29] 2022-11-22 05:22:15,215 | INFO | pilot.control.job | make_job_report | sizes: {0: 2573294, 11: 2573294, 491: 2595656, 492: 2605636}
[2022-11-22 06:22:29] 2022-11-22 05:22:15,215 | INFO | pilot.control.job | make_job_report | --------------------------------------------------
[2022-11-22 06:22:29] 2022-11-22 05:22:15,215 | INFO | pilot.control.job | make_job_report |
[2022-11-22 06:22:29] 2022-11-22 05:22:15,215 | DEBUG | pilot.control.job | has_job_completed | ls -lF /var/lib/boinc_data/boinc-01/slots/3:
[2022-11-22 06:22:29]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,215 | INFO | pilot.util.container | print_executable | executing command: ls -lF /var/lib/boinc_data/boinc-01/slots/3
[2022-11-22 06:22:29] 2022-11-22 05:22:15,258 | DEBUG | pilot.control.job | has_job_completed | total 42336
[2022-11-22 06:22:29] -rw-r--r-- 2 yeti yeti 37224345 Nov 22 06:13 EVNT.04972714._000026.pool.root.1
[2022-11-22 06:22:29] drwxrwx--- 3 yeti yeti 4096 Nov 22 06:22 PanDA_Pilot-5672623273/
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 1729337 Nov 22 06:13 agis_ddmendpoints.agis.ALL.json
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 1030312 Nov 22 06:13 agis_schedconf.cvmfs.json
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 0 Nov 22 06:13 boinc_lockfile
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 8192 Nov 22 06:22 boinc_mmap_file
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 532 Nov 22 06:20 boinc_task_state.xml
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 187160 Nov 22 06:22 c73d1ab6-1b3f-49ab-9a84-0016410d71fa_13219.1.job.log
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 431287 Nov 22 06:22 c73d1ab6-1b3f-49ab-9a84-0016410d71fa_13219.1.job.log.tgz
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 143 Nov 22 06:13 drSNDmBhZH2n7Olcko1bjSoqABFKDmABFKDmFymXDmHaOKDmTTzHHn.diag
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 7739 Nov 22 06:22 heartbeat.json
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 6499 Nov 22 06:13 init_data.xml
[2022-11-22 06:22:29] -rw-r--r-- 2 yeti yeti 401829 Nov 22 06:13 input.tar.gz
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 112 Nov 22 06:13 job.xml
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 999 Nov 22 06:22 memory_monitor_summary.json
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 1600225 Nov 22 06:22 output.1.c73d1ab6-1b3f-49ab-9a84-0016410d71fa_13219.pool.root
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 464 Nov 22 06:22 output.list
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 2656 Nov 22 06:13 pandaJob.out
[2022-11-22 06:22:29] drwx------ 4 yeti yeti 4096 Nov 22 06:13 pilot3/
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 390917 Nov 22 05:04 pilot3.tar.gz
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 166895 Nov 22 06:22 pilotlog.txt
[2022-11-22 06:22:29] -rw-r--r-- 1 yeti yeti 4540 Nov 22 05:02 queuedata.json
[2022-11-22 06:22:29] -rwxr-xr-x 1 yeti yeti 8273 Nov 22 06:13 run_atlas*
[2022-11-22 06:22:29] -rwx------ 1 yeti yeti 26316 Nov 22 05:04 runpilot2-wrapper.sh*
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 407 Nov 22 06:13 runtime_log
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 7037 Nov 22 06:13 runtime_log.err
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 469 Nov 22 06:13 setup.sh.local
[2022-11-22 06:22:29] drwxrwx--x 2 yeti yeti 4096 Nov 22 06:13 shared/
[2022-11-22 06:22:29] -rw-r--r-- 2 yeti yeti 17790 Nov 22 06:13 start_atlas.sh
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 2283 Nov 22 06:13 stderr.txt
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 107 Nov 22 06:13 wrapper_26015_x86_64-pc-linux-gnu
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 24 Nov 22 06:22 wrapper_checkpoint.txt
[2022-11-22 06:22:29] 2022-11-22 05:22:15,258 | INFO | pilot.util.queuehandling | queue_report | queue jobs had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,258 | INFO | pilot.util.queuehandling | queue_report | queue payloads had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,258 | INFO | pilot.util.queuehandling | queue_report | queue data_in had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,258 | INFO | pilot.util.queuehandling | queue_report | queue data_out had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,258 | INFO | pilot.util.queuehandling | queue_report | queue current_data_in had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,258 | INFO | pilot.util.queuehandling | queue_report | queue validated_jobs had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,258 | INFO | pilot.util.queuehandling | queue_report | queue validated_payloads had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,259 | INFO | pilot.util.queuehandling | queue_report | queue monitored_payloads had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,259 | INFO | pilot.util.queuehandling | queue_report | queue finished_jobs had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,259 | INFO | pilot.util.queuehandling | queue_report | queue finished_payloads had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,259 | INFO | pilot.util.queuehandling | queue_report | queue finished_data_in had 1 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,259 | INFO | pilot.util.queuehandling | queue_report | queue finished_data_out had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,259 | INFO | pilot.util.queuehandling | queue_report | queue failed_jobs had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,259 | INFO | pilot.util.queuehandling | queue_report | queue failed_payloads had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,259 | INFO | pilot.util.queuehandling | queue_report | queue failed_data_in had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,259 | INFO | pilot.util.queuehandling | queue_report | queue failed_data_out had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,259 | INFO | pilot.util.queuehandling | queue_report | queue completed_jobs had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,259 | INFO | pilot.util.queuehandling | queue_report | queue completed_jobids has 1 job(s)
[2022-11-22 06:22:29] 2022-11-22 05:22:15,259 | INFO | pilot.util.queuehandling | queue_report | queue realtimelog_payloads had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,259 | INFO | pilot.util.queuehandling | queue_report | queue messages had 0 job(s) [purged]
[2022-11-22 06:22:29] 2022-11-22 05:22:15,259 | INFO | pilot.control.job | has_job_completed | job 5672623273 has completed (purged errors)
[2022-11-22 06:22:29] 2022-11-22 05:22:15,259 | DEBUG | pilot.util.realtimelogger | cleanup | attempting real-time logger cleanup
[2022-11-22 06:22:29] 2022-11-22 05:22:15,259 | INFO | pilot.util.processes | cleanup | overall cleanup function is called
[2022-11-22 06:22:29] 2022-11-22 05:22:15,261 | DEBUG | pilot.util.processes | cleanup | work directory was removed: /var/lib/boinc_data/boinc-01/slots/3/PanDA_Pilot-5672623273
[2022-11-22 06:22:29] 2022-11-22 05:22:16,267 | INFO | pilot.info.jobdata | collect_zombies | --- collectZombieJob: --- 10, [2811846]
[2022-11-22 06:22:29] 2022-11-22 05:22:16,267 | INFO | pilot.info.jobdata | collect_zombies | zombie collector trying to kill pid 2811846
[2022-11-22 06:22:29] 2022-11-22 05:22:16,267 | INFO | pilot.info.jobdata | collect_zombies | harmless exception when collecting zombies: [Errno 10] No child processes
[2022-11-22 06:22:29] 2022-11-22 05:22:17,273 | INFO | pilot.util.processes | cleanup | collected zombie processes
[2022-11-22 06:22:29] 2022-11-22 05:22:17,273 | INFO | pilot.util.processes | cleanup | will now attempt to kill all subprocesses of pid=2811846
[2022-11-22 06:22:29] 2022-11-22 05:22:17,355 | INFO | pilot.util.processes | kill_processes | process IDs to be killed: [2811846] (in reverse order)
[2022-11-22 06:22:29] 2022-11-22 05:22:17,429 | WARNING | pilot.util.processes | kill_processes | found no corresponding commands to process id(s)
[2022-11-22 06:22:29] 2022-11-22 05:22:17,429 | INFO | pilot.util.processes | kill_orphans | Do not look for orphan processes in BOINC jobs
[2022-11-22 06:22:29] 2022-11-22 05:22:17,429 | DEBUG | pilot.util.queuehandling | purge_queue | queue purged
[2022-11-22 06:22:29] 2022-11-22 05:22:17,429 | INFO | pilot.control.job | retrieve | ready for new job
[2022-11-22 06:22:29] 2022-11-22 05:22:17,429 | INFO | root | retrieve | pilot has finished with previous job - re-establishing logging
[2022-11-22 06:22:29] 2022-11-22 05:22:17,430 | INFO | pilot.util.auxiliary | pilot_version_banner | ***************************************
[2022-11-22 06:22:29] 2022-11-22 05:22:17,430 | INFO | pilot.util.auxiliary | pilot_version_banner | *** PanDA Pilot version 3.4.4 (1) ***
[2022-11-22 06:22:29] 2022-11-22 05:22:17,430 | INFO | pilot.util.auxiliary | pilot_version_banner | ***************************************
[2022-11-22 06:22:29] 2022-11-22 05:22:17,430 | INFO | pilot.util.auxiliary | pilot_version_banner |
[2022-11-22 06:22:29] 2022-11-22 05:22:17,430 | INFO | pilot.util.auxiliary | pilot_version_banner | pilot is running in a VM
[2022-11-22 06:22:29] 2022-11-22 05:22:17,430 | INFO | pilot.util.auxiliary | display_architecture_info | architecture information:
[2022-11-22 06:22:29] 2022-11-22 05:22:17,637 | INFO | pilot.util.auxiliary | display_architecture_info |
[2022-11-22 06:22:29] LSB Version: :core-4.1-amd64:core-4.1-noarch
[2022-11-22 06:22:29] Distributor ID: CentOS
[2022-11-22 06:22:29] Description: CentOS Linux release 7.9.2009 (Core)
[2022-11-22 06:22:29] Release: 7.9.2009
[2022-11-22 06:22:29] Codename: Core
[2022-11-22 06:22:29] 2022-11-22 05:22:17,637 | INFO | pilot.util.auxiliary | pilot_version_banner | ***************************************
[2022-11-22 06:22:29] 2022-11-22 05:22:17,815 | DEBUG | pilot.control.job | job_monitor | job_monitor_tasks returned 0,
[2022-11-22 06:22:29] 2022-11-22 05:22:17,815 | INFO | pilot.control.job | job_monitor | aborting job monitoring since job object (job id=5672623273) has expired
[2022-11-22 06:22:29] 2022-11-22 05:22:18,141 | DEBUG | pilot.util.monitoring | check_local_space | checking local space on /var/lib/boinc_data/boinc-01/slots/3
[2022-11-22 06:22:29] 2022-11-22 05:22:18,141 | INFO | pilot.util.container | print_executable | executing command: df -mP /var/lib/boinc_data/boinc-01/slots/3
[2022-11-22 06:22:29] 2022-11-22 05:22:18,175 | DEBUG | pilot.util.workernode | get_local_disk_space | stdout=Filesystem 1048576-blocks Used Available Capacity Mounted on
[2022-11-22 06:22:29] /dev/sda5 62572 27551 31812 47% /var/lib/boinc_data/boinc-01/slots/3
[2022-11-22 06:22:29] 2022-11-22 05:22:18,175 | DEBUG | pilot.util.workernode | get_local_disk_space | stderr=
[2022-11-22 06:22:29] 2022-11-22 05:22:18,175 | INFO | pilot.util.monitoring | check_local_space | sufficient remaining disk space (33357299712 B)
[2022-11-22 06:22:29] 2022-11-22 05:22:18,175 | WARNING | pilot.control.job | proceed_with_getjob | since timefloor is set to 0, pilot was only allowed to run one job
[2022-11-22 06:22:29] 2022-11-22 05:22:18,176 | DEBUG | pilot.control.job | retrieve | will not set job_aborted yet
[2022-11-22 06:22:29] 2022-11-22 05:22:18,176 | INFO | pilot.control.job | retrieve | [job] retrieve thread has finished
[2022-11-22 06:22:29] 2022-11-22 05:22:18,176 | WARNING | pilot.control.monitor | control | aborting monitor loop since graceful_stop has been set
[2022-11-22 06:22:29] 2022-11-22 05:22:18,176 | INFO | pilot.control.monitor | control | [monitor] control thread has ended
[2022-11-22 06:22:29] 2022-11-22 05:22:18,176 | WARNING | pilot.util.common | should_abort | data:copytool_out:received graceful stop - abort after this iteration
[2022-11-22 06:22:29] 2022-11-22 05:22:18,319 | WARNING | pilot.util.common | should_abort | job:job_monitor:received graceful stop - abort after this iteration
[2022-11-22 06:22:29] 2022-11-22 05:22:18,503 | DEBUG | pilot.control.payload | failed_post | will not set job_aborted yet
[2022-11-22 06:22:29] 2022-11-22 05:22:18,503 | INFO | pilot.control.payload | failed_post | [payload] failed_post thread has finished
[2022-11-22 06:22:29] 2022-11-22 05:22:18,539 | DEBUG | pilot.control.payload | execute_payloads | will not set job_aborted yet
[2022-11-22 06:22:29] 2022-11-22 05:22:18,539 | INFO | pilot.control.payload | execute_payloads | [payload] execute_payloads thread has finished
[2022-11-22 06:22:29] 2022-11-22 05:22:18,694 | DEBUG | pilot.control.job | control | job control ending since graceful_stop has been set
[2022-11-22 06:22:29] 2022-11-22 05:22:18,694 | DEBUG | pilot.control.job | control | will not set job_aborted yet
[2022-11-22 06:22:29] 2022-11-22 05:22:18,694 | INFO | pilot.control.job | control | [job] control thread has finished
[2022-11-22 06:22:29] 2022-11-22 05:22:18,870 | DEBUG | pilot.control.data | control | data control ending since graceful_stop has been set
[2022-11-22 06:22:29] 2022-11-22 05:22:18,870 | DEBUG | pilot.control.data | control | will not set job_aborted yet
[2022-11-22 06:22:29] 2022-11-22 05:22:18,870 | INFO | pilot.control.data | control | [data] control thread has finished
[2022-11-22 06:22:29] 2022-11-22 05:22:18,958 | DEBUG | pilot.control.job | create_data_payload | will not set job_aborted yet
[2022-11-22 06:22:29] 2022-11-22 05:22:18,958 | INFO | pilot.control.job | create_data_payload | [job] create_data_payload thread has finished
[2022-11-22 06:22:29] 2022-11-22 05:22:18,972 | DEBUG | pilot.control.payload | validate_post | will not set job_aborted yet
[2022-11-22 06:22:29] 2022-11-22 05:22:18,972 | INFO | pilot.control.payload | validate_post | [payload] validate_post thread has finished
[2022-11-22 06:22:29] 2022-11-22 05:22:19,024 | DEBUG | pilot.control.payload | validate_pre | will not set job_aborted yet
[2022-11-22 06:22:29] 2022-11-22 05:22:19,024 | INFO | pilot.control.payload | validate_pre | [payload] validate_pre thread has finished
[2022-11-22 06:22:29] 2022-11-22 05:22:19,071 | DEBUG | pilot.control.job | validate | will not set job_aborted yet
[2022-11-22 06:22:29] 2022-11-22 05:22:19,071 | INFO | pilot.control.job | validate | [job] validate thread has finished
[2022-11-22 06:22:29] 2022-11-22 05:22:19,177 | DEBUG | pilot.control.data | copytool_out | will not set job_aborted yet
[2022-11-22 06:22:29] 2022-11-22 05:22:19,178 | INFO | pilot.control.data | copytool_out | [data] copytool_out thread has finished
[2022-11-22 06:22:29] 2022-11-22 05:22:19,310 | DEBUG | pilot.control.payload | control | payload control ending since graceful_stop has been set
[2022-11-22 06:22:29] 2022-11-22 05:22:19,310 | DEBUG | pilot.control.payload | control | will not set job_aborted yet
[2022-11-22 06:22:29] 2022-11-22 05:22:19,310 | INFO | pilot.control.payload | control | [payload] control thread has finished
[2022-11-22 06:22:29] 2022-11-22 05:22:19,325 | DEBUG | pilot.control.job | job_monitor | will not set job_aborted yet
[2022-11-22 06:22:29] 2022-11-22 05:22:19,325 | INFO | pilot.control.job | job_monitor | [job] job monitor thread has finished
[2022-11-22 06:22:29] 2022-11-22 05:22:19,339 | DEBUG | pilot.control.data | copytool_in | will not set job_aborted yet
[2022-11-22 06:22:29] 2022-11-22 05:22:19,339 | INFO | pilot.control.data | copytool_in | [data] copytool_in thread has finished
[2022-11-22 06:22:29] 2022-11-22 05:22:20,225 | WARNING | pilot.util.common | should_abort | job:queue_monitor:received graceful stop - abort after this iteration
[2022-11-22 06:22:29] 2022-11-22 05:22:20,226 | DEBUG | pilot.control.job | queue_monitor | will not set job_aborted yet
[2022-11-22 06:22:29] 2022-11-22 05:22:20,226 | INFO | pilot.control.job | queue_monitor | [job] queue monitor thread has finished
[2022-11-22 06:22:29] 2022-11-22 05:22:20,802 | WARNING | pilot.util.common | should_abort | data:queue_monitoring:received graceful stop - abort after this iteration
[2022-11-22 06:22:29] 2022-11-22 05:22:23,805 | DEBUG | pilot.control.data | queue_monitoring | will not set job_aborted yet
[2022-11-22 06:22:29] 2022-11-22 05:22:23,805 | INFO | pilot.control.data | queue_monitoring | [data] queue_monitor thread has finished
[2022-11-22 06:22:29] 2022-11-22 05:22:28,398 | INFO | pilot.control.payload | get_logging_info | job.realtimelogging is not enabled
[2022-11-22 06:22:29] 2022-11-22 05:22:28,398 | DEBUG | pilot.control.payload | run_realtimelog | real-time logging not needed at this point
[2022-11-22 06:22:29] 2022-11-22 05:22:28,398 | DEBUG | pilot.control.payload | run_realtimelog | realtime logger was not found, waiting ..
[2022-11-22 06:22:29] 2022-11-22 05:22:28,398 | DEBUG | pilot.control.payload | run_realtimelog | will not set job_aborted yet
[2022-11-22 06:22:29] 2022-11-22 05:22:28,398 | INFO | pilot.control.payload | run_realtimelog | [payload] run_realtimelog thread has finished
[2022-11-22 06:22:29] 2022-11-22 05:22:28,660 | DEBUG | pilot.util.processes | threads_aborted | aborting since only the main Pilot thread is still running (total thread count=1 with 0 daemon threa
[2022-11-22 06:22:29] 2022-11-22 05:22:28,661 | DEBUG | pilot.workflow.generic | run | all relevant threads have aborted (thread count=1)
[2022-11-22 06:22:29] 2022-11-22 05:22:28,661 | INFO | pilot.workflow.generic | run | end of generic workflow (traces error code: 0)
[2022-11-22 06:22:29] 2022-11-22 05:22:28,661 | INFO | root | wrap_up | traces error code: 0
[2022-11-22 06:22:29] 2022-11-22 05:22:28,661 | INFO | root | wrap_up | pilot has finished
[2022-11-22 06:22:29] 2022-11-22 05:22:28,719 [wrapper] ==== pilot stdout END ====
[2022-11-22 06:22:29] 2022-11-22 05:22:28,732 [wrapper] ==== wrapper stdout RESUME ====
[2022-11-22 06:22:29] 2022-11-22 05:22:28,746 [wrapper] pilotpid: 2808319
[2022-11-22 06:22:29] 2022-11-22 05:22:28,761 [wrapper] Pilot exit status: 0
[2022-11-22 06:22:29] 2022-11-22 05:22:28,811 [wrapper] pandaids: 5672623273
[2022-11-22 06:22:29] 2022-11-22 05:22:28,849 [wrapper] apfmon messages muted
[2022-11-22 06:22:29] 2022-11-22 05:22:28,867 [wrapper] Test setup, not cleaning
[2022-11-22 06:22:29] 2022-11-22 05:22:28,882 [wrapper] ==== wrapper stdout END ====
[2022-11-22 06:22:29] 2022-11-22 05:22:28,897 [wrapper] ==== wrapper stderr END ====
[2022-11-22 06:22:29] 2022-11-22 05:22:28,926 [wrapper] wrapperexiting ec=0, duration=550
[2022-11-22 06:22:29] 2022-11-22 05:22:28,942 [wrapper] apfmon messages muted
[2022-11-22 06:22:29] *** Error codes and diagnostics ***
[2022-11-22 06:22:29] "exeErrorCode": 0,
[2022-11-22 06:22:29] "exeErrorDiag": "",
[2022-11-22 06:22:29] "pilotErrorCode": 0,
[2022-11-22 06:22:29] "pilotErrorDiag": "",
[2022-11-22 06:22:29] *** Listing of results directory ***
[2022-11-22 06:22:29] insgesamt 44424
[2022-11-22 06:22:29] -rw-r--r-- 1 yeti yeti 4540 Nov 22 05:02 queuedata.json
[2022-11-22 06:22:29] -rwx------ 1 yeti yeti 26316 Nov 22 05:04 runpilot2-wrapper.sh
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 390917 Nov 22 05:04 pilot3.tar.gz
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 107 Nov 22 06:13 wrapper_26015_x86_64-pc-linux-gnu
[2022-11-22 06:22:29] -rwxr-xr-x 1 yeti yeti 8273 Nov 22 06:13 run_atlas
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 112 Nov 22 06:13 job.xml
[2022-11-22 06:22:29] -rw-r--r-- 2 yeti yeti 17790 Nov 22 06:13 start_atlas.sh
[2022-11-22 06:22:29] drwxrwx--x 2 yeti yeti 4096 Nov 22 06:13 shared
[2022-11-22 06:22:29] -rw-r--r-- 2 yeti yeti 401829 Nov 22 06:13 input.tar.gz
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 6499 Nov 22 06:13 init_data.xml
[2022-11-22 06:22:29] -rw-r--r-- 2 yeti yeti 37224345 Nov 22 06:13 EVNT.04972714._000026.pool.root.1
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 0 Nov 22 06:13 boinc_lockfile
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 2656 Nov 22 06:13 pandaJob.out
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 469 Nov 22 06:13 setup.sh.local
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 1030312 Nov 22 06:13 agis_schedconf.cvmfs.json
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 1729337 Nov 22 06:13 agis_ddmendpoints.agis.ALL.json
[2022-11-22 06:22:29] drwx------ 4 yeti yeti 4096 Nov 22 06:13 pilot3
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 532 Nov 22 06:20 boinc_task_state.xml
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 1600225 Nov 22 06:22 output.1.c73d1ab6-1b3f-49ab-9a84-0016410d71fa_13219.pool.root
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 999 Nov 22 06:22 memory_monitor_summary.json
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 431287 Nov 22 06:22 c73d1ab6-1b3f-49ab-9a84-0016410d71fa_13219.1.job.log.tgz
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 7739 Nov 22 06:22 heartbeat.json
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 8192 Nov 22 06:22 boinc_mmap_file
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 24 Nov 22 06:22 wrapper_checkpoint.txt
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 9383 Nov 22 06:22 pilotlog.txt
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 204499 Nov 22 06:22 c73d1ab6-1b3f-49ab-9a84-0016410d71fa_13219.1.job.log
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 464 Nov 22 06:22 output.list
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 748 Nov 22 06:22 runtime_log
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 2252800 Nov 22 06:22 result.tar.gz
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 11292 Nov 22 06:22 runtime_log.err
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 621 Nov 22 06:22 drSNDmBhZH2n7Olcko1bjSoqABFKDmABFKDmFymXDmHaOKDmTTzHHn.diag
[2022-11-22 06:22:29] -rw-rw-r-- 1 yeti yeti 31087 Nov 22 06:22 stderr.txt
[2022-11-22 06:22:29] HITS file was successfully produced:
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 1600225 Nov 22 06:22 shared/HITS.pool.root.1
[2022-11-22 06:22:29] *** Contents of shared directory: ***
[2022-11-22 06:22:29] insgesamt 40536
[2022-11-22 06:22:29] -rw-r--r-- 2 yeti yeti 17790 Nov 22 06:13 start_atlas.sh
[2022-11-22 06:22:29] -rw-r--r-- 2 yeti yeti 401829 Nov 22 06:13 input.tar.gz
[2022-11-22 06:22:29] -rw-r--r-- 2 yeti yeti 37224345 Nov 22 06:13 ATLAS.root_0
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 1600225 Nov 22 06:22 HITS.pool.root.1
[2022-11-22 06:22:29] -rw------- 1 yeti yeti 2252800 Nov 22 06:22 result.tar.gz
06:22:31 (2802742): run_atlas exited; CPU time 295.332894
06:22:31 (2802742): called boinc_finish(0)
</stderr_txt>
]]>
©2024 CERN