Jobsub ID 47410.43@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
igproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=39 anode=0
[11:11:43.781] D [ glue ] <FrameFanin:nfsp> EOS at call=39 with 8
[11:11:43.781] D [ glue ] frame sink sees EOS
[11:11:43.781] D [ pgraph ] <Pgrapher:> graph execution complete
[11:11:43.781] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.59 sec
[11:11:43.781] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.34 sec
[11:11:43.781] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.28 sec
[11:11:43.781] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8 sec
[11:11:43.781] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.94 sec
[11:11:43.781] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.41 sec
[11:11:43.781] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.3 sec
[11:11:43.781] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.04 sec
[11:11:43.781] I [ timer ] Timer: WireCell::Aux::Resampler : 1.02 sec
[11:11:43.781] I [ timer ] Timer: WireCell::Aux::Resampler : 0.98 sec
[11:11:43.781] I [ timer ] Timer: WireCell::Aux::Resampler : 0.97 sec
[11:11:43.781] I [ timer ] Timer: WireCell::Aux::Resampler : 0.95 sec
[11:11:43.781] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[11:11:43.781] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:11:43.781] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:11:43.781] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:11:43.781] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:11:43.781] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:11:43.781] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:11:43.781] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:11:43.781] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:11:43.781] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:11:43.781] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[11:11:43.781] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:11:43.781] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:11:43.781] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:11:43.781] I [ timer ] Timer: Total node execution : 66.86000011116266 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 47071 traces tagged "gauss"
FrameSaver: q=7.01745e+06 n=900201 tag=gauss
wclsFrameSaver: saving 58798 traces tagged "wiener"
FrameSaver: q=7.42341e+06 n=856277 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 129 XUs and 158 XVs -> 3 XUVs
C:0 T:1 719 XUs and 618 XVs -> 35 XUVs
C:0 T:2 1651 XUs and 1634 XVs -> 66 XUVs
C:0 T:3 835 XUs and 1016 XVs -> 44 XUVs
C:0 T:4 2970 XUs and 2577 XVs -> 240 XUVs
C:0 T:5 916 XUs and 1053 XVs -> 131 XUVs
C:0 T:6 2302 XUs and 2072 XVs -> 117 XUVs
C:0 T:7 288 XUs and 330 XVs -> 10 XUVs
C:0 T:8 349 XUs and 540 XVs -> 21 XUVs
C:0 T:9 3543 XUs and 3953 XVs -> 236 XUVs
C:0 T:10 2709 XUs and 2576 XVs -> 121 XUVs
C:0 T:11 2943 XUs and 3850 XVs -> 175 XUVs
C:0 T:12 1692 XUs and 1892 XVs -> 158 XUVs
C:0 T:13 1772 XUs and 1961 XVs -> 133 XUVs
C:0 T:14 367 XUs and 419 XVs -> 43 XUVs
C:0 T:15 964 XUs and 1025 XVs -> 71 XUVs
1604 XUVs total
1273 collection wire objects
1604 potential space points
Neighbour search...
15324 tests to find 8242 neighbours
Iterating with no regularization...
Begin: 3.06253e+08
0 2.95023e+08
1 2.94836e+08
Now with regularization...
Begin: 2.87388e+08
0 2.87369e+08
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
17-Sep-2025 11:12:45 BST Closed output file "np02vd_raw_run039388_0485_df-s05-d3_dw_0_20250916T232427_reco_stage1_20250917T101245_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 27.7115 179.661 347.388 171.643 58.3571 20
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.3681e-05 0.000140014 0.000401865 0.000120777 7.24489e-05 20
produce:tpcrawdecoder:PDVDTPCReader 9.66625 16.7064 17.7685 17.0042 1.65737 20
produce:triggerrawdecoder:PDVDTriggerReader4 0.304046 0.322437 0.416715 0.308266 0.0352762 20
produce:pdvddaphne:DAPHNEReaderPDVD 0.000216503 0.000402233 0.000961572 0.000378939 0.00013706 20
produce:ophit:OpHitFinder 0.000217933 0.000334777 0.00122186 0.000276538 0.000215493 20
produce:opflash:OpFlashFinderVerticalDrift 5.181e-05 9.86119e-05 0.000505177 7.6891e-05 9.41126e-05 20
produce:wclsdatavd:WireCellToolkit 12.7017 69.7237 81.4817 71.3087 13.7338 20
produce:gaushit:GausHitFinder 0.430498 1.63215 2.59848 1.57628 0.438514 20
produce:nhitsfilter:NumberOfHitsFilter 0.000122881 0.000378255 0.00068828 0.000340695 0.000129931 20
produce:reco3d:SpacePointSolver 1.31098 16.5245 29.5843 15.1229 5.99551 20
produce:hitpdune:DisambigFromSpacePoints 0.0248723 0.26906 0.570155 0.244996 0.114873 20
produce:pandora:StandardPandora 1.76796 67.0882 208.869 61.2556 42.2377 20
produce:pandoraTrack:LArPandoraTrackCreation 0.0981193 1.43489 3.30907 1.34928 0.714391 20
produce:pandoraGnocalo:GnocchiCalorimetry 0.00920885 0.0405075 0.0637629 0.0396747 0.0127229 20
[art]:TriggerResults:TriggerResultInserter 2.965e-05 4.84566e-05 0.000121911 4.41605e-05 2.04265e-05 20
end_path:out1:RootOutput 5.85e-06 1.32843e-05 6.0231e-05 1.1095e-05 1.09562e-05 20
end_path:out1:RootOutput(write) 1.37586 5.84507 6.66488 5.96868 1.06738 20
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4752.61 MB
Peak resident set size usage (VmHWM): 2811.45 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039388_0485_df-s05-d3_dw_0_20250916T232427_reco_stage1_20250917T101245_keepup.root
\tHists: np02vd_raw_run039388_0485_df-s05-d3_dw_0_20250916T232427_reco_stage1_20250917T101245_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039388_0485_df-s05-d3_dw_0_20250916T232427_reco_stage1_20250917T101245_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039388_0485_df-s05-d3_dw_0_20250916T232427_reco_stage1_20250917T101245_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": 1758103966.0,
"core.end_time": 1758103966.0,
"core.events": [
37375,
37379,
37383,
37387,
37391,
37395,
37399,
37403,
37407,
37411,
37415,
37419,
37423,
37427,
37431,
37435,
37439,
37443,
37447,
37451
],
"core.event_count": 20,
"core.first_event_number": 37375,
"core.last_event_number": 37451,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39388
],
"core.runs_subruns": [
3938800001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039388_0485_df-s05-d3_dw_0_20250916T232427.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039388_0485_df-s05-d3_dw_0_20250916T232427_reco_stage1_20250917T101245_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": 1758103966.0,
"core.end_time": 1758103966.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39388
],
"core.runs_subruns": [
3938800001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039388_0485_df-s05-d3_dw_0_20250916T232427.hdf5"
}
]
} 