Jobsub ID 235526.12@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
smp0> EOS at call=79
[21:40:37.761] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=79 anode=0
[21:40:37.761] D [ glue ] <FrameFanin:nfsp> EOS at call=79 with 8
[21:40:37.761] D [ glue ] frame sink sees EOS
[21:40:37.761] D [ pgraph ] <Pgrapher:> graph execution complete
[21:40:37.761] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.75 sec
[21:40:37.761] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.74 sec
[21:40:37.761] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 10.17 sec
[21:40:37.761] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 9.3 sec
[21:40:37.761] I [ timer ] Timer: WireCell::Aux::Resampler : 1.18 sec
[21:40:37.761] I [ timer ] Timer: WireCell::Aux::Resampler : 1.16 sec
[21:40:37.761] I [ timer ] Timer: WireCell::Aux::Resampler : 1.15 sec
[21:40:37.761] I [ timer ] Timer: WireCell::Aux::Resampler : 1.13 sec
[21:40:37.761] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:40:37.761] I [ timer ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[21:40:37.761] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[21:40:37.761] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[21:40:37.761] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:40:37.761] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[21:40:37.761] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:40:37.761] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:40:37.761] I [ timer ] Timer: WireCell::Gen::DumpFrames : 0 sec
[21:40:37.761] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:40:37.761] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[21:40:37.761] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:40:37.761] I [ timer ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[21:40:37.761] I [ timer ] Timer: WireCell::Gen::Retagger : 0 sec
[21:40:37.761] I [ timer ] Timer: WireCell::Gen::FrameFanout : 0 sec
[21:40:37.761] I [ timer ] Timer: wcls::RawFrameSource : 0 sec
[21:40:37.761] I [ timer ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[21:40:37.761] I [ timer ] Timer: wcls::FrameSaver : 0 sec
[21:40:37.761] I [ timer ] Timer: Total node execution : 45.6099999230355 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 15992 traces tagged "gauss"
FrameSaver: q=3.33397e+06 n=327340 tag=gauss
wclsFrameSaver: saving 20292 traces tagged "wiener"
FrameSaver: q=3.54625e+06 n=310871 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 505 XUs and 837 XVs -> 47 XUVs
C:0 T:1 776 XUs and 1089 XVs -> 62 XUVs
C:0 T:2 587 XUs and 698 XVs -> 22 XUVs
C:0 T:3 293 XUs and 382 XVs -> 17 XUVs
C:0 T:4 857 XUs and 945 XVs -> 36 XUVs
C:0 T:5 553 XUs and 474 XVs -> 18 XUVs
C:0 T:6 174 XUs and 253 XVs -> 13 XUVs
C:0 T:7 381 XUs and 445 XVs -> 13 XUVs
228 XUVs total
193 collection wire objects
228 potential space points
Neighbour search...
1580 tests to find 820 neighbours
Iterating with no regularization...
Begin: 1.07617e+08
0 1.05527e+08
1 1.05501e+08
Now with regularization...
Begin: 1.04015e+08
0 1.0401e+08
30-Oct-2025 21:40:51 GMT Closed output file "np02vd_raw_run040330_0012_df-s04-d0_dw_0_20251030T181942_reco_stage1_20251030T214050_keepup.root"
==================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
==================================================================================================================================
Full event 6.86089 103.152 207.807 135.307 69.8504 60
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read) 5.9034e-05 7.83834e-05 0.000215912 7.62105e-05 1.98833e-05 60
produce:tpcrawdecoder:PDVDTPCReader 6.23797 56.6463 125.295 70.8561 37.5959 60
produce:triggerrawdecoder:PDVDTriggerReader4 0.507902 0.613188 0.978893 0.57331 0.100487 60
produce:pdvddaphne:DAPHNEReaderPDVD 0.00046851 0.00050982 0.000981603 0.000499966 6.5001e-05 60
produce:ophit:OpHitFinder 6.2781e-05 8.2593e-05 0.00065084 7.0839e-05 7.45947e-05 60
produce:opflash:OpFlashFinderVerticalDrift 4.6655e-05 6.09591e-05 0.000405917 5.31525e-05 4.61327e-05 60
produce:wclsdatavd:WireCellToolkit 0.000514226 32.0841 51.6262 46.6775 22.7234 60
produce:gaushit:GausHitFinder 4.6719e-05 0.431779 0.967581 0.545651 0.326258 60
produce:nhitsfilter:NumberOfHitsFilter 1.4379e-05 8.63695e-05 0.000322003 9.41855e-05 5.96944e-05 60
produce:reco3d:SpacePointSolver 8.1929e-05 3.19797 7.38477 3.83408 2.53525 60
produce:hitpdune:DisambigFromSpacePoints 0.000172015 0.029248 0.0747333 0.0307217 0.0244539 60
produce:pandora:StandardPandora 0.000634018 8.22859 81.6064 6.95667 11.3886 60
produce:pandoraTrack:LArPandoraTrackCreation 0.000128504 0.154095 0.513168 0.143449 0.135298 60
produce:pandoraGnocalo:GnocchiCalorimetry 0.000135265 0.0108208 0.0249083 0.0131793 0.00817845 60
[art]:TriggerResults:TriggerResultInserter 1.514e-05 2.37953e-05 5.9652e-05 2.26545e-05 6.9509e-06 60
end_path:out1:RootOutput 3.486e-06 6.006e-06 2.7299e-05 4.452e-06 3.64573e-06 60
end_path:out1:RootOutput(write) 0.0113403 1.74175 4.40318 2.48141 1.24558 60
==================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 4045.73 MB
Peak resident set size usage (VmHWM): 2005.03 MB
Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040330_0012_df-s04-d0_dw_0_20251030T181942_reco_stage1_20251030T214050_keepup.root
\tHists: np02vd_raw_run040330_0012_df-s04-d0_dw_0_20251030T181942_reco_stage1_20251030T214052_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040330_0012_df-s04-d0_dw_0_20251030T181942_reco_stage1_20251030T214050_keepup.root
Ran successfully
{
"name": "np02vd_raw_run040330_0012_df-s04-d0_dw_0_20251030T181942_reco_stage1_20251030T214050_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": 1761860452.0,
"core.end_time": 1761860452.0,
"core.events": [
713,
714,
715,
716,
717,
718,
719,
720,
721,
722,
723,
724,
725,
726,
727,
728,
729,
730,
731,
732,
733,
734,
735,
736,
737,
738,
739,
740,
741,
742,
743,
744,
745,
746,
747,
748,
749,
750,
751,
752,
753,
754,
755,
756,
757,
758,
759,
760,
761,
762,
763,
764,
765,
766,
767,
768,
769,
770,
771,
772
],
"core.event_count": 60,
"core.first_event_number": 713,
"core.last_event_number": 772,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40330
],
"core.runs_subruns": [
4033000001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040330_0012_df-s04-d0_dw_0_20251030T181942.hdf5"
}
]
}Forming hist metadata
formed
{
"name": "np02vd_raw_run040330_0012_df-s04-d0_dw_0_20251030T181942_reco_stage1_20251030T214052_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": 1761860452.0,
"core.end_time": 1761860452.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40330
],
"core.runs_subruns": [
4033000001
],
"dune.daq_test": true,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune:np02vd_raw_run040330_0012_df-s04-d0_dw_0_20251030T181942.hdf5"
}
]
} 