Jobsub ID 242278.44@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
ameFanin:nfsp> EOS at call=45 with 8
[11:36:12.727] D [ glue ] frame sink sees EOS
[11:36:12.727] D [ pgraph ] <Pgrapher:> graph execution complete
[11:36:12.727] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 16.37 sec
[11:36:12.727] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.23 sec
[11:36:12.727] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.66 sec
[11:36:12.727] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.63 sec
[11:36:12.727] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.73 sec
[11:36:12.727] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.94 sec
[11:36:12.727] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.89 sec
[11:36:12.727] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.36 sec
[11:36:12.727] I [ timer ] Timer: WireCell::Aux::Resampler : 0.49 sec
[11:36:12.727] I [ timer ] Timer: WireCell::Aux::Resampler : 0.46 sec
[11:36:12.727] I [ timer ] Timer: WireCell::Aux::Resampler : 0.46 sec
[11:36:12.727] I [ timer ] Timer: WireCell::Aux::Resampler : 0.45 sec
[11:36:12.727] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.04 sec
[11:36:12.727] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[11:36:12.727] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:36:12.727] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:36:12.727] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:36:12.727] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[11:36:12.727] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:36:12.727] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:36:12.727] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:36:12.727] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:36:12.727] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:36:12.727] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:36:12.727] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[11:36:12.727] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[11:36:12.727] I [ timer ] Timer: Total node execution : 91.76999947801232 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 55822 traces tagged "gauss"
FrameSaver: q=1.57208e+07 n=1265011 tag=gauss
wclsFrameSaver: saving 69934 traces tagged "wiener"
FrameSaver: q=1.70159e+07 n=1221865 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 11859 XUs and 10661 XVs -> 935 XUVs
C:0 T:1 14396 XUs and 13353 XVs -> 1033 XUVs
C:0 T:2 2988 XUs and 2653 XVs -> 165 XUVs
C:0 T:3 1654 XUs and 1737 XVs -> 75 XUVs
C:0 T:4 226 XUs and 243 XVs -> 6 XUVs
C:0 T:5 128 XUs and 170 XVs -> 6 XUVs
C:0 T:6 681 XUs and 874 XVs -> 31 XUVs
C:0 T:7 398 XUs and 404 XVs -> 11 XUVs
C:0 T:8 2015 XUs and 2427 XVs -> 131 XUVs
C:0 T:9 1470 XUs and 1805 XVs -> 78 XUVs
C:0 T:10 501 XUs and 546 XVs -> 30 XUVs
C:0 T:11 1466 XUs and 1768 XVs -> 151 XUVs
C:0 T:12 652 XUs and 1124 XVs -> 74 XUVs
C:0 T:13 782 XUs and 834 XVs -> 56 XUVs
C:0 T:14 746 XUs and 670 XVs -> 70 XUVs
C:0 T:15 1155 XUs and 1652 XVs -> 112 XUVs
2964 XUVs total
1855 collection wire objects
2964 potential space points
Neighbour search...
67434 tests to find 19320 neighbours
Iterating with no regularization...
Begin: 1.8157e+10
0 1.70945e+10
1 1.70509e+10
2 1.70496e+10
Now with regularization...
Begin: 1.6762e+10
0 1.67615e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
03-Nov-2025 11:37:31 UTC Closed output file "np02vd_raw_run040346_3249_df-s04-d1_dw_0_20251103T093324_reco_stage1_20251103T113731_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 89.046 141.375 231.502 122.741 42.2983 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.1412e-05 0.000420412 0.00671609 0.000116513 0.00134414 23
produce:tpcrawdecoder:PDVDTPCReader 15.0569 16.702 34.6723 15.6896 3.87705 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.692206 0.712135 0.733525 0.712714 0.0104069 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.00036567 0.000679249 0.00319053 0.000533154 0.000566396 23
produce:ophit:OpHitFinder 7.0282e-05 0.00024383 0.00100796 0.000160424 0.000238036 23
produce:opflash:OpFlashFinderVerticalDrift 6.5762e-05 0.0001276 0.000639286 0.000102302 0.000113571 23
produce:wclsdatavd:WireCellToolkit 52.2927 73.8215 118.708 64.6816 20.4896 23
produce:gaushit:GausHitFinder 0.696708 1.31199 2.10737 1.29779 0.365415 23
produce:nhitsfilter:NumberOfHitsFilter 0.000250166 0.000510545 0.00124635 0.00038668 0.000264314 23
produce:reco3d:SpacePointSolver 5.64161 11.4341 20.0899 12.1716 3.50211 23
produce:hitpdune:DisambigFromSpacePoints 0.084618 0.212042 0.408877 0.200981 0.0874849 23
produce:pandora:StandardPandora 8.71995 31.0951 92.6669 24.9602 22.0838 23
produce:pandoraTrack:LArPandoraTrackCreation 0.333893 1.13209 3.41338 0.89474 0.746766 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0186644 0.0383656 0.0957591 0.0347671 0.0157494 23
[art]:TriggerResults:TriggerResultInserter 2.293e-05 5.49606e-05 0.000152613 5.0361e-05 2.84096e-05 23
end_path:out1:RootOutput 5.41e-06 1.46812e-05 2.6841e-05 1.327e-05 4.95716e-06 23
end_path:out1:RootOutput(write) 3.87001 4.83582 6.21104 4.68757 0.581121 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5899.96 MB
Peak resident set size usage (VmHWM): 3511.26 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3249_df-s04-d1_dw_0_20251103T093324_reco_stage1_20251103T113731_keepup.root
\tHists: np02vd_raw_run040346_3249_df-s04-d1_dw_0_20251103T093324_reco_stage1_20251103T113731_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3249_df-s04-d1_dw_0_20251103T093324_reco_stage1_20251103T113731_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3249_df-s04-d1_dw_0_20251103T093324_reco_stage1_20251103T113731_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": 1762169852.0,
"core.end_time": 1762169852.0,
"core.events": [
300021,
300025,
300029,
300033,
300037,
300041,
300045,
300049,
300053,
300057,
300061,
300065,
300069,
300073,
300077,
300081,
300085,
300089,
300093,
300097,
300101,
300105,
300109
],
"core.event_count": 23,
"core.first_event_number": 300021,
"core.last_event_number": 300109,
"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_3249_df-s04-d1_dw_0_20251103T093324.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3249_df-s04-d1_dw_0_20251103T093324_reco_stage1_20251103T113731_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": 1762169852.0,
"core.end_time": 1762169852.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_3249_df-s04-d1_dw_0_20251103T093324.hdf5"
}
]
} 