Jobsub ID 236737.116@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
Proc:anode0sigproc0> EOS at call=45 anode=0
[18:34:48.865] D [ glue ] <FrameFanin:nfsp> EOS at call=45 with 8
[18:34:48.865] D [ glue ] frame sink sees EOS
[18:34:48.865] D [ pgraph ] <Pgrapher:> graph execution complete
[18:34:48.865] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 23.78 sec
[18:34:48.865] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 21.7 sec
[18:34:48.865] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.37 sec
[18:34:48.865] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 11.25 sec
[18:34:48.865] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.97 sec
[18:34:48.865] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.67 sec
[18:34:48.865] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.56 sec
[18:34:48.865] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.89 sec
[18:34:48.865] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[18:34:48.865] I [ timer ] Timer: WireCell::Aux::Resampler : 0.26 sec
[18:34:48.865] I [ timer ] Timer: WireCell::Aux::Resampler : 0.25 sec
[18:34:48.865] I [ timer ] Timer: WireCell::Aux::Resampler : 0.24 sec
[18:34:48.865] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.05 sec
[18:34:48.865] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[18:34:48.865] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[18:34:48.865] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[18:34:48.865] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[18:34:48.865] I [ timer ] Timer: WireCell::Gen::Retagger : 0.01 sec
[18:34:48.865] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:34:48.865] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:34:48.865] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:34:48.865] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[18:34:48.865] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[18:34:48.865] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[18:34:48.865] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[18:34:48.865] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[18:34:48.865] I [ timer ] Timer: Total node execution : 111.3100024163723 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 55049 traces tagged "gauss"
FrameSaver: q=3.57496e+07 n=1562964 tag=gauss
wclsFrameSaver: saving 69260 traces tagged "wiener"
FrameSaver: q=3.77645e+07 n=1509428 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 14315 XUs and 28913 XVs -> 1044 XUVs
C:0 T:1 1841 XUs and 2051 XVs -> 91 XUVs
C:0 T:2 30204 XUs and 53602 XVs -> 3150 XUVs
C:0 T:3 5868 XUs and 10048 XVs -> 413 XUVs
C:0 T:4 845 XUs and 923 XVs -> 38 XUVs
C:0 T:5 438 XUs and 526 XVs -> 3 XUVs
C:0 T:6 791 XUs and 608 XVs -> 69 XUVs
C:0 T:7 779 XUs and 1088 XVs -> 50 XUVs
C:0 T:8 770 XUs and 814 XVs -> 59 XUVs
C:0 T:9 64 XUs and 151 XVs -> 5 XUVs
C:0 T:10 1370 XUs and 1543 XVs -> 151 XUVs
C:0 T:11 589 XUs and 1091 XVs -> 37 XUVs
C:0 T:12 519 XUs and 732 XVs -> 28 XUVs
C:0 T:13 1267 XUs and 1580 XVs -> 73 XUVs
C:0 T:14 507 XUs and 813 XVs -> 33 XUVs
C:0 T:15 1768 XUs and 1802 XVs -> 88 XUVs
5332 XUVs total
2726 collection wire objects
5332 potential space points
Neighbour search...
207556 tests to find 45048 neighbours
Iterating with no regularization...
Begin: 3.49765e+11
0 3.41905e+11
1 3.41172e+11
2 3.41158e+11
Now with regularization...
Begin: 3.39032e+11
0 3.39028e+11
03-Nov-2025 18:38:15 GMT Closed output file "np02vd_raw_run040346_3605_df-s04-d3_dw_0_20251103T144357_reco_stage1_20251103T183815_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 186.236 437.91 1907.3 344.008 339.837 23
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.3572e-05 8.02766e-05 0.000205628 6.8622e-05 3.15391e-05 23
produce:tpcrawdecoder:PDVDTPCReader 78.5618 216.427 364.032 216.981 74.0345 23
produce:triggerrawdecoder:PDVDTriggerReader4 0.850162 1.56248 3.03006 1.46979 0.495913 23
produce:pdvddaphne:DAPHNEReaderPDVD 0.000349856 0.000388868 0.000739205 0.00036978 7.64158e-05 23
produce:ophit:OpHitFinder 5.309e-05 0.000102195 0.000583935 6.6005e-05 0.000107668 23
produce:opflash:OpFlashFinderVerticalDrift 4.0859e-05 5.843e-05 0.000295281 4.6936e-05 5.09467e-05 23
produce:wclsdatavd:WireCellToolkit 51.7636 84.5404 125.438 79.6101 22.2214 23
produce:gaushit:GausHitFinder 0.704769 1.34469 2.54795 1.26029 0.456863 23
produce:nhitsfilter:NumberOfHitsFilter 0.000252995 0.00041634 0.000728619 0.000396641 0.000109355 23
produce:reco3d:SpacePointSolver 4.95426 12.4499 28.1177 11.4768 5.51407 23
produce:hitpdune:DisambigFromSpacePoints 0.103404 0.25553 0.859808 0.20702 0.180537 23
produce:pandora:StandardPandora 12.6917 114.659 1603.3 27.7533 324.344 23
produce:pandoraTrack:LArPandoraTrackCreation 0.53823 2.35305 13.7022 1.36425 3.14101 23
produce:pandoraGnocalo:GnocchiCalorimetry 0.018232 0.0338023 0.0708595 0.0325689 0.0114414 23
[art]:TriggerResults:TriggerResultInserter 1.8091e-05 3.51864e-05 5.4878e-05 3.2855e-05 8.24855e-06 23
end_path:out1:RootOutput 7.555e-06 1.0708e-05 2.2313e-05 9.703e-06 2.92999e-06 23
end_path:out1:RootOutput(write) 3.58429 4.2134 5.54202 4.04943 0.580448 23
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 6051.98 MB
Peak resident set size usage (VmHWM): 4011.07 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040346_3605_df-s04-d3_dw_0_20251103T144357_reco_stage1_20251103T183815_keepup.root
\tHists: np02vd_raw_run040346_3605_df-s04-d3_dw_0_20251103T144357_reco_stage1_20251103T183817_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040346_3605_df-s04-d3_dw_0_20251103T144357_reco_stage1_20251103T183815_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040346_3605_df-s04-d3_dw_0_20251103T144357_reco_stage1_20251103T183815_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": 1762195098.0,
"core.end_time": 1762195098.0,
"core.events": [
332595,
332599,
332603,
332607,
332611,
332615,
332619,
332623,
332627,
332631,
332635,
332639,
332643,
332647,
332651,
332655,
332659,
332663,
332667,
332671,
332675,
332679,
332683
],
"core.event_count": 23,
"core.first_event_number": 332595,
"core.last_event_number": 332683,
"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_3605_df-s04-d3_dw_0_20251103T144357.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040346_3605_df-s04-d3_dw_0_20251103T144357_reco_stage1_20251103T183817_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": 1762195098.0,
"core.end_time": 1762195098.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_3605_df-s04-d3_dw_0_20251103T144357.hdf5"
}
]
} 