Jobsub ID 293877.41@justin-prod-sched01.dune.hep.ac.uk
Jobscript log (last 10,000 characters)
AFM detsim jobscript.
Input PFN = root://xrootd.echo.stfc.ac.uk:1094/dune:/protodune/RSE/usertests/ba/ce/000876_reco_data_2024-11-14T_093527Z_reco_data_2024-11-14T_140523Z.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 ===
[03:17:34.284] I [ timer ] Timer: WireCell::Gen::Reframer : 59.967613 sec
[03:17:34.284] I [ timer ] Timer: WireCell::Gen::Reframer : 59.66763 sec
[03:17:34.284] I [ timer ] Timer: WireCell::Gen::Reframer : 59.55761 sec
[03:17:34.284] I [ timer ] Timer: WireCell::Gen::Reframer : 59.44766 sec
[03:17:34.284] I [ timer ] Timer: WireCell::Gen::Reframer : 59.177673 sec
[03:17:34.284] I [ timer ] Timer: WireCell::Gen::Reframer : 58.29779 sec
[03:17:34.284] I [ timer ] Timer: wcls::SimDepoSource : 40.429222 sec
[03:17:34.284] I [ timer ] Timer: WireCell::Gen::DumpFrames : 34.450226 sec
[03:17:34.284] I [ timer ] Timer: WireCell::Gen::FrameFanin : 34.21027 sec
[03:17:34.284] I [ timer ] Timer: WireCell::Gen::FrameFanin : 33.92032 sec
[03:17:34.284] I [ timer ] Timer: WireCell::Gen::FrameFanin : 33.870323 sec
[03:17:34.284] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 33.23043 sec
[03:17:34.284] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 32.480556 sec
[03:17:34.284] I [ timer ] Timer: wcls::FrameSaver : 32.47056 sec
[03:17:34.284] I [ timer ] Timer: WireCell::Gen::Retagger : 31.870634 sec
[03:17:34.284] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 31.66063 sec
[03:17:34.284] I [ timer ] Timer: Total node execution : 122074.83394432068 sec
wclsFrameSaver: saving 908 traces tagged "gauss"
FrameSaver: q=18683.4 n=10383 tag=gauss
wclsFrameSaver: saving 891 traces tagged "wiener"
FrameSaver: q=29306.6 n=10452 tag=wiener
wclsFrameSaver: no traces tagged "dnnsp"
FrameSaver: q=0 n=0 tag=dnnsp
20-Nov-2024 03:17:39 GMT Closed output file "000876_reco_data_2024-11-14T_093527Z_reco_data_2024-11-14T_140523Z_reco_data_2024-11-18T_132733Z.root"
20-Nov-2024 03:17:39 GMT Closed input file "root://xrootd.echo.stfc.ac.uk:1094/dune:/protodune/RSE/usertests/ba/ce/000876_reco_data_2024-11-14T_093527Z_reco_data_2024-11-14T_140523Z.root"
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 90.5296 135.908 914.88 131.711 35.6179 1000
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000393004 0.00820052 0.345971 0.000873723 0.0297621 1000
simulate:tpcrawdecoder:WireCellToolkit 90.4799 135.664 908.404 131.544 35.2495 1000
simulate:opdigi:OpDetDigitizerDUNE 0.000211981 0.124771 4.57176 0.00117249 0.307195 1000
simulate:rns:RandomNumberSaver 2.632e-05 4.19438e-05 0.000296531 3.8067e-05 1.75456e-05 1000
[art]:TriggerResults:TriggerResultInserter 9.798e-06 1.719e-05 0.000230246 1.5289e-05 1.10983e-05 1000
end_path:out1:RootOutput 4.559e-06 6.94259e-06 8.2736e-05 6.202e-06 6.29816e-06 1000
end_path:out1:RootOutput(write) 0.0467019 0.110947 1.90291 0.079618 0.119158 1000
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4210.43 MB
Peak resident set size usage (VmHWM): 2540.52 MB
====================================================================================================
ProvidedPedestalAdditionService::dtor: Deleting random engine with seed 75218639
ExponentialChannelNoiseService::dtor: Deleting random engine with seed 585439679
StuckBitAdcDistortionService:dtor: Deleting random engine with seed 845582279
Art has completed and will exit with status 0.
=== End last 50 lines of lar log file ===
lar exit code 0
outfile is 000876_reco_data_2024-11-14T_093527Z_reco_data_2024-11-14T_140523Z_reco_data_2024-11-18T_132733Z.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": 1732072659.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": 1731936748.0
},
"parents": [
{
"did": "usertests:000876_reco_data_2024-11-14T_093527Z_reco_data_2024-11-14T_140523Z.root"
}
]
}
===================
.:
total 2308704
-rw-r--r--. 1 job0016 job0016 2287496700 Nov 20 03:17 000876_reco_data_2024-11-14T_093527Z_reco_data_2024-11-14T_140523Z_reco_data_2024-11-18T_132733Z.root
-rw-r--r--. 1 job0016 job0016 38606666 Nov 20 03:17 000876_reco_data_2024-11-14T_093527Z_reco_data_2024-11-14T_140523Z_reco_2024-11-18T_132733Z.log
-rw-r--r--. 1 job0016 job0016 37967566 Nov 20 03:17 wcls-sim-drift-simchannel-nf-sp.log
-rw-r--r--. 1 job0016 job0016 6072 Nov 20 03:17 jobscript.log
-rw-r--r--. 1 job0016 job0016 1115 Nov 20 03:17 000876_reco_data_2024-11-14T_093527Z_reco_data_2024-11-14T_140523Z_reco_data_2024-11-18T_132733Z.root.json
-rw-r--r--. 1 job0016 job0016 930 Nov 20 03:17 000876_reco_data_2024-11-14T_093527Z_reco_data_2024-11-14T_140523Z_reco_data_2024-11-18T_132733Z.root.ext.json
-rw-r--r--. 1 job0016 job0016 164 Nov 18 13:27 all-input-dids.txt
-rw-r--r--. 1 job0016 job0016 143 Nov 20 03:17 justin-processed-pfns.txt
-rw-r--r--. 1 job0016 job0016 0 Nov 18 13:28 debugprod.log 