justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 79001.1@justin-prod-sched02.dune.hep.ac.uk

Jobsub ID79001.1@justin-prod-sched02.dune.hep.ac.uk
Workflow ID3449
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 13:54:21
SiteUK_Sheffield
EntryDUNE_UK_Sheffield_lcgce1
Last heartbeat2024-09-26 14:40:50
From worker nodeHostnamewn034.hep
cpuinfoAMD EPYC 75F3 32-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4193255424 (3999 MiB)
Wall seconds limit257400 (71 hours)
Inner Apptainer?True
Job statejobscript_error
Allocator namejustin-allocator-pro.dune.hep.ac.uk
Started2024-09-26 13:54:46
Input fileshd-protodune:np04hd_raw_run029107_0711_dataflow0_datawriter_0_20240907T024555.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 14:40:50
Saved logsjustin-logs:79001.1-justin-prod-sched02.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

uss with 42799 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 42799 traces tagged "gauss"
FrameSaver: q=945691 n=801900 tag=gauss
wclsFrameSaver: saving 58142 traces tagged "wiener"
FrameSaver: q=1.00754e+06 n=722758 tag=wiener
1437 hits in TPC 0
1857 hits in TPC 1
5482 hits in TPC 2
3650 hits in TPC 3
2637 hits in TPC 4
3497 hits in TPC 5
7984 hits in TPC 6
3933 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 48 XUs and 11 XVs -> 1 XUVs
C:0 T:2 22762 XUs and 25558 XVs -> 638 XUVs
C:0 T:3 48 XUs and 74 XVs -> 1 XUVs
C:0 T:4 89 XUs and 107 XVs -> 0 XUVs
C:0 T:5 9915 XUs and 8307 XVs -> 345 XUVs
C:0 T:6 36769 XUs and 43817 XVs -> 975 XUVs
C:0 T:7 62 XUs and 78 XVs -> 4 XUVs
1964 XUVs total
1242 collection wire objects
1964 potential space points
Neighbour search...
36426 tests to find 21186 neighbours
Iterating with no regularization...
Begin: 8.61173e+06
0 8.00044e+06
1 7.97701e+06
2 7.97461e+06
Now with regularization...
Begin: 7.59367e+06
0 7.59264e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 29th record. run: 29107 subRun: 1 event: 170896 at 26-Sep-2024 15:38:16 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 60127 traces, 60127 summary
Retagger: tagging trace set: gauss with 46216 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 46216 traces tagged "gauss"
FrameSaver: q=998416 n=883709 tag=gauss
wclsFrameSaver: saving 60127 traces tagged "wiener"
FrameSaver: q=1.06983e+06 n=812552 tag=wiener
4048 hits in TPC 0
3607 hits in TPC 1
3955 hits in TPC 2
2118 hits in TPC 3
6558 hits in TPC 4
7217 hits in TPC 5
3969 hits in TPC 6
1690 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 68 XUs and 104 XVs -> 5 XUVs
C:0 T:1 693 XUs and 451 XVs -> 59 XUVs
C:0 T:2 7288 XUs and 4620 XVs -> 309 XUVs
C:0 T:3 59 XUs and 47 XVs -> 0 XUVs
C:0 T:4 2338 XUs and 3632 XVs -> 199 XUVs
C:0 T:5 30382 XUs and 48059 XVs -> 2247 XUVs
C:0 T:6 4655 XUs and 5377 XVs -> 611 XUVs
C:0 T:7 56 XUs and 70 XVs -> 6 XUVs
3436 XUVs total
1557 collection wire objects
3436 potential space points
Neighbour search...
106726 tests to find 60904 neighbours
Iterating with no regularization...
Begin: 4.35922e+06
0 3.887e+06
1 3.85431e+06
2 3.85035e+06
3 3.84954e+06
Now with regularization...
Begin: 3.34231e+06
0 3.34162e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 30th record. run: 29107 subRun: 1 event: 170920 at 26-Sep-2024 15:39:10 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 66651 traces, 66651 summary
Retagger: tagging trace set: gauss with 47679 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 47679 traces tagged "gauss"
FrameSaver: q=1.00391e+06 n=862552 tag=gauss
wclsFrameSaver: saving 66651 traces tagged "wiener"
FrameSaver: q=1.09218e+06 n=772130 tag=wiener
3443 hits in TPC 0
2974 hits in TPC 1
3916 hits in TPC 2
2522 hits in TPC 3
2859 hits in TPC 4
6422 hits in TPC 5
7155 hits in TPC 6
5865 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 129 XUs and 195 XVs -> 4 XUVs
C:0 T:2 9744 XUs and 18600 XVs -> 420 XUVs
C:0 T:4 145 XUs and 194 XVs -> 12 XUVs
C:0 T:5 29722 XUs and 51795 XVs -> 2163 XUVs
C:0 T:6 56896 XUs and 67680 XVs -> 1508 XUVs
C:0 T:7 1127 XUs and 2366 XVs -> 69 XUVs
4176 XUVs total
1531 collection wire objects
4176 potential space points
Neighbour search...
289166 tests to find 137036 neighbours
Iterating with no regularization...
Begin: 1.17953e+07
0 1.07281e+07
1 1.06872e+07
2 1.06817e+07
Now with regularization...
Begin: 1.0396e+07
0 1.03934e+07
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

