Jobsub ID 236984.1@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
7359e-05 2.47359e-05 2.47359e-05 0.000117064 0 0 
----view 1: 7.73072e-05 1.71661e-05 1.71661e-05 1.71661e-05 1.72257e-05 1.71661e-05 1.71661e-05 1.71661e-05 1.71661e-05 1.71661e-05 1.72257e-05 1.71661e-05 1.71661e-05 
Begin processing the 9th record. run: 20250627 subRun: 1 event: 98 at 03-Nov-2025 18:11:15 EST
Running Ophitfinder with InputDigiType = 'recob'
Found hits: 18412!
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=6000 keeping as is
Retagger: tagging trace set: wiener with 99671 traces, 99671 summary
Retagger: tagging trace set: gauss with 81433 traces, 0 summary
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 81433 traces tagged "gauss"
FrameSaver: q=2.54373e+06 n=1340955 tag=gauss
wclsFrameSaver: saving 99671 traces tagged "wiener"
FrameSaver: q=3.32977e+06 n=1319165 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 14881 XUs and 10151 XVs -> 146 XUVs
C:0 T:1 112166 XUs and 82316 XVs -> 5178 XUVs
C:0 T:2 37358 XUs and 44531 XVs -> 855 XUVs
C:0 T:3 8 XUs and 12 XVs -> 0 XUVs
C:0 T:4 6 XUs and 5 XVs -> 0 XUVs
C:0 T:5 22494 XUs and 25071 XVs -> 618 XUVs
C:0 T:6 32311 XUs and 42754 XVs -> 781 XUVs
C:0 T:7 10 XUs and 10 XVs -> 0 XUVs
7578 XUVs total
2955 collection wire objects
7578 potential space points
Neighbour search...
468922 tests to find 151420 neighbours
Iterating with no regularization...
Begin: 4.63627e+07
0 3.93298e+07
1 3.8872e+07
2 3.8831e+07
3 3.88243e+07
Now with regularization...
Begin: 3.73559e+07
0 3.73508e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
Begin processing the 10th record. run: 20250627 subRun: 1 event: 99 at 03-Nov-2025 18:14:15 EST
Running Ophitfinder with InputDigiType = 'recob'
Found hits: 14812!
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=6000 keeping as is
Retagger: tagging trace set: wiener with 96248 traces, 96248 summary
Retagger: tagging trace set: gauss with 72857 traces, 0 summary
wclsFrameSaver saving cooked to 6000 ticks
wclsFrameSaver: saving 72857 traces tagged "gauss"
FrameSaver: q=2.16719e+06 n=1098989 tag=gauss
wclsFrameSaver: saving 96248 traces tagged "wiener"
FrameSaver: q=2.73324e+06 n=1033571 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 8303 XUs and 8871 XVs -> 69 XUVs
C:0 T:1 50571 XUs and 49450 XVs -> 1440 XUVs
C:0 T:2 67450 XUs and 56002 XVs -> 1268 XUVs
C:0 T:5 61201 XUs and 81984 XVs -> 1748 XUVs
C:0 T:6 57955 XUs and 41279 XVs -> 2152 XUVs
6677 XUVs total
2279 collection wire objects
6677 potential space points
Neighbour search...
385309 tests to find 207456 neighbours
Iterating with no regularization...
Begin: 5.00054e+07
0 4.62533e+07
1 4.60069e+07
2 4.5976e+07
Now with regularization...
Begin: 4.4254e+07
0 4.42404e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
03-Nov-2025 18:16:34 EST  Closed output file "pdhd_prod_beam__226470_34_1_20251008T212535Z_gen_g4_IonScintPDExt.root_227040_113_1_20251010T170736Z_PDInt.root_236984_1_1_20251103T215609Z_detsim_reco1.root"
03-Nov-2025 18:16:34 EST  Closed input file "temp_detsim.root"
======================================================================================================================================
TimeTracker printout (sec)                              Min           Avg           Max         Median          RMS         nEvts   
======================================================================================================================================
Full event                                            115.512       156.832       229.769       138.636       36.1813        10     
--------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                              0.00132141    0.00723146     0.0154572    0.00719587    0.00383928       10     
reco:opdec:Deconvolution                             0.702207      0.872778       1.00241      0.870886      0.0895004       10     
reco:ophitspe:OpHitFinderDeco                         0.1339       0.200976       0.27381      0.214759      0.0392986       10     
reco:opflash:OpFlashFinder                           0.0661181     0.0954567     0.123359      0.0935068     0.0158069       10     
reco:opslicer:OpSlicer                               0.228573      0.274213      0.310316      0.273209      0.0230254       10     
reco:wclsdatahd:WireCellToolkit                       42.2545       46.3503       52.7628       46.0115       3.37428        10     
reco:gaushit:GausHitFinder                           0.640354      0.953064       1.45438       0.87999      0.269715        10     
reco:nhitsfilter:NumberOfHitsFilter                 0.000344457   0.000450814   0.000580245   0.000447204   7.56906e-05      10     
reco:reco3d:SpacePointSolver                         0.694495       1.60912       3.21529       1.5356       0.670088        10     
reco:hitpdune:DisambigFromSpacePoints                 0.41854      0.896631       1.6228       0.706821       0.38362        10     
reco:pandora:StandardPandora                          17.5588       31.7841        53.69        28.7318       12.7372        10     
reco:pandoraWriter:StandardPandora                   0.220854      0.365227      0.532427      0.374203      0.0950533       10     
reco:pandoraTrack:LArPandoraTrackCreation             1.30583       3.16129       5.43853       2.82162       1.21011        10     
reco:pandoraShower:LArPandoraShowerCreation           1.37463       3.22139       6.81006       2.76511        1.732         10     
reco:pandoracalo:Calorimetry                         0.633811       1.48558       2.86195       1.27314      0.695368        10     
reco:pandoracalonosce:Calorimetry                    0.584199       1.49767       2.96663       1.26702      0.735843        10     
reco:pandoraShowercalo:ShowerCalorimetry              1.25892       3.05436       7.69868       2.47532       1.84641        10     
reco:pandoraShowercalonosce:ShowerCalorimetry         1.09785       2.8012        7.46648       2.15955       1.81006        10     
reco:pandoraGnocchiCalo:GnocchiCalorimetry           0.0460213     0.0722846     0.113972      0.0717227     0.0192061       10     
reco:pandoraGnocchiCalonosce:GnocchiCalorimetry      0.0468065     0.0718276     0.112927      0.0724224     0.0185085       10     
reco:emtrkmichelid:EmTrackMichelId                    26.8416       35.6164       47.652        35.5098       5.70498        10     
reco:crttag:SingleCRTMatchingProducer               0.00608563    0.00791641     0.0103571    0.00792709     0.0012811       10     
reco:crtreco:TwoCRTMatchingProducer                 0.00457789    0.00653343    0.00933561    0.00636218    0.00139996       10     
reco:pandora2Track:LArPandoraTrackCreation            3.54556       6.99294       12.8272       6.09358       2.87834        10     
reco:pandora2calo:ShowerCalorimetry                   0.96109       2.21106       4.09556       1.65307       1.08668        10     
reco:pandora2calonosce:ShowerCalorimetry             0.949863       2.06356       3.99617       1.47811       1.09666        10     
reco:pandora2GnocchiCalo:GnocchiCalorimetry          0.0461314     0.0658707     0.0988352     0.0626443     0.0161825       10     
reco:pandora2GnocchiCalonosce:GnocchiCalorimetry     0.0426346     0.0620959     0.0964296     0.0568696     0.015765        10     
reco:pandora2Shower:LArPandoraShowerCreation          1.21569       2.75707       4.88663       2.27946       1.20573        10     
reco:pandora2Showercalo:ShowerCalorimetry             1.30853       2.88946       5.18834       2.40164       1.35359        10     
reco:pandora2Showercalonosce:ShowerCalorimetry        1.31626       2.70636       4.93442       2.25495       1.21005        10     
[art]:TriggerResults:TriggerResultInserter          3.6118e-05    8.09428e-05   0.000340659   4.8436e-05    8.82586e-05      10     
end_path:out1:RootOutput                             9.979e-06    1.45622e-05   3.4344e-05    1.15065e-05   7.18515e-06      10     
end_path:out1:RootOutput(write)                       1.91829       2.48659       3.25516       2.43244       0.40151        10     
======================================================================================================================================
====================================================================================================
MemoryTracker summary (base-10 MB units used)
  Peak virtual memory usage (VmPeak)  : 6450.31 MB
  Peak resident set size usage (VmHWM): 4224.04 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Reco returns 0
