Jobsub ID 236577.63@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
de=0
[12:40:35.000] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[12:40:35.000] D [ glue ] frame sink sees EOS
[12:40:35.000] D [ pgraph ] <Pgrapher:> graph execution complete
[12:40:35.000] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.48 sec
[12:40:35.000] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.34 sec
[12:40:35.000] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.17 sec
[12:40:35.001] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.06 sec
[12:40:35.001] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.86 sec
[12:40:35.001] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.8 sec
[12:40:35.001] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.47 sec
[12:40:35.001] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.62 sec
[12:40:35.001] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[12:40:35.001] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[12:40:35.001] I [ timer ] Timer: WireCell::Aux::Resampler : 0.25 sec
[12:40:35.001] I [ timer ] Timer: WireCell::Aux::Resampler : 0.25 sec
[12:40:35.001] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:40:35.001] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[12:40:35.001] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:40:35.001] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[12:40:35.001] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:40:35.001] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:40:35.001] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:40:35.001] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:40:35.001] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[12:40:35.001] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:40:35.001] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:40:35.001] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[12:40:35.001] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[12:40:35.001] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[12:40:35.001] I [ timer ] Timer: Total node execution : 63.86000017076731 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 43395 traces tagged "gauss"
FrameSaver: q=1.0754e+07 n=894637 tag=gauss
wclsFrameSaver: saving 54283 traces tagged "wiener"
FrameSaver: q=1.17192e+07 n=856692 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 312 XUs and 462 XVs -> 19 XUVs
C:0 T:1 674 XUs and 867 XVs -> 26 XUVs
C:0 T:2 1175 XUs and 1149 XVs -> 41 XUVs
C:0 T:3 262 XUs and 317 XVs -> 22 XUVs
C:0 T:4 1092 XUs and 1258 XVs -> 25 XUVs
C:0 T:5 664 XUs and 893 XVs -> 31 XUVs
C:0 T:6 7310 XUs and 7557 XVs -> 747 XUVs
C:0 T:7 5274 XUs and 4993 XVs -> 403 XUVs
C:0 T:8 1115 XUs and 1384 XVs -> 127 XUVs
C:0 T:9 4326 XUs and 3619 XVs -> 337 XUVs
C:0 T:10 318 XUs and 439 XVs -> 54 XUVs
C:0 T:11 1415 XUs and 1925 XVs -> 91 XUVs
C:0 T:12 171 XUs and 433 XVs -> 9 XUVs
C:0 T:13 27 XUs and 46 XVs -> 1 XUVs
C:0 T:14 232 XUs and 294 XVs -> 26 XUVs
C:0 T:15 813 XUs and 1079 XVs -> 60 XUVs
2019 XUVs total
1253 collection wire objects
2019 potential space points
Neighbour search...
62409 tests to find 17626 neighbours
Iterating with no regularization...
Begin: 1.95023e+10
0 1.93237e+10
1 1.93145e+10
Now with regularization...
Begin: 1.88899e+10
0 1.88897e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
03-Nov-2025 12:41:25 CET Closed output file "np02vd_raw_run040346_3112_df-s04-d3_dw_0_20251103T073432_reco_stage1_20251103T114125_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 112.81 390.599 3600.22 143.934 827.091 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.658e-05 7.52665e-05 0.00020789 6.5489e-05 3.07667e-05 23
produce:tpcrawdecoder:PDVDTPCReader 13.3537 15.4653 20.9189 15.1566 1.45195 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.472773 0.50548 0.758953 0.490602 0.0576173 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000386859 0.000437403 0.000795787 0.000416679 7.99234e-05 23
produce:ophit:OpHitFinder 5.531e-05 0.000112069 0.000592828 9.1229e-05 0.000106009 23
produce:opflash:OpFlashFinderVerticalDrift 4.524e-05 7.2997e-05 0.000303179 5.908e-05 5.05735e-05 23
produce:wclsdatavd:WireCellToolkit 58.3373 81.879 185.037 67.8654 32.0386 23
produce:gaushit:GausHitFinder 0.983629 1.43675 3.58541 1.24812 0.692763 23
produce:nhitsfilter:NumberOfHitsFilter 0.000255569 0.000332819 0.000605418 0.000292539 9.95966e-05 23
produce:reco3d:SpacePointSolver 9.47722 17.021 80.9352 11.4755 16.0541 23
produce:hitpdune:DisambigFromSpacePoints 0.138725 0.303982 1.60006 0.172579 0.390687 23
produce:pandora:StandardPandora 15.8782 263.391 3320.25 27.0629 778.022 23
produce:pandoraTrack:LArPandoraTrackCreation 0.547658 1.99823 16.783 0.919452 3.5091 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.0217845 0.0378271 0.0792077 0.034918 0.013057 23
[art]:TriggerResults:TriggerResultInserter 2.237e-05 2.8149e-05 6.068e-05 2.609e-05 8.37248e-06 23
end_path:out1:RootOutput 6.34e-06 1.11939e-05 2.1789e-05 9.93e-06 4.01304e-06 23
end_path:out1:RootOutput(write) 5.28324 8.51823 14.7649 8.29676 2.01983 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 8796.16 MB
Peak resident set size usage (VmHWM): 6725.16 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3112_df-s04-d3_dw_0_20251103T073432_reco_stage1_20251103T114125_keepup.root
\tHists: np02vd_raw_run040346_3112_df-s04-d3_dw_0_20251103T073432_reco_stage1_20251103T114125_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3112_df-s04-d3_dw_0_20251103T073432_reco_stage1_20251103T114125_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3112_df-s04-d3_dw_0_20251103T073432_reco_stage1_20251103T114125_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": 1762170086.0,
"core.end_time": 1762170086.0,
"core.events": [
287219,
287223,
287227,
287231,
287235,
287239,
287243,
287247,
287251,
287255,
287259,
287263,
287267,
287271,
287275,
287279,
287283,
287287,
287291,
287295,
287299,
287303,
287307
],
"core.event_count": 23,
"core.first_event_number": 287219,
"core.last_event_number": 287307,
"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_3112_df-s04-d3_dw_0_20251103T073432.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3112_df-s04-d3_dw_0_20251103T073432_reco_stage1_20251103T114125_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": 1762170086.0,
"core.end_time": 1762170086.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_3112_df-s04-d3_dw_0_20251103T073432.hdf5"
}
]
} 