26-Sep-2024 15:40:06 BST  Closed output file "np04hd_raw_run029107_0711_dataflow0_datawriter_0_20240907T024555_reco_stage1_reco_stage2_20240926T144006_keepup.root"
26-Sep-2024 15:40:06 BST  Closed input file "np04hd_raw_run029107_0711_dataflow0_datawriter_0_20240907T024555_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              37.8153       52.1241       68.9396       52.2794       8.06115        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000265323   0.00079452    0.00115694    0.00079829    0.000237332      30     
produce:fembfilter:PDHDFEMBFilter                      0.368681      0.376549       0.41601      0.373331      0.0102837       30     
produce:wclsdatahd:WireCellToolkit                      22.7836       24.1252       25.3229       24.2104      0.733476        30     
produce:gaushit:GausHitFinder                          0.198544       0.32836      0.440551      0.326542      0.0589087       30     
produce:nhitsfilter:NumberOfHitsFilter                0.000105681   0.000177973   0.000286564   0.000176359   3.79973e-05      30     
produce:reco3d:SpacePointSolver                        0.0926935      0.41856       1.34756      0.313663      0.297102        30     
produce:hitpdune:DisambigFromSpacePoints               0.0844194     0.217864      0.440333      0.207414      0.0891859       30     
produce:pandora:StandardPandora                         2.29502       5.39989       9.34695       5.58594       1.86595        30     
produce:pandoraWriter:StandardPandora                  0.0377776     0.0626747     0.0982438     0.0615322     0.0154566       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.267457      0.560168       1.01829       0.50539      0.192332        30     
produce:pandoraShower:LArPandoraShowerCreation         0.225503      0.707299       1.67882      0.659941      0.317929        30     
produce:pandoracalo:Calorimetry                        0.158932       0.32301      0.571014      0.309323      0.104061        30     
produce:pandoracalonosce:Calorimetry                   0.141962      0.298146      0.532454       0.28506      0.100201        30     
produce:pandorapid:Chi2ParticleID                     0.000614667   0.000967078   0.00180227    0.000946215   0.000250493      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.185578      0.699253       1.67471      0.664416      0.314836        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.181258      0.678462       1.59663      0.647367      0.299199        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.037482      0.0643813     0.0927876     0.0639495     0.0142292       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0131188     0.0223292     0.0351743     0.0219332    0.00525031       30     
produce:emtrkmichelid:EmTrackMichelId                   9.88813       17.0303       25.6281       17.4699       4.27489        30     
[art]:TriggerResults:TriggerResultInserter             1.042e-05    1.91806e-05   7.9782e-05    1.62155e-05   1.23752e-05      30     
end_path:out1:RootOutput                               2.725e-06    7.42337e-06    9.456e-05    3.6915e-06    1.65828e-05      30     
end_path:michelremoving:michelremoving                0.00885851     0.0537625     0.171502      0.0277518     0.0494581       30     
end_path:michelremovinggnocchi:michelremoving         0.00867182     0.0435646     0.136162      0.0252244     0.037229        30     
end_path:calibanagnocchi:CalibAnaTree                  0.119616      0.252035      0.419924      0.240286      0.0788853       30     
end_path:out1:RootOutput(write)                        0.258271      0.402537      0.595641      0.407248      0.0807725       30     
========================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4934.9 MB
  Peak resident set size usage (VmHWM): 2325.62 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run029107_0711_dataflow0_datawriter_0_20240907T024555_reco_stage1_reco_stage2_20240926T144006_keepup.root
\tHists: np04hd_raw_run029107_0711_dataflow0_datawriter_0_20240907T024555_reco_stage1_reco_stage2_20240926T144006_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:37:13 UTC       justIN version: 01.01.08