Jobsub ID 101168.11@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/ee/37/000673_reco_data_2024-11-14T_093321Z_reco_data_2024-11-14T_142647Z.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 ===
[23:55:26.700] I [ timer ] Timer: WireCell::Gen::Reframer : 65.077354 sec
[23:55:26.700] I [ timer ] Timer: WireCell::Gen::Reframer : 64.9974 sec
[23:55:26.700] I [ timer ] Timer: WireCell::Gen::Reframer : 64.56727 sec
[23:55:26.700] I [ timer ] Timer: WireCell::Gen::Reframer : 64.18735 sec
[23:55:26.700] I [ timer ] Timer: WireCell::Gen::Reframer : 64.117256 sec
[23:55:26.700] I [ timer ] Timer: WireCell::Gen::Reframer : 64.06734 sec
[23:55:26.700] I [ timer ] Timer: wcls::SimDepoSource : 34.750175 sec
[23:55:26.700] I [ timer ] Timer: WireCell::Gen::DumpFrames : 33.390404 sec
[23:55:26.700] I [ timer ] Timer: WireCell::Gen::FrameFanin : 32.15061 sec
[23:55:26.700] I [ timer ] Timer: WireCell::Gen::FrameFanin : 31.590628 sec
[23:55:26.700] I [ timer ] Timer: WireCell::Gen::Retagger : 30.890612 sec
[23:55:26.700] I [ timer ] Timer: WireCell::Gen::FrameFanin : 30.760609 sec
[23:55:26.700] I [ timer ] Timer: wcls::FrameSaver : 30.030592 sec
[23:55:26.700] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 29.52058 sec
[23:55:26.700] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 29.08057 sec
[23:55:26.700] I [ timer ] Timer: WireCell::Gen::DepoSetFanout : 28.840565 sec
[23:55:26.700] I [ timer ] Timer: Total node execution : 113666.85078239441 sec
wclsFrameSaver: saving 917 traces tagged "gauss"
FrameSaver: q=19221.7 n=10700 tag=gauss
wclsFrameSaver: saving 900 traces tagged "wiener"
FrameSaver: q=30012.1 n=10826 tag=wiener
wclsFrameSaver: no traces tagged "dnnsp"
FrameSaver: q=0 n=0 tag=dnnsp
19-Nov-2024 23:55:32 GMT Closed output file "000673_reco_data_2024-11-14T_093321Z_reco_data_2024-11-14T_142647Z_reco_data_2024-11-18T_125601Z.root"
19-Nov-2024 23:55:32 GMT Closed input file "root://xrootd.echo.stfc.ac.uk:1094/dune:/protodune/RSE/usertests/ee/37/000673_reco_data_2024-11-14T_093321Z_reco_data_2024-11-14T_142647Z.root"
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 89.9182 125.685 648.017 123.799 28.8089 1000
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000338841 0.00799555 0.397685 0.000887384 0.0303849 1000
simulate:tpcrawdecoder:WireCellToolkit 89.8695 125.463 641.992 123.721 28.4744 1000
simulate:opdigi:OpDetDigitizerDUNE 0.000230377 0.108542 4.18869 0.00116412 0.267323 1000
simulate:rns:RandomNumberSaver 2.555e-05 3.96347e-05 0.000376332 3.59765e-05 1.96536e-05 1000
[art]:TriggerResults:TriggerResultInserter 1.193e-05 1.83382e-05 0.00028232 1.651e-05 1.43492e-05 1000
end_path:out1:RootOutput 4.67e-06 6.91354e-06 0.000100844 6.3955e-06 6.10029e-06 1000
end_path:out1:RootOutput(write) 0.0469319 0.105002 1.83434 0.0775048 0.111596 1000
================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4144.28 MB
Peak resident set size usage (VmHWM): 2480.37 MB
====================================================================================================
ProvidedPedestalAdditionService::dtor: Deleting random engine with seed 735903462
ExponentialChannelNoiseService::dtor: Deleting random engine with seed 565544165
StuckBitAdcDistortionService:dtor: Deleting random engine with seed 283840206
Art has completed and will exit with status 0.
=== End last 50 lines of lar log file ===
lar exit code 0
outfile is 000673_reco_data_2024-11-14T_093321Z_reco_data_2024-11-14T_142647Z_reco_data_2024-11-18T_125601Z.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": 1732060532.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": 1731934763.0
},
"parents": [
{
"did": "usertests:000673_reco_data_2024-11-14T_093321Z_reco_data_2024-11-14T_142647Z.root"
}
]
}
===================
.:
total 2222456
-rw-r--r--. 1 job0006 job0006 2199186578 Nov 19 23:55 000673_reco_data_2024-11-14T_093321Z_reco_data_2024-11-14T_142647Z_reco_data_2024-11-18T_125601Z.root
-rw-r--r--. 1 job0006 job0006 38602622 Nov 19 23:55 000673_reco_data_2024-11-14T_093321Z_reco_data_2024-11-14T_142647Z_reco_2024-11-18T_125601Z.log
-rw-r--r--. 1 job0006 job0006 37963693 Nov 19 23:55 wcls-sim-drift-simchannel-nf-sp.log
-rw-r--r--. 1 job0006 job0006 6074 Nov 19 23:55 jobscript.log
-rw-r--r--. 1 job0006 job0006 1115 Nov 19 23:55 000673_reco_data_2024-11-14T_093321Z_reco_data_2024-11-14T_142647Z_reco_data_2024-11-18T_125601Z.root.json
-rw-r--r--. 1 job0006 job0006 930 Nov 19 23:55 000673_reco_data_2024-11-14T_093321Z_reco_data_2024-11-14T_142647Z_reco_data_2024-11-18T_125601Z.root.ext.json
-rw-r--r--. 1 job0006 job0006 164 Nov 18 12:55 all-input-dids.txt
-rw-r--r--. 1 job0006 job0006 143 Nov 19 23:55 justin-processed-pfns.txt
-rw-r--r--. 1 job0006 job0006 0 Nov 18 12:56 debugprod.log 