Jobsub ID 42635.7@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
] <Resampler:resmp0> EOS at call=79
[00:32:42.690] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=79 anode=0
[00:32:42.690] D [ glue ] <FrameFanin:nfsp> EOS at call=79 with 8
[00:32:42.690] D [ glue ] frame sink sees EOS
[00:32:42.690] D [ pgraph ] <Pgrapher:> graph execution complete
[00:32:42.690] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.29 sec
[00:32:42.690] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.48 sec
[00:32:42.690] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.45 sec
[00:32:42.690] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.27 sec
[00:32:42.690] I [ timer ] Timer: WireCell::Aux::Resampler : 0.56 sec
[00:32:42.690] I [ timer ] Timer: WireCell::Aux::Resampler : 0.51 sec
[00:32:42.690] I [ timer ] Timer: WireCell::Aux::Resampler : 0.49 sec
[00:32:42.690] I [ timer ] Timer: WireCell::Aux::Resampler : 0.46 sec
[00:32:42.690] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[00:32:42.690] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:32:42.690] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:32:42.690] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:32:42.690] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:32:42.690] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:32:42.690] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:32:42.690] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:32:42.690] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:32:42.690] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:32:42.690] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[00:32:42.690] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:32:42.690] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:32:42.690] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[00:32:42.690] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[00:32:42.690] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[00:32:42.690] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:32:42.690] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[00:32:42.690] I [ timer ] Timer: Total node execution : 47.54999978095293 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 25865 traces tagged "gauss"
FrameSaver: q=4.58856e+06 n=575691 tag=gauss
wclsFrameSaver: saving 33097 traces tagged "wiener"
FrameSaver: q=5.02407e+06 n=553804 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 601 XUs and 968 XVs -> 51 XUVs
C:0 T:1 1516 XUs and 1352 XVs -> 77 XUVs
C:0 T:2 322 XUs and 450 XVs -> 27 XUVs
C:0 T:3 1791 XUs and 2106 XVs -> 124 XUVs
C:0 T:4 766 XUs and 787 XVs -> 19 XUVs
C:0 T:5 19705 XUs and 33968 XVs -> 1982 XUVs
C:0 T:6 1032 XUs and 1436 XVs -> 31 XUVs
C:0 T:7 156 XUs and 147 XVs -> 6 XUVs
2317 XUVs total
1171 collection wire objects
2317 potential space points
Neighbour search...
101111 tests to find 22024 neighbours
Iterating with no regularization...
Begin: 5.01755e+09
0 4.66051e+09
1 4.63862e+09
2 4.6382e+09
Now with regularization...
Begin: 4.59428e+09
0 4.59412e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
19-Sep-2025 00:33:27 BST Closed output file "np02vd_raw_run039499_0086_df-s04-d3_dw_0_20250918T203557_reco_stage1_20250918T233327_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 51.7061 87.9833 254.99 75.605 34.9793 40
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.9391e-05 9.973e-05 0.000211697 9.6165e-05 2.64426e-05 40
produce:tpcrawdecoder:PDVDTPCReader 5.91319 6.87159 9.62276 6.65748 0.864239 40
produce:triggerrawdecoder:PDVDTriggerReader4 0.302008 0.307581 0.325854 0.305556 0.00552647 40
produce:pdvddaphne:DAPHNEReaderPDVD 2.90908 3.29824 3.75958 3.30438 0.214679 40
produce:ophit:OpHitFinder 0.0325772 0.05403 0.0621047 0.0560487 0.00668046 40
produce:opflash:OpFlashFinderVerticalDrift 0.00478837 0.0125895 0.0229699 0.0126198 0.00356808 40
produce:wclsdatavd:WireCellToolkit 28.2703 41.5132 90.7384 36.6016 13.4327 40
produce:gaushit:GausHitFinder 0.338493 0.761867 1.67813 0.744499 0.227785 40
produce:nhitsfilter:NumberOfHitsFilter 0.000102813 0.000174645 0.000391173 0.000168526 5.4572e-05 40
produce:reco3d:SpacePointSolver 2.60665 6.76919 25.6348 6.19805 3.58374 40
produce:hitpdune:DisambigFromSpacePoints 0.0196651 0.092191 0.413559 0.0837186 0.0626974 40
produce:pandora:StandardPandora 2.61416 24.7717 122.719 18.3509 21.1462 40
produce:pandoraTrack:LArPandoraTrackCreation 0.115185 0.348763 1.05021 0.325402 0.17046 40
produce:pandoraGnocalo:GnocchiCalorimetry 0.00942647 0.0199011 0.0383704 0.0195993 0.00559157 40
[art]:TriggerResults:TriggerResultInserter 1.7633e-05 3.55833e-05 7.8307e-05 3.1704e-05 1.38205e-05 40
end_path:out1:RootOutput 4.428e-06 8.18997e-06 2.2071e-05 8.531e-06 3.07889e-06 40
end_path:out1:RootOutput(write) 2.58435 3.13688 3.60745 3.15559 0.23429 40
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 7388.6 MB
Peak resident set size usage (VmHWM): 5433.94 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0086_df-s04-d3_dw_0_20250918T203557_reco_stage1_20250918T233327_keepup.root
\tHists: np02vd_raw_run039499_0086_df-s04-d3_dw_0_20250918T203557_reco_stage1_20250918T233327_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0086_df-s04-d3_dw_0_20250918T203557_reco_stage1_20250918T233327_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0086_df-s04-d3_dw_0_20250918T203557_reco_stage1_20250918T233327_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": 1758238408.0,
"core.end_time": 1758238408.0,
"core.events": [
20978,
20984,
20990,
20996,
21002,
21008,
21014,
21020,
21026,
21032,
21038,
21044,
21050,
21056,
21062,
21068,
21074,
21080,
21086,
21092,
21098,
21104,
21110,
21116,
21122,
21128,
21134,
21140,
21146,
21152,
21158,
21164,
21170,
21176,
21182,
21188,
21194,
21200,
21206,
21212
],
"core.event_count": 40,
"core.first_event_number": 20978,
"core.last_event_number": 21212,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39499
],
"core.runs_subruns": [
3949900001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039499_0086_df-s04-d3_dw_0_20250918T203557.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0086_df-s04-d3_dw_0_20250918T203557_reco_stage1_20250918T233327_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": 1758238408.0,
"core.end_time": 1758238408.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39499
],
"core.runs_subruns": [
3949900001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039499_0086_df-s04-d3_dw_0_20250918T203557.hdf5"
}
]
} 