justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263758.5@justin-prod-sched01.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:23:34
SiteUK_Durham
EntryDUNE_UK_SGridDurham_ce3
Last heartbeat2024-09-26 15:17:50
From worker nodeHostnamen182.dur.scotgrid.ac.uk
cpuinfoIntel(R) Xeon(R) Gold 5220 CPU @ 2.20GHz
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4193255424 (3999 MiB)
Wall seconds limit171000 (47 hours)
Inner Apptainer?True
Job statejobscript_error
Allocator namejustin-allocator-pro.dune.hep.ac.uk
Started2024-09-26 13:24:53
Input fileshd-protodune:np04hd_raw_run029107_0786_dataflow2_datawriter_0_20240907T034547.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 15:17:50
Saved logsjustin-logs:263758.5-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

6540 XVs -> 217 XUVs
C:0 T:3 264 XUs and 481 XVs -> 19 XUVs
C:0 T:4 104 XUs and 165 XVs -> 3 XUVs
C:0 T:5 31997 XUs and 62658 XVs -> 2884 XUVs
C:0 T:6 24272 XUs and 45247 XVs -> 1127 XUVs
C:0 T:7 80 XUs and 96 XVs -> 0 XUVs
4253 XUVs total
1654 collection wire objects
4253 potential space points
Neighbour search...
275587 tests to find 141960 neighbours
Iterating with no regularization...
Begin: 1.10396e+07
0 1.01656e+07
1 1.00967e+07
2 1.00908e+07
Now with regularization...
Begin: 9.60504e+06
0 9.60259e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 29th record. run: 29107 subRun: 1 event: 188914 at 26-Sep-2024 16:14:02 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 44404 traces, 44404 summary
Retagger: tagging trace set: gauss with 35039 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 35039 traces tagged "gauss"
FrameSaver: q=784071 n=721267 tag=gauss
wclsFrameSaver: saving 44404 traces tagged "wiener"
FrameSaver: q=850822 n=672057 tag=wiener
1608 hits in TPC 0
2254 hits in TPC 1
3363 hits in TPC 2
2565 hits in TPC 3
3533 hits in TPC 4
4933 hits in TPC 5
4268 hits in TPC 6
3691 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 356 XUs and 2058 XVs -> 37 XUVs
C:0 T:2 7395 XUs and 7861 XVs -> 477 XUVs
C:0 T:3 26 XUs and 81 XVs -> 1 XUVs
C:0 T:4 107 XUs and 209 XVs -> 2 XUVs
C:0 T:5 49290 XUs and 86048 XVs -> 4328 XUVs
C:0 T:6 11824 XUs and 11702 XVs -> 298 XUVs
C:0 T:7 286 XUs and 321 XVs -> 19 XUVs
5162 XUVs total
1418 collection wire objects
5162 potential space points
Neighbour search...
504922 tests to find 261626 neighbours
Iterating with no regularization...
Begin: 1.12076e+07
0 1.03123e+07
1 1.02662e+07
2 1.02576e+07
Now with regularization...
Begin: 9.72041e+06
0 9.71648e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 30th record. run: 29107 subRun: 1 event: 188922 at 26-Sep-2024 16:15:24 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 67444 traces, 67444 summary
Retagger: tagging trace set: gauss with 51241 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 51241 traces tagged "gauss"
FrameSaver: q=1.15626e+06 n=1029351 tag=gauss
wclsFrameSaver: saving 67444 traces tagged "wiener"
FrameSaver: q=1.23579e+06 n=943315 tag=wiener
4974 hits in TPC 0
4126 hits in TPC 1
5299 hits in TPC 2
4866 hits in TPC 3
3485 hits in TPC 4
3819 hits in TPC 5
7187 hits in TPC 6
3932 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 996 XUs and 1512 XVs -> 76 XUVs
C:0 T:2 20374 XUs and 13753 XVs -> 482 XUVs
C:0 T:3 143 XUs and 147 XVs -> 5 XUVs
C:0 T:4 180 XUs and 274 XVs -> 2 XUVs
C:0 T:5 9002 XUs and 45867 XVs -> 1518 XUVs
C:0 T:6 25676 XUs and 15962 XVs -> 641 XUVs
C:0 T:7 45 XUs and 36 XVs -> 0 XUVs
2724 XUVs total
1196 collection wire objects
2724 potential space points
Neighbour search...
111522 tests to find 54884 neighbours
Iterating with no regularization...
Begin: 9.12626e+06
0 8.22718e+06
1 8.16834e+06
2 8.16469e+06
Now with regularization...
Begin: 7.70381e+06
0 7.70267e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  0   event 188922 TrkackID 9 startx, y, z 41.2564 590.999 344.808  wireno, peakt tpcno 83 1726.74 5 dist 0min T, max_T1726.74 3923
no of hits closeby  0   event 188922 TrkackID 9 startx, y, z 41.2564 590.999 344.808  wireno, peakt tpcno 83 1726.74 5 dist 0min T, max_T1726.74 3923
26-Sep-2024 16:17:21 BST  Closed output file "np04hd_raw_run029107_0786_dataflow2_datawriter_0_20240907T034547_reco_stage1_reco_stage2_20240926T151721_keepup.root"
26-Sep-2024 16:17:21 BST  Closed input file "np04hd_raw_run029107_0786_dataflow2_datawriter_0_20240907T034547_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              81.6637       116.492       170.278       111.792       26.2975        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000287936   0.00111202    0.00317264    0.000892005   0.000810755      30     
produce:fembfilter:PDHDFEMBFilter                      0.690031      0.940972       1.12034        1.056       0.174749        30     
produce:wclsdatahd:WireCellToolkit                      43.7241       60.0725       69.858        64.1863       8.22187        30     
produce:gaushit:GausHitFinder                          0.457352       1.0132        1.62686      0.894165      0.339329        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000264645   0.000486151   0.00122621    0.000426679   0.00021254       30     
produce:reco3d:SpacePointSolver                        0.238219       1.18071       3.1967       0.861118       0.80166        30     
produce:hitpdune:DisambigFromSpacePoints               0.260355      0.793063       1.67088      0.675322      0.408228        30     
produce:pandora:StandardPandora                         6.1324        17.1713       38.4301       13.4412       9.2626         30     
produce:pandoraWriter:StandardPandora                  0.0994585     0.193868      0.353211      0.178534      0.0657023       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.778015       2.20831       5.34074       1.86208       1.19769        30     
produce:pandoraShower:LArPandoraShowerCreation         0.478542       2.24854       6.69013       1.7879        1.59715        30     
produce:pandoracalo:Calorimetry                        0.314299       0.87785       2.08456      0.748525      0.469049        30     
produce:pandoracalonosce:Calorimetry                   0.273634      0.806833       1.9077       0.685651      0.433376        30     
produce:pandorapid:Chi2ParticleID                     0.00135338    0.00243926    0.00596165    0.00215612    0.00102065       30     
produce:pandoraShowercalo:ShowerCalorimetry            0.378568       1.84389       5.10433       1.47638       1.27852        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.367276       1.80661       5.20923       1.47005       1.24435        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0751704     0.167614      0.309221      0.154679      0.0648608       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0320349     0.0683376     0.124006      0.0585976     0.0278299       30     
produce:emtrkmichelid:EmTrackMichelId                   12.5781       22.9831       39.493        22.4375       6.62626        30     
[art]:TriggerResults:TriggerResultInserter            2.8722e-05    3.91867e-05   7.0321e-05    3.65735e-05   8.14662e-06      30     
end_path:out1:RootOutput                               7.171e-06    1.09207e-05   2.1592e-05    1.01115e-05   3.22612e-06      30     
end_path:michelremoving:michelremoving                 0.0200487     0.093352      0.348038      0.0494509     0.0886278       30     
end_path:michelremovinggnocchi:michelremoving          0.0181142     0.0902921     0.343694      0.044896      0.0881696       30     
end_path:calibanagnocchi:CalibAnaTree                  0.207937      0.702411       1.4567        0.5952       0.354586        30     
end_path:out1:RootOutput(write)                        0.501448       1.06378       1.68099      0.995065      0.356862        30     
========================================================================================================================================

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

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