Jobsub ID 272059.153@justin-prod-sched01.dune.hep.ac.uk
Jobscript log (last 10,000 characters)
AFM detsim jobscript.
Input PFN = root://mover.pp.rl.ac.uk:1094/pnfs/pp.rl.ac.uk/data/dune/usertests/f1/3e/000021_reco_data_2024-10-07T_114521Z_reco_data_2024-10-08T_094930Z.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 ===
[15:40:28.355] I [ timer ] Timer: WireCell::Gen::FrameFanin : 50.947456 sec
[15:40:28.355] I [ timer ] Timer: WireCell::Gen::Reframer : 50.817593 sec
[15:40:28.355] I [ timer ] Timer: wcls::FrameSaver : 50.81748 sec
[15:40:28.355] I [ timer ] Timer: WireCell::Gen::Reframer : 50.79762 sec
[15:40:28.355] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 50.767487 sec
[15:40:28.355] I [ timer ] Timer: WireCell::Gen::Reframer : 50.757652 sec
[15:40:28.355] I [ timer ] Timer: WireCell::Gen::FrameFanin : 50.6775 sec
[15:40:28.355] I [ timer ] Timer: WireCell::Gen::Reframer : 50.52766 sec
[15:40:28.355] I [ timer ] Timer: WireCell::Gen::FrameFanin : 50.517548 sec
[15:40:28.355] I [ timer ] Timer: WireCell::Gen::Reframer : 49.887753 sec
[15:40:28.355] I [ timer ] Timer: WireCell::Gen::Retagger : 49.577686 sec
[15:40:28.355] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 49.527695 sec
[15:40:28.355] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 49.43771 sec
[15:40:28.355] I [ timer ] Timer: WireCell::Gen::Reframer : 49.367844 sec
[15:40:28.355] I [ timer ] Timer: WireCell::Gen::Reframer : 49.32787 sec
[15:40:28.355] I [ timer ] Timer: WireCell::Gen::Reframer : 49.017902 sec
[15:40:28.355] I [ timer ] Timer: Total node execution : 8286.614398956299 sec
wclsFrameSaver: saving 5401 traces tagged "gauss"
FrameSaver: q=954494 n=74764 tag=gauss
wclsFrameSaver: saving 8760 traces tagged "wiener"
FrameSaver: q=1.08737e+06 n=65389 tag=wiener
wclsFrameSaver: no traces tagged "dnnsp"
FrameSaver: q=0 n=0 tag=dnnsp
09-Oct-2024 15:40:30 CEST Closed output file "000021_reco_data_2024-10-07T_114521Z_reco_data_2024-10-08T_094930Z_reco_data_2024-10-09T_101405Z.root"
09-Oct-2024 15:40:30 CEST Closed input file "root://mover.pp.rl.ac.uk:1094/pnfs/pp.rl.ac.uk/data/dune/usertests/f1/3e/000021_reco_data_2024-10-07T_114521Z_reco_data_2024-10-08T_094930Z.root"
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 108.346 455.567 6647.26 141.373 1244.67 27
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000478386 0.00401317 0.0298926 0.00135949 0.00723328 27
simulate:tpcrawdecoder:WireCellToolkit 108.244 453.083 6608.79 140.672 1237.42 27
simulate:opdigi:OpDetDigitizerDUNE 0.000651038 2.08692 35.2788 0.612805 6.62444 27
simulate:rns:RandomNumberSaver 3.701e-05 6.75515e-05 0.000335115 5.4691e-05 5.65178e-05 27
[art]:TriggerResults:TriggerResultInserter 1.4941e-05 2.47702e-05 5.7081e-05 2.0291e-05 1.18596e-05 27
end_path:out1:RootOutput 5.18e-06 8.36978e-06 2.539e-05 7.72e-06 3.8426e-06 27
end_path:out1:RootOutput(write) 0.0396331 0.391765 3.19118 0.119969 0.734365 27
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 8457.15 MB
Peak resident set size usage (VmHWM): 6680.09 MB
====================================================================================================
ProvidedPedestalAdditionService::dtor: Deleting random engine with seed 192306382
ExponentialChannelNoiseService::dtor: Deleting random engine with seed 42704420
StuckBitAdcDistortionService:dtor: Deleting random engine with seed 645309573
Art has completed and will exit with status 0.
=== End last 50 lines of lar log file ===
lar exit code 0
outfile is 000021_reco_data_2024-10-07T_114521Z_reco_data_2024-10-08T_094930Z_reco_data_2024-10-09T_101405Z.root
extractor_prod.py exit code 0
pdjson2metadata exit code 0
Metadata extraction succeeds
===Metadata JSON===
{
"metadata": {
"DUNE.campaign": "justIN.w3659s1",
"art.file_format_era": "ART_2011a",
"art.file_format_version": 15,
"art.first_event": 28,
"art.last_event": 997,
"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": 1728481230.0,
"core.event_count": 27,
"core.file_format": "artroot",
"core.file_type": "mc",
"core.first_event_number": 28,
"core.last_event_number": 997,
"core.run_type": "physics",
"core.runs": [
20000031
],
"core.runs_subruns": [
2000003100000
],
"core.start_time": 1728469032.0
},
"parents": [
{
"did": "usertests:000021_reco_data_2024-10-07T_114521Z_reco_data_2024-10-08T_094930Z.root"
}
]
}
===================
.:
total 1432992
-rw-r--r--. 1 duneprd np-comp 1465125877 Oct 9 15:40 000021_reco_data_2024-10-07T_114521Z_reco_data_2024-10-08T_094930Z_reco_data_2024-10-09T_101405Z.root
-rw-r--r--. 1 duneprd np-comp 1124817 Oct 9 15:40 000021_reco_data_2024-10-07T_114521Z_reco_data_2024-10-08T_094930Z_reco_2024-10-09T_101405Z.log
-rw-r--r--. 1 duneprd np-comp 1101279 Oct 9 15:40 wcls-sim-drift-simchannel-nf-sp.log
-rw-r--r--. 1 duneprd np-comp 6084 Oct 9 15:40 jobscript.log
-rw-r--r--. 1 duneprd np-comp 1113 Oct 9 15:40 000021_reco_data_2024-10-07T_114521Z_reco_data_2024-10-08T_094930Z_reco_data_2024-10-09T_101405Z.root.json
-rw-r--r--. 1 duneprd np-comp 928 Oct 9 15:40 000021_reco_data_2024-10-07T_114521Z_reco_data_2024-10-08T_094930Z_reco_data_2024-10-09T_101405Z.root.ext.json
-rw-r--r--. 1 duneprd np-comp 164 Oct 9 12:13 all-input-dids.txt
-rw-r--r--. 1 duneprd np-comp 145 Oct 9 15:40 justin-processed-pfns.txt
-rw-r--r--. 1 duneprd np-comp 0 Oct 9 12:14 debugprod.log 