Jobsub ID 42563.80@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
hsel0> see EOS at call=39
[00:44:25.655] D [ aux ] <Resampler:resmp0> EOS at call=79
[00:44:25.655] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=79 anode=0
[00:44:25.655] D [ glue ] <FrameFanin:nfsp> EOS at call=79 with 8
[00:44:25.655] D [ glue ] frame sink sees EOS
[00:44:25.655] D [ pgraph ] <Pgrapher:> graph execution complete
[00:44:25.655] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.07 sec
[00:44:25.655] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.54 sec
[00:44:25.655] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.97 sec
[00:44:25.655] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 4.91 sec
[00:44:25.655] I [ timer ] Timer: WireCell::Aux::Resampler : 0.16 sec
[00:44:25.655] I [ timer ] Timer: WireCell::Aux::Resampler : 0.16 sec
[00:44:25.655] I [ timer ] Timer: WireCell::Aux::Resampler : 0.15 sec
[00:44:25.655] I [ timer ] Timer: WireCell::Aux::Resampler : 0.15 sec
[00:44:25.656] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[00:44:25.656] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:44:25.656] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:44:25.656] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:44:25.656] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:44:25.656] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:44:25.656] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:44:25.656] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:44:25.656] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[00:44:25.656] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:44:25.656] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:44:25.656] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:44:25.656] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:44:25.656] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[00:44:25.656] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[00:44:25.656] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[00:44:25.656] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:44:25.656] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[00:44:25.656] I [ timer ] Timer: Total node execution : 22.11999977566302 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 26980 traces tagged "gauss"
FrameSaver: q=5.47756e+06 n=622480 tag=gauss
wclsFrameSaver: saving 34766 traces tagged "wiener"
FrameSaver: q=5.95913e+06 n=597057 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 6738 XUs and 6821 XVs -> 435 XUVs
C:0 T:1 1344 XUs and 1444 XVs -> 66 XUVs
C:0 T:2 23418 XUs and 22239 XVs -> 1940 XUVs
C:0 T:3 689 XUs and 722 XVs -> 30 XUVs
C:0 T:4 725 XUs and 652 XVs -> 38 XUVs
C:0 T:5 451 XUs and 609 XVs -> 21 XUVs
C:0 T:6 1314 XUs and 1770 XVs -> 112 XUVs
C:0 T:7 761 XUs and 1008 XVs -> 37 XUVs
2679 XUVs total
1432 collection wire objects
2679 potential space points
Neighbour search...
114463 tests to find 25672 neighbours
Iterating with no regularization...
Begin: 7.21423e+09
0 6.74812e+09
1 6.72434e+09
2 6.72404e+09
Now with regularization...
Begin: 6.66241e+09
0 6.66233e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
19-Sep-2025 00:44:56 CEST Closed output file "np02vd_raw_run039499_0037_df-s04-d2_dw_0_20250918T185652_reco_stage1_20250918T224456_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 41.0825 213.553 5536.19 63.3176 854.506 40
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.963e-05 9.23017e-05 0.00017395 8.7615e-05 2.62176e-05 40
produce:tpcrawdecoder:PDVDTPCReader 5.09822 6.00187 7.63005 5.77705 0.748025 40
produce:triggerrawdecoder:PDVDTriggerReader4 0.264803 0.268794 0.276048 0.267867 0.00276354 40
produce:pdvddaphne:DAPHNEReaderPDVD 2.36307 3.06184 3.47449 3.06581 0.258607 40
produce:ophit:OpHitFinder 0.034818 0.0515861 0.0696335 0.0511447 0.00774978 40
produce:opflash:OpFlashFinderVerticalDrift 0.00508919 0.0148136 0.135428 0.0116796 0.019677 40
produce:wclsdatavd:WireCellToolkit 21.083 33.8116 54.3289 30.3633 8.65247 40
produce:gaushit:GausHitFinder 0.190411 0.601982 1.64416 0.603226 0.231007 40
produce:nhitsfilter:NumberOfHitsFilter 5.531e-05 0.000144222 0.000577991 0.000130785 9.09684e-05 40
produce:reco3d:SpacePointSolver 1.11004 5.95008 42.6947 4.65404 6.26342 40
produce:hitpdune:DisambigFromSpacePoints 0.0114485 0.08165 0.752258 0.0593486 0.114017 40
produce:pandora:StandardPandora 2.43909 160.567 5418.13 15.7651 843.829 40
produce:pandoraTrack:LArPandoraTrackCreation 0.076999 0.389062 5.19519 0.245705 0.780128 40
produce:pandoraGnocalo:GnocchiCalorimetry 0.00721201 0.0157179 0.0481025 0.0147518 0.00654111 40
[art]:TriggerResults:TriggerResultInserter 1.405e-05 2.2902e-05 3.961e-05 2.264e-05 4.94711e-06 40
end_path:out1:RootOutput 2.96e-06 7.79953e-06 1.899e-05 7.3e-06 3.0715e-06 40
end_path:out1:RootOutput(write) 1.83929 2.72315 3.86965 2.69468 0.380561 40
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5271.7 MB
Peak resident set size usage (VmHWM): 3372.06 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0037_df-s04-d2_dw_0_20250918T185652_reco_stage1_20250918T224456_keepup.root
\tHists: np02vd_raw_run039499_0037_df-s04-d2_dw_0_20250918T185652_reco_stage1_20250918T224456_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0037_df-s04-d2_dw_0_20250918T185652_reco_stage1_20250918T224456_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039499_0037_df-s04-d2_dw_0_20250918T185652_reco_stage1_20250918T224456_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": 1758235496.0,
"core.end_time": 1758235496.0,
"core.events": [
9025,
9031,
9037,
9043,
9049,
9055,
9061,
9067,
9073,
9079,
9085,
9091,
9097,
9103,
9109,
9115,
9121,
9127,
9133,
9139,
9145,
9151,
9157,
9163,
9169,
9175,
9181,
9187,
9193,
9199,
9205,
9211,
9217,
9223,
9229,
9235,
9241,
9247,
9253,
9259
],
"core.event_count": 40,
"core.first_event_number": 9025,
"core.last_event_number": 9259,
"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_0037_df-s04-d2_dw_0_20250918T185652.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039499_0037_df-s04-d2_dw_0_20250918T185652_reco_stage1_20250918T224456_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": 1758235496.0,
"core.end_time": 1758235496.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_0037_df-s04-d2_dw_0_20250918T185652.hdf5"
}
]
} 