Jobsub ID 101168.137@justin-prod-sched02.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/1d/b3/000017_reco_data_2024-11-14T_094302Z_reco_data_2024-11-14T_140214Z.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 ===
[00:42:13.839] I [ timer ] Timer: WireCell::Gen::Reframer : 41.54998 sec
[00:42:13.839] I [ timer ] Timer: WireCell::Gen::Reframer : 41.439987 sec
[00:42:13.839] I [ timer ] Timer: WireCell::Gen::Reframer : 41.400017 sec
[00:42:13.839] I [ timer ] Timer: WireCell::Gen::Reframer : 41.339993 sec
[00:42:13.839] I [ timer ] Timer: WireCell::Gen::Reframer : 41.319977 sec
[00:42:13.839] I [ timer ] Timer: WireCell::Gen::DepoBagger : 38.289577 sec
[00:42:13.839] I [ timer ] Timer: wcls::SimDepoSource : 20.140366 sec
[00:42:13.839] I [ timer ] Timer: WireCell::Gen::FrameFanin : 14.960247 sec
[00:42:13.839] I [ timer ] Timer: WireCell::Gen::FrameFanin : 14.20023 sec
[00:42:13.839] I [ timer ] Timer: WireCell::Gen::FrameFanin : 13.200207 sec
[00:42:13.839] I [ timer ] Timer: WireCell::Gen::DumpFrames : 13.020203 sec
[00:42:13.839] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 12.46019 sec
[00:42:13.839] I [ timer ] Timer: WireCell::Gen::Retagger : 12.310186 sec
[00:42:13.839] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 12.02018 sec
[00:42:13.839] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 11.990179 sec
[00:42:13.839] I [ timer ] Timer: wcls::FrameSaver : 11.650171 sec
[00:42:13.839] I [ timer ] Timer: Total node execution : 124621.79821205139 sec
wclsFrameSaver: saving 2592 traces tagged "gauss"
FrameSaver: q=342221 n=41317 tag=gauss
wclsFrameSaver: saving 2958 traces tagged "wiener"
FrameSaver: q=364259 n=38119 tag=wiener
wclsFrameSaver: no traces tagged "dnnsp"
FrameSaver: q=0 n=0 tag=dnnsp
20-Nov-2024 00:42:15 GMT Closed output file "000017_reco_data_2024-11-14T_094302Z_reco_data_2024-11-14T_140214Z_reco_data_2024-11-18T_133157Z.root"
20-Nov-2024 00:42:15 GMT Closed input file "root://xrootd.echo.stfc.ac.uk:1094/dune:/protodune/RSE/usertests/1d/b3/000017_reco_data_2024-11-14T_094302Z_reco_data_2024-11-14T_140214Z.root"
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 102.559 126.386 613.277 120.541 23.4025 1000
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000286103 0.00783002 0.452595 0.000658736 0.0302563 1000
simulate:tpcrawdecoder:WireCellToolkit 102.521 126.182 608.672 120.426 23.1402 1000
simulate:opdigi:OpDetDigitizerDUNE 0.000231547 0.137633 3.91565 0.0010844 0.301426 1000
simulate:rns:RandomNumberSaver 2.5958e-05 3.53434e-05 0.000192835 3.2801e-05 1.05265e-05 1000
[art]:TriggerResults:TriggerResultInserter 1.0079e-05 1.39493e-05 4.8642e-05 1.2825e-05 4.16284e-06 1000
end_path:out1:RootOutput 4.398e-06 6.5613e-06 2.5679e-05 6.256e-06 1.81457e-06 1000
end_path:out1:RootOutput(write) 0.0315718 0.0585818 1.04655 0.0364852 0.0718463 1000
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4385.49 MB
Peak resident set size usage (VmHWM): 2690.04 MB
====================================================================================================
ProvidedPedestalAdditionService::dtor: Deleting random engine with seed 671747806
ExponentialChannelNoiseService::dtor: Deleting random engine with seed 138279951
StuckBitAdcDistortionService:dtor: Deleting random engine with seed 672699130
Art has completed and will exit with status 0.
=== End last 50 lines of lar log file ===
lar exit code 0
outfile is 000017_reco_data_2024-11-14T_094302Z_reco_data_2024-11-14T_140214Z_reco_data_2024-11-18T_133157Z.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": 1732063335.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": 1731936918.0
},
"parents": [
{
"did": "usertests:000017_reco_data_2024-11-14T_094302Z_reco_data_2024-11-14T_140214Z.root"
}
]
}
===================
.:
total 2607520
-rw-r--r-- 1 pltdune002 pltdune 2593477928 Nov 20 00:42 000017_reco_data_2024-11-14T_094302Z_reco_data_2024-11-14T_140214Z_reco_data_2024-11-18T_133157Z.root
-rw-r--r-- 1 pltdune002 pltdune 38609184 Nov 20 00:42 000017_reco_data_2024-11-14T_094302Z_reco_data_2024-11-14T_140214Z_reco_2024-11-18T_133157Z.log
-rw-r--r-- 1 pltdune002 pltdune 37969775 Nov 20 00:42 wcls-sim-drift-simchannel-nf-sp.log
-rw-r--r-- 1 pltdune002 pltdune 6080 Nov 20 00:42 jobscript.log
-rw-r--r-- 1 pltdune002 pltdune 1115 Nov 20 00:42 000017_reco_data_2024-11-14T_094302Z_reco_data_2024-11-14T_140214Z_reco_data_2024-11-18T_133157Z.root.json
-rw-r--r-- 1 pltdune002 pltdune 930 Nov 20 00:42 000017_reco_data_2024-11-14T_094302Z_reco_data_2024-11-14T_140214Z_reco_data_2024-11-18T_133157Z.root.ext.json
-rw-r--r-- 1 pltdune002 pltdune 164 Nov 18 13:31 all-input-dids.txt
-rw-r--r-- 1 pltdune002 pltdune 143 Nov 20 00:42 justin-processed-pfns.txt
-rw-r--r-- 1 pltdune002 pltdune 0 Nov 18 13:32 debugprod.log 