Jobsub ID 48994.33@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
mpler:resmp0> EOS at call=79
[21:56:12.538] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=79 anode=0
[21:56:12.538] D [ glue ] <FrameFanin:nfsp> EOS at call=79 with 8
[21:56:12.538] D [ glue ] frame sink sees EOS
[21:56:12.538] D [ pgraph ] <Pgrapher:> graph execution complete
[21:56:12.539] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.59 sec
[21:56:12.539] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.68 sec
[21:56:12.539] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.65 sec
[21:56:12.539] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.24 sec
[21:56:12.539] I [ timer ] Timer: WireCell::Aux::Resampler : 0.32 sec
[21:56:12.539] I [ timer ] Timer: WireCell::Aux::Resampler : 0.32 sec
[21:56:12.539] I [ timer ] Timer: WireCell::Aux::Resampler : 0.32 sec
[21:56:12.539] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[21:56:12.539] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[21:56:12.539] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:56:12.539] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:56:12.539] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[21:56:12.539] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:56:12.539] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:56:12.539] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[21:56:12.539] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:56:12.539] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:56:12.539] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[21:56:12.539] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:56:12.539] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[21:56:12.539] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:56:12.539] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[21:56:12.539] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[21:56:12.539] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[21:56:12.539] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[21:56:12.539] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[21:56:12.539] I [ timer ] Timer: Total node execution : 36.439999816939235 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 24201 traces tagged "gauss"
FrameSaver: q=3.51651e+06 n=524145 tag=gauss
wclsFrameSaver: saving 30547 traces tagged "wiener"
FrameSaver: q=3.7451e+06 n=499865 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 739 XUs and 1044 XVs -> 17 XUVs
C:0 T:1 1568 XUs and 1681 XVs -> 61 XUVs
C:0 T:2 549 XUs and 703 XVs -> 23 XUVs
C:0 T:3 1310 XUs and 1535 XVs -> 62 XUVs
C:0 T:4 592 XUs and 539 XVs -> 52 XUVs
C:0 T:5 524 XUs and 374 XVs -> 25 XUVs
C:0 T:6 8904 XUs and 13394 XVs -> 584 XUVs
C:0 T:7 389 XUs and 555 XVs -> 18 XUVs
842 XUVs total
655 collection wire objects
842 potential space points
Neighbour search...
15694 tests to find 4630 neighbours
Iterating with no regularization...
Begin: 1.92462e+09
0 1.88839e+09
1 1.88704e+09
Now with regularization...
Begin: 1.86676e+09
0 1.86676e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
18-Sep-2025 21:56:47 BST Closed output file "np02vd_raw_run039499_0018_df-s05-d3_dw_0_20250918T181808_reco_stage1_20250918T205647_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 61.1669 108.945 407.542 91.2896 60.2603 40
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 7.3909e-05 0.000306273 0.00550909 0.000160797 0.000835839 40
produce:tpcrawdecoder:PDVDTPCReader 7.49274 13.6691 18.7275 13.4424 2.58551 40
produce:triggerrawdecoder:PDVDTriggerReader4 0.378618 0.408254 0.789508 0.387837 0.0754236 40
produce:pdvddaphne:DAPHNEReaderPDVD 3.72841 4.57529 6.72192 4.43644 0.558187 40
produce:ophit:OpHitFinder 0.0379209 0.0602462 0.1136 0.0596171 0.011696 40
produce:opflash:OpFlashFinderVerticalDrift 0.00670539 0.0153638 0.052991 0.0143444 0.00752372 40
produce:wclsdatavd:WireCellToolkit 29.6031 56.9643 369.438 39.9331 60.0831 40
produce:gaushit:GausHitFinder 0.444535 1.12229 7.07388 0.810694 1.36192 40
produce:nhitsfilter:NumberOfHitsFilter 0.000124775 0.000268607 0.00120992 0.000213717 0.000205883 40
produce:reco3d:SpacePointSolver 2.60436 6.705 13.0288 6.33084 2.477 38
produce:hitpdune:DisambigFromSpacePoints 0.0264328 0.0942426 0.192987 0.09054 0.0464313 38
produce:pandora:StandardPandora 4.98809 22.9452 62.0161 19.1966 13.7938 38
produce:pandoraTrack:LArPandoraTrackCreation 0.0999433 0.38285 0.98713 0.331275 0.192874 38
produce:pandoraGnocalo:GnocchiCalorimetry 0.00736063 0.0217007 0.0410656 0.0214789 0.0071096 38
[art]:TriggerResults:TriggerResultInserter 2.694e-05 5.05664e-05 0.000125326 4.6703e-05 2.18067e-05 40
end_path:out1:RootOutput 5.45e-06 1.3563e-05 4.8592e-05 1.1556e-05 8.99166e-06 40
end_path:out1:RootOutput(write) 2.10047 3.45679 6.05742 3.3143 0.659205 40
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 12697.7 MB
Peak resident set size usage (VmHWM): 10755.7 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0018_df-s05-d3_dw_0_20250918T181808_reco_stage1_20250918T205647_keepup.root
\tHists: np02vd_raw_run039499_0018_df-s05-d3_dw_0_20250918T181808_reco_stage1_20250918T205648_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0018_df-s05-d3_dw_0_20250918T181808_reco_stage1_20250918T205647_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0018_df-s05-d3_dw_0_20250918T181808_reco_stage1_20250918T205647_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": 1758229010.0,
"core.end_time": 1758229010.0,
"core.events": [
4419,
4425,
4431,
4437,
4443,
4449,
4455,
4461,
4467,
4473,
4479,
4485,
4491,
4497,
4503,
4509,
4515,
4521,
4527,
4533,
4539,
4545,
4551,
4557,
4563,
4569,
4575,
4581,
4587,
4593,
4599,
4605,
4611,
4617,
4623,
4629,
4635,
4641,
4647,
4653
],
"core.event_count": 40,
"core.first_event_number": 4419,
"core.last_event_number": 4653,
"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_0018_df-s05-d3_dw_0_20250918T181808.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0018_df-s05-d3_dw_0_20250918T181808_reco_stage1_20250918T205648_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": 1758229010.0,
"core.end_time": 1758229010.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_0018_df-s05-d3_dw_0_20250918T181808.hdf5"
}
]
} 