Jobsub ID 237201.95@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
call=45
[14:05:24.944] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=45 anode=0
[14:05:24.944] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[14:05:24.944] D [ glue ] frame sink sees EOS
[14:05:24.945] D [ pgraph ] <Pgrapher:> graph execution complete
[14:05:24.945] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.16 sec
[14:05:24.945] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.48 sec
[14:05:24.945] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.85 sec
[14:05:24.945] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.46 sec
[14:05:24.945] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.45 sec
[14:05:24.945] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.36 sec
[14:05:24.945] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.32 sec
[14:05:24.945] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.87 sec
[14:05:24.945] I [ timer ] Timer: WireCell::Aux::Resampler : 0.57 sec
[14:05:24.945] I [ timer ] Timer: WireCell::Aux::Resampler : 0.56 sec
[14:05:24.945] I [ timer ] Timer: WireCell::Aux::Resampler : 0.56 sec
[14:05:24.945] I [ timer ] Timer: WireCell::Aux::Resampler : 0.54 sec
[14:05:24.945] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[14:05:24.945] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[14:05:24.945] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:05:24.945] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:05:24.945] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:05:24.945] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:05:24.945] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:05:24.945] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[14:05:24.945] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:05:24.945] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:05:24.945] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[14:05:24.945] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[14:05:24.945] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[14:05:24.945] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[14:05:24.945] I [ timer ] Timer: Total node execution : 64.19999935105443 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 48806 traces tagged "gauss"
FrameSaver: q=1.60993e+07 n=1190343 tag=gauss
wclsFrameSaver: saving 61452 traces tagged "wiener"
FrameSaver: q=1.71567e+07 n=1142495 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 674 XUs and 580 XVs -> 40 XUVs
C:0 T:1 186 XUs and 161 XVs -> 6 XUVs
C:0 T:2 951 XUs and 684 XVs -> 37 XUVs
C:0 T:3 660 XUs and 796 XVs -> 37 XUVs
C:0 T:4 508 XUs and 574 XVs -> 10 XUVs
C:0 T:5 1201 XUs and 1395 XVs -> 67 XUVs
C:0 T:6 678 XUs and 636 XVs -> 29 XUVs
C:0 T:7 23553 XUs and 28419 XVs -> 1898 XUVs
C:0 T:8 382 XUs and 476 XVs -> 30 XUVs
C:0 T:9 713 XUs and 978 XVs -> 30 XUVs
C:0 T:10 1661 XUs and 1893 XVs -> 171 XUVs
C:0 T:11 1763 XUs and 2073 XVs -> 115 XUVs
C:0 T:12 1227 XUs and 1201 XVs -> 70 XUVs
C:0 T:13 388 XUs and 533 XVs -> 24 XUVs
C:0 T:14 2556 XUs and 2700 XVs -> 229 XUVs
C:0 T:15 3799 XUs and 3494 XVs -> 193 XUVs
2986 XUVs total
1827 collection wire objects
2986 potential space points
Neighbour search...
72526 tests to find 17112 neighbours
Iterating with no regularization...
Begin: 2.0967e+10
0 2.02367e+10
1 2.01915e+10
2 2.01904e+10
Now with regularization...
Begin: 1.99446e+10
0 1.99443e+10
04-Nov-2025 14:06:00 CET Closed output file "np02vd_raw_run040348_1019_df-s04-d1_dw_0_20251104T091729_reco_stage1_20251104T130600_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 67.2235 126.083 365.9 95.9644 77.7441 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.4001e-05 8.47357e-05 0.000162931 7.4601e-05 2.5961e-05 23
produce:tpcrawdecoder:PDVDTPCReader 4.0871 4.9876 6.68809 4.80567 0.545275 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.010581 0.0236112 0.05604 0.0165191 0.0140913 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000224072 0.000348033 0.000740807 0.000333144 9.50989e-05 23
produce:ophit:OpHitFinder 5.432e-05 0.000111169 0.000591066 9.289e-05 0.000104495 23
produce:opflash:OpFlashFinderVerticalDrift 4.112e-05 7.45312e-05 0.000337514 6.1111e-05 5.73728e-05 23
produce:wclsdatavd:WireCellToolkit 39.5423 58.3065 105.43 52.9885 17.5636 23
produce:gaushit:GausHitFinder 0.579853 0.920688 1.66788 0.841299 0.257977 23
produce:nhitsfilter:NumberOfHitsFilter 0.000150881 0.000218331 0.000355193 0.000204572 5.01766e-05 23
produce:reco3d:SpacePointSolver 5.29101 9.68486 19.2272 8.85296 3.13289 23
produce:hitpdune:DisambigFromSpacePoints 0.0678119 0.15657 0.363227 0.136523 0.0774019 23
produce:pandora:StandardPandora 10.8554 46.9211 227.818 20.6526 61.2876 23
produce:pandoraTrack:LArPandoraTrackCreation 0.274114 0.785642 1.51522 0.727641 0.306555 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0131849 0.0238171 0.0387129 0.02308 0.00589566 23
[art]:TriggerResults:TriggerResultInserter 1.451e-05 2.15929e-05 5.6971e-05 1.901e-05 8.54733e-06 23
end_path:out1:RootOutput 5.07e-06 6.9127e-06 2.026e-05 6.06e-06 2.99908e-06 23
end_path:out1:RootOutput(write) 3.32847 4.24375 6.02261 4.12919 0.69884 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5828.07 MB
Peak resident set size usage (VmHWM): 3859.83 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040348_1019_df-s04-d1_dw_0_20251104T091729_reco_stage1_20251104T130600_keepup.root
\tHists: np02vd_raw_run040348_1019_df-s04-d1_dw_0_20251104T091729_reco_stage1_20251104T130600_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040348_1019_df-s04-d1_dw_0_20251104T091729_reco_stage1_20251104T130600_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040348_1019_df-s04-d1_dw_0_20251104T091729_reco_stage1_20251104T130600_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": 1762261560.0,
"core.end_time": 1762261560.0,
"core.events": [
94273,
94277,
94281,
94285,
94289,
94293,
94297,
94301,
94305,
94309,
94313,
94317,
94321,
94325,
94329,
94333,
94337,
94341,
94345,
94349,
94353,
94357,
94361
],
"core.event_count": 23,
"core.first_event_number": 94273,
"core.last_event_number": 94361,
"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_1019_df-s04-d1_dw_0_20251104T091729.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040348_1019_df-s04-d1_dw_0_20251104T091729_reco_stage1_20251104T130600_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": 1762261560.0,
"core.end_time": 1762261560.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_1019_df-s04-d1_dw_0_20251104T091729.hdf5"
}
]
} 