Jobsub ID 49050.70@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
rame sink sees EOS
[23:44:32.897] D [ pgraph ] <Pgrapher:> graph execution complete
[23:44:32.898] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.91 sec
[23:44:32.898] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.81 sec
[23:44:32.898] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.1 sec
[23:44:32.898] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.34 sec
[23:44:32.898] I [ timer ] Timer: WireCell::Aux::Resampler : 1.55 sec
[23:44:32.898] I [ timer ] Timer: WireCell::Aux::Resampler : 1.55 sec
[23:44:32.898] I [ timer ] Timer: WireCell::Aux::Resampler : 1.49 sec
[23:44:32.898] I [ timer ] Timer: WireCell::Aux::Resampler : 1.32 sec
[23:44:32.898] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[23:44:32.898] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[23:44:32.898] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[23:44:32.898] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[23:44:32.898] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[23:44:32.898] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[23:44:32.898] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[23:44:32.898] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[23:44:32.898] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[23:44:32.898] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[23:44:32.898] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[23:44:32.898] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[23:44:32.898] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[23:44:32.898] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[23:44:32.898] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[23:44:32.898] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[23:44:32.898] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[23:44:32.898] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[23:44:32.898] I [ timer ] Timer: Total node execution : 63.10000076703727 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 24787 traces tagged "gauss"
FrameSaver: q=3.1619e+06 n=558368 tag=gauss
wclsFrameSaver: saving 30120 traces tagged "wiener"
FrameSaver: q=3.29171e+06 n=543058 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 391 XUs and 640 XVs -> 22 XUVs
C:0 T:1 947 XUs and 1062 XVs -> 19 XUVs
C:0 T:2 1762 XUs and 1903 XVs -> 65 XUVs
C:0 T:3 1007 XUs and 1244 XVs -> 164 XUVs
C:0 T:4 1060 XUs and 988 XVs -> 55 XUVs
C:0 T:5 2242 XUs and 2674 XVs -> 96 XUVs
C:0 T:6 393 XUs and 580 XVs -> 34 XUVs
C:0 T:7 672 XUs and 846 XVs -> 28 XUVs
483 XUVs total
327 collection wire objects
483 potential space points
Neighbour search...
6579 tests to find 3992 neighbours
Iterating with no regularization...
Begin: 7.346e+07
0 7.18772e+07
1 7.18346e+07
Now with regularization...
Begin: 7.07011e+07
0 7.06917e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
18-Sep-2025 23:45:18 BST Closed output file "np02vd_raw_run039499_0056_df-s04-d1_dw_0_20250918T193504_reco_stage1_20250918T224518_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 79.7823 145.096 710.583 116.68 105.724 41
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 8.3687e-05 0.000110139 0.000304582 0.000102383 4.2077e-05 41
produce:tpcrawdecoder:PDVDTPCReader 7.74785 8.80671 11.8813 8.60463 0.918856 41
produce:triggerrawdecoder:PDVDTriggerReader4 0.38715 0.398535 0.427105 0.396837 0.010061 41
produce:pdvddaphne:DAPHNEReaderPDVD 4.0962 4.42351 4.77283 4.41359 0.185442 41
produce:ophit:OpHitFinder 0.0520177 0.0670638 0.0941317 0.0670241 0.00885652 41
produce:opflash:OpFlashFinderVerticalDrift 0.00825277 0.0175305 0.0383546 0.017497 0.00527005 41
produce:wclsdatavd:WireCellToolkit 45.8219 59.4307 99.3209 52.4457 14.3103 41
produce:gaushit:GausHitFinder 0.679996 1.09297 2.23849 1.029 0.314239 41
produce:nhitsfilter:NumberOfHitsFilter 0.000162305 0.000269042 0.000602813 0.000237496 9.08788e-05 41
produce:reco3d:SpacePointSolver 5.06347 9.96921 35.5462 9.12958 5.48874 41
produce:hitpdune:DisambigFromSpacePoints 0.0529696 0.167382 1.26288 0.106903 0.20032 41
produce:pandora:StandardPandora 7.15951 55.8558 584.637 29.3076 93.4722 41
produce:pandoraTrack:LArPandoraTrackCreation 0.190012 0.687125 4.9092 0.470777 0.799654 41
produce:pandoraGnocalo:GnocchiCalorimetry 0.0144125 0.029931 0.0851861 0.0277113 0.0120541 41
[art]:TriggerResults:TriggerResultInserter 2.2893e-05 3.20739e-05 7.9118e-05 2.9225e-05 1.05403e-05 41
end_path:out1:RootOutput 3.908e-06 6.00646e-06 2.4025e-05 5.33e-06 3.15043e-06 41
end_path:out1:RootOutput(write) 3.02489 4.12126 4.96515 4.06089 0.406775 41
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4891.63 MB
Peak resident set size usage (VmHWM): 2945.62 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0056_df-s04-d1_dw_0_20250918T193504_reco_stage1_20250918T224518_keepup.root
\tHists: np02vd_raw_run039499_0056_df-s04-d1_dw_0_20250918T193504_reco_stage1_20250918T224518_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0056_df-s04-d1_dw_0_20250918T193504_reco_stage1_20250918T224518_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0056_df-s04-d1_dw_0_20250918T193504_reco_stage1_20250918T224518_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": 1758235519.0,
"core.end_time": 1758235519.0,
"core.events": [
13620,
13626,
13632,
13638,
13644,
13650,
13656,
13662,
13668,
13674,
13680,
13686,
13692,
13698,
13704,
13710,
13716,
13722,
13728,
13734,
13740,
13746,
13752,
13758,
13764,
13770,
13776,
13782,
13788,
13794,
13800,
13806,
13812,
13818,
13824,
13830,
13836,
13842,
13848,
13854,
13860
],
"core.event_count": 41,
"core.first_event_number": 13620,
"core.last_event_number": 13860,
"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_0056_df-s04-d1_dw_0_20250918T193504.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0056_df-s04-d1_dw_0_20250918T193504_reco_stage1_20250918T224518_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": 1758235519.0,
"core.end_time": 1758235519.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_0056_df-s04-d1_dw_0_20250918T193504.hdf5"
}
]
} 