justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263931.145@justin-prod-sched01.dune.hep.ac.uk
Workflow ID3448
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:23:56
SiteUK_Sheffield
EntryDUNE_UK_Sheffield_lcgce1
Last heartbeat2024-09-26 15:56:49
From worker nodeHostnamewn023.hep
cpuinfoIntel(R) Xeon(R) Gold 5115 CPU @ 2.40GHz
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:25:16
Input fileshd-protodune:np04hd_raw_run029094_0043_dataflow4_datawriter_0_20240906T085353.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 15:56:49
Saved logsjustin-logs:263931.145-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

gged "wiener"
FrameSaver: q=873795 n=595396 tag=wiener
1878 hits in TPC 0
2127 hits in TPC 1
3874 hits in TPC 2
2382 hits in TPC 3
3655 hits in TPC 4
5900 hits in TPC 5
4954 hits in TPC 6
2110 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 67 XUs and 33 XVs -> 14 XUVs
C:0 T:1 16 XUs and 11 XVs -> 0 XUVs
C:0 T:2 17622 XUs and 18008 XVs -> 586 XUVs
C:0 T:3 44 XUs and 88 XVs -> 3 XUVs
C:0 T:4 150 XUs and 253 XVs -> 12 XUVs
C:0 T:5 21918 XUs and 23247 XVs -> 735 XUVs
C:0 T:6 15081 XUs and 20512 XVs -> 494 XUVs
C:0 T:7 105 XUs and 137 XVs -> 10 XUVs
1854 XUVs total
824 collection wire objects
1854 potential space points
Neighbour search...
64336 tests to find 37104 neighbours
Iterating with no regularization...
Begin: 1.0717e+07
0 9.56156e+06
1 9.52671e+06
2 9.52234e+06
Now with regularization...
Begin: 9.00892e+06
0 9.00545e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 29th record. run: 29094 subRun: 1 event: 10548 at 26-Sep-2024 16:52:58 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 37454 traces, 37454 summary
Retagger: tagging trace set: gauss with 29045 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 29045 traces tagged "gauss"
FrameSaver: q=602269 n=553525 tag=gauss
wclsFrameSaver: saving 37454 traces tagged "wiener"
FrameSaver: q=631626 n=497960 tag=wiener
2033 hits in TPC 0
1273 hits in TPC 1
5006 hits in TPC 2
2573 hits in TPC 3
2548 hits in TPC 4
3795 hits in TPC 5
1668 hits in TPC 6
1295 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 4 XUs and 0 XVs -> 0 XUVs
C:0 T:2 12027 XUs and 8984 XVs -> 302 XUVs
C:0 T:3 263 XUs and 259 XVs -> 13 XUVs
C:0 T:4 25 XUs and 28 XVs -> 3 XUVs
C:0 T:5 9131 XUs and 7283 XVs -> 170 XUVs
C:0 T:6 1293 XUs and 2528 XVs -> 244 XUVs
C:0 T:7 134 XUs and 247 XVs -> 6 XUVs
738 XUVs total
627 collection wire objects
738 potential space points
Neighbour search...
5508 tests to find 2892 neighbours
Iterating with no regularization...
Begin: 2.23847e+06
0 2.16968e+06
1 2.16868e+06
Now with regularization...
Begin: 1.983e+06
0 1.98288e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  1   event 10548 TrkackID 0 startx, y, z 120.572 599.296 147.774  wireno, peakt tpcno 423 10.239 5 dist 0min T, max_T1.87761 3552.15
no of hits closeby  1   event 10548 TrkackID 0 startx, y, z 120.572 599.296 147.774  wireno, peakt tpcno 423 10.239 5 dist 0min T, max_T1.87761 3552.15
Begin processing the 30th record. run: 29094 subRun: 1 event: 10556 at 26-Sep-2024 16:54:36 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 56733 traces, 56733 summary
Retagger: tagging trace set: gauss with 42402 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 42402 traces tagged "gauss"
FrameSaver: q=987696 n=820060 tag=gauss
wclsFrameSaver: saving 56733 traces tagged "wiener"
FrameSaver: q=1.04325e+06 n=740518 tag=wiener
4339 hits in TPC 0
2911 hits in TPC 1
3002 hits in TPC 2
1696 hits in TPC 3
3114 hits in TPC 4
5482 hits in TPC 5
6814 hits in TPC 6
3090 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 236 XUs and 355 XVs -> 14 XUVs
C:0 T:2 7761 XUs and 7369 XVs -> 603 XUVs
C:0 T:3 107 XUs and 84 XVs -> 9 XUVs
C:0 T:4 243 XUs and 581 XVs -> 26 XUVs
C:0 T:5 15111 XUs and 14167 XVs -> 536 XUVs
C:0 T:6 19003 XUs and 27258 XVs -> 1161 XUVs
C:0 T:7 255 XUs and 261 XVs -> 6 XUVs
2355 XUVs total
1073 collection wire objects
2355 potential space points
Neighbour search...
110391 tests to find 65366 neighbours
Iterating with no regularization...
Begin: 7.12078e+06
0 6.75269e+06
1 6.72886e+06
2 6.72431e+06
Now with regularization...
Begin: 6.30014e+06
0 6.29878e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

