Jobsub ID 42777.6@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
de0sigproc0> EOS at call=81 anode=0
[05:54:04.323] D [ glue ] <FrameFanin:nfsp> EOS at call=81 with 8
[05:54:04.323] D [ glue ] frame sink sees EOS
[05:54:04.323] D [ pgraph ] <Pgrapher:> graph execution complete
[05:54:04.323] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 24.36 sec
[05:54:04.323] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.18 sec
[05:54:04.323] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.34 sec
[05:54:04.323] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14 sec
[05:54:04.323] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[05:54:04.323] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[05:54:04.323] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[05:54:04.323] I [ timer ] Timer: WireCell::Aux::Resampler : 0.32 sec
[05:54:04.323] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[05:54:04.323] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0.01 sec
[05:54:04.323] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[05:54:04.323] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:54:04.323] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:54:04.323] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[05:54:04.323] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:54:04.323] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:54:04.323] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:54:04.323] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[05:54:04.323] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:54:04.323] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[05:54:04.323] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:54:04.323] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:54:04.323] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[05:54:04.323] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[05:54:04.323] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[05:54:04.323] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[05:54:04.323] I [ timer ] Timer: Total node execution : 69.21000109985471 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 28576 traces tagged "gauss"
FrameSaver: q=1.35035e+07 n=821797 tag=gauss
wclsFrameSaver: saving 37561 traces tagged "wiener"
FrameSaver: q=1.41144e+07 n=785872 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1018 XUs and 1644 XVs -> 73 XUVs
C:0 T:1 710 XUs and 877 XVs -> 36 XUVs
C:0 T:2 2672 XUs and 2093 XVs -> 90 XUVs
C:0 T:3 54871 XUs and 93120 XVs -> 4509 XUVs
C:0 T:4 645 XUs and 481 XVs -> 26 XUVs
C:0 T:5 144 XUs and 170 XVs -> 2 XUVs
C:0 T:6 389 XUs and 425 XVs -> 21 XUVs
C:0 T:7 188 XUs and 226 XVs -> 19 XUVs
4776 XUVs total
2562 collection wire objects
4776 potential space points
Neighbour search...
153032 tests to find 32048 neighbours
Iterating with no regularization...
Begin: 1.61189e+11
0 1.57606e+11
1 1.57454e+11
Now with regularization...
Begin: 1.56202e+11
0 1.56196e+11
PcaShowerParticleBuildingAlgorithm::OpeningAngle - principal eigenvalue less than or equal to 0.
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
19-Sep-2025 05:56:36 CEST Closed output file "np02vd_raw_run039499_0159_df-s04-d1_dw_0_20250918T230058_reco_stage1_20250919T035636_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 52.9302 134.92 1215.35 79.3673 185.25 41
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.767e-05 0.000106781 0.000272631 9.828e-05 4.07482e-05 41
produce:tpcrawdecoder:PDVDTPCReader 5.68649 7.09711 8.80473 7.17982 0.880836 41
produce:triggerrawdecoder:PDVDTriggerReader4 0.266362 0.271289 0.2859 0.269383 0.00471643 41
produce:pdvddaphne:DAPHNEReaderPDVD 2.82554 3.49341 4.50772 3.47272 0.300208 41
produce:ophit:OpHitFinder 0.0394959 0.0550172 0.0889229 0.0532486 0.0113011 41
produce:opflash:OpFlashFinderVerticalDrift 0.00687734 0.0128457 0.0285796 0.0117904 0.00436254 41
produce:wclsdatavd:WireCellToolkit 27.0453 37.4843 78.3469 33.0759 11.1167 41
produce:gaushit:GausHitFinder 0.405102 0.819242 1.78571 0.768539 0.276907 41
produce:nhitsfilter:NumberOfHitsFilter 9.087e-05 0.000300507 0.00518873 0.00017574 0.00077515 41
produce:reco3d:SpacePointSolver 1.55475 7.93309 22.1314 7.60716 4.28871 41
produce:hitpdune:DisambigFromSpacePoints 0.014898 0.136186 0.6231 0.0930838 0.119541 41
produce:pandora:StandardPandora 3.92301 73.9226 1097.75 22.1918 174.443 41
produce:pandoraTrack:LArPandoraTrackCreation 0.114773 0.45137 1.39575 0.353848 0.299325 41
produce:pandoraGnocalo:GnocchiCalorimetry 0.00892996 0.0205135 0.0374321 0.0200046 0.00663406 41
[art]:TriggerResults:TriggerResultInserter 1.985e-05 2.85267e-05 6.507e-05 2.706e-05 7.3716e-06 41
end_path:out1:RootOutput 4.46e-06 9.42005e-06 3.04e-05 8.59e-06 3.89073e-06 41
end_path:out1:RootOutput(write) 2.67499 3.20598 4.11458 3.1569 0.325126 41
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5691.47 MB
Peak resident set size usage (VmHWM): 3778.96 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0159_df-s04-d1_dw_0_20250918T230058_reco_stage1_20250919T035636_keepup.root
\tHists: np02vd_raw_run039499_0159_df-s04-d1_dw_0_20250918T230058_reco_stage1_20250919T035636_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0159_df-s04-d1_dw_0_20250918T230058_reco_stage1_20250919T035636_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0159_df-s04-d1_dw_0_20250918T230058_reco_stage1_20250919T035636_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": 1758254197.0,
"core.end_time": 1758254197.0,
"core.events": [
38742,
38748,
38754,
38760,
38766,
38772,
38778,
38784,
38790,
38796,
38802,
38808,
38814,
38820,
38826,
38832,
38838,
38844,
38850,
38856,
38862,
38868,
38874,
38880,
38886,
38892,
38898,
38904,
38910,
38916,
38922,
38928,
38934,
38940,
38946,
38952,
38958,
38964,
38970,
38976,
38982
],
"core.event_count": 41,
"core.first_event_number": 38742,
"core.last_event_number": 38982,
"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_0159_df-s04-d1_dw_0_20250918T230058.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0159_df-s04-d1_dw_0_20250918T230058_reco_stage1_20250919T035636_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": 1758254197.0,
"core.end_time": 1758254197.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_0159_df-s04-d1_dw_0_20250918T230058.hdf5"
}
]
} 