Jobsub ID 242845.50@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
ler:resmp0> EOS at call=47
[11:15:22.551] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=47 anode=0
[11:15:22.551] D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[11:15:22.551] D [ glue ] frame sink sees EOS
[11:15:22.551] D [ pgraph ] <Pgrapher:> graph execution complete
[11:15:22.551] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.63 sec
[11:15:22.551] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.17 sec
[11:15:22.551] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.94 sec
[11:15:22.551] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.43 sec
[11:15:22.551] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.2 sec
[11:15:22.551] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.18 sec
[11:15:22.551] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.51 sec
[11:15:22.551] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.33 sec
[11:15:22.551] I [ timer ] Timer: WireCell::Aux::Resampler : 0.51 sec
[11:15:22.551] I [ timer ] Timer: WireCell::Aux::Resampler : 0.41 sec
[11:15:22.551] I [ timer ] Timer: WireCell::Aux::Resampler : 0.32 sec
[11:15:22.551] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[11:15:22.551] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[11:15:22.551] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[11:15:22.551] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:15:22.551] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:15:22.551] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:15:22.551] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:15:22.551] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:15:22.551] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:15:22.551] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:15:22.551] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:15:22.551] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[11:15:22.551] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:15:22.551] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:15:22.552] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:15:22.552] I [ timer ] Timer: Total node execution : 60.969999857246876 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 36091 traces tagged "gauss"
FrameSaver: q=1.13215e+07 n=833297 tag=gauss
wclsFrameSaver: saving 44077 traces tagged "wiener"
FrameSaver: q=1.20711e+07 n=799042 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 7223 XUs and 10940 XVs -> 484 XUVs
C:0 T:1 68 XUs and 62 XVs -> 1 XUVs
C:0 T:2 1899 XUs and 1709 XVs -> 83 XUVs
C:0 T:3 430 XUs and 564 XVs -> 24 XUVs
C:0 T:4 613 XUs and 777 XVs -> 50 XUVs
C:0 T:5 42 XUs and 41 XVs -> 0 XUVs
C:0 T:6 1504 XUs and 1700 XVs -> 58 XUVs
C:0 T:7 617 XUs and 615 XVs -> 34 XUVs
C:0 T:8 455 XUs and 473 XVs -> 28 XUVs
C:0 T:9 410 XUs and 774 XVs -> 37 XUVs
C:0 T:10 684 XUs and 874 XVs -> 36 XUVs
C:0 T:11 497 XUs and 509 XVs -> 21 XUVs
C:0 T:12 1223 XUs and 1968 XVs -> 87 XUVs
C:0 T:13 816 XUs and 800 XVs -> 16 XUVs
C:0 T:14 462 XUs and 641 XVs -> 23 XUVs
C:0 T:15 500 XUs and 434 XVs -> 20 XUVs
1002 XUVs total
764 collection wire objects
1002 potential space points
Neighbour search...
14506 tests to find 4428 neighbours
Iterating with no regularization...
Begin: 7.78901e+10
0 7.76942e+10
1 7.76765e+10
Now with regularization...
Begin: 7.64991e+10
0 7.64986e+10
04-Nov-2025 11:16:01 CET Closed output file "np02vd_raw_run040348_0926_df-s04-d1_dw_0_20251104T075905_reco_stage1_20251104T101601_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 76.6818 112.118 163.505 103.634 29.6159 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.285e-05 8.95483e-05 0.00013628 7.741e-05 2.52898e-05 24
produce:tpcrawdecoder:PDVDTPCReader 10.3998 12.0155 18.6141 11.4922 1.60432 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.4632 0.478229 0.497294 0.47562 0.00939149 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000313879 0.000381098 0.00073102 0.00036283 7.95213e-05 24
produce:ophit:OpHitFinder 5.784e-05 0.00012306 0.00056567 0.000102255 9.48614e-05 24
produce:opflash:OpFlashFinderVerticalDrift 4.757e-05 8.42683e-05 0.00034738 6.773e-05 5.68547e-05 24
produce:wclsdatavd:WireCellToolkit 44.3476 59.8826 91.8398 56.656 13.5053 24
produce:gaushit:GausHitFinder 0.520445 1.01719 1.89281 0.988933 0.354294 24
produce:nhitsfilter:NumberOfHitsFilter 0.00011365 0.000233718 0.00058071 0.00021326 0.000119553 24
produce:reco3d:SpacePointSolver 3.67822 9.02217 16.1211 8.87202 3.43955 24
produce:hitpdune:DisambigFromSpacePoints 0.0509482 0.158761 0.397728 0.137724 0.0867877 24
produce:pandora:StandardPandora 8.461 23.5459 55.3209 16.5627 13.8188 24
produce:pandoraTrack:LArPandoraTrackCreation 0.273778 0.676251 1.34752 0.577903 0.326682 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.0105298 0.0258981 0.0451939 0.0256999 0.00961185 24
[art]:TriggerResults:TriggerResultInserter 1.279e-05 2.02888e-05 5.676e-05 1.951e-05 8.32788e-06 24
end_path:out1:RootOutput 2.72e-06 6.76e-06 2.269e-05 5.845e-06 3.75045e-06 24
end_path:out1:RootOutput(write) 3.50917 5.2664 8.4732 5.15898 1.26412 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4961.97 MB
Peak resident set size usage (VmHWM): 3047.94 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040348_0926_df-s04-d1_dw_0_20251104T075905_reco_stage1_20251104T101601_keepup.root
\tHists: np02vd_raw_run040348_0926_df-s04-d1_dw_0_20251104T075905_reco_stage1_20251104T101601_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040348_0926_df-s04-d1_dw_0_20251104T075905_reco_stage1_20251104T101601_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040348_0926_df-s04-d1_dw_0_20251104T075905_reco_stage1_20251104T101601_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": 1762251361.0,
"core.end_time": 1762251361.0,
"core.events": [
85629,
85633,
85637,
85641,
85645,
85649,
85653,
85657,
85661,
85665,
85669,
85673,
85677,
85681,
85685,
85689,
85693,
85697,
85701,
85705,
85709,
85713,
85717,
85721
],
"core.event_count": 24,
"core.first_event_number": 85629,
"core.last_event_number": 85721,
"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_0926_df-s04-d1_dw_0_20251104T075905.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040348_0926_df-s04-d1_dw_0_20251104T075905_reco_stage1_20251104T101601_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": 1762251361.0,
"core.end_time": 1762251361.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_0926_df-s04-d1_dw_0_20251104T075905.hdf5"
}
]
} 