Jobsub ID 272060.3@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/29/54/000628_reco_data_2024-10-07T_115853Z_reco_data_2024-10-08T_090815Z.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:17.700] I [ timer ] Timer: wcls::FrameSaver : 54.466866 sec
[15:40:17.700] I [ timer ] Timer: WireCell::Gen::Reframer : 54.396904 sec
[15:40:17.700] I [ timer ] Timer: WireCell::Gen::FrameFanin : 54.326893 sec
[15:40:17.700] I [ timer ] Timer: WireCell::Gen::Reframer : 54.316914 sec
[15:40:17.700] I [ timer ] Timer: WireCell::Gen::Reframer : 53.92698 sec
[15:40:17.700] I [ timer ] Timer: WireCell::Gen::Reframer : 53.92697 sec
[15:40:17.700] I [ timer ] Timer: WireCell::Gen::Reframer : 53.886986 sec
[15:40:17.700] I [ timer ] Timer: WireCell::Gen::FrameFanin : 53.83697 sec
[15:40:17.700] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 53.71699 sec
[15:40:17.700] I [ timer ] Timer: WireCell::Gen::FrameFanin : 53.447037 sec
[15:40:17.700] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 53.147087 sec
[15:40:17.700] I [ timer ] Timer: WireCell::Gen::Reframer : 53.087116 sec
[15:40:17.700] I [ timer ] Timer: WireCell::Gen::Reframer : 52.997128 sec
[15:40:17.700] I [ timer ] Timer: WireCell::Gen::Reframer : 52.937145 sec
[15:40:17.700] I [ timer ] Timer: WireCell::Gen::Retagger : 52.877132 sec
[15:40:17.700] I [ timer ] Timer: WireCell::Gen::Reframer : 52.337242 sec
[15:40:17.700] I [ timer ] Timer: Total node execution : 11149.009952545166 sec
wclsFrameSaver: saving 2145 traces tagged "gauss"
FrameSaver: q=254551 n=30177 tag=gauss
wclsFrameSaver: saving 2574 traces tagged "wiener"
FrameSaver: q=275687 n=27265 tag=wiener
wclsFrameSaver: no traces tagged "dnnsp"
FrameSaver: q=0 n=0 tag=dnnsp
09-Oct-2024 15:40:19 BST Closed output file "000628_reco_data_2024-10-07T_115853Z_reco_data_2024-10-08T_090815Z_reco_data_2024-10-09T_101617Z.root"
09-Oct-2024 15:40:19 BST Closed input file "root://mover.pp.rl.ac.uk:1094/pnfs/pp.rl.ac.uk/data/dune/usertests/29/54/000628_reco_data_2024-10-07T_115853Z_reco_data_2024-10-08T_090815Z.root"
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 136.888 1424.61 12625 198.11 3548.86 11
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000303502 0.00324689 0.0104283 0.00284352 0.00267432 11
simulate:tpcrawdecoder:WireCellToolkit 136.558 1417.86 12571.5 196.695 3533.97 11
simulate:opdigi:OpDetDigitizerDUNE 0.235582 6.00583 50.0153 0.787756 14.06 11
simulate:rns:RandomNumberSaver 2.639e-05 5.63215e-05 0.000293422 3.0277e-05 7.53427e-05 11
[art]:TriggerResults:TriggerResultInserter 1.4948e-05 1.96381e-05 5.2178e-05 1.6401e-05 1.03628e-05 11
end_path:out1:RootOutput 3.626e-06 6.24364e-06 2.5098e-05 4.318e-06 5.97992e-06 11
end_path:out1:RootOutput(write) 0.0762245 0.742683 3.43978 0.169331 1.03269 11
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 10616 MB
Peak resident set size usage (VmHWM): 8511.56 MB
====================================================================================================
ProvidedPedestalAdditionService::dtor: Deleting random engine with seed 237080013
ExponentialChannelNoiseService::dtor: Deleting random engine with seed 607463757
StuckBitAdcDistortionService:dtor: Deleting random engine with seed 279328856
Art has completed and will exit with status 0.
=== End last 50 lines of lar log file ===
lar exit code 0
outfile is 000628_reco_data_2024-10-07T_115853Z_reco_data_2024-10-08T_090815Z_reco_data_2024-10-09T_101617Z.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": 22,
"art.last_event": 898,
"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": 1728484819.0,
"core.event_count": 11,
"core.file_format": "artroot",
"core.file_type": "mc",
"core.first_event_number": 22,
"core.last_event_number": 898,
"core.run_type": "physics",
"core.runs": [
20000031
],
"core.runs_subruns": [
2000003100000
],
"core.start_time": 1728469819.0
},
"parents": [
{
"did": "usertests:000628_reco_data_2024-10-07T_115853Z_reco_data_2024-10-08T_090815Z.root"
}
]
}
===================
.:
total 1190104
-rw-r--r-- 1 dune01 dune01 1217619977 Oct 9 15:40 000628_reco_data_2024-10-07T_115853Z_reco_data_2024-10-08T_090815Z_reco_data_2024-10-09T_101617Z.root
-rw-r--r-- 1 dune01 dune01 509071 Oct 9 15:40 000628_reco_data_2024-10-07T_115853Z_reco_data_2024-10-08T_090815Z_reco_2024-10-09T_101617Z.log
-rw-r--r-- 1 dune01 dune01 495910 Oct 9 15:40 wcls-sim-drift-simchannel-nf-sp.log
-rw-r--r-- 1 dune01 dune01 6075 Oct 9 15:40 jobscript.log
-rw-r--r-- 1 dune01 dune01 1113 Oct 9 15:40 000628_reco_data_2024-10-07T_115853Z_reco_data_2024-10-08T_090815Z_reco_data_2024-10-09T_101617Z.root.json
-rw-r--r-- 1 dune01 dune01 928 Oct 9 15:40 000628_reco_data_2024-10-07T_115853Z_reco_data_2024-10-08T_090815Z_reco_data_2024-10-09T_101617Z.root.ext.json
-rw-r--r-- 1 dune01 dune01 164 Oct 9 11:16 all-input-dids.txt
-rw-r--r-- 1 dune01 dune01 145 Oct 9 15:40 justin-processed-pfns.txt
-rw-r--r-- 1 dune01 dune01 0 Oct 9 11:16 debugprod.log 