justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263900.1@justin-prod-sched01.dune.hep.ac.uk
Workflow ID3460
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 14:06:14
SiteUK_Manchester
EntryUBoone_T2_UK_Manchester_ce02
Last heartbeat2024-09-26 15:18:19
From worker nodeHostnamewn2210251.tier2.hep.manchester.ac.uk
cpuinfoAMD EPYC 7513 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 14:10:55
Input fileshd-protodune:np04hd_raw_run029218_0103_dataflow7_datawriter_0_20240917T165527.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 15:18:19
Saved logsjustin-logs:263900.1-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

"
FrameSaver: q=870780 n=582211 tag=wiener
1198 hits in TPC 0
1230 hits in TPC 1
4473 hits in TPC 2
2660 hits in TPC 3
2804 hits in TPC 4
4075 hits in TPC 5
5708 hits in TPC 6
4241 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 414 XUs and 150 XVs -> 0 XUVs
C:0 T:2 11411 XUs and 10780 XVs -> 206 XUVs
C:0 T:3 122 XUs and 393 XVs -> 30 XUVs
C:0 T:4 4 XUs and 11 XVs -> 0 XUVs
C:0 T:5 10328 XUs and 9468 XVs -> 239 XUVs
C:0 T:6 20611 XUs and 20163 XVs -> 1319 XUVs
C:0 T:7 133 XUs and 248 XVs -> 7 XUVs
1801 XUVs total
708 collection wire objects
1801 potential space points
Neighbour search...
41053 tests to find 26002 neighbours
Iterating with no regularization...
Begin: 3.18381e+06
0 2.88656e+06
1 2.85472e+06
2 2.85194e+06
Now with regularization...
Begin: 2.65105e+06
0 2.65033e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 30th record. run: 29218 subRun: 1 event: 25783 at 26-Sep-2024 16:15:41 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 46081 traces, 46081 summary
Retagger: tagging trace set: gauss with 34092 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 34092 traces tagged "gauss"
FrameSaver: q=709242 n=635485 tag=gauss
wclsFrameSaver: saving 46081 traces tagged "wiener"
FrameSaver: q=760148 n=570228 tag=wiener
3417 hits in TPC 0
2173 hits in TPC 1
4136 hits in TPC 2
1898 hits in TPC 3
967 hits in TPC 4
1811 hits in TPC 5
6222 hits in TPC 6
3752 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 199 XUs and 194 XVs -> 7 XUVs
C:0 T:2 36531 XUs and 19478 XVs -> 1689 XUVs
C:0 T:3 13 XUs and 17 XVs -> 1 XUVs
C:0 T:4 2 XUs and 2 XVs -> 0 XUVs
C:0 T:5 1684 XUs and 1961 XVs -> 51 XUVs
C:0 T:6 19720 XUs and 18506 XVs -> 378 XUVs
C:0 T:7 159 XUs and 236 XVs -> 5 XUVs
2131 XUVs total
567 collection wire objects
2131 potential space points
Neighbour search...
286327 tests to find 159220 neighbours
Iterating with no regularization...
Begin: 3.7902e+06
0 3.64469e+06
1 3.63736e+06
2 3.63631e+06
Now with regularization...
Begin: 3.47908e+06
0 3.47849e+06
BdtBeamParticleIdTool::SliceFeatures::GetLArTPCIntercepts - inconsistent number of intercepts between a line and the LArTPC
BdtBeamParticleIdTool::SliceFeatures::GetLArTPCIntercepts - inconsistent number of intercepts between a line and the LArTPC
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 31st record. run: 29218 subRun: 1 event: 25791 at 26-Sep-2024 16:16:42 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 72317 traces, 72317 summary
Retagger: tagging trace set: gauss with 53437 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 53437 traces tagged "gauss"
FrameSaver: q=1.20859e+06 n=989809 tag=gauss
wclsFrameSaver: saving 72317 traces tagged "wiener"
FrameSaver: q=1.28476e+06 n=883997 tag=wiener
2386 hits in TPC 0
2965 hits in TPC 1
6966 hits in TPC 2
5525 hits in TPC 3
3585 hits in TPC 4
5652 hits in TPC 5
6097 hits in TPC 6
4144 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 3676 XUs and 2063 XVs -> 5 XUVs
C:0 T:2 22797 XUs and 23746 XVs -> 687 XUVs
C:0 T:3 412 XUs and 310 XVs -> 20 XUVs
C:0 T:4 103 XUs and 104 XVs -> 7 XUVs
C:0 T:5 19976 XUs and 11167 XVs -> 528 XUVs
C:0 T:6 18652 XUs and 24336 XVs -> 1530 XUVs
C:0 T:7 39 XUs and 51 XVs -> 2 XUVs
2779 XUVs total
1431 collection wire objects
2779 potential space points
Neighbour search...
61269 tests to find 35846 neighbours
Iterating with no regularization...
Begin: 8.05041e+06
0 7.4258e+06
1 7.38326e+06
2 7.37859e+06
Now with regularization...
Begin: 6.7794e+06
0 6.7777e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

