Jobsub ID 237284.139@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
sigproc0> EOS at call=47 anode=0
[14:55:54.964] D [ glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[14:55:54.964] D [ glue ] frame sink sees EOS
[14:55:54.964] D [ pgraph ] <Pgrapher:> graph execution complete
[14:55:54.964] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.93 sec
[14:55:54.964] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.43 sec
[14:55:54.964] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.36 sec
[14:55:54.964] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.08 sec
[14:55:54.964] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.69 sec
[14:55:54.964] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.44 sec
[14:55:54.964] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.04 sec
[14:55:54.964] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.88 sec
[14:55:54.964] I [ timer ] Timer: WireCell::Aux::Resampler : 0.37 sec
[14:55:54.964] I [ timer ] Timer: WireCell::Aux::Resampler : 0.37 sec
[14:55:54.964] I [ timer ] Timer: WireCell::Aux::Resampler : 0.35 sec
[14:55:54.964] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[14:55:54.964] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.04 sec
[14:55:54.964] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[14:55:54.964] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[14:55:54.964] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[14:55:54.964] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[14:55:54.964] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[14:55:54.964] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:55:54.964] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:55:54.964] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[14:55:54.964] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:55:54.964] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[14:55:54.964] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[14:55:54.964] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[14:55:54.964] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[14:55:54.964] I [ timer ] Timer: Total node execution : 100.36999944224954 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 45517 traces tagged "gauss"
FrameSaver: q=1.35098e+07 n=1038567 tag=gauss
wclsFrameSaver: saving 58412 traces tagged "wiener"
FrameSaver: q=1.46547e+07 n=994853 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 2420 XUs and 2941 XVs -> 116 XUVs
C:0 T:1 380 XUs and 752 XVs -> 27 XUVs
C:0 T:2 275 XUs and 294 XVs -> 13 XUVs
C:0 T:3 22672 XUs and 37466 XVs -> 1936 XUVs
C:0 T:4 1295 XUs and 1589 XVs -> 43 XUVs
C:0 T:5 417 XUs and 740 XVs -> 21 XUVs
C:0 T:6 1537 XUs and 2076 XVs -> 200 XUVs
C:0 T:7 2030 XUs and 2136 XVs -> 103 XUVs
C:0 T:8 897 XUs and 1078 XVs -> 90 XUVs
C:0 T:9 690 XUs and 1002 XVs -> 72 XUVs
C:0 T:10 180 XUs and 298 XVs -> 20 XUVs
C:0 T:11 645 XUs and 890 XVs -> 41 XUVs
C:0 T:12 636 XUs and 950 XVs -> 58 XUVs
C:0 T:13 772 XUs and 1013 XVs -> 212 XUVs
C:0 T:14 512 XUs and 598 XVs -> 40 XUVs
C:0 T:15 682 XUs and 832 XVs -> 41 XUVs
3033 XUVs total
1735 collection wire objects
3033 potential space points
Neighbour search...
76217 tests to find 21214 neighbours
Iterating with no regularization...
Begin: 2.25774e+10
0 2.09775e+10
1 2.08699e+10
2 2.08673e+10
Now with regularization...
Begin: 2.0663e+10
0 2.06622e+10
04-Nov-2025 14:56:46 UTC Closed output file "np02vd_raw_run040348_1268_df-s04-d1_dw_0_20251104T124704_reco_stage1_20251104T145646_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 113.573 164.96 285.209 156.88 46.5758 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 9.2219e-05 0.000157323 0.000341355 0.000133441 6.09673e-05 24
produce:tpcrawdecoder:PDVDTPCReader 13.5112 16.4085 25.244 16.4096 2.34757 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.673234 0.709856 0.771681 0.704673 0.0209368 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000321254 0.00063222 0.00217503 0.000495787 0.000470594 24
produce:ophit:OpHitFinder 8.3456e-05 0.000226213 0.00100986 0.000173541 0.000203512 24
produce:opflash:OpFlashFinderVerticalDrift 5.289e-05 0.000126575 0.000433083 0.000110592 7.61581e-05 24
produce:wclsdatavd:WireCellToolkit 51.8161 79.1522 119.182 71.497 20.4646 24
produce:gaushit:GausHitFinder 1.07182 1.52875 1.91694 1.52744 0.187812 24
produce:nhitsfilter:NumberOfHitsFilter 0.000278049 0.000598913 0.00142127 0.000514696 0.000275758 24
produce:reco3d:SpacePointSolver 9.97913 13.7105 18.1809 13.2271 2.25063 24
produce:hitpdune:DisambigFromSpacePoints 0.1448 0.245286 0.386576 0.248854 0.0615059 24
produce:pandora:StandardPandora 19.3356 46.0169 141.461 34.715 32.0477 24
produce:pandoraTrack:LArPandoraTrackCreation 0.923545 1.87568 3.81829 1.74098 0.677046 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.0295878 0.0445891 0.0665665 0.0432562 0.00830971 24
[art]:TriggerResults:TriggerResultInserter 3.4763e-05 7.27542e-05 0.000138829 6.989e-05 2.04036e-05 24
end_path:out1:RootOutput 9.224e-06 1.61907e-05 3.9961e-05 1.5108e-05 6.28613e-06 24
end_path:out1:RootOutput(write) 4.09898 5.12617 6.07802 5.13096 0.467091 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6122.82 MB
Peak resident set size usage (VmHWM): 3979.83 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040348_1268_df-s04-d1_dw_0_20251104T124704_reco_stage1_20251104T145646_keepup.root
\tHists: np02vd_raw_run040348_1268_df-s04-d1_dw_0_20251104T124704_reco_stage1_20251104T145647_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040348_1268_df-s04-d1_dw_0_20251104T124704_reco_stage1_20251104T145646_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040348_1268_df-s04-d1_dw_0_20251104T124704_reco_stage1_20251104T145646_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_11_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": 1762268208.0,
"core.end_time": 1762268208.0,
"core.events": [
117265,
117269,
117273,
117277,
117281,
117285,
117289,
117293,
117297,
117301,
117305,
117309,
117313,
117317,
117321,
117325,
117329,
117333,
117337,
117341,
117345,
117349,
117353,
117357
],
"core.event_count": 24,
"core.first_event_number": 117265,
"core.last_event_number": 117357,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40348
],
"core.runs_subruns": [
4034800001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040348_1268_df-s04-d1_dw_0_20251104T124704.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040348_1268_df-s04-d1_dw_0_20251104T124704_reco_stage1_20251104T145647_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_11_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": 1762268208.0,
"core.end_time": 1762268208.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40348
],
"core.runs_subruns": [
4034800001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040348_1268_df-s04-d1_dw_0_20251104T124704.hdf5"
}
]
} 