justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID78721.187@justin-prod-sched02.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 11:42:40
SiteUK_Manchester
EntryUBoone_T2_UK_Manchester_ce01
Last heartbeat2024-09-26 14:15:00
From worker nodeHostnamewn1205091.tier2.hep.manchester.ac.uk
cpuinfoAMD EPYC 7702P 64-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 12:35:48
Input fileshd-protodune:np04hd_raw_run029081_0484_dataflow6_datawriter_0_20240905T003233.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 14:15:00
Saved logsjustin-logs:78721.187-justin-prod-sched02.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

n wire objects
2218 potential space points
Neighbour search...
67218 tests to find 41096 neighbours
Iterating with no regularization...
Begin: 1.02235e+07
0 9.86861e+06
1 9.8492e+06
2 9.84473e+06
Now with regularization...
Begin: 9.32185e+06
0 9.31984e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  5   event 116382 TrkackID 4 startx, y, z 71.8222 476.782 453.342  wireno, peakt tpcno 53 889.969 5 dist 0.558122min T, max_T438.112 1368.77
no of hits closeby  5   event 116382 TrkackID 4 startx, y, z 71.8222 476.782 453.342  wireno, peakt tpcno 53 889.969 5 dist 0.558122min T, max_T438.112 1368.77
Begin processing the 29th record. run: 29081 subRun: 1 event: 116390 at 26-Sep-2024 15:05:55 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 91472 traces, 91472 summary
Retagger: tagging trace set: gauss with 65757 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 65757 traces tagged "gauss"
FrameSaver: q=1.37108e+06 n=1120637 tag=gauss
wclsFrameSaver: saving 91472 traces tagged "wiener"
FrameSaver: q=1.52631e+06 n=1018646 tag=wiener
6319 hits in TPC 0
7315 hits in TPC 1
5852 hits in TPC 2
3376 hits in TPC 3
4505 hits in TPC 4
6998 hits in TPC 5
6117 hits in TPC 6
4160 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 32674 XUs and 32926 XVs -> 3617 XUVs
C:0 T:2 19540 XUs and 24838 XVs -> 839 XUVs
C:0 T:3 202 XUs and 257 XVs -> 5 XUVs
C:0 T:4 29 XUs and 19 XVs -> 2 XUVs
C:0 T:5 26639 XUs and 24211 XVs -> 1420 XUVs
C:0 T:6 25564 XUs and 53195 XVs -> 1846 XUVs
C:0 T:7 190 XUs and 87 XVs -> 3 XUVs
7732 XUVs total
1952 collection wire objects
7732 potential space points
Neighbour search...
421346 tests to find 253382 neighbours
Iterating with no regularization...
Begin: 1.72357e+07
0 1.58765e+07
1 1.57164e+07
2 1.57023e+07
Now with regularization...
Begin: 1.52146e+07
0 1.52097e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  0   event 116390 TrkackID 17 startx, y, z 52.9322 595.057 117.278  wireno, peakt tpcno 244 2693 2 dist 73.9115min T, max_T2083.42 2726.26
no of hits closeby  0   event 116390 TrkackID 17 startx, y, z 52.9322 595.057 117.278  wireno, peakt tpcno 244 2693 2 dist 73.9115min T, max_T2083.42 2726.26
Begin processing the 30th record. run: 29081 subRun: 1 event: 116398 at 26-Sep-2024 15:08:35 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 38511 traces, 38511 summary
Retagger: tagging trace set: gauss with 28785 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 28785 traces tagged "gauss"
FrameSaver: q=635737 n=535617 tag=gauss
wclsFrameSaver: saving 38511 traces tagged "wiener"
FrameSaver: q=684933 n=484459 tag=wiener
1685 hits in TPC 0
1817 hits in TPC 1
4042 hits in TPC 2
2229 hits in TPC 3
1686 hits in TPC 4
2815 hits in TPC 5
3264 hits in TPC 6
2783 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 116 XUs and 65 XVs -> 9 XUVs
C:0 T:2 11356 XUs and 6894 XVs -> 220 XUVs
C:0 T:3 136 XUs and 161 XVs -> 8 XUVs
C:0 T:4 270 XUs and 443 XVs -> 85 XUVs
C:0 T:5 5400 XUs and 3774 XVs -> 239 XUVs
C:0 T:6 7938 XUs and 9176 XVs -> 1123 XUVs
C:0 T:7 145 XUs and 135 XVs -> 12 XUVs
1696 XUVs total
643 collection wire objects
1696 potential space points
Neighbour search...
78138 tests to find 46160 neighbours
Iterating with no regularization...
Begin: 4.86649e+06
0 4.5465e+06
1 4.51733e+06
2 4.51333e+06
Now with regularization...
Begin: 4.12894e+06
0 4.1259e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

