justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 293869.33@justin-prod-sched01.dune.hep.ac.uk

Jobsub ID293869.33@justin-prod-sched01.dune.hep.ac.uk
Workflow ID4131
Stage ID1
User nameamoor@fnal.gov
HTCondor Groupgroup_dune
RequestedProcessors1
RSS bytes6291456000 (6000 MiB)
Wall seconds limit80000 (22 hours)
Submitted time2024-11-18 12:52:58
SiteES_PIC
EntryDUNE_T1_ES_PIC_ce13-multicore
Last heartbeat2024-11-20 08:40:57
From worker nodeHostnametds206.pic.es
cpuinfoIntel(R) Xeon(R) CPU E5-2640 v3 @ 2.60GHz
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes6291456000 (6000 MiB)
Wall seconds limit216000 (60 hours)
Inner Apptainer?True
Job statefinished
Allocator namejustin-allocator-pro.dune.hep.ac.uk
Started2024-11-18 12:55:36
Input filesusertests:000839_reco_data_2024-11-14T_093501Z_reco_data_2024-11-14T_134005Z.root
JobscriptExit code0
Real time1d (157394s)
CPU time1d (154605s = 98%)
Outputting started2024-11-20 08:38:51
Output filesusertests:000839_reco_data_2024-11-14T_093501Z_reco_data_2024-11-14T_134005Z_reco_data_2024-11-18T_125607Z.root
Finished2024-11-20 08:40:57
Saved logsjustin-logs:293869.33-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

AFM detsim jobscript.
Input PFN = root://xrootd.echo.stfc.ac.uk:1094/dune:/protodune/RSE/usertests/7c/fc/000839_reco_data_2024-11-14T_093501Z_reco_data_2024-11-14T_134005Z.root
Setting up larsoft UPS area... /cvmfs/larsoft.opensciencegrid.org
Setting up DUNE UPS area... /cvmfs/dune.opensciencegrid.org/products/dune/
/cvmfs/larsoft.opensciencegrid.org/products/xrootd/v5_4_3b/Linux64bit+3.10-2.17-e20-p3913-prof/lib/libXrdPosixPreload.so
=== Start last 50 lines of lar log file ===
[09:38:41.106] I [ timer  ] Timer: WireCell::Gen::Reframer : 62.387966 sec
[09:38:41.106] I [ timer  ] Timer: WireCell::Gen::Reframer : 62.137886 sec
[09:38:41.106] I [ timer  ] Timer: WireCell::Gen::Reframer : 62.007977 sec
[09:38:41.106] I [ timer  ] Timer: WireCell::Gen::Reframer : 61.92799 sec
[09:38:41.106] I [ timer  ] Timer: WireCell::Gen::Reframer : 61.727974 sec
[09:38:41.106] I [ timer  ] Timer: WireCell::Gen::Reframer : 61.008106 sec
[09:38:41.106] I [ timer  ] Timer: wcls::SimDepoSource : 48.927795 sec
[09:38:41.106] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 38.609528 sec
[09:38:41.106] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 35.899982 sec
[09:38:41.107] I [ timer  ] Timer: WireCell::Gen::DepoSetFanout : 35.170105 sec
[09:38:41.107] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 35.160103 sec
[09:38:41.107] I [ timer  ] Timer: wcls::FrameSaver : 35.09012 sec
[09:38:41.107] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 34.290253 sec
[09:38:41.107] I [ timer  ] Timer: WireCell::Gen::DepoSetFanout : 33.88032 sec
[09:38:41.107] I [ timer  ] Timer: WireCell::Gen::Retagger : 33.600365 sec
[09:38:41.107] I [ timer  ] Timer: WireCell::Gen::DepoSetFanout : 33.390404 sec
[09:38:41.107] I [ timer  ] Timer: Total node execution : 151631.9416923523 sec
wclsFrameSaver: saving 865 traces tagged "gauss"
FrameSaver: q=17884.1 n=9972 tag=gauss
wclsFrameSaver: saving 850 traces tagged "wiener"
FrameSaver: q=28281.4 n=10151 tag=wiener
wclsFrameSaver: no traces tagged "dnnsp"
FrameSaver: q=0 n=0 tag=dnnsp
20-Nov-2024 09:38:42 CET  Closed output file "000839_reco_data_2024-11-14T_093501Z_reco_data_2024-11-14T_134005Z_reco_data_2024-11-18T_125607Z.root"
20-Nov-2024 09:38:42 CET  Closed input file "root://xrootd.echo.stfc.ac.uk:1094/dune:/protodune/RSE/usertests/7c/fc/000839_reco_data_2024-11-14T_093501Z_reco_data_2024-11-14T_134005Z.root"

