Jobsub ID 242847.97@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
gproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=47 anode=0
[12:52:14.471] D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[12:52:14.471] D [ glue ] frame sink sees EOS
[12:52:14.471] D [ pgraph ] <Pgrapher:> graph execution complete
[12:52:14.471] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 18.44 sec
[12:52:14.471] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 17.3 sec
[12:52:14.471] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.94 sec
[12:52:14.471] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.71 sec
[12:52:14.471] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.24 sec
[12:52:14.471] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.4 sec
[12:52:14.471] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.82 sec
[12:52:14.471] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.18 sec
[12:52:14.471] I [ timer ] Timer: WireCell::Aux::Resampler : 0.92 sec
[12:52:14.471] I [ timer ] Timer: WireCell::Aux::Resampler : 0.5 sec
[12:52:14.471] I [ timer ] Timer: WireCell::Aux::Resampler : 0.47 sec
[12:52:14.471] I [ timer ] Timer: WireCell::Aux::Resampler : 0.46 sec
[12:52:14.471] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.04 sec
[12:52:14.471] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[12:52:14.471] I [ timer ] Timer: WireCell::Gen::Retagger : 0.02 sec
[12:52:14.471] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:52:14.471] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:52:14.471] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0.01 sec
[12:52:14.471] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:52:14.471] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:52:14.472] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:52:14.472] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[12:52:14.472] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:52:14.472] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:52:14.472] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[12:52:14.472] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[12:52:14.472] I [ timer ] Timer: Total node execution : 126.48999880068004 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 46133 traces tagged "gauss"
FrameSaver: q=1.43651e+07 n=1084155 tag=gauss
wclsFrameSaver: saving 57689 traces tagged "wiener"
FrameSaver: q=1.56783e+07 n=1042989 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 868 XUs and 1078 XVs -> 30 XUVs
C:0 T:1 364 XUs and 482 XVs -> 22 XUVs
C:0 T:2 13104 XUs and 17537 XVs -> 1067 XUVs
C:0 T:3 10978 XUs and 15686 XVs -> 1027 XUVs
C:0 T:4 403 XUs and 547 XVs -> 17 XUVs
C:0 T:5 806 XUs and 1272 XVs -> 38 XUVs
C:0 T:6 303 XUs and 448 XVs -> 17 XUVs
C:0 T:7 636 XUs and 524 XVs -> 15 XUVs
C:0 T:8 866 XUs and 1154 XVs -> 63 XUVs
C:0 T:9 1371 XUs and 2050 XVs -> 65 XUVs
C:0 T:10 418 XUs and 493 XVs -> 36 XUVs
C:0 T:11 295 XUs and 388 XVs -> 43 XUVs
C:0 T:12 950 XUs and 1266 XVs -> 54 XUVs
C:0 T:13 514 XUs and 984 XVs -> 45 XUVs
C:0 T:14 470 XUs and 968 XVs -> 49 XUVs
C:0 T:15 261 XUs and 301 XVs -> 32 XUVs
2620 XUVs total
1572 collection wire objects
2620 potential space points
Neighbour search...
82106 tests to find 18350 neighbours
Iterating with no regularization...
Begin: 2.13593e+10
0 2.06482e+10
1 2.05956e+10
2 2.05948e+10
Now with regularization...
Begin: 2.02726e+10
0 2.02719e+10
04-Nov-2025 12:54:05 CET Closed output file "np02vd_raw_run040348_0997_df-s04-d3_dw_0_20251104T085734_reco_stage1_20251104T115405_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 164.379 296.819 723.185 230.269 164.411 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 9.3902e-05 0.000160433 0.000384855 0.00014895 6.56667e-05 24
produce:tpcrawdecoder:PDVDTPCReader 20.1027 23.3345 39.3234 21.6121 4.29054 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.888803 0.916947 1.0018 0.906545 0.0291349 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000556281 0.000740893 0.00118698 0.000698381 0.000158002 24
produce:ophit:OpHitFinder 0.000121824 0.000232727 0.000847832 0.000187749 0.00014701 24
produce:opflash:OpFlashFinderVerticalDrift 8.5327e-05 0.000168777 0.000502532 0.00013744 9.1672e-05 24
produce:wclsdatavd:WireCellToolkit 93.3889 133.726 225.854 119.331 35.7332 24
produce:gaushit:GausHitFinder 1.32008 2.44839 3.88629 2.51296 0.56145 24
produce:nhitsfilter:NumberOfHitsFilter 0.000442734 0.000779358 0.0013553 0.000760645 0.000232509 24
produce:reco3d:SpacePointSolver 8.6879 17.898 35.0947 18.0238 5.86736 24
produce:hitpdune:DisambigFromSpacePoints 0.153368 0.448242 1.21137 0.375543 0.25007 24
produce:pandora:StandardPandora 24.1928 107.098 463.659 46.3628 127.967 24
produce:pandoraTrack:LArPandoraTrackCreation 1.243 3.59953 9.74287 2.93512 2.31223 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.0357552 0.0640108 0.132218 0.0609514 0.0213847 24
[art]:TriggerResults:TriggerResultInserter 4.7664e-05 6.95503e-05 0.000174068 6.5314e-05 2.53518e-05 24
end_path:out1:RootOutput 9.733e-06 1.80593e-05 5.2801e-05 1.58115e-05 9.11903e-06 24
end_path:out1:RootOutput(write) 4.89382 7.18879 9.32079 7.38788 1.21149 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5920.92 MB
Peak resident set size usage (VmHWM): 4006.29 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040348_0997_df-s04-d3_dw_0_20251104T085734_reco_stage1_20251104T115405_keepup.root
\tHists: np02vd_raw_run040348_0997_df-s04-d3_dw_0_20251104T085734_reco_stage1_20251104T115406_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040348_0997_df-s04-d3_dw_0_20251104T085734_reco_stage1_20251104T115405_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040348_0997_df-s04-d3_dw_0_20251104T085734_reco_stage1_20251104T115405_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": 1762257247.0,
"core.end_time": 1762257247.0,
"core.events": [
92111,
92115,
92119,
92123,
92127,
92131,
92135,
92139,
92143,
92147,
92151,
92155,
92159,
92163,
92167,
92171,
92175,
92179,
92183,
92187,
92191,
92195,
92199,
92203
],
"core.event_count": 24,
"core.first_event_number": 92111,
"core.last_event_number": 92203,
"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_0997_df-s04-d3_dw_0_20251104T085734.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040348_0997_df-s04-d3_dw_0_20251104T085734_reco_stage1_20251104T115406_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": 1762257247.0,
"core.end_time": 1762257247.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_0997_df-s04-d3_dw_0_20251104T085734.hdf5"
}
]
} 