26-Sep-2024 15:10:09 BST  Closed output file "np04hd_raw_run029081_0484_dataflow6_datawriter_0_20240905T003233_reco_stage1_reco_stage2_20240926T141009_keepup.root"
26-Sep-2024 15:10:09 BST  Closed input file "np04hd_raw_run029081_0484_dataflow6_datawriter_0_20240905T003233_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              93.9248       121.848       159.216       121.449       17.955         30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000574311   0.00301946     0.0045791    0.00306828    0.00122289       30     
produce:fembfilter:PDHDFEMBFilter                      0.808856       1.04331       1.4371       0.987261      0.127645        30     
produce:wclsdatahd:WireCellToolkit                      46.207        51.9743       59.0594       51.823        2.56843        30     
produce:gaushit:GausHitFinder                          0.650607       1.03388       1.39599       1.01935      0.206681        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000306848   0.000585735    0.0028791    0.000479602   0.000453031      30     
produce:reco3d:SpacePointSolver                        0.290659       1.17647       3.28235       1.06305      0.681663        30     
produce:hitpdune:DisambigFromSpacePoints               0.284374      0.673481       1.31123       0.63026      0.245494        30     
produce:pandora:StandardPandora                         7.2363        17.2472       30.8939       16.5232       5.8877         30     
produce:pandoraWriter:StandardPandora                  0.150172      0.258873      0.454943      0.254009      0.0565204       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.777445       1.75486       3.24473       1.66853       0.62812        30     
produce:pandoraShower:LArPandoraShowerCreation         0.737631       2.00981       4.20061       1.79051       0.97595        30     
produce:pandoracalo:Calorimetry                        0.426993      0.877943       1.6439       0.873181      0.292488        30     
produce:pandoracalonosce:Calorimetry                   0.361712      0.799896       1.64132       0.77307      0.281661        30     
produce:pandorapid:Chi2ParticleID                     0.00163478    0.00279501    0.00495383    0.00278538    0.000695822      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.620365       1.76686       3.58089       1.56678       0.77387        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.564645       1.72298       3.05863       1.57465      0.721308        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.119993      0.233007      0.318089      0.245494      0.045013        30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.046079      0.0760224     0.103445      0.078279      0.0154907       30     
produce:emtrkmichelid:EmTrackMichelId                   24.2906       36.9631       52.4242       35.7957       7.08731        30     
[art]:TriggerResults:TriggerResultInserter            2.8915e-05    5.27914e-05   0.00010671    5.2078e-05    1.85871e-05      30     
end_path:out1:RootOutput                               4.679e-06    9.07647e-06   3.3584e-05    6.6175e-06    6.65455e-06      30     
end_path:michelremoving:michelremoving                  0.02645      0.131277      0.425228      0.058089      0.108684        30     
end_path:michelremovinggnocchi:michelremoving          0.0260367     0.130214       0.42233      0.0560707     0.109278        30     
end_path:calibanagnocchi:CalibAnaTree                  0.311337      0.599304       1.00553       0.5995       0.187201        30     
end_path:out1:RootOutput(write)                        0.714419       1.1878        1.61162       1.20921      0.237114        30     
========================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4926.28 MB
  Peak resident set size usage (VmHWM): 2563.25 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run029081_0484_dataflow6_datawriter_0_20240905T003233_reco_stage1_reco_stage2_20240926T141009_keepup.root
\tHists: np04hd_raw_run029081_0484_dataflow6_datawriter_0_20240905T003233_reco_stage1_reco_stage2_20240926T141009_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 08:01:55 UTC       justIN version: 01.01.09