Jobsub ID 49096.78@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
n:nfsp> EOS at call=81 with 8
[02:50:36.380] D [ glue ] frame sink sees EOS
[02:50:36.380] D [ pgraph ] <Pgrapher:> graph execution complete
[02:50:36.380] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.91 sec
[02:50:36.380] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.23 sec
[02:50:36.380] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.01 sec
[02:50:36.380] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.8 sec
[02:50:36.380] I [ timer ] Timer: WireCell::Aux::Resampler : 0.31 sec
[02:50:36.380] I [ timer ] Timer: WireCell::Aux::Resampler : 0.31 sec
[02:50:36.380] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[02:50:36.380] I [ timer ] Timer: WireCell::Aux::Resampler : 0.19 sec
[02:50:36.380] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0.01 sec
[02:50:36.380] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[02:50:36.380] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[02:50:36.380] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[02:50:36.380] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[02:50:36.380] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[02:50:36.380] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0 sec
[02:50:36.380] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[02:50:36.380] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[02:50:36.380] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[02:50:36.380] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[02:50:36.380] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[02:50:36.380] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[02:50:36.380] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[02:50:36.380] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[02:50:36.380] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[02:50:36.380] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[02:50:36.380] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[02:50:36.380] I [ timer ] Timer: Total node execution : 36.07999982312322 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 30925 traces tagged "gauss"
FrameSaver: q=7.30957e+06 n=730757 tag=gauss
wclsFrameSaver: saving 38833 traces tagged "wiener"
FrameSaver: q=7.86652e+06 n=703395 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 41 XUs and 31 XVs -> 0 XUVs
C:0 T:1 1390 XUs and 1275 XVs -> 57 XUVs
C:0 T:2 29796 XUs and 44934 XVs -> 2524 XUVs
C:0 T:3 2093 XUs and 1814 XVs -> 74 XUVs
C:0 T:4 852 XUs and 1060 XVs -> 47 XUVs
C:0 T:5 2990 XUs and 2492 XVs -> 99 XUVs
C:0 T:6 297 XUs and 382 XVs -> 10 XUVs
C:0 T:7 2090 XUs and 3238 XVs -> 116 XUVs
2927 XUVs total
1508 collection wire objects
2927 potential space points
Neighbour search...
111489 tests to find 24064 neighbours
Iterating with no regularization...
Begin: 2.33502e+10
0 2.16005e+10
1 2.14364e+10
2 2.1434e+10
Now with regularization...
Begin: 2.11443e+10
0 2.11433e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
19-Sep-2025 02:51:57 BST Closed output file "np02vd_raw_run039499_0106_df-s05-d3_dw_0_20250918T211618_reco_stage1_20250919T015157_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 84.2098 205.468 1434.69 129.459 277.217 41
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.445e-05 0.000133434 0.000354326 0.000123512 4.9946e-05 41
produce:tpcrawdecoder:PDVDTPCReader 25.6675 41.7211 68.5902 40.8842 8.42423 41
produce:triggerrawdecoder:PDVDTriggerReader4 0.37434 0.421867 0.667319 0.409129 0.0523548 41
produce:pdvddaphne:DAPHNEReaderPDVD 6.25116 9.32383 14.0869 9.4041 1.91559 41
produce:ophit:OpHitFinder 0.0342206 0.0530751 0.0783142 0.0546051 0.00918727 41
produce:opflash:OpFlashFinderVerticalDrift 0.00428212 0.0128572 0.0295055 0.0131373 0.0047596 41
produce:wclsdatavd:WireCellToolkit 28.3146 44.6443 93.1907 37.4458 14.1366 41
produce:gaushit:GausHitFinder 0.405574 0.850923 1.97375 0.736166 0.371962 41
produce:nhitsfilter:NumberOfHitsFilter 0.000118002 0.000217527 0.000643069 0.000186922 9.89259e-05 41
produce:reco3d:SpacePointSolver 2.73141 7.94541 38.2583 6.87547 5.97328 41
produce:hitpdune:DisambigFromSpacePoints 0.0249263 0.143119 1.38164 0.0918581 0.225909 41
produce:pandora:StandardPandora 5.06415 96.7328 1254.62 22.3515 262.195 41
produce:pandoraTrack:LArPandoraTrackCreation 0.114607 0.455661 2.91791 0.307987 0.494507 41
produce:pandoraGnocalo:GnocchiCalorimetry 0.0076214 0.0196568 0.0446928 0.0184048 0.00898652 41
[art]:TriggerResults:TriggerResultInserter 1.8214e-05 3.65702e-05 0.000142358 3.2731e-05 2.02461e-05 41
end_path:out1:RootOutput 3.767e-06 9.77261e-06 4.5566e-05 8.506e-06 6.50547e-06 41
end_path:out1:RootOutput(write) 2.24543 3.10898 4.11901 3.15193 0.454598 41
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5712.69 MB
Peak resident set size usage (VmHWM): 3744.45 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0106_df-s05-d3_dw_0_20250918T211618_reco_stage1_20250919T015157_keepup.root
\tHists: np02vd_raw_run039499_0106_df-s05-d3_dw_0_20250918T211618_reco_stage1_20250919T015157_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0106_df-s05-d3_dw_0_20250918T211618_reco_stage1_20250919T015157_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0106_df-s05-d3_dw_0_20250918T211618_reco_stage1_20250919T015157_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": 1758246718.0,
"core.end_time": 1758246718.0,
"core.events": [
25941,
25947,
25953,
25959,
25965,
25971,
25977,
25983,
25989,
25995,
26001,
26007,
26013,
26019,
26025,
26031,
26037,
26043,
26049,
26055,
26061,
26067,
26073,
26079,
26085,
26091,
26097,
26103,
26109,
26115,
26121,
26127,
26133,
26139,
26145,
26151,
26157,
26163,
26169,
26175,
26181
],
"core.event_count": 41,
"core.first_event_number": 25941,
"core.last_event_number": 26181,
"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_0106_df-s05-d3_dw_0_20250918T211618.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0106_df-s05-d3_dw_0_20250918T211618_reco_stage1_20250919T015157_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": 1758246718.0,
"core.end_time": 1758246718.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_0106_df-s05-d3_dw_0_20250918T211618.hdf5"
}
]
} 