Jobsub ID 49050.184@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
ue ] <FrameFanin:nfsp> EOS at call=79 with 8
[00:50:03.870] D [ glue ] frame sink sees EOS
[00:50:03.870] D [ pgraph ] <Pgrapher:> graph execution complete
[00:50:03.870] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 19.7 sec
[00:50:03.870] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.3 sec
[00:50:03.870] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.1 sec
[00:50:03.870] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.08 sec
[00:50:03.870] I [ timer ] Timer: WireCell::Aux::Resampler : 0.42 sec
[00:50:03.870] I [ timer ] Timer: WireCell::Aux::Resampler : 0.42 sec
[00:50:03.870] I [ timer ] Timer: WireCell::Aux::Resampler : 0.41 sec
[00:50:03.870] I [ timer ] Timer: WireCell::Aux::Resampler : 0.41 sec
[00:50:03.870] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[00:50:03.870] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:50:03.870] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[00:50:03.870] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:50:03.870] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:50:03.870] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:50:03.870] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:50:03.870] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:50:03.870] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:50:03.870] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:50:03.870] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[00:50:03.870] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:50:03.870] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:50:03.870] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:50:03.870] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[00:50:03.870] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[00:50:03.870] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:50:03.870] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[00:50:03.870] I [ timer ] Timer: Total node execution : 59.870001224800944 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 28851 traces tagged "gauss"
FrameSaver: q=8.69312e+06 n=746066 tag=gauss
wclsFrameSaver: saving 37825 traces tagged "wiener"
FrameSaver: q=9.34875e+06 n=710723 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 275 XUs and 379 XVs -> 18 XUVs
C:0 T:1 1043 XUs and 1608 XVs -> 134 XUVs
C:0 T:2 19979 XUs and 27342 XVs -> 1677 XUVs
C:0 T:3 24396 XUs and 36492 XVs -> 2602 XUVs
C:0 T:4 622 XUs and 857 XVs -> 48 XUVs
C:0 T:5 1248 XUs and 1664 XVs -> 108 XUVs
C:0 T:6 365 XUs and 669 XVs -> 42 XUVs
C:0 T:7 464 XUs and 421 XVs -> 16 XUVs
4645 XUVs total
2652 collection wire objects
4645 potential space points
Neighbour search...
199879 tests to find 44358 neighbours
Iterating with no regularization...
Begin: 1.03334e+11
0 9.58493e+10
1 9.58289e+10
Now with regularization...
Begin: 9.47206e+10
0 9.47186e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
19-Sep-2025 00:53:56 BST Closed output file "np02vd_raw_run039499_0084_df-s04-d3_dw_0_20250918T203158_reco_stage1_20250918T235356_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 70.7503 131.286 311.427 115.663 52.2744 40
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.228e-05 0.000113067 0.000337043 9.71485e-05 4.46256e-05 40
produce:tpcrawdecoder:PDVDTPCReader 11.8955 13.631 16.6181 12.8817 1.34805 40
produce:triggerrawdecoder:PDVDTriggerReader4 0.297912 0.305441 0.388743 0.301011 0.0159664 40
produce:pdvddaphne:DAPHNEReaderPDVD 5.55813 6.17267 6.85477 6.16096 0.283402 40
produce:ophit:OpHitFinder 0.0358345 0.0605959 0.0874494 0.062157 0.00761624 40
produce:opflash:OpFlashFinderVerticalDrift 0.0101366 0.0197819 0.0342713 0.019344 0.00542386 40
produce:wclsdatavd:WireCellToolkit 38.667 61.6795 112.402 60.0202 14.7221 40
produce:gaushit:GausHitFinder 0.455173 1.04083 3.56041 0.955178 0.51091 40
produce:nhitsfilter:NumberOfHitsFilter 8.4291e-05 0.000167089 0.000336723 0.000158986 5.95478e-05 40
produce:reco3d:SpacePointSolver 2.48922 7.16843 18.7345 6.73531 3.2636 40
produce:hitpdune:DisambigFromSpacePoints 0.0216304 0.147132 0.784024 0.0994327 0.140467 40
produce:pandora:StandardPandora 5.85372 37.1397 210.94 22.5645 43.903 40
produce:pandoraTrack:LArPandoraTrackCreation 0.108461 0.513534 1.20865 0.457618 0.304456 40
produce:pandoraGnocalo:GnocchiCalorimetry 0.0109237 0.0218311 0.0399351 0.019044 0.00791616 40
[art]:TriggerResults:TriggerResultInserter 1.6592e-05 2.72159e-05 8.0989e-05 2.62145e-05 1.04935e-05 40
end_path:out1:RootOutput 4.494e-06 1.11112e-05 4.3872e-05 9.007e-06 6.94929e-06 40
end_path:out1:RootOutput(write) 2.44381 3.36635 4.8413 3.16284 0.728818 40
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 7121.4 MB
Peak resident set size usage (VmHWM): 5225.27 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0084_df-s04-d3_dw_0_20250918T203158_reco_stage1_20250918T235356_keepup.root
\tHists: np02vd_raw_run039499_0084_df-s04-d3_dw_0_20250918T203158_reco_stage1_20250918T235357_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0084_df-s04-d3_dw_0_20250918T203158_reco_stage1_20250918T235356_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0084_df-s04-d3_dw_0_20250918T203158_reco_stage1_20250918T235356_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": 1758239638.0,
"core.end_time": 1758239638.0,
"core.events": [
20498,
20504,
20510,
20516,
20522,
20528,
20534,
20540,
20546,
20552,
20558,
20564,
20570,
20576,
20582,
20588,
20594,
20600,
20606,
20612,
20618,
20624,
20630,
20636,
20642,
20648,
20654,
20660,
20666,
20672,
20678,
20684,
20690,
20696,
20702,
20708,
20714,
20720,
20726,
20732
],
"core.event_count": 40,
"core.first_event_number": 20498,
"core.last_event_number": 20732,
"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_0084_df-s04-d3_dw_0_20250918T203158.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0084_df-s04-d3_dw_0_20250918T203158_reco_stage1_20250918T235357_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": 1758239638.0,
"core.end_time": 1758239638.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_0084_df-s04-d3_dw_0_20250918T203158.hdf5"
}
]
} 