justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263290.78@justin-prod-sched01.dune.hep.ac.uk
Workflow ID3461
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:57:08
SiteUK_Sheffield
EntryDUNE_UK_Sheffield_lcgce1
Last heartbeat2024-09-26 11:45:21
From worker nodeHostnamewn014.hep
cpuinfoIntel(R) Core(TM) i7-5960X CPU @ 3.00GHz
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 09:59:53
Input fileshd-protodune:np04hd_raw_run027298_0217_dataflow1_datawriter_0_20240619T150340.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 11:45:21
Saved logsjustin-logs:263290.78-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

> 10 XUVs
C:0 T:4 32 XUs and 73 XVs -> 0 XUVs
C:0 T:5 27680 XUs and 47085 XVs -> 1709 XUVs
C:0 T:6 16666 XUs and 8471 XVs -> 278 XUVs
C:0 T:7 76 XUs and 71 XVs -> 2 XUVs
2966 XUVs total
1378 collection wire objects
2966 potential space points
Neighbour search...
73210 tests to find 45058 neighbours
Iterating with no regularization...
Begin: 3.99888e+07
0 3.73043e+07
1 3.68977e+07
2 3.68774e+07
Now with regularization...
Begin: 3.52474e+07
0 3.5241e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  4   event 30509 TrkackID 7 startx, y, z 30.3479 373.865 446.819  wireno, peakt tpcno 272 2451.03 5 dist 7.97298min T, max_T2410.41 2883.15
no of hits closeby  4   event 30509 TrkackID 7 startx, y, z 30.3479 373.865 446.819  wireno, peakt tpcno 272 2451.03 5 dist 7.97298min T, max_T2410.41 2883.15
Begin processing the 34th record. run: 27298 subRun: 1 event: 30513 at 26-Sep-2024 12:29:47 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 57955 traces, 57955 summary
Retagger: tagging trace set: gauss with 44583 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 44583 traces tagged "gauss"
FrameSaver: q=1.66941e+06 n=836727 tag=gauss
wclsFrameSaver: saving 57955 traces tagged "wiener"
FrameSaver: q=1.78578e+06 n=762143 tag=wiener
4005 hits in TPC 0
3980 hits in TPC 1
6387 hits in TPC 2
3425 hits in TPC 3
4177 hits in TPC 4
7257 hits in TPC 5
2056 hits in TPC 6
1569 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 8 XUs and 0 XVs -> 0 XUVs
C:0 T:1 6593 XUs and 6203 XVs -> 1236 XUVs
C:0 T:2 15081 XUs and 16418 XVs -> 1005 XUVs
C:0 T:3 59 XUs and 81 XVs -> 0 XUVs
C:0 T:4 211 XUs and 282 XVs -> 14 XUVs
C:0 T:5 33223 XUs and 24016 XVs -> 591 XUVs
C:0 T:6 2061 XUs and 1671 XVs -> 57 XUVs
C:0 T:7 118 XUs and 86 XVs -> 6 XUVs
2909 XUVs total
1268 collection wire objects
2909 potential space points
Neighbour search...
71003 tests to find 43968 neighbours
Iterating with no regularization...
Begin: 1.95011e+07
0 1.82396e+07
1 1.81844e+07
2 1.81779e+07
Now with regularization...
Begin: 1.70478e+07
0 1.70466e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 35th record. run: 27298 subRun: 1 event: 30517 at 26-Sep-2024 12:31:37 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 62562 traces, 62562 summary
Retagger: tagging trace set: gauss with 45886 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 45886 traces tagged "gauss"
FrameSaver: q=1.6835e+06 n=794428 tag=gauss
wclsFrameSaver: saving 62562 traces tagged "wiener"
FrameSaver: q=1.82772e+06 n=715525 tag=wiener
4672 hits in TPC 0
6500 hits in TPC 1
4261 hits in TPC 2
2798 hits in TPC 3
3785 hits in TPC 4
5983 hits in TPC 5
2977 hits in TPC 6
1971 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 7400 XUs and 6302 XVs -> 468 XUVs
C:0 T:2 14936 XUs and 19705 XVs -> 452 XUVs
C:0 T:3 262 XUs and 370 XVs -> 7 XUVs
C:0 T:4 13 XUs and 17 XVs -> 0 XUVs
C:0 T:5 21377 XUs and 19363 XVs -> 1038 XUVs
C:0 T:6 3850 XUs and 5440 XVs -> 819 XUVs
C:0 T:7 453 XUs and 392 XVs -> 21 XUVs
2805 XUVs total
1226 collection wire objects
2805 potential space points
Neighbour search...
58927 tests to find 35794 neighbours
Iterating with no regularization...
Begin: 2.02039e+07
0 1.89576e+07
1 1.88586e+07
2 1.8848e+07
Now with regularization...
Begin: 1.75687e+07
0 1.75661e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

