justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263269.132@justin-prod-sched01.dune.hep.ac.uk
Workflow ID3447
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:51:00
SiteFR_CCIN2P3
EntryDUNE_FR_CCIN2P3_cccondorce03
Last heartbeat2024-09-26 11:06:08
From worker nodeHostnameccwcondor0586
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 09:53:32
Input fileshd-protodune:np04hd_raw_run029092_0679_dataflow4_datawriter_0_20240906T034150.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 11:06:08
Saved logsjustin-logs:263269.132-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

5.68067e+06
1 5.65905e+06
2 5.65714e+06
Now with regularization...
Begin: 5.41315e+06
0 5.41262e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 29th record. run: 29092 subRun: 1 event: 163220 at 26-Sep-2024 12:53:33 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 78793 traces, 78793 summary
Retagger: tagging trace set: gauss with 56940 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 56940 traces tagged "gauss"
FrameSaver: q=1.2065e+06 n=1041044 tag=gauss
wclsFrameSaver: saving 78793 traces tagged "wiener"
FrameSaver: q=1.29962e+06 n=934152 tag=wiener
4364 hits in TPC 0
4353 hits in TPC 1
7301 hits in TPC 2
4764 hits in TPC 3
2335 hits in TPC 4
4607 hits in TPC 5
7304 hits in TPC 6
5358 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 88 XUs and 52 XVs -> 4 XUVs
C:0 T:1 1409 XUs and 927 XVs -> 17 XUVs
C:0 T:2 54985 XUs and 50523 XVs -> 1182 XUVs
C:0 T:3 558 XUs and 568 XVs -> 7 XUVs
C:0 T:4 252 XUs and 216 XVs -> 7 XUVs
C:0 T:5 20474 XUs and 13894 XVs -> 670 XUVs
C:0 T:6 88669 XUs and 74483 XVs -> 4018 XUVs
C:0 T:7 52 XUs and 92 XVs -> 9 XUVs
5914 XUVs total
1762 collection wire objects
5914 potential space points
Neighbour search...
887722 tests to find 398388 neighbours
Iterating with no regularization...
Begin: 8.76168e+06
0 7.73045e+06
1 7.68418e+06
2 7.67766e+06
Now with regularization...
Begin: 7.31824e+06
0 7.31543e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 30th record. run: 29092 subRun: 1 event: 163228 at 26-Sep-2024 12:55:03 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 78055 traces, 78055 summary
Retagger: tagging trace set: gauss with 57789 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 57789 traces tagged "gauss"
FrameSaver: q=1.32933e+06 n=1110331 tag=gauss
wclsFrameSaver: saving 78055 traces tagged "wiener"
FrameSaver: q=1.44724e+06 n=1012113 tag=wiener
4570 hits in TPC 0
4540 hits in TPC 1
9258 hits in TPC 2
4029 hits in TPC 3
2309 hits in TPC 4
4967 hits in TPC 5
8819 hits in TPC 6
5064 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 1190 XUs and 2682 XVs -> 68 XUVs
C:0 T:2 61291 XUs and 52676 XVs -> 2243 XUVs
C:0 T:3 254 XUs and 299 XVs -> 18 XUVs
C:0 T:4 36 XUs and 42 XVs -> 4 XUVs
C:0 T:5 11243 XUs and 36395 XVs -> 1173 XUVs
C:0 T:6 39338 XUs and 38330 XVs -> 771 XUVs
C:0 T:7 494 XUs and 491 XVs -> 24 XUVs
4301 XUVs total
2009 collection wire objects
4301 potential space points
Neighbour search...
304741 tests to find 120198 neighbours
Iterating with no regularization...
Begin: 3.30248e+07
0 2.5432e+07
1 2.49201e+07
2 2.48466e+07
3 2.48299e+07
Now with regularization...
Begin: 2.39576e+07
0 2.39461e+07
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  3   event 163228 TrkackID 5 startx, y, z 16.8936 592.402 156.519  wireno, peakt tpcno 241 15.5665 5 dist 0.0394524min T, max_T6.18049 2361.73
no of hits closeby  0   event 163228 TrkackID 24 startx, y, z 364.982 426.513 351.396  wireno, peakt tpcno 425 5854.86 6 dist 1.20911min T, max_T5353 5854.86
no of hits closeby  3   event 163228 TrkackID 5 startx, y, z 16.8936 592.402 156.519  wireno, peakt tpcno 241 15.5665 5 dist 0.0394524min T, max_T6.18049 2361.73
no of hits closeby  0   event 163228 TrkackID 24 startx, y, z 364.982 426.513 351.396  wireno, peakt tpcno 425 5854.86 6 dist 1.20911min T, max_T5353 5854.86
26-Sep-2024 12:56:46 CEST  Closed output file "np04hd_raw_run029092_0679_dataflow4_datawriter_0_20240906T034150_reco_stage1_reco_stage2_20240926T105646_keepup.root"
26-Sep-2024 12:56:46 CEST  Closed input file "np04hd_raw_run029092_0679_dataflow4_datawriter_0_20240906T034150_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              58.1722       93.1279       149.338       89.6308       23.5592        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000356305   0.00278482    0.00580135    0.00280709    0.00135314       30     
produce:fembfilter:PDHDFEMBFilter                       0.50427      0.596713      0.750016      0.598313      0.0701736       30     
produce:wclsdatahd:WireCellToolkit                      31.9682       36.9956       41.2951       36.8853       2.61965        30     
produce:gaushit:GausHitFinder                          0.289629      0.582748       1.1597       0.538971       0.17608        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000231672   0.000318666   0.000580003   0.000296153   7.88678e-05      30     
produce:reco3d:SpacePointSolver                        0.141044      0.981084       2.76638      0.807243      0.711506        30     
produce:hitpdune:DisambigFromSpacePoints               0.154634      0.496465       1.22581      0.388989      0.303069        30     
produce:pandora:StandardPandora                         3.73884       12.7658       46.3725       10.2632       8.75265        30     
produce:pandoraWriter:StandardPandora                  0.0775677     0.142766      0.278009      0.126027      0.0526495       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.546129       1.75871       4.33096       1.58897       1.02084        30     
produce:pandoraShower:LArPandoraShowerCreation         0.389807       2.19585       7.52245       1.82738       1.64476        30     
produce:pandoracalo:Calorimetry                        0.295685      0.834079       2.58324      0.703644      0.504888        30     
produce:pandoracalonosce:Calorimetry                   0.254839      0.776412       2.19672      0.577448      0.482412        30     
produce:pandorapid:Chi2ParticleID                     0.00123797    0.00206526     0.0042364    0.00191533    0.000729448      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.328701       2.10501       6.95343       1.72799       1.56607        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.230517       2.09553       8.11792       1.65237       1.72543        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0565589     0.120834      0.257253      0.106805      0.052663        30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.017912      0.0465132     0.0984517     0.0409636     0.021457        30     
produce:emtrkmichelid:EmTrackMichelId                   13.3656       29.0081       46.6523       28.8199       7.81282        30     
[art]:TriggerResults:TriggerResultInserter            2.2852e-05    5.76485e-05   0.000124783   5.92505e-05   1.93363e-05      30     
end_path:out1:RootOutput                                5.5e-06     1.50281e-05   3.5045e-05    1.52635e-05   5.37409e-06      30     
end_path:michelremoving:michelremoving                 0.0164399     0.0984205     0.512519       0.04709      0.107495        30     
end_path:michelremovinggnocchi:michelremoving          0.0201276     0.0860723      0.3917       0.0416193     0.0875498       30     
end_path:calibanagnocchi:CalibAnaTree                  0.142236      0.578281       2.17346      0.492424      0.383364        30     
end_path:out1:RootOutput(write)                         0.42147      0.666807       1.15992      0.644851      0.182378        30     
========================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4926.96 MB
  Peak resident set size usage (VmHWM): 2356.68 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run029092_0679_dataflow4_datawriter_0_20240906T034150_reco_stage1_reco_stage2_20240926T105646_keepup.root
\tHists: np04hd_raw_run029092_0679_dataflow4_datawriter_0_20240906T034150_reco_stage1_reco_stage2_20240926T105646_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-11-17 04:02:18 UTC       justIN version: 01.01.09