Jobsub ID 40882.60@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
=35 with 8
[14:04:58.214] D [ glue ] frame sink sees EOS
[14:04:58.214] D [ pgraph ] <Pgrapher:> graph execution complete
[14:04:58.214] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 23.67 sec
[14:04:58.214] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 17.13 sec
[14:04:58.214] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 15.39 sec
[14:04:58.214] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.32 sec
[14:04:58.214] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.19 sec
[14:04:58.214] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.95 sec
[14:04:58.214] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.85 sec
[14:04:58.214] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.48 sec
[14:04:58.214] I [ timer ] Timer: WireCell::Aux::Resampler : 1.35 sec
[14:04:58.214] I [ timer ] Timer: WireCell::Aux::Resampler : 1.25 sec
[14:04:58.214] I [ timer ] Timer: WireCell::Aux::Resampler : 1.12 sec
[14:04:58.214] I [ timer ] Timer: WireCell::Aux::Resampler : 1.08 sec
[14:04:58.214] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.06 sec
[14:04:58.214] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[14:04:58.214] I [ timer ] Timer: WireCell::Gen::Retagger : 0.02 sec
[14:04:58.214] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[14:04:58.214] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[14:04:58.214] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[14:04:58.214] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[14:04:58.214] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:04:58.214] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:04:58.214] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[14:04:58.214] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:04:58.214] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[14:04:58.214] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[14:04:58.214] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[14:04:58.214] I [ timer ] Timer: Total node execution : 120.91999865695834 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 62729 traces tagged "gauss"
FrameSaver: q=1.29095e+07 n=1517777 tag=gauss
wclsFrameSaver: saving 79570 traces tagged "wiener"
FrameSaver: q=1.42367e+07 n=1446597 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 127 XUs and 173 XVs -> 6 XUVs
C:0 T:1 324 XUs and 430 XVs -> 14 XUVs
C:0 T:2 567 XUs and 705 XVs -> 40 XUVs
C:0 T:3 205 XUs and 179 XVs -> 6 XUVs
C:0 T:4 863 XUs and 828 XVs -> 54 XUVs
C:0 T:5 594 XUs and 544 XVs -> 21 XUVs
C:0 T:6 441 XUs and 262 XVs -> 13 XUVs
C:0 T:7 638 XUs and 681 XVs -> 24 XUVs
C:0 T:8 43529 XUs and 94135 XVs -> 14734 XUVs
C:0 T:9 24713 XUs and 39606 XVs -> 6062 XUVs
C:0 T:10 24691 XUs and 42987 XVs -> 6797 XUVs
C:0 T:11 15248 XUs and 21541 XVs -> 3437 XUVs
C:0 T:12 4458 XUs and 7877 XVs -> 1370 XUVs
C:0 T:13 13903 XUs and 16615 XVs -> 2030 XUVs
C:0 T:14 16070 XUs and 32752 XVs -> 5266 XUVs
C:0 T:15 4342 XUs and 6826 XVs -> 457 XUVs
40331 XUVs total
3773 collection wire objects
40331 potential space points
Neighbour search...
6370123 tests to find 2635810 neighbours
Iterating with no regularization...
Begin: 8.42919e+08
0 6.8219e+08
1 6.71896e+08
2 6.69777e+08
3 6.68985e+08
4 6.68587e+08
Now with regularization...
Begin: 6.49865e+08
0 6.49301e+08
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
17-Sep-2025 14:11:18 CEST Closed output file "np02vd_raw_run039279_0017_df-s02-d1_dw_0_20250902T164139_reco_stage1_20250917T121118_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 210.191 321.793 544.146 294.917 94.9784 18
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.3968e-05 0.000108108 0.000278112 9.6776e-05 4.92797e-05 18
produce:tpcrawdecoder:PDVDTPCReader 8.73173 9.82957 10.9166 9.69266 0.554489 18
produce:triggerrawdecoder:PDVDTriggerReader4 0.000991868 0.00166853 0.0051994 0.00136992 0.000939291 18
produce:pdvddaphne:DAPHNEReaderPDVD 9.85342 10.8376 11.9298 10.906 0.477619 18
produce:ophit:OpHitFinder 0.0583246 0.0898662 0.131126 0.0893811 0.0225126 18
produce:opflash:OpFlashFinderVerticalDrift 0.0147215 0.0243182 0.0384526 0.0219178 0.00659711 18
produce:wclsdatavd:WireCellToolkit 105.458 121.728 148.161 123.381 11.7587 18
produce:gaushit:GausHitFinder 1.47894 2.53548 4.03265 2.48475 0.661796 18
produce:nhitsfilter:NumberOfHitsFilter 0.000400132 0.000638984 0.000912741 0.000587879 0.000158724 18
produce:reco3d:SpacePointSolver 14.7024 26.0626 41.2957 23.8103 9.0031 18
produce:hitpdune:DisambigFromSpacePoints 0.19555 0.600049 1.15948 0.586126 0.316175 18
produce:pandora:StandardPandora 52.7004 138.464 325.194 118.504 77.9852 18
produce:pandoraTrack:LArPandoraTrackCreation 1.35288 3.61333 6.91271 3.50152 1.59249 18
produce:pandoraGnocalo:GnocchiCalorimetry 0.0288406 0.0564561 0.0868915 0.0554378 0.0164365 18
[art]:TriggerResults:TriggerResultInserter 3.2382e-05 5.00372e-05 9.3574e-05 4.40255e-05 1.72555e-05 18
end_path:out1:RootOutput 5.041e-06 1.19876e-05 3.692e-05 8.7805e-06 7.79599e-06 18
end_path:out1:RootOutput(write) 7.01271 7.89195 9.34968 7.54289 0.783576 18
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5369 MB
Peak resident set size usage (VmHWM): 3423.65 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039279_0017_df-s02-d1_dw_0_20250902T164139_reco_stage1_20250917T121118_keepup.root
\tHists: np02vd_raw_run039279_0017_df-s02-d1_dw_0_20250902T164139_reco_stage1_20250917T121118_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039279_0017_df-s02-d1_dw_0_20250902T164139_reco_stage1_20250917T121118_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039279_0017_df-s02-d1_dw_0_20250902T164139_reco_stage1_20250917T121118_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": 1758111078.0,
"core.end_time": 1758111078.0,
"core.events": [
6123,
6143,
6163,
6183,
6203,
6223,
6243,
6263,
6283,
6303,
6323,
6343,
6363,
6383,
6403,
6423,
6443,
6463
],
"core.event_count": 18,
"core.first_event_number": 6123,
"core.last_event_number": 6463,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39279
],
"core.runs_subruns": [
3927900001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039279_0017_df-s02-d1_dw_0_20250902T164139.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039279_0017_df-s02-d1_dw_0_20250902T164139_reco_stage1_20250917T121118_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": 1758111078.0,
"core.end_time": 1758111078.0,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39279
],
"core.runs_subruns": [
3927900001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039279_0017_df-s02-d1_dw_0_20250902T164139.hdf5"
}
]
} 