Jobsub ID 242471.72@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
] <FrameFanin:nfsp> EOS at call=45 with 8
[21:34:20.785] D [ glue ] frame sink sees EOS
[21:34:20.785] D [ pgraph ] <Pgrapher:> graph execution complete
[21:34:20.785] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 14.35 sec
[21:34:20.786] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 13.47 sec
[21:34:20.786] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 12.46 sec
[21:34:20.786] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.87 sec
[21:34:20.786] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.72 sec
[21:34:20.786] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.39 sec
[21:34:20.786] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.34 sec
[21:34:20.786] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.33 sec
[21:34:20.786] I [ timer ] Timer: WireCell::Aux::Resampler : 0.54 sec
[21:34:20.786] I [ timer ] Timer: WireCell::Aux::Resampler : 0.54 sec
[21:34:20.786] I [ timer ] Timer: WireCell::Aux::Resampler : 0.53 sec
[21:34:20.786] I [ timer ] Timer: WireCell::Aux::Resampler : 0.53 sec
[21:34:20.786] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[21:34:20.786] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[21:34:20.786] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[21:34:20.786] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[21:34:20.786] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:34:20.786] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:34:20.786] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:34:20.786] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[21:34:20.786] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:34:20.786] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[21:34:20.786] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:34:20.786] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[21:34:20.786] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[21:34:20.786] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[21:34:20.786] I [ timer ] Timer: Total node execution : 100.19000124186277 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 36321 traces tagged "gauss"
FrameSaver: q=1.03991e+07 n=783643 tag=gauss
wclsFrameSaver: saving 46156 traces tagged "wiener"
FrameSaver: q=1.11345e+07 n=743881 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 683 XUs and 1038 XVs -> 52 XUVs
C:0 T:1 164 XUs and 205 XVs -> 3 XUVs
C:0 T:2 545 XUs and 601 XVs -> 11 XUVs
C:0 T:3 507 XUs and 832 XVs -> 16 XUVs
C:0 T:4 719 XUs and 721 XVs -> 25 XUVs
C:0 T:5 7522 XUs and 12711 XVs -> 595 XUVs
C:0 T:6 228 XUs and 257 XVs -> 11 XUVs
C:0 T:7 1733 XUs and 2287 XVs -> 144 XUVs
C:0 T:8 481 XUs and 1120 XVs -> 33 XUVs
C:0 T:9 1581 XUs and 1487 XVs -> 98 XUVs
C:0 T:10 152 XUs and 243 XVs -> 17 XUVs
C:0 T:11 715 XUs and 673 XVs -> 33 XUVs
C:0 T:12 869 XUs and 930 XVs -> 55 XUVs
C:0 T:13 248 XUs and 421 XVs -> 19 XUVs
C:0 T:14 384 XUs and 410 XVs -> 24 XUVs
C:0 T:15 1337 XUs and 1654 XVs -> 71 XUVs
1207 XUVs total
893 collection wire objects
1207 potential space points
Neighbour search...
20707 tests to find 5458 neighbours
Iterating with no regularization...
Begin: 2.98194e+10
0 2.90101e+10
1 2.89494e+10
2 2.8949e+10
Now with regularization...
Begin: 2.88096e+10
0 2.88094e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
03-Nov-2025 21:35:00 GMT Closed output file "np02vd_raw_run040346_3748_df-s04-d2_dw_0_20251103T165614_reco_stage1_20251103T213500_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 214.719 567.823 5471.48 255.855 1098.4 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 0.000100599 0.000158294 0.000267563 0.000142758 4.24102e-05 23
produce:tpcrawdecoder:PDVDTPCReader 67.6059 124.362 226.493 121.181 33.5854 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.763646 1.00007 1.4886 0.930829 0.191705 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000443403 0.000588734 0.001164 0.000529425 0.00016859 23
produce:ophit:OpHitFinder 0.0001385 0.000238798 0.000889982 0.000210907 0.000146188 23
produce:opflash:OpFlashFinderVerticalDrift 7.8498e-05 0.00016989 0.000498827 0.000144041 0.000101943 23
produce:wclsdatavd:WireCellToolkit 63.0883 90.7708 192.184 83.1127 31.1709 23
produce:gaushit:GausHitFinder 1.00978 1.8718 5.58142 1.65874 0.925065 23
produce:nhitsfilter:NumberOfHitsFilter 0.000320362 0.000593963 0.0016679 0.000496543 0.000283447 23
produce:reco3d:SpacePointSolver 7.33935 18.3925 101.21 13.6563 18.2881 23
produce:hitpdune:DisambigFromSpacePoints 0.115474 0.368628 2.57016 0.236636 0.485018 23
produce:pandora:StandardPandora 12.9702 321.218 4977.74 34.2205 1042.42 23
produce:pandoraTrack:LArPandoraTrackCreation 0.621147 3.53921 32.9898 1.75344 6.42274 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.031297 0.0572907 0.150518 0.051136 0.0245476 23
[art]:TriggerResults:TriggerResultInserter 5.0034e-05 9.44731e-05 0.000170611 8.9889e-05 2.39941e-05 23
end_path:out1:RootOutput 1.1532e-05 2.39472e-05 4.8121e-05 2.16e-05 9.5813e-06 23
end_path:out1:RootOutput(write) 5.20315 6.13248 8.16244 5.81492 0.772274 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 9252.66 MB
Peak resident set size usage (VmHWM): 7185.7 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3748_df-s04-d2_dw_0_20251103T165614_reco_stage1_20251103T213500_keepup.root
\tHists: np02vd_raw_run040346_3748_df-s04-d2_dw_0_20251103T165614_reco_stage1_20251103T213500_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3748_df-s04-d2_dw_0_20251103T165614_reco_stage1_20251103T213500_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3748_df-s04-d2_dw_0_20251103T165614_reco_stage1_20251103T213500_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": 1762205702.0,
"core.end_time": 1762205702.0,
"core.events": [
346078,
346082,
346086,
346090,
346094,
346098,
346102,
346106,
346110,
346114,
346118,
346122,
346126,
346130,
346134,
346138,
346142,
346146,
346150,
346154,
346158,
346162,
346166
],
"core.event_count": 23,
"core.first_event_number": 346078,
"core.last_event_number": 346166,
"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_3748_df-s04-d2_dw_0_20251103T165614.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3748_df-s04-d2_dw_0_20251103T165614_reco_stage1_20251103T213500_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": 1762205702.0,
"core.end_time": 1762205702.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_3748_df-s04-d2_dw_0_20251103T165614.hdf5"
}
]
} 