Jobsub ID 41149.34@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=97 anode=0
[22:22:46.105] D [ glue ] <FrameFanin:nfsp> EOS at call=97 with 8
[22:22:46.105] D [ glue ] frame sink sees EOS
[22:22:46.105] D [ pgraph ] <Pgrapher:> graph execution complete
[22:22:46.105] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9 sec
[22:22:46.105] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.95 sec
[22:22:46.105] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.81 sec
[22:22:46.105] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.47 sec
[22:22:46.105] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[22:22:46.105] I [ timer ] Timer: WireCell::Aux::Resampler : 0.3 sec
[22:22:46.105] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[22:22:46.105] I [ timer ] Timer: WireCell::Aux::Resampler : 0.29 sec
[22:22:46.105] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[22:22:46.105] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[22:22:46.105] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:22:46.105] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:22:46.105] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[22:22:46.105] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:22:46.105] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:22:46.105] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:22:46.105] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[22:22:46.105] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:22:46.105] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[22:22:46.105] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:22:46.105] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[22:22:46.105] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[22:22:46.105] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[22:22:46.105] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[22:22:46.105] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[22:22:46.105] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[22:22:46.105] I [ timer ] Timer: Total node execution : 30.419999549165368 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 19911 traces tagged "gauss"
FrameSaver: q=5.18646e+06 n=525564 tag=gauss
wclsFrameSaver: saving 25922 traces tagged "wiener"
FrameSaver: q=5.61022e+06 n=507283 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1641 XUs and 1197 XVs -> 55 XUVs
C:0 T:1 678 XUs and 641 XVs -> 42 XUVs
C:0 T:2 723 XUs and 581 XVs -> 64 XUVs
C:0 T:3 260 XUs and 297 XVs -> 20 XUVs
C:0 T:4 10435 XUs and 9567 XVs -> 793 XUVs
C:0 T:5 14998 XUs and 14911 XVs -> 1539 XUVs
C:0 T:6 1537 XUs and 1815 XVs -> 73 XUVs
C:0 T:7 705 XUs and 602 XVs -> 48 XUVs
2634 XUVs total
1077 collection wire objects
2634 potential space points
Neighbour search...
97400 tests to find 21882 neighbours
Iterating with no regularization...
Begin: 8.33913e+09
0 7.80265e+09
1 7.75993e+09
2 7.75806e+09
Now with regularization...
Begin: 7.59922e+09
0 7.59837e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
17-Sep-2025 22:23:12 CEST Closed output file "np02vd_raw_run039433_0024_df-s05-d0_dw_0_20250917T163530_reco_stage1_20250917T202312_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 71.9164 140.992 1521.38 101.871 210.047 49
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 4.2113e-05 6.05534e-05 0.000107916 5.8619e-05 1.13692e-05 49
produce:tpcrawdecoder:PDVDTPCReader 13.8669 39.1891 61.6358 39.1817 12.335 49
produce:triggerrawdecoder:PDVDTriggerReader4 0.289867 0.36201 1.00922 0.324866 0.121547 49
produce:pdvddaphne:DAPHNEReaderPDVD 5.03646 10.5785 17.2695 10.1063 3.2781 49
produce:ophit:OpHitFinder 0.0320827 0.0374336 0.0410961 0.0378295 0.00225976 49
produce:opflash:OpFlashFinderVerticalDrift 0.00503966 0.00906251 0.0137048 0.00892238 0.00200599 49
produce:wclsdatavd:WireCellToolkit 26.1727 34.5346 81.7641 28.8722 12.7408 49
produce:gaushit:GausHitFinder 0.224949 0.564611 1.84556 0.502186 0.283047 49
produce:nhitsfilter:NumberOfHitsFilter 7.248e-05 0.000142907 0.000373195 0.000127794 6.26341e-05 49
produce:reco3d:SpacePointSolver 0.454858 5.02513 32.7156 4.00964 4.86727 49
produce:hitpdune:DisambigFromSpacePoints 0.0161125 0.107689 1.42534 0.0599915 0.206778 49
produce:pandora:StandardPandora 2.13838 48.2645 1330.56 10.7991 193.55 49
produce:pandoraTrack:LArPandoraTrackCreation 0.0223113 0.360428 4.41027 0.235336 0.622419 49
produce:pandoraGnocalo:GnocchiCalorimetry 0.00235758 0.0113585 0.0308195 0.0109936 0.00458457 49
[art]:TriggerResults:TriggerResultInserter 1.3182e-05 1.85563e-05 3.8088e-05 1.7949e-05 3.9826e-06 49
end_path:out1:RootOutput 2.647e-06 5.4422e-06 1.4135e-05 5.504e-06 1.88225e-06 49
end_path:out1:RootOutput(write) 1.58536 1.93476 2.85177 1.86947 0.248115 49
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6698.24 MB
Peak resident set size usage (VmHWM): 4811.51 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0024_df-s05-d0_dw_0_20250917T163530_reco_stage1_20250917T202312_keepup.root
\tHists: np02vd_raw_run039433_0024_df-s05-d0_dw_0_20250917T163530_reco_stage1_20250917T202313_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0024_df-s05-d0_dw_0_20250917T163530_reco_stage1_20250917T202312_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0024_df-s05-d0_dw_0_20250917T163530_reco_stage1_20250917T202312_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": 1758140593.0,
"core.end_time": 1758140593.0,
"core.events": [
6924,
6930,
6936,
6942,
6948,
6954,
6960,
6966,
6972,
6978,
6984,
6990,
6996,
7002,
7008,
7014,
7020,
7026,
7032,
7038,
7044,
7050,
7056,
7062,
7068,
7074,
7080,
7086,
7092,
7098,
7104,
7110,
7116,
7122,
7128,
7134,
7140,
7146,
7152,
7158,
7164,
7170,
7176,
7182,
7188,
7194,
7200,
7206,
7212
],
"core.event_count": 49,
"core.first_event_number": 6924,
"core.last_event_number": 7212,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0024_df-s05-d0_dw_0_20250917T163530.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0024_df-s05-d0_dw_0_20250917T163530_reco_stage1_20250917T202313_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": 1758140593.0,
"core.end_time": 1758140593.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39433
],
"core.runs_subruns": [
3943300001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039433_0024_df-s05-d0_dw_0_20250917T163530.hdf5"
}
]
} 