Jobsub ID 41697.130@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
:25.870] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=93 anode=0
[12:15:25.870] D [ glue ] <FrameFanin:nfsp> EOS at call=93 with 8
[12:15:25.870] D [ glue ] frame sink sees EOS
[12:15:25.870] D [ pgraph ] <Pgrapher:> graph execution complete
[12:15:25.870] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.21 sec
[12:15:25.870] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.82 sec
[12:15:25.870] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.11 sec
[12:15:25.870] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.03 sec
[12:15:25.870] I [ timer ] Timer: WireCell::Aux::Resampler : 0.67 sec
[12:15:25.870] I [ timer ] Timer: WireCell::Aux::Resampler : 0.66 sec
[12:15:25.870] I [ timer ] Timer: WireCell::Aux::Resampler : 0.65 sec
[12:15:25.870] I [ timer ] Timer: WireCell::Aux::Resampler : 0.65 sec
[12:15:25.870] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[12:15:25.870] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:15:25.871] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:15:25.871] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[12:15:25.871] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:15:25.871] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:15:25.871] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:15:25.871] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:15:25.871] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:15:25.871] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[12:15:25.871] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:15:25.871] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:15:25.871] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:15:25.871] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:15:25.871] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:15:25.871] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[12:15:25.871] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[12:15:25.871] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[12:15:25.871] I [ timer ] Timer: Total node execution : 44.83999911695719 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 13638 traces tagged "gauss"
FrameSaver: q=2.23261e+06 n=319016 tag=gauss
wclsFrameSaver: saving 17056 traces tagged "wiener"
FrameSaver: q=2.37344e+06 n=308199 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 120 XUs and 271 XVs -> 7 XUVs
C:0 T:1 160 XUs and 129 XVs -> 5 XUVs
C:0 T:2 140 XUs and 163 XVs -> 6 XUVs
C:0 T:3 101 XUs and 91 XVs -> 6 XUVs
C:0 T:4 7909 XUs and 6319 XVs -> 471 XUVs
C:0 T:5 203 XUs and 184 XVs -> 8 XUVs
C:0 T:6 2220 XUs and 2245 XVs -> 151 XUVs
C:0 T:7 519 XUs and 317 XVs -> 30 XUVs
684 XUVs total
480 collection wire objects
684 potential space points
Neighbour search...
17252 tests to find 5314 neighbours
Iterating with no regularization...
Begin: 8.94054e+08
0 8.6269e+08
1 8.58911e+08
2 8.58865e+08
Now with regularization...
Begin: 8.45814e+08
0 8.45802e+08
18-Sep-2025 12:15:46 CEST Closed output file "np02vd_raw_run039433_0371_df-s05-d3_dw_0_20250918T063622_reco_stage1_20250918T101546_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 50.3765 220.289 3239.62 97.2918 570.574 47
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.5636e-05 0.000107154 0.00022335 0.000103899 2.18824e-05 47
produce:tpcrawdecoder:PDVDTPCReader 4.83418 6.44534 8.95145 6.20388 1.05267 47
produce:triggerrawdecoder:PDVDTriggerReader4 0.17533 0.180088 0.215154 0.177471 0.00688179 47
produce:pdvddaphne:DAPHNEReaderPDVD 4.53253 5.53823 6.65593 5.50176 0.378083 47
produce:ophit:OpHitFinder 0.0486051 0.0632744 0.0739559 0.064547 0.00621646 47
produce:opflash:OpFlashFinderVerticalDrift 0.00378876 0.0191776 0.0334351 0.0194593 0.00627968 47
produce:wclsdatavd:WireCellToolkit 32.5191 54.0936 99.1864 47.1588 13.9788 47
produce:gaushit:GausHitFinder 0.322784 0.96726 3.55277 0.905198 0.497783 47
produce:nhitsfilter:NumberOfHitsFilter 8.6785e-05 0.000206861 0.000530473 0.000182504 8.77476e-05 47
produce:reco3d:SpacePointSolver 0.919126 6.95882 36.6276 6.04147 5.95007 47
produce:hitpdune:DisambigFromSpacePoints 0.012819 0.183581 1.82989 0.108016 0.285968 47
produce:pandora:StandardPandora 3.90183 141.653 3104.92 18.9644 559.511 47
produce:pandoraTrack:LArPandoraTrackCreation 0.0880542 0.664412 4.55009 0.440323 0.808092 47
produce:pandoraGnocalo:GnocchiCalorimetry 0.00837491 0.0248068 0.0818297 0.0228311 0.0122219 47
[art]:TriggerResults:TriggerResultInserter 2.7094e-05 4.00593e-05 7.9745e-05 3.7835e-05 9.39038e-06 47
end_path:out1:RootOutput 4.043e-06 7.6977e-06 3.1111e-05 5.756e-06 4.42263e-06 47
end_path:out1:RootOutput(write) 1.87573 3.46924 4.40524 3.39827 0.522584 47
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4853.35 MB
Peak resident set size usage (VmHWM): 2961.56 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0371_df-s05-d3_dw_0_20250918T063622_reco_stage1_20250918T101546_keepup.root
\tHists: np02vd_raw_run039433_0371_df-s05-d3_dw_0_20250918T063622_reco_stage1_20250918T101546_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0371_df-s05-d3_dw_0_20250918T063622_reco_stage1_20250918T101546_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0371_df-s05-d3_dw_0_20250918T063622_reco_stage1_20250918T101546_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": 1758190547.0,
"core.end_time": 1758190547.0,
"core.events": [
106533,
106539,
106545,
106551,
106557,
106563,
106569,
106575,
106581,
106587,
106593,
106599,
106605,
106611,
106617,
106623,
106629,
106635,
106641,
106647,
106653,
106659,
106665,
106671,
106677,
106683,
106689,
106695,
106701,
106707,
106713,
106719,
106725,
106731,
106737,
106743,
106749,
106755,
106761,
106767,
106773,
106779,
106785,
106791,
106797,
106803,
106809
],
"core.event_count": 47,
"core.first_event_number": 106533,
"core.last_event_number": 106809,
"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_0371_df-s05-d3_dw_0_20250918T063622.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0371_df-s05-d3_dw_0_20250918T063622_reco_stage1_20250918T101546_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": 1758190547.0,
"core.end_time": 1758190547.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_0371_df-s05-d3_dw_0_20250918T063622.hdf5"
}
]
} 