Jobsub ID 242909.133@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
9.193] D [ glue ] frame sink sees EOS
[17:37:19.193] D [ pgraph ] <Pgrapher:> graph execution complete
[17:37:19.193] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.16 sec
[17:37:19.193] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.51 sec
[17:37:19.193] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.88 sec
[17:37:19.194] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.39 sec
[17:37:19.194] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.69 sec
[17:37:19.194] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.18 sec
[17:37:19.194] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.08 sec
[17:37:19.194] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.86 sec
[17:37:19.194] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[17:37:19.194] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[17:37:19.194] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[17:37:19.194] I [ timer ] Timer: WireCell::Aux::Resampler : 0.28 sec
[17:37:19.194] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[17:37:19.194] I [ timer ] Timer: WireCell::Gen::Retagger : 0.02 sec
[17:37:19.194] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[17:37:19.194] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[17:37:19.194] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[17:37:19.194] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:37:19.194] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:37:19.194] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:37:19.194] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[17:37:19.194] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:37:19.194] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:37:19.194] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[17:37:19.194] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[17:37:19.194] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[17:37:19.194] I [ timer ] Timer: Total node execution : 85.98999999463558 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 38675 traces tagged "gauss"
FrameSaver: q=1.28707e+07 n=961410 tag=gauss
wclsFrameSaver: saving 47205 traces tagged "wiener"
FrameSaver: q=1.38247e+07 n=930092 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1145 XUs and 1145 XVs -> 35 XUVs
C:0 T:1 143 XUs and 88 XVs -> 6 XUVs
C:0 T:2 11070 XUs and 14186 XVs -> 856 XUVs
C:0 T:3 53 XUs and 53 XVs -> 0 XUVs
C:0 T:4 438 XUs and 529 XVs -> 27 XUVs
C:0 T:5 2702 XUs and 2893 XVs -> 174 XUVs
C:0 T:6 612 XUs and 583 XVs -> 45 XUVs
C:0 T:7 341 XUs and 359 XVs -> 16 XUVs
C:0 T:8 2099 XUs and 2406 XVs -> 151 XUVs
C:0 T:9 113 XUs and 131 XVs -> 6 XUVs
C:0 T:10 1032 XUs and 1116 XVs -> 82 XUVs
C:0 T:11 220 XUs and 172 XVs -> 10 XUVs
C:0 T:12 161 XUs and 290 XVs -> 8 XUVs
C:0 T:13 6361 XUs and 5995 XVs -> 385 XUVs
C:0 T:14 462 XUs and 511 XVs -> 24 XUVs
C:0 T:15 459 XUs and 599 XVs -> 48 XUVs
1873 XUVs total
1244 collection wire objects
1873 potential space points
Neighbour search...
56721 tests to find 15922 neighbours
Iterating with no regularization...
Begin: 2.27392e+10
0 2.22851e+10
1 2.22632e+10
Now with regularization...
Begin: 2.21913e+10
0 2.21909e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
04-Nov-2025 17:38:02 CET Closed output file "np02vd_raw_run040348_1237_df-s04-d2_dw_0_20251104T122032_reco_stage1_20251104T163802_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 139.919 595.941 7882.71 188.67 1582.52 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.1825e-05 9.30227e-05 0.000197056 8.1303e-05 2.84119e-05 23
produce:tpcrawdecoder:PDVDTPCReader 11.8206 16.105 43.7415 14.5455 6.2181 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.308143 0.326027 0.527829 0.313708 0.0438802 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000414247 0.000620663 0.00122364 0.000608073 0.000142209 23
produce:ophit:OpHitFinder 7.0342e-05 0.000146057 0.00114258 8.8846e-05 0.000214151 23
produce:opflash:OpFlashFinderVerticalDrift 5.3902e-05 9.88519e-05 0.00056518 7.5654e-05 9.99833e-05 23
produce:wclsdatavd:WireCellToolkit 77.8216 111.356 202.524 96.0857 34.039 23
produce:gaushit:GausHitFinder 1.10861 1.88153 3.22015 1.7918 0.567251 23
produce:nhitsfilter:NumberOfHitsFilter 0.000284866 0.000455802 0.000819466 0.00043587 0.000142099 23
produce:reco3d:SpacePointSolver 8.76064 18.0977 63.7696 15.2867 11.4344 23
produce:hitpdune:DisambigFromSpacePoints 0.11631 0.394567 1.55244 0.334602 0.320154 23
produce:pandora:StandardPandora 18.8843 439.045 7609.24 50.4966 1553.03 23
produce:pandoraTrack:LArPandoraTrackCreation 0.719574 2.66979 15.6018 1.74901 3.05367 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0273657 0.052879 0.109458 0.0419798 0.022719 23
[art]:TriggerResults:TriggerResultInserter 2.3677e-05 4.12642e-05 8.3415e-05 4.141e-05 1.27882e-05 23
end_path:out1:RootOutput 6.322e-06 1.05801e-05 3.0712e-05 8.909e-06 4.8247e-06 23
end_path:out1:RootOutput(write) 4.34918 5.95918 10.1778 5.55066 1.61738 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6397.96 MB
Peak resident set size usage (VmHWM): 4483.61 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040348_1237_df-s04-d2_dw_0_20251104T122032_reco_stage1_20251104T163802_keepup.root
\tHists: np02vd_raw_run040348_1237_df-s04-d2_dw_0_20251104T122032_reco_stage1_20251104T163803_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040348_1237_df-s04-d2_dw_0_20251104T122032_reco_stage1_20251104T163802_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040348_1237_df-s04-d2_dw_0_20251104T122032_reco_stage1_20251104T163802_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_11_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": 1762274283.0,
"core.end_time": 1762274283.0,
"core.events": [
114346,
114350,
114354,
114358,
114362,
114366,
114370,
114374,
114378,
114382,
114386,
114390,
114394,
114398,
114402,
114406,
114410,
114414,
114418,
114422,
114426,
114430,
114434
],
"core.event_count": 23,
"core.first_event_number": 114346,
"core.last_event_number": 114434,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40348
],
"core.runs_subruns": [
4034800001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040348_1237_df-s04-d2_dw_0_20251104T122032.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040348_1237_df-s04-d2_dw_0_20251104T122032_reco_stage1_20251104T163803_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_11_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": 1762274283.0,
"core.end_time": 1762274283.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40348
],
"core.runs_subruns": [
4034800001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040348_1237_df-s04-d2_dw_0_20251104T122032.hdf5"
}
]
} 