Jobsub ID 41697.78@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
sSigProc:anode0sigproc0> EOS at call=97 anode=0
[10:57:48.027] D [ glue ] <FrameFanin:nfsp> EOS at call=97 with 8
[10:57:48.027] D [ glue ] frame sink sees EOS
[10:57:48.027] D [ pgraph ] <Pgrapher:> graph execution complete
[10:57:48.027] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.48 sec
[10:57:48.027] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.21 sec
[10:57:48.027] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.05 sec
[10:57:48.027] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.46 sec
[10:57:48.027] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[10:57:48.027] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[10:57:48.027] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[10:57:48.027] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[10:57:48.027] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[10:57:48.027] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:57:48.027] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:57:48.027] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:57:48.027] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:57:48.027] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:57:48.027] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:57:48.027] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:57:48.027] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[10:57:48.028] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:57:48.028] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:57:48.028] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:57:48.028] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:57:48.028] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[10:57:48.028] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[10:57:48.028] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[10:57:48.028] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:57:48.028] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[10:57:48.028] I [ timer ] Timer: Total node execution : 21.530000338330865 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 19944 traces tagged "gauss"
FrameSaver: q=3.23204e+06 n=445096 tag=gauss
wclsFrameSaver: saving 25628 traces tagged "wiener"
FrameSaver: q=3.48437e+06 n=423801 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1639 XUs and 1696 XVs -> 110 XUVs
C:0 T:1 43 XUs and 35 XVs -> 4 XUVs
C:0 T:2 921 XUs and 934 XVs -> 52 XUVs
C:0 T:3 5635 XUs and 4446 XVs -> 292 XUVs
C:0 T:4 1718 XUs and 1351 XVs -> 108 XUVs
C:0 T:5 824 XUs and 657 XVs -> 35 XUVs
C:0 T:6 11568 XUs and 10421 XVs -> 1080 XUVs
C:0 T:7 340 XUs and 286 XVs -> 29 XUVs
1710 XUVs total
902 collection wire objects
1710 potential space points
Neighbour search...
69874 tests to find 15962 neighbours
Iterating with no regularization...
Begin: 1.47691e+09
0 1.31778e+09
1 1.30749e+09
2 1.30726e+09
Now with regularization...
Begin: 1.281e+09
0 1.28091e+09
18-Sep-2025 10:58:10 CEST Closed output file "np02vd_raw_run039433_0362_df-s05-d2_dw_0_20250918T061312_reco_stage1_20250918T085810_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 70.1261 145.827 1185.38 111.992 166.526 49
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 4.9987e-05 6.2735e-05 0.000178525 5.8314e-05 1.84155e-05 49
produce:tpcrawdecoder:PDVDTPCReader 28.6282 45.3698 68.5972 44.5709 9.93181 49
produce:triggerrawdecoder:PDVDTriggerReader4 0.305537 0.435248 0.862695 0.418451 0.103231 49
produce:pdvddaphne:DAPHNEReaderPDVD 6.72002 11.2506 18.182 10.845 2.48799 49
produce:ophit:OpHitFinder 0.029533 0.0406881 0.0876184 0.0393421 0.00813478 49
produce:opflash:OpFlashFinderVerticalDrift 0.00360234 0.00950239 0.0148894 0.00990899 0.00236837 49
produce:wclsdatavd:WireCellToolkit 19.6911 34.0323 75.0615 27.4698 14.1062 49
produce:gaushit:GausHitFinder 0.295198 0.605132 1.48229 0.570837 0.240916 49
produce:nhitsfilter:NumberOfHitsFilter 6.692e-05 0.000135077 0.000338424 0.000126274 5.4525e-05 49
produce:reco3d:SpacePointSolver 1.61897 5.7556 29.0796 4.59862 4.54639 49
produce:hitpdune:DisambigFromSpacePoints 0.0162665 0.0833519 0.458521 0.0541344 0.0862842 49
produce:pandora:StandardPandora 3.24326 45.7167 998.368 11.0938 150.35 49
produce:pandoraTrack:LArPandoraTrackCreation 0.0660208 0.354571 2.77461 0.231462 0.500403 49
produce:pandoraGnocalo:GnocchiCalorimetry 0.00556741 0.0142344 0.0356112 0.0135125 0.0057173 49
[art]:TriggerResults:TriggerResultInserter 1.3185e-05 1.94287e-05 7.4905e-05 1.6462e-05 9.13228e-06 49
end_path:out1:RootOutput 3.778e-06 5.6012e-06 2.551e-05 4.598e-06 3.95534e-06 49
end_path:out1:RootOutput(write) 1.83546 2.14258 2.79754 2.09326 0.228921 49
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4927.17 MB
Peak resident set size usage (VmHWM): 3037.7 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0362_df-s05-d2_dw_0_20250918T061312_reco_stage1_20250918T085810_keepup.root
\tHists: np02vd_raw_run039433_0362_df-s05-d2_dw_0_20250918T061312_reco_stage1_20250918T085811_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0362_df-s05-d2_dw_0_20250918T061312_reco_stage1_20250918T085810_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0362_df-s05-d2_dw_0_20250918T061312_reco_stage1_20250918T085810_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": 1758185891.0,
"core.end_time": 1758185891.0,
"core.events": [
103838,
103844,
103850,
103856,
103862,
103868,
103874,
103880,
103886,
103892,
103898,
103904,
103910,
103916,
103922,
103928,
103934,
103940,
103946,
103952,
103958,
103964,
103970,
103976,
103982,
103988,
103994,
104000,
104006,
104012,
104018,
104024,
104030,
104036,
104042,
104048,
104054,
104060,
104066,
104072,
104078,
104084,
104090,
104096,
104102,
104108,
104114,
104120,
104126
],
"core.event_count": 49,
"core.first_event_number": 103838,
"core.last_event_number": 104126,
"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_0362_df-s05-d2_dw_0_20250918T061312.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0362_df-s05-d2_dw_0_20250918T061312_reco_stage1_20250918T085811_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": 1758185891.0,
"core.end_time": 1758185891.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_0362_df-s05-d2_dw_0_20250918T061312.hdf5"
}
]
} 