justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID79114.2@justin-prod-sched02.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 14:31:11
SiteUK_Brunel
EntryCMSHTPC_T2_UK_London_Brunel_dc2_22
Last heartbeat2024-09-26 15:50:22
From worker nodeHostnamewn-b8-27-00.brunel.ac.uk
cpuinfoIntel(R) Xeon(R) CPU E5-2630 v3 @ 2.40GHz
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 14:31:43
Input fileshd-protodune:np04hd_raw_run029107_0860_dataflow2_datawriter_0_20240907T044430.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 15:50:22
Saved logsjustin-logs:79114.2-justin-prod-sched02.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

ts - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  12   event 206642 TrkackID 24 startx, y, z 12.8061 107.869 451.294  wireno, peakt tpcno 440 5649.23 5 dist 7.57624min T, max_T5549.62 5721.53
no of hits closeby  12   event 206642 TrkackID 24 startx, y, z 12.8061 107.869 451.294  wireno, peakt tpcno 440 5649.23 5 dist 7.57624min T, max_T5549.62 5721.53
Begin processing the 29th record. run: 29107 subRun: 1 event: 206650 at 26-Sep-2024 16:46:46 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 86929 traces, 86929 summary
Retagger: tagging trace set: gauss with 63868 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 63868 traces tagged "gauss"
FrameSaver: q=1.34622e+06 n=1175787 tag=gauss
wclsFrameSaver: saving 86929 traces tagged "wiener"
FrameSaver: q=1.45537e+06 n=1065585 tag=wiener
3794 hits in TPC 0
2976 hits in TPC 1
9031 hits in TPC 2
4674 hits in TPC 3
3412 hits in TPC 4
7060 hits in TPC 5
9848 hits in TPC 6
4950 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 163 XUs and 224 XVs -> 39 XUVs
C:0 T:2 36175 XUs and 65404 XVs -> 1346 XUVs
C:0 T:3 432 XUs and 730 XVs -> 8 XUVs
C:0 T:4 298 XUs and 248 XVs -> 4 XUVs
C:0 T:5 23502 XUs and 21378 XVs -> 370 XUVs
C:0 T:6 39494 XUs and 62306 XVs -> 1532 XUVs
C:0 T:7 176 XUs and 132 XVs -> 8 XUVs
3307 XUVs total
1633 collection wire objects
3307 potential space points
Neighbour search...
70035 tests to find 40474 neighbours
Iterating with no regularization...
Begin: 9.64453e+06
0 9.16845e+06
1 9.1385e+06
2 9.13625e+06
Now with regularization...
Begin: 8.54989e+06
0 8.54914e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  1   event 206650 TrkackID 4 startx, y, z 164.222 586.567 235.386  wireno, peakt tpcno 457 2028.18 2 dist 196.705min T, max_T5 2041
no of hits closeby  2   event 206650 TrkackID 18 startx, y, z 146.343 259.3 354.073  wireno, peakt tpcno 393 1774.1 5 dist 0min T, max_T4.66938 1858.05
no of hits closeby  1   event 206650 TrkackID 4 startx, y, z 164.222 586.567 235.386  wireno, peakt tpcno 457 2028.18 2 dist 196.705min T, max_T5 2041
no of hits closeby  2   event 206650 TrkackID 18 startx, y, z 146.343 259.3 354.073  wireno, peakt tpcno 393 1774.1 5 dist 0min T, max_T4.66938 1858.05
Begin processing the 30th record. run: 29107 subRun: 1 event: 206666 at 26-Sep-2024 16:48:40 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 49307 traces, 49307 summary
Retagger: tagging trace set: gauss with 36390 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 36390 traces tagged "gauss"
FrameSaver: q=740746 n=670384 tag=gauss
wclsFrameSaver: saving 49307 traces tagged "wiener"
FrameSaver: q=799220 n=603436 tag=wiener
784 hits in TPC 0
1408 hits in TPC 1
6448 hits in TPC 2
4134 hits in TPC 3
1217 hits in TPC 4
2645 hits in TPC 5
6532 hits in TPC 6
4033 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 192 XUs and 270 XVs -> 15 XUVs
C:0 T:2 40600 XUs and 54401 XVs -> 1541 XUVs
C:0 T:3 83 XUs and 73 XVs -> 2 XUVs
C:0 T:4 86 XUs and 119 XVs -> 13 XUVs
C:0 T:5 2079 XUs and 1758 XVs -> 61 XUVs
C:0 T:6 35104 XUs and 69028 XVs -> 1450 XUVs
C:0 T:7 1869 XUs and 1487 XVs -> 521 XUVs
3603 XUVs total
975 collection wire objects
3603 potential space points
Neighbour search...
326275 tests to find 168648 neighbours
Iterating with no regularization...
Begin: 3.95231e+06
0 3.45037e+06
1 3.41166e+06
2 3.40368e+06
3 3.40091e+06
Now with regularization...
Begin: 3.21364e+06
0 3.21201e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

