Jobsub ID 41739.89@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
roc ] <OmnibusSigProc:anode0sigproc0> EOS at call=97 anode=0
[12:20:41.243] D [ glue ] <FrameFanin:nfsp> EOS at call=97 with 8
[12:20:41.243] D [ glue ] frame sink sees EOS
[12:20:41.243] D [ pgraph ] <Pgrapher:> graph execution complete
[12:20:41.243] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 6.78 sec
[12:20:41.243] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.88 sec
[12:20:41.243] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.09 sec
[12:20:41.243] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 5.06 sec
[12:20:41.243] I [ timer ] Timer: WireCell::Aux::Resampler : 0.28 sec
[12:20:41.243] I [ timer ] Timer: WireCell::Aux::Resampler : 0.27 sec
[12:20:41.243] I [ timer ] Timer: WireCell::Aux::Resampler : 0.27 sec
[12:20:41.243] I [ timer ] Timer: WireCell::Aux::Resampler : 0.27 sec
[12:20:41.243] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[12:20:41.243] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[12:20:41.243] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:20:41.243] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:20:41.243] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:20:41.243] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:20:41.243] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0 sec
[12:20:41.243] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:20:41.243] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:20:41.243] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:20:41.243] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[12:20:41.243] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:20:41.243] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:20:41.243] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[12:20:41.243] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[12:20:41.243] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[12:20:41.243] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[12:20:41.243] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[12:20:41.243] I [ timer ] Timer: Total node execution : 23.920000452548265 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 20105 traces tagged "gauss"
FrameSaver: q=3.26357e+06 n=433300 tag=gauss
wclsFrameSaver: saving 25349 traces tagged "wiener"
FrameSaver: q=3.50025e+06 n=414305 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 165 XUs and 180 XVs -> 6 XUVs
C:0 T:1 108 XUs and 218 XVs -> 6 XUVs
C:0 T:2 318 XUs and 344 XVs -> 20 XUVs
C:0 T:3 228 XUs and 423 XVs -> 14 XUVs
C:0 T:4 1494 XUs and 1946 XVs -> 84 XUVs
C:0 T:5 13381 XUs and 14660 XVs -> 824 XUVs
C:0 T:6 3265 XUs and 5224 XVs -> 160 XUVs
C:0 T:7 778 XUs and 1071 XVs -> 52 XUVs
1166 XUVs total
807 collection wire objects
1166 potential space points
Neighbour search...
25338 tests to find 7068 neighbours
Iterating with no regularization...
Begin: 1.56269e+09
0 1.51712e+09
1 1.5159e+09
Now with regularization...
Begin: 1.48827e+09
0 1.48824e+09
18-Sep-2025 12:21:00 CEST Closed output file "np02vd_raw_run039433_0387_df-s05-d3_dw_0_20250918T071524_reco_stage1_20250918T102100_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 45.3721 162.539 1437.59 107.413 218.014 49
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.7262e-05 9.54033e-05 0.000308609 8.5193e-05 3.65655e-05 49
produce:tpcrawdecoder:PDVDTPCReader 9.85382 54.948 640.762 49.6623 86.8044 49
produce:triggerrawdecoder:PDVDTriggerReader4 0.576281 0.649503 0.942035 0.606483 0.0911984 49
produce:pdvddaphne:DAPHNEReaderPDVD 2.65891 9.40083 19.3167 10.4687 4.27326 49
produce:ophit:OpHitFinder 0.0250636 0.0426787 0.072705 0.040141 0.0111924 49
produce:opflash:OpFlashFinderVerticalDrift 0.00366763 0.0104537 0.0200998 0.00976785 0.0044202 49
produce:wclsdatavd:WireCellToolkit 20.1901 46.0481 775.619 25.7095 107.26 49
produce:gaushit:GausHitFinder 0.203929 0.495097 1.53757 0.447221 0.211943 49
produce:nhitsfilter:NumberOfHitsFilter 5.4592e-05 0.000270093 0.000781995 0.000226547 0.000176896 49
produce:reco3d:SpacePointSolver 1.20736 7.19703 128.646 3.91695 17.8424 49
produce:hitpdune:DisambigFromSpacePoints 0.0159054 0.0636743 0.360862 0.0460651 0.0652988 49
produce:pandora:StandardPandora 2.22316 39.0121 391.804 10.3324 91.8476 49
produce:pandoraTrack:LArPandoraTrackCreation 0.0641359 2.85081 125.267 0.188224 17.6735 49
produce:pandoraGnocalo:GnocchiCalorimetry 0.00484437 0.0139602 0.074218 0.0123331 0.00973488 49
[art]:TriggerResults:TriggerResultInserter 1.282e-05 2.19361e-05 5.0401e-05 2.008e-05 8.32481e-06 49
end_path:out1:RootOutput 3.33e-06 5.09543e-06 2.1711e-05 4.55e-06 2.71927e-06 49
end_path:out1:RootOutput(write) 1.50306 1.78259 3.36932 1.68664 0.296077 49
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4519.01 MB
Peak resident set size usage (VmHWM): 2582.91 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0387_df-s05-d3_dw_0_20250918T071524_reco_stage1_20250918T102100_keepup.root
\tHists: np02vd_raw_run039433_0387_df-s05-d3_dw_0_20250918T071524_reco_stage1_20250918T102100_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0387_df-s05-d3_dw_0_20250918T071524_reco_stage1_20250918T102100_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0387_df-s05-d3_dw_0_20250918T071524_reco_stage1_20250918T102100_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": 1758190860.0,
"core.end_time": 1758190860.0,
"core.events": [
111123,
111129,
111135,
111141,
111147,
111153,
111159,
111165,
111171,
111177,
111183,
111189,
111195,
111201,
111207,
111213,
111219,
111225,
111231,
111237,
111243,
111249,
111255,
111261,
111267,
111273,
111279,
111285,
111291,
111297,
111303,
111309,
111315,
111321,
111327,
111333,
111339,
111345,
111351,
111357,
111363,
111369,
111375,
111381,
111387,
111393,
111399,
111405,
111411
],
"core.event_count": 49,
"core.first_event_number": 111123,
"core.last_event_number": 111411,
"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_0387_df-s05-d3_dw_0_20250918T071524.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0387_df-s05-d3_dw_0_20250918T071524_reco_stage1_20250918T102100_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": 1758190860.0,
"core.end_time": 1758190860.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_0387_df-s05-d3_dw_0_20250918T071524.hdf5"
}
]
} 