Jobsub ID 49096.82@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
ue ] <ChannelSelector:chsel0> see EOS at call=39
[01:33:00.681] D [ aux ] <Resampler:resmp0> EOS at call=79
[01:33:00.681] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=79 anode=0
[01:33:00.681] D [ glue ] <FrameFanin:nfsp> EOS at call=79 with 8
[01:33:00.681] D [ glue ] frame sink sees EOS
[01:33:00.681] D [ pgraph ] <Pgrapher:> graph execution complete
[01:33:00.681] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.73 sec
[01:33:00.681] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.89 sec
[01:33:00.681] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.84 sec
[01:33:00.681] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.29 sec
[01:33:00.681] I [ timer ] Timer: WireCell::Aux::Resampler : 0.64 sec
[01:33:00.682] I [ timer ] Timer: WireCell::Aux::Resampler : 0.6 sec
[01:33:00.682] I [ timer ] Timer: WireCell::Aux::Resampler : 0.59 sec
[01:33:00.682] I [ timer ] Timer: WireCell::Aux::Resampler : 0.56 sec
[01:33:00.682] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[01:33:00.682] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[01:33:00.682] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[01:33:00.682] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[01:33:00.682] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:33:00.682] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:33:00.682] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:33:00.682] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:33:00.682] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:33:00.682] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[01:33:00.682] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:33:00.682] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:33:00.682] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[01:33:00.682] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[01:33:00.682] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:33:00.682] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[01:33:00.682] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:33:00.682] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[01:33:00.682] I [ timer ] Timer: Total node execution : 50.1899999845773 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 25290 traces tagged "gauss"
FrameSaver: q=3.81298e+06 n=533580 tag=gauss
wclsFrameSaver: saving 32522 traces tagged "wiener"
FrameSaver: q=4.19998e+06 n=511971 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1620 XUs and 1127 XVs -> 126 XUVs
C:0 T:1 7147 XUs and 5776 XVs -> 571 XUVs
C:0 T:2 2909 XUs and 2140 XVs -> 350 XUVs
C:0 T:3 410 XUs and 497 XVs -> 38 XUVs
C:0 T:4 149 XUs and 220 XVs -> 6 XUVs
C:0 T:5 2263 XUs and 1739 XVs -> 79 XUVs
C:0 T:6 2046 XUs and 2577 XVs -> 120 XUVs
C:0 T:7 13521 XUs and 16006 XVs -> 1286 XUVs
2576 XUVs total
1386 collection wire objects
2576 potential space points
Neighbour search...
108004 tests to find 31318 neighbours
Iterating with no regularization...
Begin: 2.60892e+09
0 2.514e+09
1 2.51147e+09
2 2.51139e+09
Now with regularization...
Begin: 2.44886e+09
0 2.44877e+09
19-Sep-2025 01:33:35 BST Closed output file "np02vd_raw_run039499_0107_df-s04-d2_dw_0_20250918T211734_reco_stage1_20250919T003335_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 53.1817 92.7099 234.379 77.5333 41.1246 40
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 9.5218e-05 0.000253533 0.00408618 0.000118713 0.000619891 40
produce:tpcrawdecoder:PDVDTPCReader 6.29592 7.1778 13.2221 6.75162 1.14826 40
produce:triggerrawdecoder:PDVDTriggerReader4 0.304078 0.319922 0.41097 0.313249 0.021022 40
produce:pdvddaphne:DAPHNEReaderPDVD 3.008 3.29977 3.78777 3.28757 0.173675 40
produce:ophit:OpHitFinder 0.0405178 0.0564053 0.0673922 0.0569598 0.00607249 40
produce:opflash:OpFlashFinderVerticalDrift 0.00807621 0.0138587 0.0184319 0.0140862 0.00241949 40
produce:wclsdatavd:WireCellToolkit 29.0826 39.3079 63.6761 35.1977 8.78825 40
produce:gaushit:GausHitFinder 0.402192 0.766158 1.33715 0.697177 0.215518 40
produce:nhitsfilter:NumberOfHitsFilter 0.000117991 0.000196792 0.000466134 0.000182192 6.13653e-05 40
produce:reco3d:SpacePointSolver 2.06828 6.55159 14.5311 6.2681 2.85997 40
produce:hitpdune:DisambigFromSpacePoints 0.0251536 0.102137 0.351506 0.0903081 0.0697376 40
produce:pandora:StandardPandora 4.6846 31.4615 164.977 21.2189 34.4928 40
produce:pandoraTrack:LArPandoraTrackCreation 0.110854 0.358326 1.07649 0.293708 0.208231 40
produce:pandoraGnocalo:GnocchiCalorimetry 0.00990768 0.0200301 0.0311728 0.019548 0.00553837 40
[art]:TriggerResults:TriggerResultInserter 2.3303e-05 3.77627e-05 0.000126768 3.5461e-05 1.62133e-05 40
end_path:out1:RootOutput 5.61e-06 1.2558e-05 5.9752e-05 9.8035e-06 1.12205e-05 40
end_path:out1:RootOutput(write) 2.80363 3.2502 3.86469 3.19687 0.26243 40
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4482.21 MB
Peak resident set size usage (VmHWM): 2488.77 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0107_df-s04-d2_dw_0_20250918T211734_reco_stage1_20250919T003335_keepup.root
\tHists: np02vd_raw_run039499_0107_df-s04-d2_dw_0_20250918T211734_reco_stage1_20250919T003335_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0107_df-s04-d2_dw_0_20250918T211734_reco_stage1_20250919T003335_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0107_df-s04-d2_dw_0_20250918T211734_reco_stage1_20250919T003335_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": 1758242016.0,
"core.end_time": 1758242016.0,
"core.events": [
26083,
26089,
26095,
26101,
26107,
26113,
26119,
26125,
26131,
26137,
26143,
26149,
26155,
26161,
26167,
26173,
26179,
26185,
26191,
26197,
26203,
26209,
26215,
26221,
26227,
26233,
26239,
26245,
26251,
26257,
26263,
26269,
26275,
26281,
26287,
26293,
26299,
26305,
26311,
26317
],
"core.event_count": 40,
"core.first_event_number": 26083,
"core.last_event_number": 26317,
"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_0107_df-s04-d2_dw_0_20250918T211734.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0107_df-s04-d2_dw_0_20250918T211734_reco_stage1_20250919T003335_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": 1758242016.0,
"core.end_time": 1758242016.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_0107_df-s04-d2_dw_0_20250918T211734.hdf5"
}
]
} 