26-Sep-2024 16:56:27 BST  Closed output file "np04hd_raw_run029094_0043_dataflow4_datawriter_0_20240906T085353_reco_stage1_reco_stage2_20240926T155627_keepup.root"
26-Sep-2024 16:56:27 BST  Closed input file "np04hd_raw_run029094_0043_dataflow4_datawriter_0_20240906T085353_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              87.9829       125.314       150.33        125.71        15.5548        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000480869   0.00274094    0.00703446     0.0026353    0.00130203       30     
produce:fembfilter:PDHDFEMBFilter                      0.739905      0.758489      0.790164      0.757148     0.00907419       30     
produce:wclsdatahd:WireCellToolkit                      49.9016       52.1937       55.7399       51.8054       1.47457        30     
produce:gaushit:GausHitFinder                          0.431291      0.783836       1.00896      0.796343      0.141849        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000307618   0.000439776   0.000562895   0.000445689   5.87326e-05      30     
produce:reco3d:SpacePointSolver                        0.146578      0.828205       2.93141      0.697092      0.569612        30     
produce:hitpdune:DisambigFromSpacePoints               0.173165       0.58433       1.03266      0.634608      0.208002        30     
produce:pandora:StandardPandora                         5.89696       13.4738       18.9804       14.0277       3.89981        30     
produce:pandoraWriter:StandardPandora                   0.10336      0.166195      0.221651      0.167385      0.0286541       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.586282       1.8212        3.60468       1.78884      0.638114        30     
produce:pandoraShower:LArPandoraShowerCreation         0.628402       1.95019       4.02216       1.99837      0.755315        30     
produce:pandoracalo:Calorimetry                         0.29777      0.809148       1.60547      0.820301      0.251914        30     
produce:pandoracalonosce:Calorimetry                   0.270219      0.753566       1.58396      0.734722       0.25172        30     
produce:pandorapid:Chi2ParticleID                     0.00114002    0.00191673    0.00308878    0.00185064    0.000349833      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.511757       1.8295        4.00977       1.86358      0.729849        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.497095       1.76956       3.79378       1.81052      0.690385        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0794895     0.132087      0.176553      0.136017      0.024581        30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0314127     0.0515522     0.0698366     0.0530354    0.00950079       30     
produce:emtrkmichelid:EmTrackMichelId                   24.0159       45.7307       61.366        46.1358       8.44302        30     
[art]:TriggerResults:TriggerResultInserter            2.6517e-05    3.93764e-05   8.3198e-05    3.69775e-05   1.06132e-05      30     
end_path:out1:RootOutput                               7.385e-06    1.07255e-05    2.595e-05    9.9275e-06    3.08845e-06      30     
end_path:michelremoving:michelremoving                 0.0203691     0.0602591     0.213238      0.042958      0.0459426       30     
end_path:michelremovinggnocchi:michelremoving          0.0196527     0.0554901     0.204858      0.0383926     0.0450469       30     
end_path:calibanagnocchi:CalibAnaTree                  0.174822      0.595179       1.23556      0.579383      0.202805        30     
end_path:out1:RootOutput(write)                        0.506609      0.817142       1.08113      0.857121      0.145425        30     
========================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4971.81 MB
  Peak resident set size usage (VmHWM): 2391.53 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run029094_0043_dataflow4_datawriter_0_20240906T085353_reco_stage1_reco_stage2_20240926T155627_keepup.root
\tHists: np04hd_raw_run029094_0043_dataflow4_datawriter_0_20240906T085353_reco_stage1_reco_stage2_20240926T155627_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 04:05:15 UTC       justIN version: 01.01.09