Jobsub ID 242471.151@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
] <FrameFanin:nfsp> EOS at call=45 with 8
[18:52:16.923] D [ glue ] frame sink sees EOS
[18:52:16.923] D [ pgraph ] <Pgrapher:> graph execution complete
[18:52:16.923] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.93 sec
[18:52:16.923] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.61 sec
[18:52:16.923] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.54 sec
[18:52:16.923] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.43 sec
[18:52:16.923] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.16 sec
[18:52:16.923] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.75 sec
[18:52:16.923] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.65 sec
[18:52:16.923] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.05 sec
[18:52:16.924] I [ timer ] Timer: WireCell::Aux::Resampler : 0.39 sec
[18:52:16.924] I [ timer ] Timer: WireCell::Aux::Resampler : 0.37 sec
[18:52:16.924] I [ timer ] Timer: WireCell::Aux::Resampler : 0.33 sec
[18:52:16.924] I [ timer ] Timer: WireCell::Aux::Resampler : 0.32 sec
[18:52:16.924] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.05 sec
[18:52:16.924] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[18:52:16.924] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[18:52:16.924] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[18:52:16.924] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[18:52:16.924] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:52:16.924] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:52:16.924] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:52:16.924] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[18:52:16.924] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:52:16.924] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:52:16.924] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[18:52:16.924] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[18:52:16.924] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[18:52:16.924] I [ timer ] Timer: Total node execution : 90.61999988183379 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 43427 traces tagged "gauss"
FrameSaver: q=1.04422e+07 n=863811 tag=gauss
wclsFrameSaver: saving 53784 traces tagged "wiener"
FrameSaver: q=1.13168e+07 n=823449 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 562 XUs and 475 XVs -> 45 XUVs
C:0 T:1 12889 XUs and 12938 XVs -> 989 XUVs
C:0 T:2 170 XUs and 210 XVs -> 4 XUVs
C:0 T:3 1509 XUs and 1453 XVs -> 103 XUVs
C:0 T:4 254 XUs and 623 XVs -> 18 XUVs
C:0 T:5 257 XUs and 374 XVs -> 12 XUVs
C:0 T:6 548 XUs and 683 XVs -> 26 XUVs
C:0 T:7 536 XUs and 883 XVs -> 30 XUVs
C:0 T:8 426 XUs and 723 XVs -> 31 XUVs
C:0 T:9 1171 XUs and 1359 XVs -> 65 XUVs
C:0 T:10 1592 XUs and 1705 XVs -> 65 XUVs
C:0 T:11 1198 XUs and 1302 XVs -> 81 XUVs
C:0 T:12 516 XUs and 1001 XVs -> 42 XUVs
C:0 T:13 1922 XUs and 2113 XVs -> 101 XUVs
C:0 T:14 561 XUs and 790 XVs -> 37 XUVs
C:0 T:15 1087 XUs and 1267 XVs -> 41 XUVs
1690 XUVs total
1030 collection wire objects
1690 potential space points
Neighbour search...
53568 tests to find 12494 neighbours
Iterating with no regularization...
Begin: 7.64833e+09
0 7.32943e+09
1 7.30404e+09
2 7.30328e+09
Now with regularization...
Begin: 7.21408e+09
0 7.21353e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
03-Nov-2025 18:53:08 GMT Closed output file "np02vd_raw_run040346_3760_df-s04-d2_dw_0_20251103T170720_reco_stage1_20251103T185308_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 108.529 150.177 204.133 142.898 29.0487 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.576e-05 0.000119443 0.000329308 0.000110184 4.78818e-05 23
produce:tpcrawdecoder:PDVDTPCReader 12.9196 14.3381 21.7343 14.0481 1.80822 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.49292 0.513306 0.631019 0.500359 0.0312935 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000464931 0.000824868 0.00162045 0.000899913 0.000260313 23
produce:ophit:OpHitFinder 8.6562e-05 0.000187233 0.00111676 0.000138101 0.000200888 23
produce:opflash:OpFlashFinderVerticalDrift 6.772e-05 0.000142493 0.000779318 0.000115003 0.000137825 23
produce:wclsdatavd:WireCellToolkit 62.1301 84.0016 131.897 74.5043 18.8343 23
produce:gaushit:GausHitFinder 0.876502 1.43234 2.20305 1.41128 0.344557 23
produce:nhitsfilter:NumberOfHitsFilter 0.000340855 0.000500862 0.000729259 0.000492872 0.000107576 23
produce:reco3d:SpacePointSolver 6.02028 11.2602 18.8163 11.3615 3.1326 23
produce:hitpdune:DisambigFromSpacePoints 0.102537 0.22381 0.37111 0.213126 0.0710607 23
produce:pandora:StandardPandora 17.3074 31.0772 54.5262 29.7803 10.6876 23
produce:pandoraTrack:LArPandoraTrackCreation 0.572168 2.55248 5.07265 2.54682 1.11475 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0190817 0.0406536 0.0809796 0.0388451 0.0157356 23
[art]:TriggerResults:TriggerResultInserter 4.0368e-05 5.67039e-05 0.000110447 5.4578e-05 1.39437e-05 23
end_path:out1:RootOutput 6.986e-06 1.5091e-05 3.4473e-05 1.4006e-05 5.29043e-06 23
end_path:out1:RootOutput(write) 3.50053 4.66699 7.60204 4.10008 1.18541 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5234.25 MB
Peak resident set size usage (VmHWM): 3208.72 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3760_df-s04-d2_dw_0_20251103T170720_reco_stage1_20251103T185308_keepup.root
\tHists: np02vd_raw_run040346_3760_df-s04-d2_dw_0_20251103T170720_reco_stage1_20251103T185310_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3760_df-s04-d2_dw_0_20251103T170720_reco_stage1_20251103T185308_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3760_df-s04-d2_dw_0_20251103T170720_reco_stage1_20251103T185308_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": 1762195990.0,
"core.end_time": 1762195990.0,
"core.events": [
347178,
347182,
347186,
347190,
347194,
347198,
347202,
347206,
347210,
347214,
347218,
347222,
347226,
347230,
347234,
347238,
347242,
347246,
347250,
347254,
347258,
347262,
347266
],
"core.event_count": 23,
"core.first_event_number": 347178,
"core.last_event_number": 347266,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_3760_df-s04-d2_dw_0_20251103T170720.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3760_df-s04-d2_dw_0_20251103T170720_reco_stage1_20251103T185310_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": 1762195990.0,
"core.end_time": 1762195990.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40346
],
"core.runs_subruns": [
4034600001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040346_3760_df-s04-d2_dw_0_20251103T170720.hdf5"
}
]
} 