================================================================================================================================
TimeTracker printout (sec)                        Min           Avg           Max         Median          RMS         nEvts   
================================================================================================================================
Full event                                      95.3934       156.518       1881.19       143.818       70.5398       1000    
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                        0.00022162     0.010159      0.278461     0.000529536    0.0357773      1000    
simulate:tpcrawdecoder:WireCellToolkit          95.3588       156.148       1859.76       143.087       69.8766       1000    
simulate:opdigi:OpDetDigitizerDUNE            0.000229428    0.276063       18.4376     0.00107241     0.796152       1000    
simulate:rns:RandomNumberSaver                1.7756e-05    3.50249e-05   0.000315923   3.2926e-05    1.2049e-05      1000    
[art]:TriggerResults:TriggerResultInserter     8.658e-06    1.46709e-05   7.1532e-05    1.3172e-05    4.5241e-06      1000    
end_path:out1:RootOutput                       2.761e-06    6.05194e-06   3.2929e-05    5.5735e-06    1.82188e-06     1000    
end_path:out1:RootOutput(write)                0.0337031     0.0832814      2.9912       0.0431215      0.12872       1000    
================================================================================================================================

====================================================================================================
MemoryTracker summary (base-10 MB units used)

  Peak virtual memory usage (VmPeak)  : 5507.13 MB
  Peak resident set size usage (VmHWM): 3776.27 MB
====================================================================================================
ProvidedPedestalAdditionService::dtor: Deleting random engine with seed 378451932
ExponentialChannelNoiseService::dtor: Deleting random engine with seed 579988500
StuckBitAdcDistortionService:dtor: Deleting random engine with seed 318279876
Art has completed and will exit with status 0.
=== End last 50 lines of lar log file ===
lar exit code 0
outfile is 000839_reco_data_2024-11-14T_093501Z_reco_data_2024-11-14T_134005Z_reco_data_2024-11-18T_125607Z.root
extractor_prod.py exit code 0
pdjson2metadata exit code 0
Metadata extraction succeeds
===Metadata JSON===
{
    "metadata": {
        "DUNE.campaign": "justIN.w4131s1",
        "art.file_format_era": "ART_2011a",
        "art.file_format_version": 15,
        "art.first_event": 1,
        "art.last_event": 1000,
        "art.process_name": "detsim",
        "art.run_type": "physics",
        "core.application": "art.reco",
        "core.application.family": "art",
        "core.application.name": "reco",
        "core.application.version": "v09_75_03d00",
        "core.data_stream": "out1",
        "core.data_tier": "detector-simulated",
        "core.end_time": 1732091922.0,
        "core.event_count": 1000,
        "core.file_format": "artroot",
        "core.file_type": "mc",
        "core.first_event_number": 1,
        "core.last_event_number": 1000,
        "core.run_type": "physics",
        "core.runs": [
            20000031
        ],
        "core.runs_subruns": [
            2000003100000
        ],
        "core.start_time": 1731934823.0
    },
    "parents": [
        {
            "did": "usertests:000839_reco_data_2024-11-14T_093501Z_reco_data_2024-11-14T_134005Z.root"
        }
    ]
}
===================
.:
total 3040924
-rw-r--r-- 1 dune001 dune 3037271131 Nov 20 09:38 000839_reco_data_2024-11-14T_093501Z_reco_data_2024-11-14T_134005Z_reco_data_2024-11-18T_125607Z.root
-rw-r--r-- 1 dune001 dune   38616440 Nov 20 09:38 000839_reco_data_2024-11-14T_093501Z_reco_data_2024-11-14T_134005Z_reco_2024-11-18T_125607Z.log
-rw-r--r-- 1 dune001 dune   37976529 Nov 20 09:38 wcls-sim-drift-simchannel-nf-sp.log
-rw-r--r-- 1 dune001 dune       6077 Nov 20 09:38 jobscript.log
-rw-r--r-- 1 dune001 dune       1115 Nov 20 09:38 000839_reco_data_2024-11-14T_093501Z_reco_data_2024-11-14T_134005Z_reco_data_2024-11-18T_125607Z.root.json
-rw-r--r-- 1 dune001 dune        930 Nov 20 09:38 000839_reco_data_2024-11-14T_093501Z_reco_data_2024-11-14T_134005Z_reco_data_2024-11-18T_125607Z.root.ext.json
-rw-r--r-- 1 dune001 dune        164 Nov 18 13:55 all-input-dids.txt
-rw-r--r-- 1 dune001 dune        143 Nov 20 09:38 justin-processed-pfns.txt
-rw-r--r-- 1 dune001 dune          0 Nov 18 13:56 debugprod.log
justIN time: 2024-11-23 11:45:37 UTC       justIN version: 01.01.09