Jobsub ID 41697.69@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
pher:> graph execution complete
[12:00:57.986] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.03 sec
[12:00:57.987] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.31 sec
[12:00:57.987] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.24 sec
[12:00:57.987] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.96 sec
[12:00:57.987] I [ timer ] Timer: WireCell::Aux::Resampler : 0.76 sec
[12:00:57.987] I [ timer ] Timer: WireCell::Aux::Resampler : 0.69 sec
[12:00:57.987] I [ timer ] Timer: WireCell::Aux::Resampler : 0.62 sec
[12:00:57.987] I [ timer ] Timer: WireCell::Aux::Resampler : 0.61 sec
[12:00:57.987] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[12:00:57.987] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[12:00:57.987] I [ timer ] Timer: WireCell::Gen::Retagger : 0.02 sec
[12:00:57.987] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:00:57.987] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:00:57.987] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:00:57.987] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:00:57.987] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:00:57.987] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:00:57.987] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:00:57.987] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:00:57.987] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[12:00:57.987] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:00:57.987] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:00:57.987] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:00:57.987] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[12:00:57.987] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[12:00:57.987] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[12:00:57.987] I [ timer ] Timer: Total node execution : 48.299999967217445 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 17826 traces tagged "gauss"
FrameSaver: q=3.25829e+06 n=457487 tag=gauss
wclsFrameSaver: saving 22127 traces tagged "wiener"
FrameSaver: q=3.42665e+06 n=445017 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 322 XUs and 456 XVs -> 15 XUVs
C:0 T:1 535 XUs and 670 XVs -> 12 XUVs
C:0 T:2 802 XUs and 656 XVs -> 20 XUVs
C:0 T:3 8538 XUs and 11948 XVs -> 644 XUVs
C:0 T:4 1484 XUs and 1978 XVs -> 67 XUVs
C:0 T:5 579 XUs and 920 XVs -> 59 XUVs
C:0 T:6 422 XUs and 454 XVs -> 7 XUVs
C:0 T:7 450 XUs and 547 XVs -> 26 XUVs
850 XUVs total
599 collection wire objects
850 potential space points
Neighbour search...
13994 tests to find 4118 neighbours
Iterating with no regularization...
Begin: 1.59674e+09
0 1.54276e+09
1 1.54159e+09
Now with regularization...
Begin: 1.51289e+09
0 1.51285e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
18-Sep-2025 12:01:46 BST Closed output file "np02vd_raw_run039433_0360_df-s05-d3_dw_0_20250918T060918_reco_stage1_20250918T110146_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 108.713 313.969 3377.52 152.268 646.33 47
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 8.8027e-05 0.000140654 0.000259587 0.000136416 3.62349e-05 47
produce:tpcrawdecoder:PDVDTPCReader 25.4975 46.0991 93.3464 45.7005 12.0743 47
produce:triggerrawdecoder:PDVDTriggerReader4 0.37976 0.501355 1.21515 0.42861 0.196923 47
produce:pdvddaphne:DAPHNEReaderPDVD 8.04494 12.8273 19.9983 12.0051 2.42196 47
produce:ophit:OpHitFinder 0.0462185 0.0756502 0.112559 0.0756704 0.0113981 47
produce:opflash:OpFlashFinderVerticalDrift 0.00699976 0.0176355 0.0326321 0.0179745 0.00563478 47
produce:wclsdatavd:WireCellToolkit 43.6625 58.6789 111.997 50.4841 14.4802 47
produce:gaushit:GausHitFinder 0.552082 1.19247 3.55807 1.05545 0.686972 47
produce:nhitsfilter:NumberOfHitsFilter 0.00017595 0.000299996 0.000692991 0.000262693 0.000124156 47
produce:reco3d:SpacePointSolver 2.78152 9.07858 56.2924 6.61378 9.85819 47
produce:hitpdune:DisambigFromSpacePoints 0.0431148 0.278777 4.35409 0.111515 0.675259 47
produce:pandora:StandardPandora 6.39468 180.336 3088.86 26.4691 621.636 47
produce:pandoraTrack:LArPandoraTrackCreation 0.1142 1.01103 10.9641 0.514189 1.93707 47
produce:pandoraGnocalo:GnocchiCalorimetry 0.00879593 0.0287088 0.104976 0.0258778 0.0165547 47
[art]:TriggerResults:TriggerResultInserter 2.8114e-05 4.24266e-05 6.7991e-05 4.1993e-05 9.39666e-06 47
end_path:out1:RootOutput 4.043e-06 8.27611e-06 1.6673e-05 8.6e-06 2.32834e-06 47
end_path:out1:RootOutput(write) 2.98371 3.80397 5.98124 3.58509 0.62944 47
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5238.3 MB
Peak resident set size usage (VmHWM): 3324 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0360_df-s05-d3_dw_0_20250918T060918_reco_stage1_20250918T110146_keepup.root
\tHists: np02vd_raw_run039433_0360_df-s05-d3_dw_0_20250918T060918_reco_stage1_20250918T110148_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0360_df-s05-d3_dw_0_20250918T060918_reco_stage1_20250918T110146_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0360_df-s05-d3_dw_0_20250918T060918_reco_stage1_20250918T110146_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": 1758193309.0,
"core.end_time": 1758193309.0,
"core.events": [
103377,
103383,
103389,
103395,
103401,
103407,
103413,
103419,
103425,
103431,
103437,
103443,
103449,
103455,
103461,
103467,
103473,
103479,
103485,
103491,
103497,
103503,
103509,
103515,
103521,
103527,
103533,
103539,
103545,
103551,
103557,
103563,
103569,
103575,
103581,
103587,
103593,
103599,
103605,
103611,
103617,
103623,
103629,
103635,
103641,
103647,
103653
],
"core.event_count": 47,
"core.first_event_number": 103377,
"core.last_event_number": 103653,
"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_0360_df-s05-d3_dw_0_20250918T060918.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0360_df-s05-d3_dw_0_20250918T060918_reco_stage1_20250918T110148_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": 1758193309.0,
"core.end_time": 1758193309.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_0360_df-s05-d3_dw_0_20250918T060918.hdf5"
}
]
} 