Jobsub ID 262863.47@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
<Resampler:resmp0> EOS at call=97
[01:19:07.550] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=97 anode=0
[01:19:07.550] D [ glue ] <FrameFanin:nfsp> EOS at call=97 with 8
[01:19:07.551] D [ glue ] frame sink sees EOS
[01:19:07.551] D [ pgraph ] <Pgrapher:> graph execution complete
[01:19:07.551] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.18 sec
[01:19:07.551] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.66 sec
[01:19:07.551] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.32 sec
[01:19:07.551] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.8 sec
[01:19:07.551] I [ timer ] Timer: WireCell::Aux::Resampler : 0.35 sec
[01:19:07.551] I [ timer ] Timer: WireCell::Aux::Resampler : 0.31 sec
[01:19:07.551] I [ timer ] Timer: WireCell::Aux::Resampler : 0.31 sec
[01:19:07.551] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[01:19:07.551] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[01:19:07.551] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[01:19:07.551] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:19:07.551] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:19:07.551] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:19:07.551] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:19:07.551] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:19:07.551] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:19:07.551] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:19:07.551] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[01:19:07.551] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:19:07.551] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:19:07.551] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:19:07.551] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[01:19:07.551] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[01:19:07.551] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[01:19:07.551] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:19:07.551] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[01:19:07.551] I [ timer ] Timer: Total node execution : 60.25000002793968 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 25306 traces tagged "gauss"
FrameSaver: q=6.58864e+06 n=559813 tag=gauss
wclsFrameSaver: saving 31042 traces tagged "wiener"
FrameSaver: q=7.2088e+06 n=540071 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 2517 XUs and 2509 XVs -> 163 XUVs
C:0 T:1 320 XUs and 346 XVs -> 31 XUVs
C:0 T:2 12560 XUs and 11817 XVs -> 931 XUVs
C:0 T:3 276 XUs and 298 XVs -> 17 XUVs
C:0 T:4 1401 XUs and 1567 XVs -> 30 XUVs
C:0 T:5 1524 XUs and 1471 XVs -> 73 XUVs
C:0 T:6 2159 XUs and 2034 XVs -> 30 XUVs
C:0 T:7 537 XUs and 696 XVs -> 10 XUVs
1285 XUVs total
671 collection wire objects
1285 potential space points
Neighbour search...
33105 tests to find 8862 neighbours
Iterating with no regularization...
Begin: 9.1949e+09
0 8.80151e+09
1 8.79471e+09
Now with regularization...
Begin: 8.5389e+09
0 8.53853e+09
04-Dec-2025 01:19:41 CET Closed output file "np02vd_raw_run041106_0510_df-s04-d0_dw_0_20251203T191640_reco_stage1_20251204T001941_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 93.6643 269.282 1218.74 148.752 311.81 49
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.6357e-05 0.000114211 0.00022278 0.000102302 3.90103e-05 49
produce:tpcrawdecoder:PDVDTPCReader 2.68187 3.69843 6.23689 3.69556 0.575006 49
produce:triggerrawdecoder:PDVDTriggerReader4 0.00582211 0.0179621 0.105517 0.013206 0.0169763 49
produce:pdvddaphne:DAPHNEReaderPDVD 0.00039977 0.000648294 0.00134513 0.00059467 0.000216605 49
produce:ophit:OpHitFinder 6.8111e-05 0.000169753 0.00110832 0.000150486 0.000149236 49
produce:opflash:OpFlashFinderVerticalDrift 5.5331e-05 0.000120515 0.000684346 9.7382e-05 9.19681e-05 49
produce:wclsdatavd:WireCellToolkit 58.7388 91.749 173.862 91.2817 26.7971 49
produce:gaushit:GausHitFinder 0.634852 1.43333 3.68666 1.28714 0.60403 49
produce:nhitsfilter:NumberOfHitsFilter 0.000179235 0.000352556 0.000859108 0.000315768 0.000133623 49
produce:reco3d:SpacePointSolver 4.63954 12.9917 35.2987 10.5034 7.70647 49
produce:hitpdune:DisambigFromSpacePoints 0.0512774 0.30234 2.02507 0.171335 0.37347 49
produce:pandora:StandardPandora 13.0163 154.455 1077.93 38.0735 285.187 49
produce:pandoraTrack:LArPandoraTrackCreation 0.138794 1.11364 5.07556 0.631349 1.13115 49
produce:pandoraGnocalo:GnocchiCalorimetry 0.011256 0.0290418 0.0729242 0.025933 0.0130366 49
[art]:TriggerResults:TriggerResultInserter 2.4377e-05 4.79201e-05 0.000126192 4.4856e-05 1.91065e-05 49
end_path:out1:RootOutput 5.18e-06 1.07893e-05 3.1515e-05 9.73e-06 4.40674e-06 49
end_path:out1:RootOutput(write) 2.32183 3.45107 5.99657 3.58783 0.852103 49
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6617.54 MB
Peak resident set size usage (VmHWM): 4572.63 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run041106_0510_df-s04-d0_dw_0_20251203T191640_reco_stage1_20251204T001941_keepup.root
\tHists: np02vd_raw_run041106_0510_df-s04-d0_dw_0_20251203T191640_reco_stage1_20251204T001941_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run041106_0510_df-s04-d0_dw_0_20251203T191640_reco_stage1_20251204T001941_keepup.root
Ran successfully
{
"name": "np02vd_raw_run041106_0510_df-s04-d0_dw_0_20251203T191640_reco_stage1_20251204T001941_keepup.root",
"namespace": "vd-protodune-det-reco",
"metadata": {
"core.file_format": "artroot",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_13_00d00",
"core.data_tier": "full-reconstructed",
"dune.config_file": "standard_reco_stage1_protodunevd_keepup.fcl",
"dune.campaign": "vd-protodune-reco-keepup-v0",
"core.start_time": 1764807582.0,
"core.end_time": 1764807582.0,
"core.events": [
24902,
24903,
24904,
24905,
24906,
24907,
24908,
24909,
24910,
24911,
24912,
24913,
24914,
24915,
24916,
24917,
24918,
24919,
24920,
24921,
24922,
24923,
24924,
24925,
24926,
24927,
24928,
24929,
24930,
24931,
24932,
24933,
24934,
24935,
24936,
24937,
24938,
24939,
24940,
24941,
24942,
24943,
24944,
24945,
24946,
24947,
24948,
24949,
24950
],
"core.event_count": 49,
"core.first_event_number": 24902,
"core.last_event_number": 24950,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
41106
],
"core.runs_subruns": [
4110600001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run041106_0510_df-s04-d0_dw_0_20251203T191640.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run041106_0510_df-s04-d0_dw_0_20251203T191640_reco_stage1_20251204T001941_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_13_00d00",
"core.data_tier": "root-tuple-virtual",
"dune.config_file": "standard_reco_stage1_protodunevd_keepup.fcl",
"dune.campaign": "vd-protodune-reco-keepup-v0",
"core.start_time": 1764807582.0,
"core.end_time": 1764807582.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
41106
],
"core.runs_subruns": [
4110600001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run041106_0510_df-s04-d0_dw_0_20251203T191640.hdf5"
}
]
} 