Name PapMDmE4Fu1n7Olcko1bjSoqABFKDmABFKDmKFbSDmPpJKDmO2dJMo_1
Workunit 2210270
Created 18 Sep 2022, 22:05:45 UTC
Sent 18 Sep 2022, 22:11:42 UTC
Report deadline 25 Sep 2022, 22:11:42 UTC
Received 18 Sep 2022, 22:20:04 UTC
Server state Over
Outcome Success
Client state Done
Exit status 0 (0x00000000)
Computer ID 4712
Run time 8 min 0 sec
CPU time 7 min 28 sec
Validate state Valid
Credit 6.45
Device peak FLOPS 2.00 GFLOPS
Application version ATLAS Simulation v1.25 (native_mt)
x86_64-pc-linux-gnu
Peak working set size 1.39 GB
Peak swap size 2.12 GB
Peak disk usage 80.66 MB

Stderr output

<core_client_version>7.20.2</core_client_version>
<![CDATA[
<stderr_txt>
00:11:55 (452773): wrapper (7.7.26015): starting
00:11:55 (452773): wrapper: running run_atlas (--nthreads 2)
[2022-09-19 00:11:55] Arguments: --nthreads 2
[2022-09-19 00:11:55] Threads: 2
[2022-09-19 00:11:55] Checking for CVMFS
[2022-09-19 00:11:55] Probing /cvmfs/atlas.cern.ch... OK
[2022-09-19 00:11:55] Probing /cvmfs/atlas-condb.cern.ch... OK
[2022-09-19 00:11:55] Running cvmfs_config stat atlas.cern.ch
[2022-09-19 00:11:55] 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-09-19 00:11:55] 2.9.4.0 3142 495 69348 109692 0 60 9764963 10240001 2343 130560 0 2759213 99.998 45605 1380 http://s1cern-cvmfs.openhtc.io/cvmfs/atlas.cern.ch http://192.168.101.86:3128 1
[2022-09-19 00:11:55] CVMFS is ok
[2022-09-19 00:11:55] Using apptainer image /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7
[2022-09-19 00:11:55] Checking for apptainer binary...
[2022-09-19 00:11:55] Using apptainer found in PATH at /usr/bin/apptainer
[2022-09-19 00:11:55] Running /usr/bin/apptainer --version
[2022-09-19 00:11:55] apptainer version 1.1.0-rc.2
[2022-09-19 00:11:55] Checking apptainer works with /usr/bin/apptainer exec -B /cvmfs /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 hostname
[2022-09-19 00:11:55] julianvl
[2022-09-19 00:11:55] apptainer works
[2022-09-19 00:11:55] Set ATHENA_PROC_NUMBER=2
[2022-09-19 00:11:55] Starting ATLAS job with PandaID=5600298877
[2022-09-19 00:11:55] Try creating a directory in /tmp
[2022-09-19 00:11:55] /usr/bin/apptainer exec -B /cvmfs,/var/lib/boinc_data/boinc-01/slots/2 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7  mktemp /tmp/tmp.XXXXX
[2022-09-19 00:11:55] /tmp/tmp.ihN3f
[2022-09-19 00:11:55] Try creating a directory in /var/tmp
[2022-09-19 00:11:55] /usr/bin/apptainer exec -B /cvmfs,/var/lib/boinc_data/boinc-01/slots/2 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7  mktemp /var/tmp/tmp.XXXXX
[2022-09-19 00:11:55] /var/tmp/tmp.bv0qL
[2022-09-19 00:11:56] Running command: /usr/bin/apptainer exec -B /cvmfs,/var/lib/boinc_data/boinc-01/slots/2 /cvmfs/atlas.cern.ch/repo/containers/fs/singularity/x86_64-centos7 sh start_atlas.sh
[2022-09-19 00:19:52]  *** The last 200 lines of the pilot log: ***
[2022-09-19 00:19:52] 2022-09-18 22:19:33,737 | DEBUG    | pilot.user.atlas.common          | update_server             | no need to update logstash for this job
[2022-09-19 00:19:52] 2022-09-18 22:19:33,737 | DEBUG    | pilot.control.job                | send_state                | REACHED_MAXTIME=None
[2022-09-19 00:19:52] 2022-09-18 22:19:33,737 | INFO     | pilot.control.job                | send_state                | pilot will not update the server (heartbeat message will be written to file)
[2022-09-19 00:19:52] 2022-09-18 22:19:33,737 | INFO     | pilot.control.job                | is_final_update           | job 5600298877 has finished - writing final server update
[2022-09-19 00:19:52] 2022-09-18 22:19:33,737 | DEBUG    | pilot.control.job                | get_data_structure        | state=finished
[2022-09-19 00:19:52] 2022-09-18 22:19:33,737 | DEBUG    | pilot.user.atlas.jobmetrics      | get_job_metrics_string    | job definition core count: 2
[2022-09-19 00:19:52] 2022-09-18 22:19:33,737 | WARNING  | pilot.api.analytics              | get_fitted_data           | wrong length of table data, x=[1663539180.0, 1663539241.0, 1663539302.0, 1663539363.0, 1663539424.0,
[2022-09-19 00:19:52] 2022-09-18 22:19:33,738 | DEBUG    | pilot.user.atlas.jobmetrics      | get_job_metrics           | job metrics="actualCoreCount=1.15 nEvents=2 dbTime=2.64 dbData=4308190 workDirSize=3961019 eventnumb
[2022-09-19 00:19:52] 2022-09-18 22:19:33,738 | INFO     | pilot.control.job                | get_data_structure        | total number of processed events: 2 (read)
[2022-09-19 00:19:52] 2022-09-18 22:19:33,738 | INFO     | pilot.util.container             | print_executable          | executing command: grep -o 'avx2[^ ]*\|AVX2[^ ]*' /proc/cpuinfo
[2022-09-19 00:19:52] 2022-09-18 22:19:33,770 | INFO     | pilot.user.atlas.utilities       | get_memory_values         | using path: /var/lib/boinc_data/boinc-01/slots/2/PanDA_Pilot-5600298877/memory_monitor_summary.json 
[2022-09-19 00:19:52] 2022-09-18 22:19:33,771 | DEBUG    | pilot.user.atlas.utilities       | get_memory_monitor_info   | summary_dictionary={'Avg': {'nprocs': 5.571, 'nthreads': 9.142, 'pss': 1024866.0, 'rchar': 219745179
[2022-09-19 00:19:52] 2022-09-18 22:19:33,771 | INFO     | pilot.user.atlas.utilities       | get_memory_monitor_info   | extracted standard info from prmon json
[2022-09-19 00:19:52] 2022-09-18 22:19:33,771 | INFO     | pilot.user.atlas.utilities       | get_memory_monitor_info   | extracted standard memory fields from prmon json
[2022-09-19 00:19:52] 2022-09-18 22:19:33,771 | INFO     | pilot.util.timing                | timing_report             | ..............................
[2022-09-19 00:19:52] 2022-09-18 22:19:33,771 | INFO     | pilot.util.timing                | timing_report             | . Timing measurements:
[2022-09-19 00:19:52] 2022-09-18 22:19:33,771 | INFO     | pilot.util.timing                | timing_report             | . get job = 0 s
[2022-09-19 00:19:52] 2022-09-18 22:19:33,771 | INFO     | pilot.util.timing                | timing_report             | . initial setup = 1 s
[2022-09-19 00:19:52] 2022-09-18 22:19:33,771 | INFO     | pilot.util.timing                | timing_report             | . payload setup = 15 s
[2022-09-19 00:19:52] 2022-09-18 22:19:33,771 | INFO     | pilot.util.timing                | timing_report             | . stage-in = 0 s
[2022-09-19 00:19:52] 2022-09-18 22:19:33,771 | INFO     | pilot.util.timing                | timing_report             | . payload execution = 399 s
[2022-09-19 00:19:52] 2022-09-18 22:19:33,771 | INFO     | pilot.util.timing                | timing_report             | . stage-out = 0 s
[2022-09-19 00:19:52] 2022-09-18 22:19:33,771 | INFO     | pilot.util.timing                | timing_report             | ..............................
[2022-09-19 00:19:52] 2022-09-18 22:19:33,771 | DEBUG    | pilot.control.job                | send_state                | data={'jobId': '5600298877', 'state': 'finished', 'timestamp': '2022-09-19T00:19:33+01:00', 'siteNam
[2022-09-19 00:19:52] 2022-09-18 22:19:33,771 | DEBUG    | pilot.control.job                | write_heartbeat_to_file   | heartbeat dictionary: {'jobId': '5600298877', 'state': 'finished', 'timestamp': '2022-09-19T00:19:33
[2022-09-19 00:19:52] 2022-09-18 22:19:33,772 | DEBUG    | pilot.control.job                | write_heartbeat_to_file   | wrote heartbeat to file: /var/lib/boinc_data/boinc-01/slots/2/heartbeat.json
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | 
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | job summary report
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | --------------------------------------------------
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | PanDA job id: 5600298877
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | task id: NULL
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | errors: (none)
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | status: LOG_TRANSFER = DONE 
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | pilot state: finished 
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | transexitcode: 0
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | exeerrorcode: 0
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | exeerrordiag: 
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | exitcode: 0
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | exitmsg: OK
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | cpuconsumptiontime: 489 s
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | nevents: 2
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | neventsw: 0
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | pid: 461617
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | pgrp: 461617
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | corecount: 2
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | event service: False
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | sizes: {0: 2656878, 1: 2657084, 11: 2657112, 418: 2683396, 419: 2692506, 420: 2693352}
[2022-09-19 00:19:52] 2022-09-18 22:19:34,222 | INFO     | pilot.control.job                | make_job_report           | --------------------------------------------------
[2022-09-19 00:19:52] 2022-09-18 22:19:34,223 | INFO     | pilot.control.job                | make_job_report           | 
[2022-09-19 00:19:52] 2022-09-18 22:19:34,223 | DEBUG    | pilot.control.job                | has_job_completed         | ls -lF /var/lib/boinc_data/boinc-01/slots/2:
[2022-09-19 00:19:52] 
[2022-09-19 00:19:52] 2022-09-18 22:19:34,223 | INFO     | pilot.util.container             | print_executable          | executing command: ls -lF /var/lib/boinc_data/boinc-01/slots/2
[2022-09-19 00:19:52] 2022-09-18 22:19:34,250 | DEBUG    | pilot.control.job                | has_job_completed         | total 43200
[2022-09-19 00:19:52] -rw------- 1 yeti yeti   184531 Sep 19 00:19 58974faf-78cb-4a92-9d53-0ab80ff3ddea_89063.1.job.log
[2022-09-19 00:19:52] -rw------- 1 yeti yeti   433218 Sep 19 00:19 58974faf-78cb-4a92-9d53-0ab80ff3ddea_89063.1.job.log.tgz
[2022-09-19 00:19:52] -rw-r--r-- 2 yeti yeti 38184411 Sep 19 00:11 EVNT.04972714._000028.pool.root.1
[2022-09-19 00:19:52] drwxrwx--- 3 yeti yeti     4096 Sep 19 00:19 PanDA_Pilot-5600298877/
[2022-09-19 00:19:52] -rw------- 1 yeti yeti      144 Sep 19 00:11 PapMDmE4Fu1n7Olcko1bjSoqABFKDmABFKDmKFbSDmPpJKDmO2dJMo.diag
[2022-09-19 00:19:52] -rw------- 1 yeti yeti  1779729 Sep 19 00:12 agis_ddmendpoints.agis.ALL.json
[2022-09-19 00:19:52] -rw------- 1 yeti yeti  1059513 Sep 19 00:12 agis_schedconf.cvmfs.json
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti        0 Sep 19 00:11 boinc_lockfile
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti     8192 Sep 19 00:19 boinc_mmap_file
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti      532 Sep 19 00:17 boinc_task_state.xml
[2022-09-19 00:19:52] -rw------- 1 yeti yeti    10333 Sep 19 00:19 heartbeat.json
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti     6250 Sep 19 00:11 init_data.xml
[2022-09-19 00:19:52] -rw-r--r-- 2 yeti yeti   400447 Sep 19 00:11 input.tar.gz
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti      112 Sep 19 00:11 job.xml
[2022-09-19 00:19:52] -rw------- 1 yeti yeti     1012 Sep 19 00:19 memory_monitor_summary.json
[2022-09-19 00:19:52] -rw------- 1 yeti yeti  1454053 Sep 19 00:19 output.1.58974faf-78cb-4a92-9d53-0ab80ff3ddea_89063.pool.root
[2022-09-19 00:19:52] -rw------- 1 yeti yeti      464 Sep 19 00:19 output.list
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti     2652 Sep 19 00:11 pandaJob.out
[2022-09-19 00:19:52] drwx------ 4 yeti yeti     4096 Sep 19 00:12 pilot3/
[2022-09-19 00:19:52] -rw------- 1 yeti yeti   389243 Sep 18 22:27 pilot3.tar.gz
[2022-09-19 00:19:52] -rw------- 1 yeti yeti   164430 Sep 19 00:19 pilotlog.txt
[2022-09-19 00:19:52] -rw-r--r-- 1 yeti yeti     4534 Sep 18 22:26 queuedata.json
[2022-09-19 00:19:52] -rwxr-xr-x 1 yeti yeti     8273 Sep 19 00:11 run_atlas*
[2022-09-19 00:19:52] -rwx------ 1 yeti yeti    26695 Sep 18 22:27 runpilot2-wrapper.sh*
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti      407 Sep 19 00:11 runtime_log
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti     6987 Sep 19 00:11 runtime_log.err
[2022-09-19 00:19:52] -rw------- 1 yeti yeti      469 Sep 19 00:11 setup.sh.local
[2022-09-19 00:19:52] drwxrwx--x 2 yeti yeti     4096 Sep 19 00:11 shared/
[2022-09-19 00:19:52] -rw-r--r-- 2 yeti yeti    17264 Sep 19 00:11 start_atlas.sh
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti     2273 Sep 19 00:11 stderr.txt
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti      107 Sep 19 00:11 wrapper_26015_x86_64-pc-linux-gnu
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti       24 Sep 19 00:19 wrapper_checkpoint.txt
[2022-09-19 00:19:52] 2022-09-18 22:19:34,250 | INFO     | pilot.util.queuehandling         | queue_report              | queue jobs had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,250 | INFO     | pilot.util.queuehandling         | queue_report              | queue payloads had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,250 | INFO     | pilot.util.queuehandling         | queue_report              | queue data_in had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,250 | INFO     | pilot.util.queuehandling         | queue_report              | queue data_out had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,250 | INFO     | pilot.util.queuehandling         | queue_report              | queue current_data_in had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,250 | INFO     | pilot.util.queuehandling         | queue_report              | queue validated_jobs had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,250 | INFO     | pilot.util.queuehandling         | queue_report              | queue validated_payloads had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,250 | INFO     | pilot.util.queuehandling         | queue_report              | queue monitored_payloads had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,250 | INFO     | pilot.util.queuehandling         | queue_report              | queue finished_jobs had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,250 | INFO     | pilot.util.queuehandling         | queue_report              | queue finished_payloads had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,251 | INFO     | pilot.util.queuehandling         | queue_report              | queue finished_data_in had 1 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,251 | INFO     | pilot.util.queuehandling         | queue_report              | queue finished_data_out had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,251 | INFO     | pilot.util.queuehandling         | queue_report              | queue failed_jobs had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,251 | INFO     | pilot.util.queuehandling         | queue_report              | queue failed_payloads had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,251 | INFO     | pilot.util.queuehandling         | queue_report              | queue failed_data_in had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,251 | INFO     | pilot.util.queuehandling         | queue_report              | queue failed_data_out had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,251 | INFO     | pilot.util.queuehandling         | queue_report              | queue completed_jobs had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,251 | INFO     | pilot.util.queuehandling         | queue_report              | queue completed_jobids has 1 job(s)
[2022-09-19 00:19:52] 2022-09-18 22:19:34,251 | INFO     | pilot.util.queuehandling         | queue_report              | queue realtimelog_payloads had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,251 | INFO     | pilot.util.queuehandling         | queue_report              | queue messages had 0 job(s) [purged]
[2022-09-19 00:19:52] 2022-09-18 22:19:34,251 | INFO     | pilot.control.job                | has_job_completed         | job 5600298877 has completed (purged errors)
[2022-09-19 00:19:52] 2022-09-18 22:19:34,251 | DEBUG    | pilot.util.realtimelogger        | cleanup                   | attempting real-time logger cleanup
[2022-09-19 00:19:52] 2022-09-18 22:19:34,251 | INFO     | pilot.util.processes             | cleanup                   | overall cleanup function is called
[2022-09-19 00:19:52] 2022-09-18 22:19:34,253 | DEBUG    | pilot.util.processes             | cleanup                   | work directory was removed: /var/lib/boinc_data/boinc-01/slots/2/PanDA_Pilot-5600298877
[2022-09-19 00:19:52] 2022-09-18 22:19:34,971 | DEBUG    | pilot.control.job                | job_monitor               | job_monitor_tasks returned 0, 
[2022-09-19 00:19:52] 2022-09-18 22:19:34,971 | INFO     | pilot.control.job                | job_monitor               | aborting job monitoring since job object (job id=5600298877) has expired
[2022-09-19 00:19:52] 2022-09-18 22:19:35,257 | INFO     | pilot.info.jobdata               | collect_zombies           | --- collectZombieJob: --- 10, [461617]
[2022-09-19 00:19:52] 2022-09-18 22:19:35,257 | INFO     | pilot.info.jobdata               | collect_zombies           | zombie collector trying to kill pid 461617
[2022-09-19 00:19:52] 2022-09-18 22:19:35,257 | INFO     | pilot.info.jobdata               | collect_zombies           | harmless exception when collecting zombies: [Errno 10] No child processes
[2022-09-19 00:19:52] 2022-09-18 22:19:36,262 | INFO     | pilot.util.processes             | cleanup                   | collected zombie processes
[2022-09-19 00:19:52] 2022-09-18 22:19:36,262 | INFO     | pilot.util.processes             | cleanup                   | will now attempt to kill all subprocesses of pid=461617
[2022-09-19 00:19:52] 2022-09-18 22:19:36,310 | INFO     | pilot.util.processes             | kill_processes            | process IDs to be killed: [461617] (in reverse order)
[2022-09-19 00:19:52] 2022-09-18 22:19:36,361 | WARNING  | pilot.util.processes             | kill_processes            | found no corresponding commands to process id(s)
[2022-09-19 00:19:52] 2022-09-18 22:19:36,361 | INFO     | pilot.util.processes             | kill_orphans              | Do not look for orphan processes in BOINC jobs
[2022-09-19 00:19:52] 2022-09-18 22:19:36,361 | DEBUG    | pilot.util.queuehandling         | purge_queue               | queue purged
[2022-09-19 00:19:52] 2022-09-18 22:19:36,362 | INFO     | pilot.control.job                | retrieve                  | ready for new job
[2022-09-19 00:19:52] 2022-09-18 22:19:36,362 | INFO     | root                             | retrieve                  | pilot has finished with previous job - re-establishing logging
[2022-09-19 00:19:52] 2022-09-18 22:19:36,362 | INFO     | pilot.util.auxiliary             | pilot_version_banner      | *****************************************
[2022-09-19 00:19:52] 2022-09-18 22:19:36,362 | INFO     | pilot.util.auxiliary             | pilot_version_banner      | ***  PanDA Pilot version 3.3.4 (114)  ***
[2022-09-19 00:19:52] 2022-09-18 22:19:36,362 | INFO     | pilot.util.auxiliary             | pilot_version_banner      | *****************************************
[2022-09-19 00:19:52] 2022-09-18 22:19:36,362 | INFO     | pilot.util.auxiliary             | pilot_version_banner      | 
[2022-09-19 00:19:52] 2022-09-18 22:19:36,362 | INFO     | pilot.util.auxiliary             | pilot_version_banner      | pilot is running in a VM
[2022-09-19 00:19:52] 2022-09-18 22:19:36,363 | INFO     | pilot.util.auxiliary             | display_architecture_info | architecture information:
[2022-09-19 00:19:52] 2022-09-18 22:19:36,489 | INFO     | pilot.util.auxiliary             | display_architecture_info | 
[2022-09-19 00:19:52] LSB Version:	:core-4.1-amd64:core-4.1-noarch
[2022-09-19 00:19:52] Distributor ID:	CentOS
[2022-09-19 00:19:52] Description:	CentOS Linux release 7.9.2009 (Core)
[2022-09-19 00:19:52] Release:	7.9.2009
[2022-09-19 00:19:52] Codename:	Core
[2022-09-19 00:19:52] 2022-09-18 22:19:36,489 | INFO     | pilot.util.auxiliary             | pilot_version_banner      | *****************************************
[2022-09-19 00:19:52] 2022-09-18 22:19:36,993 | DEBUG    | pilot.util.monitoring            | check_local_space         | checking local space on /var/lib/boinc_data/boinc-01/slots/2
[2022-09-19 00:19:52] 2022-09-18 22:19:36,993 | INFO     | pilot.util.container             | print_executable          | executing command: df -mP /var/lib/boinc_data/boinc-01/slots/2
[2022-09-19 00:19:52] 2022-09-18 22:19:37,018 | DEBUG    | pilot.util.workernode            | get_local_disk_space      | stdout=Filesystem     1048576-blocks  Used Available Capacity Mounted on
[2022-09-19 00:19:52] /dev/sda5               62572 28082     31280      48% /var/lib/boinc_data/boinc-01/slots/2
[2022-09-19 00:19:52] 2022-09-18 22:19:37,018 | DEBUG    | pilot.util.workernode            | get_local_disk_space      | stderr=
[2022-09-19 00:19:52] 2022-09-18 22:19:37,018 | INFO     | pilot.util.monitoring            | check_local_space         | sufficient remaining disk space (32799457280 B)
[2022-09-19 00:19:52] 2022-09-18 22:19:37,019 | WARNING  | pilot.control.job                | proceed_with_getjob       | since timefloor is set to 0, pilot was only allowed to run one job
[2022-09-19 00:19:52] 2022-09-18 22:19:37,019 | DEBUG    | pilot.control.job                | retrieve                  | will not set job_aborted yet
[2022-09-19 00:19:52] 2022-09-18 22:19:37,019 | INFO     | pilot.control.job                | retrieve                  | [job] retrieve thread has finished
[2022-09-19 00:19:52] 2022-09-18 22:19:37,019 | WARNING  | pilot.control.monitor            | control                   | aborting monitor loop since graceful_stop has been set
[2022-09-19 00:19:52] 2022-09-18 22:19:37,019 | INFO     | pilot.control.monitor            | control                   | [monitor] control thread has ended
[2022-09-19 00:19:52] 2022-09-18 22:19:37,478 | DEBUG    | pilot.control.job                | job_monitor               | will not set job_aborted yet
[2022-09-19 00:19:52] 2022-09-18 22:19:37,478 | INFO     | pilot.control.job                | job_monitor               | [job] job monitor thread has finished
[2022-09-19 00:19:52] 2022-09-18 22:19:37,492 | DEBUG    | pilot.control.job                | create_data_payload       | will not set job_aborted yet
[2022-09-19 00:19:52] 2022-09-18 22:19:37,492 | INFO     | pilot.control.job                | create_data_payload       | [job] create_data_payload thread has finished
[2022-09-19 00:19:52] 2022-09-18 22:19:37,631 | DEBUG    | pilot.control.payload            | validate_pre              | will not set job_aborted yet
[2022-09-19 00:19:52] 2022-09-18 22:19:37,632 | INFO     | pilot.control.payload            | validate_pre              | [payload] validate_pre thread has finished
[2022-09-19 00:19:52] 2022-09-18 22:19:37,636 | DEBUG    | pilot.control.payload            | execute_payloads          | will not set job_aborted yet
[2022-09-19 00:19:52] 2022-09-18 22:19:37,636 | INFO     | pilot.control.payload            | execute_payloads          | [payload] execute_payloads thread has finished
[2022-09-19 00:19:52] 2022-09-18 22:19:37,707 | DEBUG    | pilot.control.job                | control                   | job control ending since graceful_stop has been set
[2022-09-19 00:19:52] 2022-09-18 22:19:37,707 | DEBUG    | pilot.control.job                | control                   | will not set job_aborted yet
[2022-09-19 00:19:52] 2022-09-18 22:19:37,707 | INFO     | pilot.control.job                | control                   | [job] control thread has finished
[2022-09-19 00:19:52] 2022-09-18 22:19:37,720 | DEBUG    | pilot.control.job                | validate                  | will not set job_aborted yet
[2022-09-19 00:19:52] 2022-09-18 22:19:37,720 | INFO     | pilot.control.job                | validate                  | [job] validate thread has finished
[2022-09-19 00:19:52] 2022-09-18 22:19:37,799 | WARNING  | pilot.util.common                | should_abort              | data:copytool_out:received graceful stop - abort after this iteration
[2022-09-19 00:19:52] 2022-09-18 22:19:37,818 | DEBUG    | pilot.control.payload            | control                   | payload control ending since graceful_stop has been set
[2022-09-19 00:19:52] 2022-09-18 22:19:37,818 | DEBUG    | pilot.control.payload            | control                   | will not set job_aborted yet
[2022-09-19 00:19:52] 2022-09-18 22:19:37,818 | INFO     | pilot.control.payload            | control                   | [payload] control thread has finished
[2022-09-19 00:19:52] 2022-09-18 22:19:37,941 | DEBUG    | pilot.control.data               | control                   | data control ending since graceful_stop has been set
[2022-09-19 00:19:52] 2022-09-18 22:19:37,941 | DEBUG    | pilot.control.data               | control                   | will not set job_aborted yet
[2022-09-19 00:19:52] 2022-09-18 22:19:37,941 | INFO     | pilot.control.data               | control                   | [data] control thread has finished
[2022-09-19 00:19:52] 2022-09-18 22:19:38,109 | DEBUG    | pilot.control.payload            | validate_post             | will not set job_aborted yet
[2022-09-19 00:19:52] 2022-09-18 22:19:38,110 | INFO     | pilot.control.payload            | validate_post             | [payload] validate_post thread has finished
[2022-09-19 00:19:52] 2022-09-18 22:19:38,131 | DEBUG    | pilot.control.data               | copytool_in               | will not set job_aborted yet
[2022-09-19 00:19:52] 2022-09-18 22:19:38,131 | INFO     | pilot.control.data               | copytool_in               | [data] copytool_in thread has finished
[2022-09-19 00:19:52] 2022-09-18 22:19:38,532 | DEBUG    | pilot.control.payload            | failed_post               | will not set job_aborted yet
[2022-09-19 00:19:52] 2022-09-18 22:19:38,532 | INFO     | pilot.control.payload            | failed_post               | [payload] failed_post thread has finished
[2022-09-19 00:19:52] 2022-09-18 22:19:38,607 | WARNING  | pilot.util.common                | should_abort              | data:queue_monitoring:received graceful stop - abort after this iteration
[2022-09-19 00:19:52] 2022-09-18 22:19:38,799 | DEBUG    | pilot.control.data               | copytool_out              | will not set job_aborted yet
[2022-09-19 00:19:52] 2022-09-18 22:19:38,799 | INFO     | pilot.control.data               | copytool_out              | [data] copytool_out thread has finished
[2022-09-19 00:19:52] 2022-09-18 22:19:38,831 | WARNING  | pilot.util.common                | should_abort              | job:queue_monitor:received graceful stop - abort after this iteration
[2022-09-19 00:19:52] 2022-09-18 22:19:38,831 | DEBUG    | pilot.control.job                | queue_monitor             | will not set job_aborted yet
[2022-09-19 00:19:52] 2022-09-18 22:19:38,831 | INFO     | pilot.control.job                | queue_monitor             | [job] queue monitor thread has finished
[2022-09-19 00:19:52] 2022-09-18 22:19:41,609 | DEBUG    | pilot.control.data               | queue_monitoring          | will not set job_aborted yet
[2022-09-19 00:19:52] 2022-09-18 22:19:41,609 | INFO     | pilot.control.data               | queue_monitoring          | [data] queue_monitor thread has finished
[2022-09-19 00:19:52] 2022-09-18 22:19:51,831 | INFO     | pilot.control.payload            | get_logging_info          | job.realtimelogging is not enabled
[2022-09-19 00:19:52] 2022-09-18 22:19:51,831 | DEBUG    | pilot.control.payload            | run_realtimelog           | real-time logging not needed at this point
[2022-09-19 00:19:52] 2022-09-18 22:19:51,831 | DEBUG    | pilot.control.payload            | run_realtimelog           | realtime logger was not found, waiting ..
[2022-09-19 00:19:52] 2022-09-18 22:19:51,831 | DEBUG    | pilot.util.processes             | threads_aborted           | aborting since the last relevant thread is about to finish
[2022-09-19 00:19:52] 2022-09-18 22:19:51,831 | DEBUG    | pilot.control.payload            | run_realtimelog           | will proceed to set job_aborted
[2022-09-19 00:19:52] 2022-09-18 22:19:51,831 | INFO     | pilot.control.payload            | run_realtimelog           | [payload] run_realtimelog thread has finished
[2022-09-19 00:19:52] 2022-09-18 22:19:52,376 | INFO     | pilot.workflow.generic           | run                       | end of generic workflow (traces error code: 0)
[2022-09-19 00:19:52] 2022-09-18 22:19:52,377 | INFO     | root                             | wrap_up                   | traces error code: 0
[2022-09-19 00:19:52] 2022-09-18 22:19:52,377 | INFO     | root                             | wrap_up                   | pilot has finished
[2022-09-19 00:19:52] 2022-09-18 22:19:52,412 [wrapper] ==== pilot stdout END ====
[2022-09-19 00:19:52] 2022-09-18 22:19:52,419 [wrapper] ==== wrapper stdout RESUME ====
[2022-09-19 00:19:52] 2022-09-18 22:19:52,426 [wrapper] pilotpid: 458413
[2022-09-19 00:19:52] 2022-09-18 22:19:52,432 [wrapper] Pilot exit status: 0
[2022-09-19 00:19:52] 2022-09-18 22:19:52,453 [wrapper] pandaids: 5600298877
[2022-09-19 00:19:52] 2022-09-18 22:19:52,465 [wrapper] apfmon messages muted
[2022-09-19 00:19:52] 2022-09-18 22:19:52,471 [wrapper] Test setup, not cleaning
[2022-09-19 00:19:52] 2022-09-18 22:19:52,477 [wrapper] ==== wrapper stdout END ====
[2022-09-19 00:19:52] 2022-09-18 22:19:52,484 [wrapper] ==== wrapper stderr END ====
[2022-09-19 00:19:52] 2022-09-18 22:19:52,496 [wrapper] wrapperexiting ec=0, duration=476
[2022-09-19 00:19:52] 2022-09-18 22:19:52,503 [wrapper] apfmon messages muted
[2022-09-19 00:19:52]  *** Error codes and diagnostics ***
[2022-09-19 00:19:52]     "exeErrorCode": 0,
[2022-09-19 00:19:52]     "exeErrorDiag": "",
[2022-09-19 00:19:52]     "pilotErrorCode": 0,
[2022-09-19 00:19:52]     "pilotErrorDiag": "",
[2022-09-19 00:19:52]  *** Listing of results directory ***
[2022-09-19 00:19:52] insgesamt 45148
[2022-09-19 00:19:52] -rw-r--r-- 1 yeti yeti     4534 Sep 18 22:26 queuedata.json
[2022-09-19 00:19:52] -rwx------ 1 yeti yeti    26695 Sep 18 22:27 runpilot2-wrapper.sh
[2022-09-19 00:19:52] -rw------- 1 yeti yeti   389243 Sep 18 22:27 pilot3.tar.gz
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti      107 Sep 19 00:11 wrapper_26015_x86_64-pc-linux-gnu
[2022-09-19 00:19:52] -rwxr-xr-x 1 yeti yeti     8273 Sep 19 00:11 run_atlas
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti      112 Sep 19 00:11 job.xml
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti     6250 Sep 19 00:11 init_data.xml
[2022-09-19 00:19:52] -rw-r--r-- 2 yeti yeti 38184411 Sep 19 00:11 EVNT.04972714._000028.pool.root.1
[2022-09-19 00:19:52] -rw-r--r-- 2 yeti yeti    17264 Sep 19 00:11 start_atlas.sh
[2022-09-19 00:19:52] drwxrwx--x 2 yeti yeti     4096 Sep 19 00:11 shared
[2022-09-19 00:19:52] -rw-r--r-- 2 yeti yeti   400447 Sep 19 00:11 input.tar.gz
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti        0 Sep 19 00:11 boinc_lockfile
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti     2652 Sep 19 00:11 pandaJob.out
[2022-09-19 00:19:52] -rw------- 1 yeti yeti      469 Sep 19 00:11 setup.sh.local
[2022-09-19 00:19:52] -rw------- 1 yeti yeti  1059513 Sep 19 00:12 agis_schedconf.cvmfs.json
[2022-09-19 00:19:52] -rw------- 1 yeti yeti  1779729 Sep 19 00:12 agis_ddmendpoints.agis.ALL.json
[2022-09-19 00:19:52] drwx------ 4 yeti yeti     4096 Sep 19 00:12 pilot3
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti      532 Sep 19 00:17 boinc_task_state.xml
[2022-09-19 00:19:52] -rw------- 1 yeti yeti  1454053 Sep 19 00:19 output.1.58974faf-78cb-4a92-9d53-0ab80ff3ddea_89063.pool.root
[2022-09-19 00:19:52] -rw------- 1 yeti yeti     1012 Sep 19 00:19 memory_monitor_summary.json
[2022-09-19 00:19:52] -rw------- 1 yeti yeti   433218 Sep 19 00:19 58974faf-78cb-4a92-9d53-0ab80ff3ddea_89063.1.job.log.tgz
[2022-09-19 00:19:52] -rw------- 1 yeti yeti    10333 Sep 19 00:19 heartbeat.json
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti       24 Sep 19 00:19 wrapper_checkpoint.txt
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti     8192 Sep 19 00:19 boinc_mmap_file
[2022-09-19 00:19:52] -rw------- 1 yeti yeti     8725 Sep 19 00:19 pilotlog.txt
[2022-09-19 00:19:52] -rw------- 1 yeti yeti   201511 Sep 19 00:19 58974faf-78cb-4a92-9d53-0ab80ff3ddea_89063.1.job.log
[2022-09-19 00:19:52] -rw------- 1 yeti yeti      464 Sep 19 00:19 output.list
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti    10985 Sep 19 00:19 runtime_log.err
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti      748 Sep 19 00:19 runtime_log
[2022-09-19 00:19:52] -rw------- 1 yeti yeti  2109440 Sep 19 00:19 result.tar.gz
[2022-09-19 00:19:52] -rw------- 1 yeti yeti      551 Sep 19 00:19 PapMDmE4Fu1n7Olcko1bjSoqABFKDmABFKDmKFbSDmPpJKDmO2dJMo.diag
[2022-09-19 00:19:52] -rw-rw-r-- 1 yeti yeti    31059 Sep 19 00:19 stderr.txt
[2022-09-19 00:19:52] HITS file was successfully produced:
[2022-09-19 00:19:52] -rw------- 1 yeti yeti 1454053 Sep 19 00:19 shared/HITS.pool.root.1
[2022-09-19 00:19:52]  *** Contents of shared directory: ***
[2022-09-19 00:19:52] insgesamt 41184
[2022-09-19 00:19:52] -rw-r--r-- 2 yeti yeti 38184411 Sep 19 00:11 ATLAS.root_0
[2022-09-19 00:19:52] -rw-r--r-- 2 yeti yeti    17264 Sep 19 00:11 start_atlas.sh
[2022-09-19 00:19:52] -rw-r--r-- 2 yeti yeti   400447 Sep 19 00:11 input.tar.gz
[2022-09-19 00:19:52] -rw------- 1 yeti yeti  1454053 Sep 19 00:19 HITS.pool.root.1
[2022-09-19 00:19:52] -rw------- 1 yeti yeti  2109440 Sep 19 00:19 result.tar.gz
00:19:54 (452773): run_atlas exited; CPU time 448.432647
00:19:54 (452773): called boinc_finish(0)

</stderr_txt>
]]>


©2024 CERN