Jobsub ID 41596.105@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
x ] <Resampler:resmp0> EOS at call=97
[11:49:21.279] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=97 anode=0
[11:49:21.279] D [ glue ] <FrameFanin:nfsp> EOS at call=97 with 8
[11:49:21.279] D [ glue ] frame sink sees EOS
[11:49:21.279] D [ pgraph ] <Pgrapher:> graph execution complete
[11:49:21.280] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.23 sec
[11:49:21.280] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.68 sec
[11:49:21.280] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.51 sec
[11:49:21.280] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.97 sec
[11:49:21.280] I [ timer ] Timer: WireCell::Aux::Resampler : 0.54 sec
[11:49:21.280] I [ timer ] Timer: WireCell::Aux::Resampler : 0.5 sec
[11:49:21.280] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[11:49:21.280] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[11:49:21.280] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[11:49:21.280] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:49:21.280] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:49:21.280] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:49:21.280] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:49:21.280] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:49:21.280] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:49:21.280] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:49:21.280] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:49:21.280] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:49:21.280] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:49:21.280] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:49:21.280] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:49:21.280] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[11:49:21.280] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:49:21.280] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:49:21.280] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[11:49:21.280] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:49:21.280] I [ timer ] Timer: Total node execution : 32.099999437108636 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 15047 traces tagged "gauss"
FrameSaver: q=2.74015e+06 n=346959 tag=gauss
wclsFrameSaver: saving 19057 traces tagged "wiener"
FrameSaver: q=2.89203e+06 n=331590 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 2323 XUs and 1993 XVs -> 99 XUVs
C:0 T:1 7495 XUs and 8387 XVs -> 693 XUVs
C:0 T:2 791 XUs and 566 XVs -> 21 XUVs
C:0 T:3 122 XUs and 218 XVs -> 11 XUVs
C:0 T:4 318 XUs and 345 XVs -> 14 XUVs
C:0 T:5 14 XUs and 20 XVs -> 0 XUVs
C:0 T:6 297 XUs and 349 XVs -> 15 XUVs
C:0 T:7 364 XUs and 564 XVs -> 25 XUVs
878 XUVs total
608 collection wire objects
878 potential space points
Neighbour search...
21888 tests to find 5196 neighbours
Iterating with no regularization...
Begin: 1.02749e+09
0 9.86372e+08
1 9.85838e+08
Now with regularization...
Begin: 9.57396e+08
0 9.57363e+08
18-Sep-2025 11:49:38 CEST Closed output file "np02vd_raw_run039433_0317_df-s05-d3_dw_0_20250918T042446_reco_stage1_20250918T094938_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 54.0473 316.941 10860.1 82.7247 1523.16 49
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.781e-05 0.000158884 0.000576935 0.000133321 8.83117e-05 49
produce:tpcrawdecoder:PDVDTPCReader 4.48353 7.47965 16.569 6.72903 2.72621 49
produce:triggerrawdecoder:PDVDTriggerReader4 0.178062 0.216035 0.480125 0.214088 0.0525092 49
produce:pdvddaphne:DAPHNEReaderPDVD 3.3026 4.41022 9.3797 4.17275 0.992555 49
produce:ophit:OpHitFinder 0.0486707 0.0676026 0.135913 0.0647565 0.0136973 49
produce:opflash:OpFlashFinderVerticalDrift 0.00520072 0.0146629 0.0396883 0.014442 0.00612068 49
produce:wclsdatavd:WireCellToolkit 28.966 47.2205 150.082 37.9061 20.8754 49
produce:gaushit:GausHitFinder 0.389815 1.03533 4.76011 0.79456 0.840186 49
produce:nhitsfilter:NumberOfHitsFilter 0.00012871 0.000284307 0.000959217 0.000217011 0.000165865 49
produce:reco3d:SpacePointSolver 2.378 8.43675 77.9631 5.2236 12.4464 49
produce:hitpdune:DisambigFromSpacePoints 0.0278112 0.169422 1.88075 0.07374 0.329853 49
produce:pandora:StandardPandora 5.78079 243.853 10650.2 17.7426 1502.56 49
produce:pandoraTrack:LArPandoraTrackCreation 0.107747 0.568966 9.44075 0.315016 1.30427 49
produce:pandoraGnocalo:GnocchiCalorimetry 0.00795351 0.0208229 0.0685204 0.0183258 0.0110687 49
[art]:TriggerResults:TriggerResultInserter 2.6471e-05 4.65834e-05 9.8851e-05 4.37e-05 1.52452e-05 49
end_path:out1:RootOutput 4.79e-06 1.03991e-05 3.695e-05 8.8e-06 5.96614e-06 49
end_path:out1:RootOutput(write) 2.05063 3.42344 10.5685 3.14111 1.44091 49
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6098.64 MB
Peak resident set size usage (VmHWM): 4203.79 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0317_df-s05-d3_dw_0_20250918T042446_reco_stage1_20250918T094938_keepup.root
\tHists: np02vd_raw_run039433_0317_df-s05-d3_dw_0_20250918T042446_reco_stage1_20250918T094938_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0317_df-s05-d3_dw_0_20250918T042446_reco_stage1_20250918T094938_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0317_df-s05-d3_dw_0_20250918T042446_reco_stage1_20250918T094938_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": 1758188980.0,
"core.end_time": 1758188980.0,
"core.events": [
91023,
91029,
91035,
91041,
91047,
91053,
91059,
91065,
91071,
91077,
91083,
91089,
91095,
91101,
91107,
91113,
91119,
91125,
91131,
91137,
91143,
91149,
91155,
91161,
91167,
91173,
91179,
91185,
91191,
91197,
91203,
91209,
91215,
91221,
91227,
91233,
91239,
91245,
91251,
91257,
91263,
91269,
91275,
91281,
91287,
91293,
91299,
91305,
91311
],
"core.event_count": 49,
"core.first_event_number": 91023,
"core.last_event_number": 91311,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0317_df-s05-d3_dw_0_20250918T042446.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0317_df-s05-d3_dw_0_20250918T042446_reco_stage1_20250918T094938_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": 1758188980.0,
"core.end_time": 1758188980.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0317_df-s05-d3_dw_0_20250918T042446.hdf5"
}
]
} 