Jobsub ID 293869.2@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/cc/cb/000591_reco_data_2024-11-14T_093223Z_reco_data_2024-11-14T_142546Z.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 ===
[06:49:40.335] I [ timer ] Timer: WireCell::Gen::Reframer : 49.159237 sec
[06:49:40.335] I [ timer ] Timer: WireCell::Gen::Reframer : 49.13919 sec
[06:49:40.335] I [ timer ] Timer: WireCell::Gen::Reframer : 49.0992 sec
[06:49:40.335] I [ timer ] Timer: WireCell::Gen::Reframer : 48.979275 sec
[06:49:40.335] I [ timer ] Timer: WireCell::Gen::Reframer : 48.599308 sec
[06:49:40.335] I [ timer ] Timer: WireCell::Gen::Reframer : 48.47929 sec
[06:49:40.335] I [ timer ] Timer: wcls::SimDepoSource : 32.770508 sec
[06:49:40.335] I [ timer ] Timer: WireCell::Gen::DumpFrames : 26.830519 sec
[06:49:40.335] I [ timer ] Timer: WireCell::Gen::FrameFanin : 26.750517 sec
[06:49:40.335] I [ timer ] Timer: WireCell::Gen::FrameFanin : 25.800495 sec
[06:49:40.335] I [ timer ] Timer: WireCell::Gen::FrameFanin : 25.57049 sec
[06:49:40.335] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 25.280483 sec
[06:49:40.335] I [ timer ] Timer: wcls::FrameSaver : 25.000477 sec
[06:49:40.335] I [ timer ] Timer: WireCell::Gen::Retagger : 24.420464 sec
[06:49:40.335] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 24.360462 sec
[06:49:40.335] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 23.870451 sec
[06:49:40.335] I [ timer ] Timer: Total node execution : 142203.51525115967 sec
wclsFrameSaver: saving 5878 traces tagged "gauss"
FrameSaver: q=1.13423e+06 n=108578 tag=gauss
wclsFrameSaver: saving 7262 traces tagged "wiener"
FrameSaver: q=1.23456e+06 n=98562 tag=wiener
wclsFrameSaver: no traces tagged "dnnsp"
FrameSaver: q=0 n=0 tag=dnnsp
20-Nov-2024 06:49:47 CET Closed output file "000591_reco_data_2024-11-14T_093223Z_reco_data_2024-11-14T_142546Z_reco_data_2024-11-18T_125550Z.root"
20-Nov-2024 06:49:47 CET Closed input file "root://xrootd.echo.stfc.ac.uk:1094/dune:/protodune/RSE/usertests/cc/cb/000591_reco_data_2024-11-14T_093223Z_reco_data_2024-11-14T_142546Z.root"
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 101.265 146.109 451.4 136.994 36.7867 1000
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000229836 0.0124278 0.523342 0.000468992 0.049026 1000
simulate:tpcrawdecoder:WireCellToolkit 101.228 145.807 448.707 136.808 36.6078 1000
simulate:opdigi:OpDetDigitizerDUNE 0.000203065 0.212589 4.10557 0.00105527 0.422849 1000
simulate:rns:RandomNumberSaver 1.8303e-05 3.58195e-05 0.000272845 3.35405e-05 1.17047e-05 1000
[art]:TriggerResults:TriggerResultInserter 9.459e-06 1.58905e-05 7.9315e-05 1.4058e-05 4.72631e-06 1000
end_path:out1:RootOutput 2.639e-06 5.64276e-06 2.8595e-05 5.3165e-06 1.79045e-06 1000
end_path:out1:RootOutput(write) 0.0343762 0.0759146 1.24235 0.0419879 0.0838942 1000
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4041.09 MB
Peak resident set size usage (VmHWM): 2151.27 MB
====================================================================================================
ProvidedPedestalAdditionService::dtor: Deleting random engine with seed 717625443
ExponentialChannelNoiseService::dtor: Deleting random engine with seed 14821948
StuckBitAdcDistortionService:dtor: Deleting random engine with seed 175009872
Art has completed and will exit with status 0.
=== End last 50 lines of lar log file ===
lar exit code 0
outfile is 000591_reco_data_2024-11-14T_093223Z_reco_data_2024-11-14T_142546Z_reco_data_2024-11-18T_125550Z.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": 1732081787.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": 1731934838.0
},
"parents": [
{
"did": "usertests:000591_reco_data_2024-11-14T_093223Z_reco_data_2024-11-14T_142546Z.root"
}
]
}
===================
.:
total 2418428
-rw-r--r-- 1 dune001 dune 2399835376 Nov 20 06:49 000591_reco_data_2024-11-14T_093223Z_reco_data_2024-11-14T_142546Z_reco_data_2024-11-18T_125550Z.root
-rw-r--r-- 1 dune001 dune 38616020 Nov 20 06:49 000591_reco_data_2024-11-14T_093223Z_reco_data_2024-11-14T_142546Z_reco_2024-11-18T_125550Z.log
-rw-r--r-- 1 dune001 dune 37976511 Nov 20 06:49 wcls-sim-drift-simchannel-nf-sp.log
-rw-r--r-- 1 dune001 dune 6087 Nov 20 06:49 jobscript.log
-rw-r--r-- 1 dune001 dune 1115 Nov 20 06:49 000591_reco_data_2024-11-14T_093223Z_reco_data_2024-11-14T_142546Z_reco_data_2024-11-18T_125550Z.root.json
-rw-r--r-- 1 dune001 dune 930 Nov 20 06:49 000591_reco_data_2024-11-14T_093223Z_reco_data_2024-11-14T_142546Z_reco_data_2024-11-18T_125550Z.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 06:49 justin-processed-pfns.txt
-rw-r--r-- 1 dune001 dune 0 Nov 18 13:56 debugprod.log 