26-Sep-2024 16:49:58 BST  Closed output file "np04hd_raw_run029107_0860_dataflow2_datawriter_0_20240907T044430_reco_stage1_reco_stage2_20240926T154958_keepup.root"
26-Sep-2024 16:49:58 BST  Closed input file "np04hd_raw_run029107_0860_dataflow2_datawriter_0_20240907T044430_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              72.1054       91.9679       116.91        89.8028       11.6539        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000341106   0.00120865     0.0022021    0.00130101    0.000606747      30     
produce:fembfilter:PDHDFEMBFilter                      0.626994      0.639756      0.700632      0.634792      0.0160022       30     
produce:wclsdatahd:WireCellToolkit                      42.6706       45.7141       49.9775       45.1909       1.73383        30     
produce:gaushit:GausHitFinder                          0.475335      0.750528       1.06734      0.733787      0.162344        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000166807   0.000291462   0.000416168   0.00029353    7.53591e-05      30     
produce:reco3d:SpacePointSolver                        0.148615      0.772991       2.79159      0.694368      0.628954        30     
produce:hitpdune:DisambigFromSpacePoints               0.183359      0.546798       1.03399      0.489489      0.263361        30     
produce:pandora:StandardPandora                         5.85557       12.5245       24.6383       11.9499       4.4278         30     
produce:pandoraWriter:StandardPandora                  0.0897878     0.129005      0.169747      0.129951      0.0253665       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.494395       1.05625       1.61417       1.05057      0.313339        30     
produce:pandoraShower:LArPandoraShowerCreation         0.463262       1.22916       2.12792       1.22356      0.450504        30     
produce:pandoracalo:Calorimetry                        0.283766      0.550993      0.814897      0.556081      0.144459        30     
produce:pandoracalonosce:Calorimetry                   0.251471      0.500934      0.747743      0.508737      0.133312        30     
produce:pandorapid:Chi2ParticleID                     0.00119199     0.0017385    0.00289915    0.00171787    0.00034714       30     
produce:pandoraShowercalo:ShowerCalorimetry            0.403979       1.13584       1.99247       1.14485      0.419249        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.395323        1.111        1.9175        1.11583      0.408598        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0838056     0.128871      0.176993      0.126032      0.0254972       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0290665     0.0447442     0.0598455     0.0445326    0.00832303       30     
produce:emtrkmichelid:EmTrackMichelId                   16.871        23.6205       31.0631       23.7086       4.3484         30     
[art]:TriggerResults:TriggerResultInserter             1.621e-05    2.17737e-05   6.1677e-05    1.9167e-05    8.23205e-06      30     
end_path:out1:RootOutput                               3.182e-06    7.62427e-06    2.188e-05    7.0415e-06    2.99186e-06      30     
end_path:michelremoving:michelremoving                 0.0177773     0.108166      0.358393      0.102158      0.0903445       30     
end_path:michelremovinggnocchi:michelremoving          0.0168523     0.105884      0.354585      0.100706      0.0899011       30     
end_path:calibanagnocchi:CalibAnaTree                  0.264417      0.452937      0.730035      0.432339      0.113152        30     
end_path:out1:RootOutput(write)                        0.516199      0.776306       1.21195       0.77786      0.171124        30     
========================================================================================================================================

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

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