Jobsub ID 41697.67@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
:22.428] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=93 anode=0
[10:42:22.428] D [ glue ] <FrameFanin:nfsp> EOS at call=93 with 8
[10:42:22.428] D [ glue ] frame sink sees EOS
[10:42:22.428] D [ pgraph ] <Pgrapher:> graph execution complete
[10:42:22.428] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.56 sec
[10:42:22.428] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.28 sec
[10:42:22.428] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.23 sec
[10:42:22.428] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.21 sec
[10:42:22.428] I [ timer ] Timer: WireCell::Aux::Resampler : 0.19 sec
[10:42:22.428] I [ timer ] Timer: WireCell::Aux::Resampler : 0.19 sec
[10:42:22.428] I [ timer ] Timer: WireCell::Aux::Resampler : 0.18 sec
[10:42:22.428] I [ timer ] Timer: WireCell::Aux::Resampler : 0.18 sec
[10:42:22.428] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[10:42:22.428] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[10:42:22.428] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:42:22.428] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:42:22.428] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:42:22.428] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:42:22.428] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:42:22.428] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:42:22.428] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:42:22.428] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[10:42:22.428] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:42:22.428] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[10:42:22.428] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:42:22.428] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[10:42:22.428] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[10:42:22.428] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[10:42:22.428] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[10:42:22.428] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[10:42:22.428] I [ timer ] Timer: Total node execution : 34.0399997420609 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 21819 traces tagged "gauss"
FrameSaver: q=4.66134e+06 n=515276 tag=gauss
wclsFrameSaver: saving 28031 traces tagged "wiener"
FrameSaver: q=5.04296e+06 n=495495 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 3336 XUs and 3523 XVs -> 224 XUVs
C:0 T:1 19465 XUs and 30198 XVs -> 1708 XUVs
C:0 T:2 1049 XUs and 1446 XVs -> 48 XUVs
C:0 T:3 775 XUs and 718 XVs -> 24 XUVs
C:0 T:4 381 XUs and 342 XVs -> 33 XUVs
C:0 T:5 5 XUs and 5 XVs -> 0 XUVs
C:0 T:6 771 XUs and 884 XVs -> 24 XUVs
C:0 T:7 758 XUs and 999 XVs -> 75 XUVs
2136 XUVs total
1153 collection wire objects
2136 potential space points
Neighbour search...
63072 tests to find 15298 neighbours
Iterating with no regularization...
Begin: 1.01262e+10
0 9.87023e+09
1 9.85823e+09
2 9.85814e+09
Now with regularization...
Begin: 9.78757e+09
0 9.78743e+09
18-Sep-2025 10:42:50 CEST Closed output file "np02vd_raw_run039433_0360_df-s05-d5_dw_0_20250918T060755_reco_stage1_20250918T084250_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 67.3661 139.055 568.575 121.223 76.487 47
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.3472e-05 6.84555e-05 0.000177898 6.2698e-05 1.89458e-05 47
produce:tpcrawdecoder:PDVDTPCReader 25.7733 57.8213 116.7 54.4179 15.927 47
produce:triggerrawdecoder:PDVDTriggerReader4 0.336646 0.493335 1.00413 0.446525 0.157952 47
produce:pdvddaphne:DAPHNEReaderPDVD 8.62866 12.4033 21.5621 12.1726 2.69279 47
produce:ophit:OpHitFinder 0.0251321 0.0389897 0.0765352 0.038598 0.00714817 47
produce:opflash:OpFlashFinderVerticalDrift 0.00277339 0.00902742 0.0309355 0.00850634 0.00420643 47
produce:wclsdatavd:WireCellToolkit 20.3268 33.87 59.4799 31.7855 9.54583 47
produce:gaushit:GausHitFinder 0.253558 0.543153 1.52803 0.49419 0.221408 47
produce:nhitsfilter:NumberOfHitsFilter 7.0695e-05 0.000140779 0.000460967 0.000118375 7.20294e-05 47
produce:reco3d:SpacePointSolver 1.66199 5.00736 23.5899 4.21177 3.63282 47
produce:hitpdune:DisambigFromSpacePoints 0.0135082 0.0717084 0.550228 0.0463562 0.0906102 47
produce:pandora:StandardPandora 2.1181 26.4046 427.259 10.3774 65.4436 47
produce:pandoraTrack:LArPandoraTrackCreation 0.0649798 0.301264 2.43763 0.192473 0.382264 47
produce:pandoraGnocalo:GnocchiCalorimetry 0.00643181 0.0130538 0.0315737 0.0119646 0.00480506 47
[art]:TriggerResults:TriggerResultInserter 1.5469e-05 2.0221e-05 5.0827e-05 1.9056e-05 5.48915e-06 47
end_path:out1:RootOutput 3.496e-06 5.47077e-06 2.5188e-05 4.329e-06 4.64648e-06 47
end_path:out1:RootOutput(write) 1.76907 2.061 2.8317 1.99591 0.252643 47
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4563.92 MB
Peak resident set size usage (VmHWM): 2677.89 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0360_df-s05-d5_dw_0_20250918T060755_reco_stage1_20250918T084250_keepup.root
\tHists: np02vd_raw_run039433_0360_df-s05-d5_dw_0_20250918T060755_reco_stage1_20250918T084250_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0360_df-s05-d5_dw_0_20250918T060755_reco_stage1_20250918T084250_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0360_df-s05-d5_dw_0_20250918T060755_reco_stage1_20250918T084250_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": 1758184971.0,
"core.end_time": 1758184971.0,
"core.events": [
103217,
103223,
103229,
103235,
103241,
103247,
103253,
103259,
103265,
103271,
103277,
103283,
103289,
103295,
103301,
103307,
103313,
103319,
103325,
103331,
103337,
103343,
103349,
103355,
103361,
103367,
103373,
103379,
103385,
103391,
103397,
103403,
103409,
103415,
103421,
103427,
103433,
103439,
103445,
103451,
103457,
103463,
103469,
103475,
103481,
103487,
103493
],
"core.event_count": 47,
"core.first_event_number": 103217,
"core.last_event_number": 103493,
"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_0360_df-s05-d5_dw_0_20250918T060755.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0360_df-s05-d5_dw_0_20250918T060755_reco_stage1_20250918T084250_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": 1758184971.0,
"core.end_time": 1758184971.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_0360_df-s05-d5_dw_0_20250918T060755.hdf5"
}
]
} 