Jobsub ID 41596.144@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
OS at call=97 with 8
[08:37:49.032] D [ glue ] frame sink sees EOS
[08:37:49.032] D [ pgraph ] <Pgrapher:> graph execution complete
[08:37:49.033] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.01 sec
[08:37:49.033] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.84 sec
[08:37:49.033] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.75 sec
[08:37:49.033] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.75 sec
[08:37:49.033] I [ timer ] Timer: WireCell::Aux::Resampler : 0.4 sec
[08:37:49.033] I [ timer ] Timer: WireCell::Aux::Resampler : 0.4 sec
[08:37:49.033] I [ timer ] Timer: WireCell::Aux::Resampler : 0.38 sec
[08:37:49.033] I [ timer ] Timer: WireCell::Aux::Resampler : 0.36 sec
[08:37:49.033] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[08:37:49.033] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:37:49.033] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:37:49.033] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[08:37:49.033] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0 sec
[08:37:49.033] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:37:49.033] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:37:49.033] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:37:49.033] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[08:37:49.033] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:37:49.033] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[08:37:49.033] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:37:49.033] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[08:37:49.033] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[08:37:49.033] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[08:37:49.033] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[08:37:49.033] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[08:37:49.033] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[08:37:49.033] I [ timer ] Timer: Total node execution : 25.8900004029274 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 21904 traces tagged "gauss"
FrameSaver: q=3.59459e+06 n=459982 tag=gauss
wclsFrameSaver: saving 27395 traces tagged "wiener"
FrameSaver: q=3.91587e+06 n=438914 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 649 XUs and 695 XVs -> 33 XUVs
C:0 T:1 187 XUs and 325 XVs -> 4 XUVs
C:0 T:2 7658 XUs and 8617 XVs -> 649 XUVs
C:0 T:3 2173 XUs and 2907 XVs -> 106 XUVs
C:0 T:4 754 XUs and 964 XVs -> 23 XUVs
C:0 T:5 685 XUs and 655 XVs -> 32 XUVs
C:0 T:6 1301 XUs and 1063 XVs -> 42 XUVs
C:0 T:7 1545 XUs and 1565 XVs -> 91 XUVs
980 XUVs total
645 collection wire objects
980 potential space points
Neighbour search...
30542 tests to find 8074 neighbours
Iterating with no regularization...
Begin: 3.25341e+09
0 3.12643e+09
1 3.11517e+09
2 3.11493e+09
Now with regularization...
Begin: 3.09059e+09
0 3.09048e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
18-Sep-2025 08:38:12 CEST Closed output file "np02vd_raw_run039433_0324_df-s05-d5_dw_0_20250918T043958_reco_stage1_20250918T063812_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 43.3666 64.4546 127.742 60.9425 17.843 49
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.074e-05 0.000117138 0.000177981 0.000120611 2.77824e-05 49
produce:tpcrawdecoder:PDVDTPCReader 5.22592 6.22435 8.36353 5.7996 0.84611 49
produce:triggerrawdecoder:PDVDTriggerReader4 0.264826 0.270797 0.287681 0.269851 0.00426461 49
produce:pdvddaphne:DAPHNEReaderPDVD 2.66437 3.36297 3.86265 3.36176 0.210958 49
produce:ophit:OpHitFinder 0.0398942 0.054558 0.0896785 0.0546104 0.00909703 49
produce:opflash:OpFlashFinderVerticalDrift 0.00550321 0.0114001 0.0188754 0.0114006 0.00327288 49
produce:wclsdatavd:WireCellToolkit 23.2762 30.6246 51.4238 27.4977 7.07626 49
produce:gaushit:GausHitFinder 0.311581 0.605273 1.04258 0.612546 0.147636 49
produce:nhitsfilter:NumberOfHitsFilter 9.799e-05 0.000152488 0.00034778 0.00014311 5.12326e-05 49
produce:reco3d:SpacePointSolver 1.9396 4.69995 11.1312 4.52995 1.80261 49
produce:hitpdune:DisambigFromSpacePoints 0.0231221 0.0711738 0.218254 0.062272 0.0417753 49
produce:pandora:StandardPandora 3.3229 15.6849 62.131 12.6484 11.5191 49
produce:pandoraTrack:LArPandoraTrackCreation 0.0785233 0.239299 0.568744 0.235207 0.104644 49
produce:pandoraGnocalo:GnocchiCalorimetry 0.00723153 0.0150166 0.0250244 0.0147771 0.00372561 49
[art]:TriggerResults:TriggerResultInserter 1.867e-05 2.57308e-05 5.511e-05 2.431e-05 6.99218e-06 49
end_path:out1:RootOutput 4.78e-06 9.9898e-06 4.054e-05 9.21e-06 5.00367e-06 49
end_path:out1:RootOutput(write) 2.09279 2.57423 3.51407 2.5086 0.295368 49
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5189.77 MB
Peak resident set size usage (VmHWM): 3285.1 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0324_df-s05-d5_dw_0_20250918T043958_reco_stage1_20250918T063812_keepup.root
\tHists: np02vd_raw_run039433_0324_df-s05-d5_dw_0_20250918T043958_reco_stage1_20250918T063812_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0324_df-s05-d5_dw_0_20250918T043958_reco_stage1_20250918T063812_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0324_df-s05-d5_dw_0_20250918T043958_reco_stage1_20250918T063812_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": 1758177493.0,
"core.end_time": 1758177493.0,
"core.events": [
92873,
92879,
92885,
92891,
92897,
92903,
92909,
92915,
92921,
92927,
92933,
92939,
92945,
92951,
92957,
92963,
92969,
92975,
92981,
92987,
92993,
92999,
93005,
93011,
93017,
93023,
93029,
93035,
93041,
93047,
93053,
93059,
93065,
93071,
93077,
93083,
93089,
93095,
93101,
93107,
93113,
93119,
93125,
93131,
93137,
93143,
93149,
93155,
93161
],
"core.event_count": 49,
"core.first_event_number": 92873,
"core.last_event_number": 93161,
"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_0324_df-s05-d5_dw_0_20250918T043958.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0324_df-s05-d5_dw_0_20250918T043958_reco_stage1_20250918T063812_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": 1758177493.0,
"core.end_time": 1758177493.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_0324_df-s05-d5_dw_0_20250918T043958.hdf5"
}
]
} 