.:
total 11512560
-rw-r--r-- 1 dune osgvo      45632 Nov  3 18:16 jobscript.log
-rw-r--r-- 1 dune osgvo        201 Nov  3 18:16 justin-processed-pfns.txt
-rw-r--r-- 1 dune osgvo        519 Nov  3 18:16 reco_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo       3574 Nov  3 18:16 Pandora_Geometry.xml
-rw-r--r-- 1 dune osgvo     200704 Nov  3 18:16 mem.db
-rw-r--r-- 1 dune osgvo      36864 Nov  3 18:16 time.db
-rw-r--r-- 1 dune osgvo 6047401813 Nov  3 18:16 pdhd_prod_beam__226470_34_1_20251008T212535Z_gen_g4_IonScintPDExt.root_227040_113_1_20251010T170736Z_PDInt.root_236984_1_1_20251103T215609Z_detsim_reco1.root
-rw-r--r-- 1 dune osgvo   55470160 Nov  3 18:15 Pandora_Events.pndr
-rw-r--r-- 1 dune osgvo          0 Nov  3 17:51 ProtoDUNE.log
-rw-r--r-- 1 dune osgvo          0 Nov  3 17:48 debugprod.log
-rw-r--r-- 1 dune osgvo        519 Nov  3 17:48 detsim_single_protodunehd_hist.root
-rw-r--r-- 1 dune osgvo 5685654763 Nov  3 17:48 temp_detsim.root
-rw-r--r-- 1 dune osgvo        122 Nov  3 16:56 all-input-dids.txt