Jobsub ID 48219.5@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
] D [ aux ] <Resampler:resmp0> EOS at call=95
[14:31:49.183] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=95 anode=0
[14:31:49.183] D [ glue ] <FrameFanin:nfsp> EOS at call=95 with 8
[14:31:49.183] D [ glue ] frame sink sees EOS
[14:31:49.183] D [ pgraph ] <Pgrapher:> graph execution complete
[14:31:49.183] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.49 sec
[14:31:49.183] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.37 sec
[14:31:49.183] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.63 sec
[14:31:49.183] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.23 sec
[14:31:49.183] I [ timer ] Timer: WireCell::Aux::Resampler : 0.58 sec
[14:31:49.183] I [ timer ] Timer: WireCell::Aux::Resampler : 0.54 sec
[14:31:49.183] I [ timer ] Timer: WireCell::Aux::Resampler : 0.52 sec
[14:31:49.183] I [ timer ] Timer: WireCell::Aux::Resampler : 0.49 sec
[14:31:49.183] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[14:31:49.183] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[14:31:49.183] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[14:31:49.183] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[14:31:49.183] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:31:49.183] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:31:49.183] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[14:31:49.183] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:31:49.183] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:31:49.183] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[14:31:49.183] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:31:49.183] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[14:31:49.183] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:31:49.183] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[14:31:49.183] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[14:31:49.183] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[14:31:49.183] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[14:31:49.183] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[14:31:49.183] I [ timer ] Timer: Total node execution : 33.87999978475273 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 15459 traces tagged "gauss"
FrameSaver: q=2.7693e+06 n=341995 tag=gauss
wclsFrameSaver: saving 19073 traces tagged "wiener"
FrameSaver: q=2.92043e+06 n=328942 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 5000 XUs and 6582 XVs -> 439 XUVs
C:0 T:1 698 XUs and 694 XVs -> 46 XUVs
C:0 T:2 1722 XUs and 1469 XVs -> 75 XUVs
C:0 T:3 1168 XUs and 1081 XVs -> 35 XUVs
C:0 T:4 570 XUs and 662 XVs -> 22 XUVs
C:0 T:5 10 XUs and 14 XVs -> 2 XUVs
C:0 T:6 283 XUs and 466 XVs -> 21 XUVs
C:0 T:7 17 XUs and 19 XVs -> 0 XUVs
640 XUVs total
439 collection wire objects
640 potential space points
Neighbour search...
12304 tests to find 3278 neighbours
Iterating with no regularization...
Begin: 2.84224e+09
0 2.82358e+09
1 2.82342e+09
Now with regularization...
Begin: 2.76984e+09
0 2.76984e+09
18-Sep-2025 14:32:07 CEST Closed output file "np02vd_raw_run039433_0241_df-s05-d1_dw_0_20250918T011944_reco_stage1_20250918T123207_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 55.3696 371.034 13694.5 80.5763 1943.7 48
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.805e-05 0.000132528 0.000494744 0.000109411 7.4146e-05 48
produce:tpcrawdecoder:PDVDTPCReader 5.59229 7.48939 13.409 6.88941 1.75266 48
produce:triggerrawdecoder:PDVDTriggerReader4 0.174902 0.188746 0.435432 0.179631 0.0393421 48
produce:pdvddaphne:DAPHNEReaderPDVD 2.80045 3.71129 5.11328 3.78346 0.560001 48
produce:ophit:OpHitFinder 0.036296 0.0537606 0.0815858 0.0507618 0.0116733 48
produce:opflash:OpFlashFinderVerticalDrift 0.00426036 0.0119079 0.0316148 0.0113077 0.00431784 48
produce:wclsdatavd:WireCellToolkit 28.3457 46.3242 210.814 37.0462 27.6324 48
produce:gaushit:GausHitFinder 0.327844 0.776432 4.55864 0.655598 0.590648 48
produce:nhitsfilter:NumberOfHitsFilter 9.666e-05 0.000222798 0.000895606 0.000191341 0.000145051 48
produce:reco3d:SpacePointSolver 2.12894 6.84594 65.6999 5.41029 8.84648 48
produce:hitpdune:DisambigFromSpacePoints 0.0314968 0.123938 1.71156 0.0763888 0.238619 48
produce:pandora:StandardPandora 6.28208 301.397 13386.5 16.9755 1908.81 48
produce:pandoraTrack:LArPandoraTrackCreation 0.132999 0.549209 9.05891 0.316314 1.26559 48
produce:pandoraGnocalo:GnocchiCalorimetry 0.00865245 0.0224676 0.148333 0.0174307 0.0219909 48
[art]:TriggerResults:TriggerResultInserter 1.969e-05 3.82653e-05 0.00012703 3.0785e-05 1.96117e-05 48
end_path:out1:RootOutput 3.83e-06 8.8515e-06 3.648e-05 7.9905e-06 5.51541e-06 48
end_path:out1:RootOutput(write) 1.99437 3.51494 12.5372 2.97033 2.22377 48
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 9601.53 MB
Peak resident set size usage (VmHWM): 7707.14 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039433_0241_df-s05-d1_dw_0_20250918T011944_reco_stage1_20250918T123207_keepup.root
\tHists: np02vd_raw_run039433_0241_df-s05-d1_dw_0_20250918T011944_reco_stage1_20250918T123208_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039433_0241_df-s05-d1_dw_0_20250918T011944_reco_stage1_20250918T123207_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039433_0241_df-s05-d1_dw_0_20250918T011944_reco_stage1_20250918T123207_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": 1758198730.0,
"core.end_time": 1758198730.0,
"core.events": [
69079,
69085,
69091,
69097,
69103,
69109,
69115,
69121,
69127,
69133,
69139,
69145,
69151,
69157,
69163,
69169,
69175,
69181,
69187,
69193,
69199,
69205,
69211,
69217,
69223,
69229,
69235,
69241,
69247,
69253,
69259,
69265,
69271,
69277,
69283,
69289,
69295,
69301,
69307,
69313,
69319,
69325,
69331,
69337,
69343,
69349,
69355,
69361
],
"core.event_count": 48,
"core.first_event_number": 69079,
"core.last_event_number": 69361,
"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_0241_df-s05-d1_dw_0_20250918T011944.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039433_0241_df-s05-d1_dw_0_20250918T011944_reco_stage1_20250918T123208_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": 1758198730.0,
"core.end_time": 1758198730.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_0241_df-s05-d1_dw_0_20250918T011944.hdf5"
}
]
} 