justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 263295.15@justin-prod-sched01.dune.hep.ac.uk

Jobsub ID263295.15@justin-prod-sched01.dune.hep.ac.uk
Workflow ID3453
Stage ID1
User namecalcuttj@fnal.gov
HTCondor Groupgroup_dune.prod_mcsim
RequestedProcessors1
RSS bytes4193255424 (3999 MiB)
Wall seconds limit18000 (5 hours)
Submitted time2024-09-26 09:58:35
SiteFR_CCIN2P3
EntryDUNE_FR_CCIN2P3_cccondorce02
Last heartbeat2024-09-26 12:03:47
From worker nodeHostnameccwcondor0559
cpuinfoAMD EPYC 7453 28-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4193255424 (3999 MiB)
Wall seconds limit106200 (29 hours)
Inner Apptainer?True
Job statejobscript_error
Allocator namejustin-allocator-pro.dune.hep.ac.uk
Started2024-09-26 10:02:57
Input fileshd-protodune:np04hd_raw_run029107_3651_dataflow5_datawriter_0_20240909T044225.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 12:03:47
Saved logsjustin-logs:263295.15-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

tagged "wiener"
FrameSaver: q=747005 n=548690 tag=wiener
2420 hits in TPC 0
2990 hits in TPC 1
3980 hits in TPC 2
2337 hits in TPC 3
1902 hits in TPC 4
3922 hits in TPC 5
4968 hits in TPC 6
1169 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 865 XUs and 467 XVs -> 6 XUVs
C:0 T:2 21567 XUs and 42356 XVs -> 2761 XUVs
C:0 T:3 54 XUs and 139 XVs -> 3 XUVs
C:0 T:4 53 XUs and 71 XVs -> 6 XUVs
C:0 T:5 6307 XUs and 4875 XVs -> 279 XUVs
C:0 T:6 32731 XUs and 56572 XVs -> 1385 XUVs
C:0 T:7 11 XUs and 14 XVs -> 0 XUVs
4440 XUVs total
1212 collection wire objects
4440 potential space points
Neighbour search...
418158 tests to find 202226 neighbours
Iterating with no regularization...
Begin: 3.72315e+06
0 3.39012e+06
1 3.36269e+06
2 3.35768e+06
3 3.35583e+06
Now with regularization...
Begin: 3.11657e+06
0 3.11529e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 29th record. run: 29107 subRun: 1 event: 876493 at 26-Sep-2024 13:04:13 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 64505 traces, 64505 summary
Retagger: tagging trace set: gauss with 49397 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 49397 traces tagged "gauss"
FrameSaver: q=1.10775e+06 n=981156 tag=gauss
wclsFrameSaver: saving 64505 traces tagged "wiener"
FrameSaver: q=1.17654e+06 n=901949 tag=wiener
1433 hits in TPC 0
2169 hits in TPC 1
7679 hits in TPC 2
3379 hits in TPC 3
3056 hits in TPC 4
6608 hits in TPC 5
6957 hits in TPC 6
4070 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 18 XUs and 25 XVs -> 0 XUVs
C:0 T:2 42366 XUs and 59490 XVs -> 1966 XUVs
C:0 T:3 36 XUs and 60 XVs -> 1 XUVs
C:0 T:4 130 XUs and 121 XVs -> 5 XUVs
C:0 T:5 36335 XUs and 41226 XVs -> 1263 XUVs
C:0 T:6 23100 XUs and 30780 XVs -> 816 XUVs
C:0 T:7 992 XUs and 1211 XVs -> 41 XUVs
4092 XUVs total
1566 collection wire objects
4092 potential space points
Neighbour search...
157238 tests to find 89374 neighbours
Iterating with no regularization...
Begin: 1.05139e+07
0 9.69096e+06
1 9.59321e+06
2 9.57861e+06
3 9.57479e+06
Now with regularization...
Begin: 8.98822e+06
0 8.98248e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 30th record. run: 29107 subRun: 1 event: 876501 at 26-Sep-2024 13:05:37 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 63474 traces, 63474 summary
Retagger: tagging trace set: gauss with 46943 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 46943 traces tagged "gauss"
FrameSaver: q=1.03587e+06 n=788675 tag=gauss
wclsFrameSaver: saving 63474 traces tagged "wiener"
FrameSaver: q=1.14388e+06 n=716471 tag=wiener
3223 hits in TPC 0
2803 hits in TPC 1
3684 hits in TPC 2
2317 hits in TPC 3
5388 hits in TPC 4
7618 hits in TPC 5
4116 hits in TPC 6
2594 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 960 XUs and 790 XVs -> 22 XUVs
C:0 T:2 36006 XUs and 23783 XVs -> 1556 XUVs
C:0 T:3 77 XUs and 152 XVs -> 14 XUVs
C:0 T:4 158 XUs and 324 XVs -> 19 XUVs
C:0 T:5 38366 XUs and 33402 XVs -> 1305 XUVs
C:0 T:6 10880 XUs and 13845 XVs -> 1513 XUVs
C:0 T:7 540 XUs and 623 XVs -> 4 XUVs
4433 XUVs total
1318 collection wire objects
4433 potential space points
Neighbour search...
249145 tests to find 147528 neighbours
Iterating with no regularization...
Begin: 1.19022e+07
0 1.05899e+07
1 1.04966e+07
2 1.04796e+07
3 1.04745e+07
Now with regularization...
Begin: 9.72502e+06
0 9.72049e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

