Jobsub ID 37976.59@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
sel0> see EOS at call=18
[05:08:50.289] D [ aux ] <Resampler:resmp0> EOS at call=37
[05:08:50.289] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=37 anode=0
[05:08:50.289] D [ glue ] <FrameFanin:nfsp> EOS at call=37 with 8
[05:08:50.289] D [ glue ] frame sink sees EOS
[05:08:50.289] D [ pgraph ] <Pgrapher:> graph execution complete
[05:08:50.289] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.67 sec
[05:08:50.289] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 1.76 sec
[05:08:50.289] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 1.43 sec
[05:08:50.289] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 1.33 sec
[05:08:50.289] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 1.31 sec
[05:08:50.289] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 1.11 sec
[05:08:50.289] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 1.09 sec
[05:08:50.289] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 1.03 sec
[05:08:50.289] I [ timer ] Timer: WireCell::Aux::Resampler : 0.07 sec
[05:08:50.289] I [ timer ] Timer: WireCell::Aux::Resampler : 0.06 sec
[05:08:50.289] I [ timer ] Timer: WireCell::Aux::Resampler : 0.04 sec
[05:08:50.289] I [ timer ] Timer: WireCell::Aux::Resampler : 0.03 sec
[05:08:50.289] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[05:08:50.289] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[05:08:50.289] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[05:08:50.289] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:08:50.289] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:08:50.289] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0 sec
[05:08:50.289] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:08:50.289] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:08:50.289] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[05:08:50.289] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[05:08:50.289] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[05:08:50.289] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[05:08:50.289] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[05:08:50.289] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[05:08:50.289] I [ timer ] Timer: Total node execution : 14.970000015571713 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 11837 traces tagged "gauss"
FrameSaver: q=1.80888e+07 n=468213 tag=gauss
wclsFrameSaver: saving 14798 traces tagged "wiener"
FrameSaver: q=1.8882e+07 n=459882 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 255 XUs and 297 XVs -> 10 XUVs
C:0 T:1 176 XUs and 189 XVs -> 41 XUVs
C:0 T:2 73 XUs and 65 XVs -> 7 XUVs
C:0 T:3 70 XUs and 132 XVs -> 0 XUVs
C:0 T:4 395 XUs and 407 XVs -> 33 XUVs
C:0 T:5 12588 XUs and 15121 XVs -> 695 XUVs
C:0 T:6 55 XUs and 71 XVs -> 1 XUVs
C:0 T:7 48 XUs and 59 XVs -> 0 XUVs
C:0 T:8 5894 XUs and 8754 XVs -> 2637 XUVs
C:0 T:9 23 XUs and 47 XVs -> 0 XUVs
C:0 T:10 10 XUs and 14 XVs -> 0 XUVs
C:0 T:11 4 XUs and 6 XVs -> 0 XUVs
C:0 T:12 123 XUs and 206 XVs -> 4 XUVs
C:0 T:13 145 XUs and 132 XVs -> 15 XUVs
C:0 T:14 7 XUs and 10 XVs -> 1 XUVs
C:0 T:15 256 XUs and 273 XVs -> 16 XUVs
3460 XUVs total
526 collection wire objects
3460 potential space points
Neighbour search...
603126 tests to find 242736 neighbours
Iterating with no regularization...
Begin: 2.15305e+11
0 2.14332e+11
1 2.14272e+11
Now with regularization...
Begin: 2.14255e+11
0 2.14255e+11
11-Sep-2025 05:08:57 CEST Closed output file "np02vd_raw_run039350_1694_df-s04-d1_dw_0_20250911T005611_reco_stage1_20250911T030857_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 26.8162 143.573 237.333 148.163 38.1437 19
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.3311e-05 0.000112956 0.000188481 0.00011668 3.13394e-05 19
produce:tpcrawdecoder:PDVDTPCReader 1.54345 7.25114 7.95263 7.54757 1.36795 19
produce:triggerrawdecoder:PDVDTriggerReader4 0.00729042 0.0118833 0.0509663 0.00875171 0.00946583 19
produce:pdvddaphne:DAPHNEReaderPDVD 2.04597 2.65783 3.01963 2.6501 0.236201 19
produce:ophit:OpHitFinder 0.0256127 0.0449535 0.0515832 0.047021 0.00678649 19
produce:opflash:OpFlashFinderVerticalDrift 0.00366532 0.0112877 0.0158578 0.0111531 0.00330786 19
produce:wclsdatavd:WireCellToolkit 15.4909 65.4174 77.2678 67.0746 12.8264 19
produce:gaushit:GausHitFinder 0.651867 1.32115 2.09092 1.14717 0.437975 19
produce:nhitsfilter:NumberOfHitsFilter 0.00013292 0.00030008 0.000466562 0.000251122 8.54809e-05 19
produce:reco3d:SpacePointSolver 1.74827 13.6798 22.8826 14.0883 4.95624 19
produce:hitpdune:DisambigFromSpacePoints 0.0439375 0.177419 0.394924 0.150564 0.0810687 19
produce:pandora:StandardPandora 2.54907 46.6035 118.123 48.7885 23.1691 19
produce:pandoraTrack:LArPandoraTrackCreation 0.1379 0.858454 1.84543 0.899261 0.334867 19
produce:pandoraGnocalo:GnocchiCalorimetry 0.0086948 0.0298956 0.0632476 0.0238988 0.013901 19
[art]:TriggerResults:TriggerResultInserter 1.523e-05 2.52416e-05 7.783e-05 1.985e-05 1.42174e-05 19
end_path:out1:RootOutput 3.59e-06 8.85689e-06 3.002e-05 7.94e-06 5.32237e-06 19
end_path:out1:RootOutput(write) 1.54772 5.47722 7.1536 5.6235 1.20613 19
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4816.81 MB
Peak resident set size usage (VmHWM): 2865.82 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039350_1694_df-s04-d1_dw_0_20250911T005611_reco_stage1_20250911T030857_keepup.root
\tHists: np02vd_raw_run039350_1694_df-s04-d1_dw_0_20250911T005611_reco_stage1_20250911T030857_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039350_1694_df-s04-d1_dw_0_20250911T005611_reco_stage1_20250911T030857_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039350_1694_df-s04-d1_dw_0_20250911T005611_reco_stage1_20250911T030857_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": 1757560138.0,
"core.end_time": 1757560138.0,
"core.events": [
123837,
123841,
123845,
123849,
123853,
123857,
123861,
123865,
123869,
123873,
123877,
123881,
123885,
123889,
123893,
123897,
123901,
123905,
123909
],
"core.event_count": 19,
"core.first_event_number": 123837,
"core.last_event_number": 123909,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39350
],
"core.runs_subruns": [
3935000001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039350_1694_df-s04-d1_dw_0_20250911T005611.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039350_1694_df-s04-d1_dw_0_20250911T005611_reco_stage1_20250911T030857_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": 1757560138.0,
"core.end_time": 1757560138.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39350
],
"core.runs_subruns": [
3935000001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039350_1694_df-s04-d1_dw_0_20250911T005611.hdf5"
}
]
} 