Jobsub ID 41697.40@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
:10.841] D [ glue ] <FrameFanin:nfsp> EOS at call=93 with 8
[10:20:10.841] D [ glue ] frame sink sees EOS
[10:20:10.841] D [ pgraph ] <Pgrapher:> graph execution complete
[10:20:10.841] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.15 sec
[10:20:10.841] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.1 sec
[10:20:10.841] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.26 sec
[10:20:10.841] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.87 sec
[10:20:10.841] I [ timer ] Timer: WireCell::Aux::Resampler : 0.32 sec
[10:20:10.841] I [ timer ] Timer: WireCell::Aux::Resampler : 0.28 sec
[10:20:10.841] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[10:20:10.841] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[10:20:10.841] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[10:20:10.841] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:20:10.842] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0 sec
[10:20:10.842] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:20:10.842] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:20:10.842] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:20:10.842] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:20:10.842] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:20:10.842] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:20:10.842] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[10:20:10.842] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:20:10.842] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:20:10.842] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:20:10.842] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:20:10.842] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:20:10.842] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[10:20:10.842] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[10:20:10.842] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[10:20:10.842] I [ timer ] Timer: Total node execution : 32.51000008918345 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 26977 traces tagged "gauss"
FrameSaver: q=5.96642e+06 n=686566 tag=gauss
wclsFrameSaver: saving 34318 traces tagged "wiener"
FrameSaver: q=6.43104e+06 n=663052 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 18455 XUs and 47746 XVs -> 2031 XUVs
C:0 T:1 163 XUs and 158 XVs -> 6 XUVs
C:0 T:2 822 XUs and 1054 XVs -> 24 XUVs
C:0 T:3 1272 XUs and 1580 XVs -> 72 XUVs
C:0 T:4 1152 XUs and 1491 XVs -> 54 XUVs
C:0 T:5 1080 XUs and 967 XVs -> 46 XUVs
C:0 T:6 3197 XUs and 3487 XVs -> 102 XUVs
C:0 T:7 523 XUs and 934 XVs -> 21 XUVs
2356 XUVs total
1422 collection wire objects
2356 potential space points
Neighbour search...
71246 tests to find 15294 neighbours
Iterating with no regularization...
Begin: 1.63017e+10
0 1.55694e+10
1 1.54946e+10
2 1.54943e+10
Now with regularization...
Begin: 1.54294e+10
0 1.54292e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
18-Sep-2025 10:20:51 CEST Closed output file "np02vd_raw_run039433_0353_df-s05-d3_dw_0_20250918T055156_reco_stage1_20250918T082051_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 80.0652 117.899 205.994 113.172 24.7095 47
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.961e-05 7.96747e-05 0.000228792 7.5123e-05 2.41985e-05 47
produce:tpcrawdecoder:PDVDTPCReader 33.7169 55.3728 90.2514 52.1482 11.6209 47
produce:triggerrawdecoder:PDVDTriggerReader4 0.320659 0.465064 0.843831 0.433394 0.122931 47
produce:pdvddaphne:DAPHNEReaderPDVD 6.79792 11.977 17.3395 11.7878 2.03284 47
produce:ophit:OpHitFinder 0.0247237 0.03841 0.0558993 0.0377589 0.00604014 47
produce:opflash:OpFlashFinderVerticalDrift 0.00281346 0.00757375 0.0159126 0.00758449 0.00236682 47
produce:wclsdatavd:WireCellToolkit 22.3654 31.2166 48.0966 28.7956 6.34892 47
produce:gaushit:GausHitFinder 0.229604 0.430553 0.82973 0.411582 0.118661 47
produce:nhitsfilter:NumberOfHitsFilter 7.6595e-05 0.000121122 0.000438365 0.000105248 5.49256e-05 47
produce:reco3d:SpacePointSolver 1.21998 4.12004 13.3214 3.71059 1.89816 47
produce:hitpdune:DisambigFromSpacePoints 0.022407 0.0494652 0.165148 0.038334 0.027907 47
produce:pandora:StandardPandora 2.88148 11.9716 74.1272 7.86933 12.5083 47
produce:pandoraTrack:LArPandoraTrackCreation 0.0679035 0.222456 1.30491 0.171385 0.182943 47
produce:pandoraGnocalo:GnocchiCalorimetry 0.00539424 0.0104044 0.0204537 0.0101106 0.00303879 47
[art]:TriggerResults:TriggerResultInserter 1.6044e-05 2.50612e-05 5.7035e-05 2.2744e-05 7.71719e-06 47
end_path:out1:RootOutput 4.247e-06 5.76572e-06 1.9438e-05 5.068e-06 2.55111e-06 47
end_path:out1:RootOutput(write) 1.65161 1.98479 3.2001 1.84556 0.372622 47
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4305.34 MB
Peak resident set size usage (VmHWM): 2416.25 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0353_df-s05-d3_dw_0_20250918T055156_reco_stage1_20250918T082051_keepup.root
\tHists: np02vd_raw_run039433_0353_df-s05-d3_dw_0_20250918T055156_reco_stage1_20250918T082051_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0353_df-s05-d3_dw_0_20250918T055156_reco_stage1_20250918T082051_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0353_df-s05-d3_dw_0_20250918T055156_reco_stage1_20250918T082051_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": 1758183652.0,
"core.end_time": 1758183652.0,
"core.events": [
101373,
101379,
101385,
101391,
101397,
101403,
101409,
101415,
101421,
101427,
101433,
101439,
101445,
101451,
101457,
101463,
101469,
101475,
101481,
101487,
101493,
101499,
101505,
101511,
101517,
101523,
101529,
101535,
101541,
101547,
101553,
101559,
101565,
101571,
101577,
101583,
101589,
101595,
101601,
101607,
101613,
101619,
101625,
101631,
101637,
101643,
101649
],
"core.event_count": 47,
"core.first_event_number": 101373,
"core.last_event_number": 101649,
"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_0353_df-s05-d3_dw_0_20250918T055156.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0353_df-s05-d3_dw_0_20250918T055156_reco_stage1_20250918T082051_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": 1758183652.0,
"core.end_time": 1758183652.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_0353_df-s05-d3_dw_0_20250918T055156.hdf5"
}
]
} 