Jobsub ID 41697.83@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
[10:51:08.355] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=93 anode=0
[10:51:08.355] D [ glue ] <FrameFanin:nfsp> EOS at call=93 with 8
[10:51:08.355] D [ glue ] frame sink sees EOS
[10:51:08.355] D [ pgraph ] <Pgrapher:> graph execution complete
[10:51:08.355] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.65 sec
[10:51:08.355] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.05 sec
[10:51:08.355] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.18 sec
[10:51:08.355] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.59 sec
[10:51:08.355] I [ timer ] Timer: WireCell::Aux::Resampler : 1.89 sec
[10:51:08.355] I [ timer ] Timer: WireCell::Aux::Resampler : 1.87 sec
[10:51:08.355] I [ timer ] Timer: WireCell::Aux::Resampler : 1.86 sec
[10:51:08.355] I [ timer ] Timer: WireCell::Aux::Resampler : 1.82 sec
[10:51:08.355] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[10:51:08.355] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[10:51:08.355] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[10:51:08.355] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:51:08.355] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:51:08.355] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:51:08.355] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:51:08.355] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:51:08.355] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:51:08.355] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:51:08.355] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[10:51:08.355] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:51:08.355] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:51:08.355] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:51:08.355] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[10:51:08.355] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[10:51:08.355] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:51:08.355] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[10:51:08.355] I [ timer ] Timer: Total node execution : 67.95999936945736 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 24909 traces tagged "gauss"
FrameSaver: q=2.96718e+06 n=513428 tag=gauss
wclsFrameSaver: saving 31110 traces tagged "wiener"
FrameSaver: q=3.12981e+06 n=492166 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 2129 XUs and 2675 XVs -> 93 XUVs
C:0 T:1 1390 XUs and 2027 XVs -> 131 XUVs
C:0 T:2 1445 XUs and 1452 XVs -> 60 XUVs
C:0 T:3 1151 XUs and 2076 XVs -> 62 XUVs
C:0 T:4 245 XUs and 249 XVs -> 10 XUVs
C:0 T:5 469 XUs and 664 XVs -> 27 XUVs
C:0 T:6 1545 XUs and 2214 XVs -> 82 XUVs
C:0 T:7 668 XUs and 803 XVs -> 47 XUVs
512 XUVs total
442 collection wire objects
512 potential space points
Neighbour search...
3176 tests to find 1538 neighbours
Iterating with no regularization...
Begin: 1.21462e+08
0 1.19994e+08
1 1.19989e+08
Now with regularization...
Begin: 1.17237e+08
0 1.17237e+08
18-Sep-2025 10:51:57 CEST Closed output file "np02vd_raw_run039433_0363_df-s05-d2_dw_0_20250918T061533_reco_stage1_20250918T085157_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 70.7767 139.439 967.835 100.14 140.867 47
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.9239e-05 0.000116575 0.00030488 0.000105512 3.43237e-05 47
produce:tpcrawdecoder:PDVDTPCReader 4.76149 6.32789 9.20267 6.17233 0.967492 47
produce:triggerrawdecoder:PDVDTriggerReader4 0.176932 0.182222 0.234138 0.178954 0.00945331 47
produce:pdvddaphne:DAPHNEReaderPDVD 4.9869 5.47858 5.92981 5.44494 0.245428 47
produce:ophit:OpHitFinder 0.0460061 0.0621975 0.0949148 0.0622165 0.00726951 47
produce:opflash:OpFlashFinderVerticalDrift 0.00797606 0.0186317 0.03432 0.0177027 0.0054657 47
produce:wclsdatavd:WireCellToolkit 42.924 59.8445 139.218 52.4136 18.1831 47
produce:gaushit:GausHitFinder 0.613277 1.0411 3.12536 0.942014 0.435444 47
produce:nhitsfilter:NumberOfHitsFilter 0.000140703 0.0002357 0.000503836 0.000215372 8.26381e-05 47
produce:reco3d:SpacePointSolver 2.71272 7.19276 27.3382 5.82516 4.46456 47
produce:hitpdune:DisambigFromSpacePoints 0.0349356 0.171029 1.13296 0.122053 0.1755 47
produce:pandora:StandardPandora 7.16292 54.7783 787.145 20.6961 122.883 47
produce:pandoraTrack:LArPandoraTrackCreation 0.226766 0.751151 5.63553 0.53421 0.866805 47
produce:pandoraGnocalo:GnocchiCalorimetry 0.0124018 0.026818 0.0672326 0.023659 0.0108177 47
[art]:TriggerResults:TriggerResultInserter 2.434e-05 3.87691e-05 9.7942e-05 3.5528e-05 1.14286e-05 47
end_path:out1:RootOutput 4.052e-06 6.91555e-06 3.4245e-05 5.079e-06 5.39825e-06 47
end_path:out1:RootOutput(write) 2.95925 3.53384 4.67534 3.38306 0.47295 47
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 7596.29 MB
Peak resident set size usage (VmHWM): 5708.82 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0363_df-s05-d2_dw_0_20250918T061533_reco_stage1_20250918T085157_keepup.root
\tHists: np02vd_raw_run039433_0363_df-s05-d2_dw_0_20250918T061533_reco_stage1_20250918T085157_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0363_df-s05-d2_dw_0_20250918T061533_reco_stage1_20250918T085157_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0363_df-s05-d2_dw_0_20250918T061533_reco_stage1_20250918T085157_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_10_00d00",
"core.data_tier": "full-reconstructed",
"dune.config_file": "standard_reco_stage1_protodunevd_keepup_all.fcl",
"dune.campaign": "vd-protodune-reco-keepup-v0",
"core.start_time": 1758185518.0,
"core.end_time": 1758185518.0,
"core.events": [
104132,
104138,
104144,
104150,
104156,
104162,
104168,
104174,
104180,
104186,
104192,
104198,
104204,
104210,
104216,
104222,
104228,
104234,
104240,
104246,
104252,
104258,
104264,
104270,
104276,
104282,
104288,
104294,
104300,
104306,
104312,
104318,
104324,
104330,
104336,
104342,
104348,
104354,
104360,
104366,
104372,
104378,
104384,
104390,
104396,
104402,
104408
],
"core.event_count": 47,
"core.first_event_number": 104132,
"core.last_event_number": 104408,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0363_df-s05-d2_dw_0_20250918T061533.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0363_df-s05-d2_dw_0_20250918T061533_reco_stage1_20250918T085157_keepup_hists.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "root",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_10_00d00",
"core.data_tier": "root-tuple-virtual",
"dune.config_file": "standard_reco_stage1_protodunevd_keepup_all.fcl",
"dune.campaign": "vd-protodune-reco-keepup-v0",
"core.start_time": 1758185518.0,
"core.end_time": 1758185518.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0363_df-s05-d2_dw_0_20250918T061533.hdf5"
}
]
} 