Jobsub ID 243027.87@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
proc ] <OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[17:42:23.236] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[17:42:23.236] D [ glue ] frame sink sees EOS
[17:42:23.236] D [ pgraph ] <Pgrapher:> graph execution complete
[17:42:23.236] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 50.6 sec
[17:42:23.236] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 19.62 sec
[17:42:23.236] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.8 sec
[17:42:23.236] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.68 sec
[17:42:23.236] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.47 sec
[17:42:23.236] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.32 sec
[17:42:23.236] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.28 sec
[17:42:23.236] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.67 sec
[17:42:23.236] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[17:42:23.236] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[17:42:23.236] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[17:42:23.236] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[17:42:23.236] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.04 sec
[17:42:23.236] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[17:42:23.236] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[17:42:23.236] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[17:42:23.236] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[17:42:23.236] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:42:23.236] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:42:23.236] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:42:23.236] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[17:42:23.236] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:42:23.236] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:42:23.236] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[17:42:23.236] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[17:42:23.236] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[17:42:23.236] I [ timer ] Timer: Total node execution : 140.6799995843321 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 82938 traces tagged "gauss"
FrameSaver: q=6.45623e+07 n=2073156 tag=gauss
wclsFrameSaver: saving 108559 traces tagged "wiener"
FrameSaver: q=6.94394e+07 n=1990759 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 201096 XUs and 265206 XVs -> 22209 XUVs
C:0 T:1 1152 XUs and 1443 XVs -> 40 XUVs
C:0 T:2 73135 XUs and 74268 XVs -> 4755 XUVs
C:0 T:3 589 XUs and 626 XVs -> 23 XUVs
C:0 T:4 826 XUs and 997 XVs -> 46 XUVs
C:0 T:5 639 XUs and 1074 XVs -> 19 XUVs
C:0 T:6 276 XUs and 446 XVs -> 6 XUVs
C:0 T:7 737 XUs and 915 XVs -> 42 XUVs
C:0 T:8 3451 XUs and 6042 XVs -> 1409 XUVs
C:0 T:9 1090 XUs and 914 XVs -> 60 XUVs
C:0 T:10 1394 XUs and 1463 XVs -> 77 XUVs
C:0 T:11 170 XUs and 220 XVs -> 16 XUVs
C:0 T:12 566 XUs and 721 XVs -> 55 XUVs
C:0 T:13 555 XUs and 646 XVs -> 42 XUVs
C:0 T:14 62 XUs and 90 XVs -> 8 XUVs
C:0 T:15 1544 XUs and 1561 XVs -> 70 XUVs
28877 XUVs total
7705 collection wire objects
28877 potential space points
Neighbour search...
1853621 tests to find 440636 neighbours
Iterating with no regularization...
Begin: 5.78119e+12
0 5.75009e+12
1 5.74584e+12
Now with regularization...
Begin: 5.739e+12
0 5.73894e+12
04-Nov-2025 19:54:28 CET Closed output file "np02vd_raw_run040348_1451_df-s04-d0_dw_0_20251104T152046_reco_stage1_20251104T185428_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 95.4751 474.236 8076.93 122.509 1621.15 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.9089e-05 0.000137096 0.000370576 0.000121809 6.67872e-05 23
produce:tpcrawdecoder:PDVDTPCReader 6.99352 8.71962 10.8271 8.56182 0.869046 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.116801 0.127997 0.168519 0.125971 0.0102242 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.00032465 0.000830724 0.00962683 0.000406234 0.00188004 23
produce:ophit:OpHitFinder 7.8869e-05 0.000150067 0.000757344 0.000113002 0.000135216 23
produce:opflash:OpFlashFinderVerticalDrift 4.5635e-05 8.80568e-05 0.000407386 7.0592e-05 7.07283e-05 23
produce:wclsdatavd:WireCellToolkit 57.9943 72.6211 144.628 64.4873 19.7439 23
produce:gaushit:GausHitFinder 0.722002 1.32647 2.64702 1.28656 0.396437 23
produce:nhitsfilter:NumberOfHitsFilter 0.000236033 0.000387883 0.000736855 0.00034515 0.000127651 23
produce:reco3d:SpacePointSolver 6.6857 14.0071 62.1633 10.5183 10.8699 23
produce:hitpdune:DisambigFromSpacePoints 0.107357 0.275991 2.0068 0.181115 0.375391 23
produce:pandora:StandardPandora 12.2693 370.256 7840.28 28.3999 1592.74 23
produce:pandoraTrack:LArPandoraTrackCreation 0.416894 1.47038 10.4905 0.940106 2.07297 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0222934 0.0424917 0.131742 0.0365896 0.0219149 23
[art]:TriggerResults:TriggerResultInserter 1.8435e-05 4.1108e-05 0.000148839 3.1389e-05 2.68972e-05 23
end_path:out1:RootOutput 3.596e-06 1.25151e-05 6.914e-05 9.067e-06 1.24278e-05 23
end_path:out1:RootOutput(write) 4.467 5.33625 6.91735 5.30485 0.61011 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6915.83 MB
Peak resident set size usage (VmHWM): 4975.8 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040348_1451_df-s04-d0_dw_0_20251104T152046_reco_stage1_20251104T185428_keepup.root
\tHists: np02vd_raw_run040348_1451_df-s04-d0_dw_0_20251104T152046_reco_stage1_20251104T185428_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040348_1451_df-s04-d0_dw_0_20251104T152046_reco_stage1_20251104T185428_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040348_1451_df-s04-d0_dw_0_20251104T152046_reco_stage1_20251104T185428_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": 1762282469.0,
"core.end_time": 1762282469.0,
"core.events": [
134152,
134156,
134160,
134164,
134168,
134172,
134176,
134180,
134184,
134188,
134192,
134196,
134200,
134204,
134208,
134212,
134216,
134220,
134224,
134228,
134232,
134236,
134240
],
"core.event_count": 23,
"core.first_event_number": 134152,
"core.last_event_number": 134240,
"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_1451_df-s04-d0_dw_0_20251104T152046.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040348_1451_df-s04-d0_dw_0_20251104T152046_reco_stage1_20251104T185428_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": 1762282469.0,
"core.end_time": 1762282469.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_1451_df-s04-d0_dw_0_20251104T152046.hdf5"
}
]
} 