Jobsub ID 40383.3@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
oc:anode0sigproc0> EOS at call=35 anode=0
[14:47:24.522] D [ glue ] <FrameFanin:nfsp> EOS at call=35 with 8
[14:47:24.522] D [ glue ] frame sink sees EOS
[14:47:24.522] D [ pgraph ] <Pgrapher:> graph execution complete
[14:47:24.522] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.42 sec
[14:47:24.522] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.43 sec
[14:47:24.522] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.2 sec
[14:47:24.522] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.1 sec
[14:47:24.522] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 8.01 sec
[14:47:24.522] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.23 sec
[14:47:24.522] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.12 sec
[14:47:24.522] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 7.02 sec
[14:47:24.522] I [ timer ] Timer: WireCell::Aux::Resampler : 1.08 sec
[14:47:24.522] I [ timer ] Timer: WireCell::Aux::Resampler : 0.85 sec
[14:47:24.522] I [ timer ] Timer: WireCell::Aux::Resampler : 0.8 sec
[14:47:24.523] I [ timer ] Timer: WireCell::Aux::Resampler : 0.77 sec
[14:47:24.523] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[14:47:24.523] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[14:47:24.523] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:47:24.523] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:47:24.523] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:47:24.523] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:47:24.523] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:47:24.523] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[14:47:24.523] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:47:24.523] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:47:24.523] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[14:47:24.523] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[14:47:24.523] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[14:47:24.523] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[14:47:24.523] I [ timer ] Timer: Total node execution : 68.07000074535608 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 59089 traces tagged "gauss"
FrameSaver: q=1.01832e+07 n=1264914 tag=gauss
wclsFrameSaver: saving 72631 traces tagged "wiener"
FrameSaver: q=1.07635e+07 n=1215953 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 508 XUs and 630 XVs -> 22 XUVs
C:0 T:1 636 XUs and 612 XVs -> 42 XUVs
C:0 T:2 2285 XUs and 2392 XVs -> 103 XUVs
C:0 T:3 945 XUs and 1133 XVs -> 76 XUVs
C:0 T:4 644 XUs and 1082 XVs -> 32 XUVs
C:0 T:5 1316 XUs and 1120 XVs -> 49 XUVs
C:0 T:6 1514 XUs and 1579 XVs -> 142 XUVs
C:0 T:7 1278 XUs and 857 XVs -> 49 XUVs
C:0 T:8 19788 XUs and 46878 XVs -> 6558 XUVs
C:0 T:9 2143 XUs and 3040 XVs -> 186 XUVs
C:0 T:10 9547 XUs and 7918 XVs -> 545 XUVs
C:0 T:11 1620 XUs and 1814 XVs -> 97 XUVs
C:0 T:12 1002 XUs and 1612 XVs -> 109 XUVs
C:0 T:13 612 XUs and 908 XVs -> 58 XUVs
C:0 T:14 1990 XUs and 2002 XVs -> 129 XUVs
C:0 T:15 2063 XUs and 2286 XVs -> 144 XUVs
8341 XUVs total
1936 collection wire objects
8341 potential space points
Neighbour search...
1267715 tests to find 497632 neighbours
Iterating with no regularization...
Begin: 6.44176e+08
0 6.12314e+08
1 6.10823e+08
2 6.10666e+08
Now with regularization...
Begin: 5.94558e+08
0 5.94456e+08
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
16-Sep-2025 14:49:14 CEST Closed output file "np02vd_raw_run039275_0339_df-s03-d3_dw_0_20250902T022940_reco_stage1_20250916T124914_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 132.622 221.665 371.984 207.48 65.3695 18
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 6.0491e-05 9.49179e-05 0.000314114 8.0996e-05 5.71224e-05 18
produce:tpcrawdecoder:PDVDTPCReader 6.57523 9.0335 36.5868 7.50462 6.69862 18
produce:triggerrawdecoder:PDVDTriggerReader4 0.00119766 0.00163407 0.00263517 0.00159888 0.000289674 18
produce:pdvddaphne:DAPHNEReaderPDVD 4.18532 5.32223 14.4253 4.69835 2.26085 18
produce:ophit:OpHitFinder 0.0399355 0.0585885 0.0779025 0.0566734 0.00866097 18
produce:opflash:OpFlashFinderVerticalDrift 0.00734516 0.0158926 0.024346 0.015678 0.00451563 18
produce:wclsdatavd:WireCellToolkit 59.4936 74.0081 88.2984 74.2454 6.50177 18
produce:gaushit:GausHitFinder 0.892015 1.42676 2.61072 1.44816 0.39033 18
produce:nhitsfilter:NumberOfHitsFilter 0.000294964 0.000465521 0.000804171 0.000449351 0.000133058 18
produce:reco3d:SpacePointSolver 11.6986 21.4589 34.1955 21.0938 6.51074 18
produce:hitpdune:DisambigFromSpacePoints 0.170827 0.335674 0.57722 0.340792 0.109344 18
produce:pandora:StandardPandora 36.8461 102.95 232.645 86.5604 54.6272 18
produce:pandoraTrack:LArPandoraTrackCreation 0.80792 1.63951 2.82175 1.66157 0.532425 18
produce:pandoraGnocalo:GnocchiCalorimetry 0.0209676 0.0379213 0.0592097 0.0378135 0.00871244 18
[art]:TriggerResults:TriggerResultInserter 1.6921e-05 2.65287e-05 8.0371e-05 2.1626e-05 1.44677e-05 18
end_path:out1:RootOutput 7.61e-06 1.24351e-05 3.756e-05 8.98e-06 8.37944e-06 18
end_path:out1:RootOutput(write) 4.74208 5.32201 6.50356 5.29803 0.398728 18
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 5227.36 MB
Peak resident set size usage (VmHWM): 3299.95 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039275_0339_df-s03-d3_dw_0_20250902T022940_reco_stage1_20250916T124914_keepup.root
\tHists: np02vd_raw_run039275_0339_df-s03-d3_dw_0_20250902T022940_reco_stage1_20250916T124914_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039275_0339_df-s03-d3_dw_0_20250902T022940_reco_stage1_20250916T124914_keepup.root
Ran successfully
{
"name": "np02vd_raw_run039275_0339_df-s03-d3_dw_0_20250902T022940_reco_stage1_20250916T124914_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": 1758026955.0,
"core.end_time": 1758026955.0,
"core.events": [
122049,
122069,
122089,
122109,
122129,
122149,
122169,
122189,
122209,
122229,
122249,
122269,
122289,
122309,
122329,
122349,
122369,
122389
],
"core.event_count": 18,
"core.first_event_number": 122049,
"core.last_event_number": 122389,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39275
],
"core.runs_subruns": [
3927500001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039275_0339_df-s03-d3_dw_0_20250902T022940.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run039275_0339_df-s03-d3_dw_0_20250902T022940_reco_stage1_20250916T124914_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": 1758026955.0,
"core.end_time": 1758026955.0,
"core.data_stream": "physics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
39275
],
"core.runs_subruns": [
3927500001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run039275_0339_df-s03-d3_dw_0_20250902T022940.hdf5"
}
]
} 