26-Sep-2024 13:06:52 CEST  Closed output file "np04hd_raw_run029107_3651_dataflow5_datawriter_0_20240909T044225_reco_stage1_reco_stage2_20240926T110652_keepup.root"
26-Sep-2024 13:06:52 CEST  Closed input file "np04hd_raw_run029107_3651_dataflow5_datawriter_0_20240909T044225_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              59.1923       92.378        135.716       92.0213       19.007         30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000496246   0.00292716    0.00685602    0.00285693    0.00140363       30     
produce:fembfilter:PDHDFEMBFilter                      0.498332      0.646732      0.930555      0.645389      0.103087        30     
produce:wclsdatahd:WireCellToolkit                      31.0885       37.3832       46.9709       36.8257       4.0156         30     
produce:gaushit:GausHitFinder                           0.30068      0.665637       1.15287      0.623478      0.224119        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000196256   0.000350333   0.000688696   0.00035083    9.75719e-05      30     
produce:reco3d:SpacePointSolver                        0.126017       1.14738       5.66087      0.811748       1.07927        30     
produce:hitpdune:DisambigFromSpacePoints               0.106177      0.549868       1.67733      0.466173      0.326679        30     
produce:pandora:StandardPandora                         4.49485       12.7781       28.1403       10.4724       5.72705        30     
produce:pandoraWriter:StandardPandora                  0.0822894     0.159442      0.224704      0.165565      0.0366445       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.501647       1.66243       3.40311       1.47976      0.676345        30     
produce:pandoraShower:LArPandoraShowerCreation         0.468143       2.00987       5.80038       1.7364        1.17157        30     
produce:pandoracalo:Calorimetry                        0.261575      0.783713       1.39728      0.729002      0.280482        30     
produce:pandoracalonosce:Calorimetry                   0.218268      0.735841       1.35788      0.728359      0.257257        30     
produce:pandorapid:Chi2ParticleID                     0.00109972    0.00207462    0.00520378    0.00186466    0.000832749      30     
produce:pandoraShowercalo:ShowerCalorimetry             0.46058       1.73664       4.43443       1.60249      0.895177        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.501035       1.62429       4.5012        1.47961      0.838718        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0583923     0.134787      0.233853      0.132883      0.0458958       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.025928      0.0495595     0.0759701     0.0510421     0.0142572       30     
produce:emtrkmichelid:EmTrackMichelId                   16.1298       28.6799       40.7452       28.6252       6.1867         30     
[art]:TriggerResults:TriggerResultInserter            2.8323e-05    6.5473e-05    0.000170428   6.52065e-05   2.59169e-05      30     
end_path:out1:RootOutput                              1.0119e-05    2.28955e-05   0.000147796   1.75275e-05   2.38374e-05      30     
end_path:michelremoving:michelremoving                 0.0153477     0.0796925     0.416441      0.0435285     0.084792        30     
end_path:michelremovinggnocchi:michelremoving          0.0132431     0.0775801     0.393309      0.0386028     0.0839427       30     
end_path:calibanagnocchi:CalibAnaTree                  0.218024      0.552398       1.20985      0.520899      0.229088        30     
end_path:out1:RootOutput(write)                        0.363196      0.708856       1.03206      0.703481      0.195112        30     
========================================================================================================================================

====================================================================================================
MemoryTracker summary (base-10 MB units used)

  Peak virtual memory usage (VmPeak)  : 4945.57 MB
  Peak resident set size usage (VmHWM): 2372.54 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run029107_3651_dataflow5_datawriter_0_20240909T044225_reco_stage1_reco_stage2_20240926T110652_keepup.root
\tHists: np04hd_raw_run029107_3651_dataflow5_datawriter_0_20240909T044225_reco_stage1_reco_stage2_20240926T110652_keepup_hists.root
Forming reco metadata
/cvmfs/larsoft.opensciencegrid.org/products/python/v3_9_15/Linux64bit+3.10-2.17/bin/python: No module named meta_maker
Error in reco metadata
justIN time: 2024-09-29 09:21:41 UTC       justIN version: 01.01.08