Jobsub ID 41801.2@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
<Resampler:resmp0> EOS at call=93
[14:53:18.424] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=93 anode=0
[14:53:18.424] D [ glue ] <FrameFanin:nfsp> EOS at call=93 with 8
[14:53:18.424] D [ glue ] frame sink sees EOS
[14:53:18.424] D [ pgraph ] <Pgrapher:> graph execution complete
[14:53:18.424] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.83 sec
[14:53:18.424] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.06 sec
[14:53:18.424] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.56 sec
[14:53:18.424] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.54 sec
[14:53:18.424] I [ timer ] Timer: WireCell::Aux::Resampler : 0.6 sec
[14:53:18.424] I [ timer ] Timer: WireCell::Aux::Resampler : 0.59 sec
[14:53:18.424] I [ timer ] Timer: WireCell::Aux::Resampler : 0.36 sec
[14:53:18.424] I [ timer ] Timer: WireCell::Aux::Resampler : 0.35 sec
[14:53:18.424] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[14:53:18.424] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[14:53:18.424] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[14:53:18.424] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[14:53:18.424] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:53:18.424] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:53:18.424] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[14:53:18.424] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:53:18.424] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:53:18.424] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:53:18.425] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[14:53:18.425] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:53:18.425] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[14:53:18.425] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:53:18.425] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[14:53:18.425] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[14:53:18.425] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[14:53:18.425] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[14:53:18.425] I [ timer ] Timer: Total node execution : 39.93000025302172 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 25874 traces tagged "gauss"
FrameSaver: q=4.73194e+06 n=598178 tag=gauss
wclsFrameSaver: saving 32294 traces tagged "wiener"
FrameSaver: q=5.25125e+06 n=584306 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 939 XUs and 851 XVs -> 20 XUVs
C:0 T:1 155 XUs and 208 XVs -> 8 XUVs
C:0 T:2 392 XUs and 271 XVs -> 6 XUVs
C:0 T:3 42 XUs and 43 XVs -> 2 XUVs
C:0 T:4 2132 XUs and 2465 XVs -> 190 XUVs
C:0 T:5 3044 XUs and 3949 XVs -> 129 XUVs
C:0 T:6 5617 XUs and 5330 XVs -> 395 XUVs
C:0 T:7 36750 XUs and 36837 XVs -> 3889 XUVs
4639 XUVs total
1351 collection wire objects
4639 potential space points
Neighbour search...
307173 tests to find 70526 neighbours
Iterating with no regularization...
Begin: 2.40919e+09
0 2.03226e+09
1 1.99973e+09
2 1.99832e+09
Now with regularization...
Begin: 1.93433e+09
0 1.93382e+09
18-Sep-2025 14:54:44 BST Closed output file "np02vd_raw_run039433_0265_df-s05-d3_dw_0_20250918T021857_reco_stage1_20250918T135444_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 105.299 330.817 8491.45 143.525 1203.99 47
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 0.00010316 0.000353033 0.00732432 0.000141749 0.00107776 47
produce:tpcrawdecoder:PDVDTPCReader 14.8643 45.7371 78.5877 43.6215 12.6372 47
produce:triggerrawdecoder:PDVDTriggerReader4 0.376539 0.485228 1.0184 0.42373 0.165014 47
produce:pdvddaphne:DAPHNEReaderPDVD 9.65782 13.0653 23.4647 13.1419 2.37087 47
produce:ophit:OpHitFinder 0.046148 0.0724534 0.109296 0.0726439 0.0103415 47
produce:opflash:OpFlashFinderVerticalDrift 0.00842007 0.0167087 0.0341594 0.0153271 0.00576387 47
produce:wclsdatavd:WireCellToolkit 33.75 53.2569 114.19 45.915 15.8666 47
produce:gaushit:GausHitFinder 0.344133 0.998878 3.02676 0.92643 0.459064 47
produce:nhitsfilter:NumberOfHitsFilter 0.000116396 0.000249843 0.000581886 0.000235022 8.673e-05 47
produce:reco3d:SpacePointSolver 1.9976 7.52086 34.705 6.69383 5.08604 47
produce:hitpdune:DisambigFromSpacePoints 0.0419249 0.148027 0.466827 0.115277 0.10882 47
produce:pandora:StandardPandora 5.57482 205.432 8314.22 20.2503 1195.87 47
produce:pandoraTrack:LArPandoraTrackCreation 0.117068 0.640662 4.11375 0.459984 0.646697 47
produce:pandoraGnocalo:GnocchiCalorimetry 0.00805541 0.0263525 0.172198 0.0233903 0.0231475 47
[art]:TriggerResults:TriggerResultInserter 1.7293e-05 3.23e-05 9.2893e-05 3.2675e-05 1.07331e-05 47
end_path:out1:RootOutput 4.714e-06 1.0095e-05 3.0193e-05 9.63e-06 4.03542e-06 47
end_path:out1:RootOutput(write) 2.07881 3.38343 6.66305 3.33595 0.854573 47
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6383.05 MB
Peak resident set size usage (VmHWM): 4487.07 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0265_df-s05-d3_dw_0_20250918T021857_reco_stage1_20250918T135444_keepup.root
\tHists: np02vd_raw_run039433_0265_df-s05-d3_dw_0_20250918T021857_reco_stage1_20250918T135446_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0265_df-s05-d3_dw_0_20250918T021857_reco_stage1_20250918T135444_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0265_df-s05-d3_dw_0_20250918T021857_reco_stage1_20250918T135444_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": 1758203686.0,
"core.end_time": 1758203686.0,
"core.events": [
76083,
76089,
76095,
76101,
76107,
76113,
76119,
76125,
76131,
76137,
76143,
76149,
76155,
76161,
76167,
76173,
76179,
76185,
76191,
76197,
76203,
76209,
76215,
76221,
76227,
76233,
76239,
76245,
76251,
76257,
76263,
76269,
76275,
76281,
76287,
76293,
76299,
76305,
76311,
76317,
76323,
76329,
76335,
76341,
76347,
76353,
76359
],
"core.event_count": 47,
"core.first_event_number": 76083,
"core.last_event_number": 76359,
"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_0265_df-s05-d3_dw_0_20250918T021857.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0265_df-s05-d3_dw_0_20250918T021857_reco_stage1_20250918T135446_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": 1758203686.0,
"core.end_time": 1758203686.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_0265_df-s05-d3_dw_0_20250918T021857.hdf5"
}
]
} 