Jobsub ID 37575.8@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
Selector:chsel0> see EOS at call=17
[16:26:00.067] D [ aux ] <Resampler:resmp0> EOS at call=35
[16:26:00.067] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=35 anode=0
[16:26:00.067] D [ glue ] <FrameFanin:nfsp> EOS at call=35 with 8
[16:26:00.067] D [ glue ] frame sink sees EOS
[16:26:00.067] D [ pgraph ] <Pgrapher:> graph execution complete
[16:26:00.067] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.93 sec
[16:26:00.067] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.76 sec
[16:26:00.067] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.69 sec
[16:26:00.067] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.56 sec
[16:26:00.067] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.55 sec
[16:26:00.067] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.51 sec
[16:26:00.067] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.95 sec
[16:26:00.067] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.87 sec
[16:26:00.067] I [ timer ] Timer: WireCell::Aux::Resampler : 0.83 sec
[16:26:00.067] I [ timer ] Timer: WireCell::Aux::Resampler : 0.8 sec
[16:26:00.067] I [ timer ] Timer: WireCell::Aux::Resampler : 0.78 sec
[16:26:00.067] I [ timer ] Timer: WireCell::Aux::Resampler : 0.78 sec
[16:26:00.067] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[16:26:00.067] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[16:26:00.067] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[16:26:00.067] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[16:26:00.067] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[16:26:00.067] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[16:26:00.067] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[16:26:00.067] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[16:26:00.067] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[16:26:00.067] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[16:26:00.067] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[16:26:00.067] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[16:26:00.067] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[16:26:00.067] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[16:26:00.067] I [ timer ] Timer: Total node execution : 71.09000058472157 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 33378 traces tagged "gauss"
FrameSaver: q=4.93294e+06 n=678468 tag=gauss
wclsFrameSaver: saving 40364 traces tagged "wiener"
FrameSaver: q=5.22434e+06 n=653342 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 963 XUs and 1573 XVs -> 90 XUVs
C:0 T:1 440 XUs and 976 XVs -> 42 XUVs
C:0 T:2 804 XUs and 847 XVs -> 19 XUVs
C:0 T:3 395 XUs and 520 XVs -> 26 XUVs
C:0 T:4 192 XUs and 420 XVs -> 19 XUVs
C:0 T:5 499 XUs and 401 XVs -> 21 XUVs
C:0 T:6 180 XUs and 207 XVs -> 6 XUVs
C:0 T:7 131 XUs and 152 XVs -> 3 XUVs
C:0 T:8 1184 XUs and 1610 XVs -> 58 XUVs
C:0 T:9 1627 XUs and 1592 XVs -> 141 XUVs
C:0 T:10 1538 XUs and 1833 XVs -> 93 XUVs
C:0 T:11 266 XUs and 514 XVs -> 26 XUVs
C:0 T:12 268 XUs and 387 XVs -> 27 XUVs
C:0 T:13 414 XUs and 340 XVs -> 32 XUVs
C:0 T:14 265 XUs and 309 XVs -> 43 XUVs
C:0 T:15 905 XUs and 1799 XVs -> 79 XUVs
725 XUVs total
627 collection wire objects
725 potential space points
Neighbour search...
3681 tests to find 1730 neighbours
Iterating with no regularization...
Begin: 3.95078e+08
0 3.90731e+08
1 3.90722e+08
Now with regularization...
Begin: 3.84935e+08
0 3.84934e+08
10-Sep-2025 16:26:37 BST Closed output file "np02vd_raw_run039350_1118_df-s04-d2_dw_0_20250910T132640_reco_stage1_20250910T152637_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 127.849 211.448 319.854 203.742 48.5239 18
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 0.000104574 0.000131765 0.000213504 0.000131796 2.44815e-05 18
produce:tpcrawdecoder:PDVDTPCReader 14.8793 17.0061 19.9215 16.8121 1.35392 18
produce:triggerrawdecoder:PDVDTriggerReader4 0.278513 0.319945 0.518703 0.281114 0.0884099 18
produce:pdvddaphne:DAPHNEReaderPDVD 4.55279 5.28879 7.08459 5.07137 0.68249 18
produce:ophit:OpHitFinder 0.0401274 0.0537273 0.0766005 0.0497812 0.0109409 18
produce:opflash:OpFlashFinderVerticalDrift 0.00842399 0.0133554 0.0262724 0.0118548 0.00488208 18
produce:wclsdatavd:WireCellToolkit 71.8086 96.6326 128.311 95.8919 15.9361 18
produce:gaushit:GausHitFinder 0.760606 1.55611 2.96734 1.367 0.550774 18
produce:nhitsfilter:NumberOfHitsFilter 0.000409405 0.000619074 0.000893569 0.000597151 0.000138484 18
produce:reco3d:SpacePointSolver 6.22851 13.5523 26.1217 13.4677 4.92754 18
produce:hitpdune:DisambigFromSpacePoints 0.104899 0.250994 0.451076 0.23038 0.0999409 18
produce:pandora:StandardPandora 21.9679 67.9471 155.912 61.7306 34.2798 18
produce:pandoraTrack:LArPandoraTrackCreation 0.913897 3.54832 6.91087 3.76862 1.72568 18
produce:pandoraGnocalo:GnocchiCalorimetry 0.0202033 0.0430267 0.0831999 0.04232 0.0154663 18
[art]:TriggerResults:TriggerResultInserter 3.6274e-05 5.81177e-05 0.000173671 5.26975e-05 2.95926e-05 18
end_path:out1:RootOutput 7.015e-06 1.44226e-05 7.2367e-05 1.1023e-05 1.43296e-05 18
end_path:out1:RootOutput(write) 4.14881 5.16377 7.40372 4.76101 0.937233 18
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4770.53 MB
Peak resident set size usage (VmHWM): 2884.35 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039350_1118_df-s04-d2_dw_0_20250910T132640_reco_stage1_20250910T152637_keepup.root
\tHists: np02vd_raw_run039350_1118_df-s04-d2_dw_0_20250910T132640_reco_stage1_20250910T152638_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039350_1118_df-s04-d2_dw_0_20250910T132640_reco_stage1_20250910T152637_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039350_1118_df-s04-d2_dw_0_20250910T132640_reco_stage1_20250910T152637_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": 1757517999.0,
"core.end_time": 1757517999.0,
"core.events": [
81850,
81854,
81858,
81862,
81866,
81870,
81874,
81878,
81882,
81886,
81890,
81894,
81898,
81902,
81906,
81910,
81914,
81918
],
"core.event_count": 18,
"core.first_event_number": 81850,
"core.last_event_number": 81918,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39350
],
"core.runs_subruns": [
3935000001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039350_1118_df-s04-d2_dw_0_20250910T132640.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039350_1118_df-s04-d2_dw_0_20250910T132640_reco_stage1_20250910T152638_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": 1757517999.0,
"core.end_time": 1757517999.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39350
],
"core.runs_subruns": [
3935000001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039350_1118_df-s04-d2_dw_0_20250910T132640.hdf5"
}
]
} 