26-Sep-2024 12:33:28 BST  Closed output file "np04hd_raw_run027298_0217_dataflow1_datawriter_0_20240619T150340_reco_stage1_reco_stage2_20240926T113328_keepup.root"
26-Sep-2024 12:33:28 BST  Closed input file "np04hd_raw_run027298_0217_dataflow1_datawriter_0_20240619T150340_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              83.5484       120.743       160.573       119.88        21.5555        35     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000398258   0.00216037     0.006244     0.00233003    0.00137456       35     
produce:fembfilter:PDHDFEMBFilter                       0.5938       0.716837       1.10312      0.647081       0.15435        35     
produce:wclsdatahd:WireCellToolkit                      53.1285       56.3151       61.6985       56.106        1.78261        35     
produce:gaushit:GausHitFinder                          0.363631      0.768556       1.2506       0.749646      0.190486        35     
produce:nhitsfilter:NumberOfHitsFilter                0.000212335   0.000360029   0.000498126   0.000359522   6.96067e-05      35     
produce:reco3d:SpacePointSolver                        0.113144      0.897262       3.26717       0.77016       0.58232        35     
produce:hitpdune:DisambigFromSpacePoints               0.156064      0.684102       1.64514      0.644631      0.344013        35     
produce:pandora:StandardPandora                         6.92017       18.0046       42.7634       16.7305       8.27734        35     
produce:pandoraWriter:StandardPandora                  0.0977675     0.150539      0.213167       0.14947      0.0302133       35     
produce:pandoraTrack:LArPandoraTrackCreation           0.721079       2.04954       3.93121       1.91072      0.847522        35     
produce:pandoraShower:LArPandoraShowerCreation         0.476742       2.47097       5.74202       2.09264       1.2897         35     
produce:pandoracalo:Calorimetry                        0.319369      0.996121       2.18195      0.861927      0.440697        35     
produce:pandoracalonosce:Calorimetry                   0.299913      0.936558       2.05717      0.859764      0.415011        35     
produce:pandorapid:Chi2ParticleID                     0.00112262     0.0018393    0.00401724    0.00170947    0.000513851      35     
produce:pandoraShowercalo:ShowerCalorimetry            0.626863       2.33009       5.93397       1.94476       1.26668        35     
produce:pandoraShowercalonosce:ShowerCalorimetry        0.6184        2.22931       5.28394       1.9625        1.20405        35     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0630531     0.123801      0.279201      0.121495      0.0399003       35     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0235026     0.0428785     0.0999185     0.0405949     0.0139907       35     
produce:emtrkmichelid:EmTrackMichelId                   15.3579       30.2407       47.4213       29.6841       7.91538        35     
[art]:TriggerResults:TriggerResultInserter            1.7215e-05    2.9396e-05    6.9849e-05    2.7132e-05    1.11338e-05      35     
end_path:out1:RootOutput                               3.167e-06    4.94146e-06   1.9707e-05     4.093e-06    2.90559e-06      35     
end_path:michelremoving:michelremoving                 0.0134693     0.0925842     0.235581      0.0533911     0.0717588       35     
end_path:michelremovinggnocchi:michelremoving          0.0131342      0.08754       0.21985      0.0523681     0.0687734       35     
end_path:calibanagnocchi:CalibAnaTree                  0.190833      0.764155       1.84358      0.666844       0.36677        35     
end_path:out1:RootOutput(write)                        0.366402      0.716949       1.21057      0.689771      0.209719        35     
========================================================================================================================================

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

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