Jobsub ID 236601.107@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
glue ] <FrameFanin:nfsp> EOS at call=47 with 8
[13:44:47.773] D [ glue ] frame sink sees EOS
[13:44:47.773] D [ pgraph ] <Pgrapher:> graph execution complete
[13:44:47.773] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.57 sec
[13:44:47.773] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.06 sec
[13:44:47.773] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.93 sec
[13:44:47.773] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.66 sec
[13:44:47.773] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.63 sec
[13:44:47.773] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.6 sec
[13:44:47.773] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.85 sec
[13:44:47.773] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.69 sec
[13:44:47.773] I [ timer ] Timer: WireCell::Aux::Resampler : 0.25 sec
[13:44:47.773] I [ timer ] Timer: WireCell::Aux::Resampler : 0.25 sec
[13:44:47.773] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[13:44:47.773] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[13:44:47.773] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:44:47.773] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[13:44:47.773] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:44:47.773] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:44:47.773] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[13:44:47.773] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:44:47.773] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:44:47.773] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:44:47.773] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[13:44:47.773] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:44:47.773] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:44:47.773] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[13:44:47.773] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[13:44:47.773] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[13:44:47.773] I [ timer ] Timer: Total node execution : 102.02000071294606 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 46516 traces tagged "gauss"
FrameSaver: q=1.17432e+07 n=972570 tag=gauss
wclsFrameSaver: saving 58247 traces tagged "wiener"
FrameSaver: q=1.25648e+07 n=933049 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1571 XUs and 2078 XVs -> 70 XUVs
C:0 T:1 2057 XUs and 2161 XVs -> 61 XUVs
C:0 T:2 426 XUs and 516 XVs -> 23 XUVs
C:0 T:3 3655 XUs and 5131 XVs -> 233 XUVs
C:0 T:4 2229 XUs and 3249 XVs -> 248 XUVs
C:0 T:5 354 XUs and 444 XVs -> 16 XUVs
C:0 T:6 2203 XUs and 2548 XVs -> 102 XUVs
C:0 T:7 780 XUs and 1015 XVs -> 30 XUVs
C:0 T:8 961 XUs and 1170 XVs -> 42 XUVs
C:0 T:9 1840 XUs and 2010 XVs -> 153 XUVs
C:0 T:10 209 XUs and 313 XVs -> 23 XUVs
C:0 T:11 157 XUs and 211 XVs -> 9 XUVs
C:0 T:12 30 XUs and 50 XVs -> 5 XUVs
C:0 T:13 924 XUs and 1028 XVs -> 96 XUVs
C:0 T:14 863 XUs and 947 XVs -> 42 XUVs
C:0 T:15 266 XUs and 292 XVs -> 32 XUVs
1185 XUVs total
923 collection wire objects
1185 potential space points
Neighbour search...
18171 tests to find 7252 neighbours
Iterating with no regularization...
Begin: 6.28875e+09
0 6.1608e+09
1 6.15969e+09
Now with regularization...
Begin: 6.04091e+09
0 6.04085e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
03-Nov-2025 13:45:31 CET Closed output file "np02vd_raw_run040346_3320_df-s04-d1_dw_0_20251103T103458_reco_stage1_20251103T124531_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 155.672 259.957 834.114 233.249 126.378 24
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.1806e-05 9.29058e-05 0.000232176 8.45655e-05 3.22088e-05 24
produce:tpcrawdecoder:PDVDTPCReader 49.384 80 127.732 82.9319 18.8048 24
produce:triggerrawdecoder:PDVDTriggerReader4 0.595488 0.766621 1.04198 0.733921 0.121275 24
produce:pdvddaphne:DAPHNEReaderPDVD 0.000324362 0.000385734 0.000708143 0.000374192 7.23467e-05 24
produce:ophit:OpHitFinder 7.5602e-05 0.000107967 0.00061122 8.13465e-05 0.000105511 24
produce:opflash:OpFlashFinderVerticalDrift 5.342e-05 7.71315e-05 0.000323531 6.02295e-05 5.2929e-05 24
produce:wclsdatavd:WireCellToolkit 58.4028 109.245 163.346 103.282 21.9266 24
produce:gaushit:GausHitFinder 0.889874 1.2723 1.9273 1.23405 0.300091 24
produce:nhitsfilter:NumberOfHitsFilter 0.000197644 0.000412578 0.000964085 0.000330042 0.000199468 24
produce:reco3d:SpacePointSolver 7.5014 13.6384 25.5657 12.6908 4.13883 24
produce:hitpdune:DisambigFromSpacePoints 0.117515 0.195138 0.558089 0.168842 0.0907929 24
produce:pandora:StandardPandora 13.1857 47.8389 539.468 25.3911 103.22 24
produce:pandoraTrack:LArPandoraTrackCreation 0.45989 1.53174 8.66828 1.0052 1.6458 24
produce:pandoraGnocalo:GnocchiCalorimetry 0.0230613 0.0343291 0.0581745 0.0329784 0.00799842 24
[art]:TriggerResults:TriggerResultInserter 3.0435e-05 4.62716e-05 0.000109563 3.8818e-05 1.70484e-05 24
end_path:out1:RootOutput 7.09e-06 1.00397e-05 3.5153e-05 8.5225e-06 5.44001e-06 24
end_path:out1:RootOutput(write) 4.90594 5.33029 6.22362 5.22068 0.36163 24
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5147.68 MB
Peak resident set size usage (VmHWM): 3120.85 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3320_df-s04-d1_dw_0_20251103T103458_reco_stage1_20251103T124531_keepup.root
\tHists: np02vd_raw_run040346_3320_df-s04-d1_dw_0_20251103T103458_reco_stage1_20251103T124531_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3320_df-s04-d1_dw_0_20251103T103458_reco_stage1_20251103T124531_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3320_df-s04-d1_dw_0_20251103T103458_reco_stage1_20251103T124531_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": 1762173932.0,
"core.end_time": 1762173932.0,
"core.events": [
306589,
306593,
306597,
306601,
306605,
306609,
306613,
306617,
306621,
306625,
306629,
306633,
306637,
306641,
306645,
306649,
306653,
306657,
306661,
306665,
306669,
306673,
306677,
306681
],
"core.event_count": 24,
"core.first_event_number": 306589,
"core.last_event_number": 306681,
"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_3320_df-s04-d1_dw_0_20251103T103458.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3320_df-s04-d1_dw_0_20251103T103458_reco_stage1_20251103T124531_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": 1762173932.0,
"core.end_time": 1762173932.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_3320_df-s04-d1_dw_0_20251103T103458.hdf5"
}
]
} 