26-Sep-2024 16:17:57 BST  Closed output file "np04hd_raw_run029218_0103_dataflow7_datawriter_0_20240917T165527_reco_stage1_reco_stage2_20240926T151757_keepup.root"
26-Sep-2024 16:17:57 BST  Closed input file "np04hd_raw_run029218_0103_dataflow7_datawriter_0_20240917T165527_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              39.1226       78.0212       130.407       73.8308       17.8093        31     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000579648   0.00247292    0.00409145    0.00242326    0.00102651       31     
produce:fembfilter:PDHDFEMBFilter                      0.503809      0.662357       0.81162      0.718972      0.114237        31     
produce:wclsdatahd:WireCellToolkit                      30.3431       34.3702       41.8994       33.765        2.7436         31     
produce:gaushit:GausHitFinder                          0.346153      0.595028       1.70389      0.553632      0.247153        31     
produce:nhitsfilter:NumberOfHitsFilter                0.000195147   0.000419892   0.00112888    0.000361368   0.000181299      31     
produce:reco3d:SpacePointSolver                         0.12048      0.554195       1.46928      0.510341      0.330578        30     
produce:hitpdune:DisambigFromSpacePoints               0.127024      0.353805      0.713356       0.33729      0.146219        30     
produce:pandora:StandardPandora                         4.65691       10.3934       33.2175       9.13275       5.67194        30     
produce:pandoraWriter:StandardPandora                  0.0987438     0.166462      0.287407      0.156328      0.0506079       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.337509       1.17042       2.24024      0.920455      0.560609        30     
produce:pandoraShower:LArPandoraShowerCreation         0.219719       1.45478       4.85711       1.08778       1.08864        30     
produce:pandoracalo:Calorimetry                        0.175511      0.546681       1.07351      0.466367      0.242184        30     
produce:pandoracalonosce:Calorimetry                   0.159016       0.49346       1.01181      0.433079       0.2243         30     
produce:pandorapid:Chi2ParticleID                     0.000994828   0.00176276    0.00339302    0.00170523    0.000639093      30     
produce:pandoraShowercalo:ShowerCalorimetry             0.19244       1.1779        3.72333      0.973547      0.768221        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.184009       1.14104       3.51199      0.874669      0.742082        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0511001     0.110895      0.253896      0.099108      0.0421335       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0174854     0.0382657     0.0738949     0.0366089     0.0119122       30     
produce:emtrkmichelid:EmTrackMichelId                   16.9229       24.6296       43.3225       23.0483       6.53179        30     
[art]:TriggerResults:TriggerResultInserter            2.3554e-05    4.94572e-05    8.535e-05    4.5746e-05    1.63783e-05      31     
end_path:out1:RootOutput                               7.424e-06    1.47529e-05   5.5174e-05    1.1392e-05    9.06862e-06      31     
end_path:michelremoving:michelremoving                3.7371e-05     0.0547627     0.241495      0.0327463     0.0511947       31     
end_path:michelremovinggnocchi:michelremoving         2.4045e-05     0.0512285     0.229549      0.0289472     0.0481974       31     
end_path:calibanagnocchi:CalibAnaTree                 2.2522e-05     0.461839       1.01331      0.460772      0.216468        31     
end_path:out1:RootOutput(write)                        0.391262      0.783659       1.3923       0.742376      0.254664        31     
========================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4941.53 MB
  Peak resident set size usage (VmHWM): 2575.99 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run029218_0103_dataflow7_datawriter_0_20240917T165527_reco_stage1_reco_stage2_20240926T151757_keepup.root
\tHists: np04hd_raw_run029218_0103_dataflow7_datawriter_0_20240917T165527_reco_stage1_reco_stage2_20240926T151757_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:45:57 UTC       justIN version: 01.01.09