Jobsub ID 49096.145@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
[03:20:12.024] D [ aux ] <Resampler:resmp0> EOS at call=81
[03:20:12.025] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=81 anode=0
[03:20:12.025] D [ glue ] <FrameFanin:nfsp> EOS at call=81 with 8
[03:20:12.025] D [ glue ] frame sink sees EOS
[03:20:12.025] D [ pgraph ] <Pgrapher:> graph execution complete
[03:20:12.025] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.65 sec
[03:20:12.025] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.16 sec
[03:20:12.025] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.61 sec
[03:20:12.025] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.89 sec
[03:20:12.025] I [ timer ] Timer: WireCell::Aux::Resampler : 0.27 sec
[03:20:12.025] I [ timer ] Timer: WireCell::Aux::Resampler : 0.27 sec
[03:20:12.025] I [ timer ] Timer: WireCell::Aux::Resampler : 0.27 sec
[03:20:12.025] I [ timer ] Timer: WireCell::Aux::Resampler : 0.25 sec
[03:20:12.025] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[03:20:12.025] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[03:20:12.025] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[03:20:12.025] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:20:12.025] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:20:12.025] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[03:20:12.025] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:20:12.025] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:20:12.025] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[03:20:12.025] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:20:12.025] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[03:20:12.025] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:20:12.025] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:20:12.025] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[03:20:12.025] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[03:20:12.025] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[03:20:12.025] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[03:20:12.025] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[03:20:12.025] I [ timer ] Timer: Total node execution : 28.389999974519014 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 14380 traces tagged "gauss"
FrameSaver: q=4.24356e+06 n=377056 tag=gauss
wclsFrameSaver: saving 18442 traces tagged "wiener"
FrameSaver: q=4.44566e+06 n=363160 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 513 XUs and 642 XVs -> 31 XUVs
C:0 T:1 1388 XUs and 2088 XVs -> 126 XUVs
C:0 T:2 929 XUs and 305 XVs -> 9 XUVs
C:0 T:3 4287 XUs and 6245 XVs -> 282 XUVs
C:0 T:4 358 XUs and 476 XVs -> 13 XUVs
C:0 T:5 279 XUs and 209 XVs -> 11 XUVs
C:0 T:6 145 XUs and 132 XVs -> 10 XUVs
C:0 T:7 24 XUs and 39 XVs -> 2 XUVs
484 XUVs total
390 collection wire objects
484 potential space points
Neighbour search...
7116 tests to find 2546 neighbours
Iterating with no regularization...
Begin: 1.16741e+10
0 1.16528e+10
1 1.16527e+10
Now with regularization...
Begin: 1.15845e+10
0 1.15845e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
19-Sep-2025 03:20:27 CEST Closed output file "np02vd_raw_run039499_0122_df-s04-d3_dw_0_20250918T214753_reco_stage1_20250919T012027_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 48.0243 98.4436 723.495 66.5755 108.325 41
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.889e-05 0.000109555 0.00019016 0.00010748 3.29725e-05 41
produce:tpcrawdecoder:PDVDTPCReader 5.53845 7.4048 20.9897 7.18765 2.34972 41
produce:triggerrawdecoder:PDVDTriggerReader4 0.267402 0.275254 0.317977 0.270206 0.0108119 41
produce:pdvddaphne:DAPHNEReaderPDVD 2.78999 3.3842 4.0623 3.4142 0.248251 41
produce:ophit:OpHitFinder 0.0434319 0.055698 0.0897229 0.0538669 0.00932854 41
produce:opflash:OpFlashFinderVerticalDrift 0.00483045 0.0119057 0.0179047 0.011531 0.00323932 41
produce:wclsdatavd:WireCellToolkit 26.5474 35.1132 70.9573 30.5941 11.2966 41
produce:gaushit:GausHitFinder 0.360457 0.717996 1.60082 0.635611 0.285412 41
produce:nhitsfilter:NumberOfHitsFilter 0.000102 0.000160908 0.000419861 0.00014574 6.04119e-05 41
produce:reco3d:SpacePointSolver 1.86856 6.76642 31.5285 4.89207 5.38664 41
produce:hitpdune:DisambigFromSpacePoints 0.0255195 0.107202 0.531907 0.070884 0.0975539 41
produce:pandora:StandardPandora 3.7239 41.1128 608.471 16.5044 95.7096 41
produce:pandoraTrack:LArPandoraTrackCreation 0.105015 0.405863 2.16704 0.276268 0.398536 41
produce:pandoraGnocalo:GnocchiCalorimetry 0.00914579 0.0186681 0.0428404 0.015641 0.00742459 41
[art]:TriggerResults:TriggerResultInserter 1.876e-05 2.9224e-05 5.955e-05 2.783e-05 7.3991e-06 41
end_path:out1:RootOutput 3.36e-06 8.18829e-06 2.617e-05 7.81e-06 3.46649e-06 41
end_path:out1:RootOutput(write) 2.63282 3.05551 3.94713 2.93812 0.327735 41
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4333.36 MB
Peak resident set size usage (VmHWM): 2414.82 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0122_df-s04-d3_dw_0_20250918T214753_reco_stage1_20250919T012027_keepup.root
\tHists: np02vd_raw_run039499_0122_df-s04-d3_dw_0_20250918T214753_reco_stage1_20250919T012028_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0122_df-s04-d3_dw_0_20250918T214753_reco_stage1_20250919T012027_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0122_df-s04-d3_dw_0_20250918T214753_reco_stage1_20250919T012027_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": 1758244829.0,
"core.end_time": 1758244829.0,
"core.events": [
29768,
29774,
29780,
29786,
29792,
29798,
29804,
29810,
29816,
29822,
29828,
29834,
29840,
29846,
29852,
29858,
29864,
29870,
29876,
29882,
29888,
29894,
29900,
29906,
29912,
29918,
29924,
29930,
29936,
29942,
29948,
29954,
29960,
29966,
29972,
29978,
29984,
29990,
29996,
30002,
30008
],
"core.event_count": 41,
"core.first_event_number": 29768,
"core.last_event_number": 30008,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39499
],
"core.runs_subruns": [
3949900001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039499_0122_df-s04-d3_dw_0_20250918T214753.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0122_df-s04-d3_dw_0_20250918T214753_reco_stage1_20250919T012028_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": 1758244829.0,
"core.end_time": 1758244829.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39499
],
"core.runs_subruns": [
3949900001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039499_0122_df-s04-d3_dw_0_20250918T214753.hdf5"
}
]
} 