Jobsub ID 41697.103@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
ibusSigProc:anode0sigproc0> EOS at call=93 anode=0
[13:42:01.763] D [ glue ] <FrameFanin:nfsp> EOS at call=93 with 8
[13:42:01.764] D [ glue ] frame sink sees EOS
[13:42:01.764] D [ pgraph ] <Pgrapher:> graph execution complete
[13:42:01.764] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.94 sec
[13:42:01.764] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.73 sec
[13:42:01.764] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.33 sec
[13:42:01.764] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.06 sec
[13:42:01.764] I [ timer ] Timer: WireCell::Aux::Resampler : 0.27 sec
[13:42:01.764] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[13:42:01.764] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[13:42:01.764] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[13:42:01.764] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[13:42:01.764] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:42:01.764] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:42:01.764] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[13:42:01.764] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:42:01.764] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:42:01.764] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[13:42:01.764] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:42:01.764] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:42:01.764] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[13:42:01.764] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:42:01.764] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[13:42:01.764] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:42:01.764] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[13:42:01.764] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[13:42:01.764] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[13:42:01.764] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[13:42:01.764] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[13:42:01.764] I [ timer ] Timer: Total node execution : 19.139999924227595 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 21521 traces tagged "gauss"
FrameSaver: q=3.52247e+06 n=489818 tag=gauss
wclsFrameSaver: saving 28540 traces tagged "wiener"
FrameSaver: q=3.79194e+06 n=469051 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1151 XUs and 1515 XVs -> 75 XUVs
C:0 T:1 136 XUs and 229 XVs -> 5 XUVs
C:0 T:2 1579 XUs and 1786 XVs -> 244 XUVs
C:0 T:3 3229 XUs and 3722 XVs -> 295 XUVs
C:0 T:4 373 XUs and 1228 XVs -> 45 XUVs
C:0 T:5 536 XUs and 696 XVs -> 41 XUVs
C:0 T:6 1018 XUs and 1392 XVs -> 332 XUVs
C:0 T:7 8155 XUs and 9372 XVs -> 789 XUVs
1826 XUVs total
667 collection wire objects
1826 potential space points
Neighbour search...
43954 tests to find 18394 neighbours
Iterating with no regularization...
Begin: 6.55815e+08
0 5.47723e+08
1 5.36652e+08
2 5.35311e+08
3 5.35065e+08
Now with regularization...
Begin: 5.14228e+08
0 5.13978e+08
18-Sep-2025 13:42:20 CEST Closed output file "np02vd_raw_run039433_0367_df-s05-d5_dw_0_20250918T062503_reco_stage1_20250918T114220_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 39.8664 341.867 6478.65 62.3815 1186.2 47
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.2842e-05 9.11878e-05 0.000246208 7.9892e-05 3.71383e-05 47
produce:tpcrawdecoder:PDVDTPCReader 8.98732 31.9099 185.671 9.63268 48.1295 47
produce:triggerrawdecoder:PDVDTriggerReader4 0.568405 0.726214 2.51976 0.578171 0.345292 47
produce:pdvddaphne:DAPHNEReaderPDVD 2.47875 7.16806 33.7036 3.34858 8.71009 47
produce:ophit:OpHitFinder 0.0257816 0.045134 0.0775698 0.0416305 0.0120763 47
produce:opflash:OpFlashFinderVerticalDrift 0.00337144 0.0109368 0.022807 0.0102109 0.00376035 47
produce:wclsdatavd:WireCellToolkit 18.7111 33.9659 187.908 27.3027 25.8051 47
produce:gaushit:GausHitFinder 0.239612 0.586318 2.66468 0.467689 0.38165 47
produce:nhitsfilter:NumberOfHitsFilter 6.5532e-05 0.00029267 0.00130609 0.000195106 0.000271177 47
produce:reco3d:SpacePointSolver 1.00613 6.71895 74.6339 4.0525 11.1845 47
produce:hitpdune:DisambigFromSpacePoints 0.0156196 0.100656 1.17925 0.0458564 0.197692 47
produce:pandora:StandardPandora 2.87379 258.179 6297.72 10.2027 1157.71 47
produce:pandoraTrack:LArPandoraTrackCreation 0.0860942 0.558059 7.70094 0.213706 1.37488 47
produce:pandoraGnocalo:GnocchiCalorimetry 0.00767773 0.0149694 0.071238 0.0123715 0.0102586 47
[art]:TriggerResults:TriggerResultInserter 1.169e-05 2.57932e-05 0.000141194 1.9271e-05 2.02024e-05 47
end_path:out1:RootOutput 3.42e-06 5.97015e-06 2.4481e-05 4.77e-06 4.42695e-06 47
end_path:out1:RootOutput(write) 1.51385 1.85597 2.73924 1.73832 0.286642 47
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 7684.72 MB
Peak resident set size usage (VmHWM): 5769.88 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0367_df-s05-d5_dw_0_20250918T062503_reco_stage1_20250918T114220_keepup.root
\tHists: np02vd_raw_run039433_0367_df-s05-d5_dw_0_20250918T062503_reco_stage1_20250918T114220_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0367_df-s05-d5_dw_0_20250918T062503_reco_stage1_20250918T114220_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0367_df-s05-d5_dw_0_20250918T062503_reco_stage1_20250918T114220_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": 1758195741.0,
"core.end_time": 1758195741.0,
"core.events": [
105227,
105233,
105239,
105245,
105251,
105257,
105263,
105269,
105275,
105281,
105287,
105293,
105299,
105305,
105311,
105317,
105323,
105329,
105335,
105341,
105347,
105353,
105359,
105365,
105371,
105377,
105383,
105389,
105395,
105401,
105407,
105413,
105419,
105425,
105431,
105437,
105443,
105449,
105455,
105461,
105467,
105473,
105479,
105485,
105491,
105497,
105503
],
"core.event_count": 47,
"core.first_event_number": 105227,
"core.last_event_number": 105503,
"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_0367_df-s05-d5_dw_0_20250918T062503.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0367_df-s05-d5_dw_0_20250918T062503_reco_stage1_20250918T114220_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": 1758195741.0,
"core.end_time": 1758195741.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_0367_df-s05-d5_dw_0_20250918T062503.hdf5"
}
]
} 