justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263283.138@justin-prod-sched01.dune.hep.ac.uk
Workflow ID3447
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:56:54
SiteFR_CCIN2P3
EntryDUNE_FR_CCIN2P3_cccondorce03
Last heartbeat2024-09-26 11:14:20
From worker nodeHostnameccwcondor0748
cpuinfoAMD EPYC 7302 16-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4193255424 (3999 MiB)
Wall seconds limit106200 (29 hours)
Inner Apptainer?True
Job statejobscript_error
Allocator namejustin-allocator-pro.dune.hep.ac.uk
Started2024-09-26 09:59:27
Input fileshd-protodune:np04hd_raw_run029081_0936_dataflow1_datawriter_0_20240905T073129.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 11:14:20
Saved logsjustin-logs:263283.138-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

tag=gauss
wclsFrameSaver: saving 88907 traces tagged "wiener"
FrameSaver: q=1.55967e+06 n=1052440 tag=wiener
4484 hits in TPC 0
3583 hits in TPC 1
6392 hits in TPC 2
5123 hits in TPC 3
5211 hits in TPC 4
7498 hits in TPC 5
7620 hits in TPC 6
5340 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 3769 XUs and 4374 XVs -> 205 XUVs
C:0 T:2 19420 XUs and 24103 XVs -> 1803 XUVs
C:0 T:3 915 XUs and 891 XVs -> 33 XUVs
C:0 T:4 270 XUs and 285 XVs -> 0 XUVs
C:0 T:5 37845 XUs and 23211 XVs -> 802 XUVs
C:0 T:6 30376 XUs and 35127 XVs -> 1833 XUVs
C:0 T:7 712 XUs and 864 XVs -> 30 XUVs
4706 XUVs total
1940 collection wire objects
4706 potential space points
Neighbour search...
141796 tests to find 88366 neighbours
Iterating with no regularization...
Begin: 1.06221e+07
0 9.98886e+06
1 9.94294e+06
2 9.93463e+06
Now with regularization...
Begin: 9.1357e+06
0 9.13238e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 29th record. run: 29081 subRun: 1 event: 224865 at 26-Sep-2024 13:01:44 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 72874 traces, 72874 summary
Retagger: tagging trace set: gauss with 53679 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 53679 traces tagged "gauss"
FrameSaver: q=1.21131e+06 n=1003063 tag=gauss
wclsFrameSaver: saving 72874 traces tagged "wiener"
FrameSaver: q=1.33547e+06 n=919126 tag=wiener
3666 hits in TPC 0
3578 hits in TPC 1
3965 hits in TPC 2
2874 hits in TPC 3
5222 hits in TPC 4
7076 hits in TPC 5
8084 hits in TPC 6
6368 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 337 XUs and 476 XVs -> 14 XUVs
C:0 T:2 7889 XUs and 11429 XVs -> 468 XUVs
C:0 T:3 194 XUs and 158 XVs -> 3 XUVs
C:0 T:4 294 XUs and 290 XVs -> 3 XUVs
C:0 T:5 40959 XUs and 44155 XVs -> 1088 XUVs
C:0 T:6 67814 XUs and 126159 XVs -> 9741 XUVs
C:0 T:7 329 XUs and 346 XVs -> 8 XUVs
11325 XUVs total
2188 collection wire objects
11325 potential space points
Neighbour search...
1521205 tests to find 679424 neighbours
Iterating with no regularization...
Begin: 1.62917e+07
0 1.11703e+07
1 1.06812e+07
2 1.05907e+07
3 1.05596e+07
4 1.0544e+07
5 1.05353e+07
Now with regularization...
Begin: 9.74955e+06
0 9.73906e+06
1 9.73373e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 30th record. run: 29081 subRun: 1 event: 224873 at 26-Sep-2024 13:03:08 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 68103 traces, 68103 summary
Retagger: tagging trace set: gauss with 49978 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 49978 traces tagged "gauss"
FrameSaver: q=1.0587e+06 n=911104 tag=gauss
wclsFrameSaver: saving 68103 traces tagged "wiener"
FrameSaver: q=1.13931e+06 n=818615 tag=wiener
1348 hits in TPC 0
2084 hits in TPC 1
5780 hits in TPC 2
3312 hits in TPC 3
2896 hits in TPC 4
5161 hits in TPC 5
9511 hits in TPC 6
5629 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 51 XUs and 28 XVs -> 5 XUVs
C:0 T:2 28395 XUs and 66661 XVs -> 760 XUVs
C:0 T:3 271 XUs and 242 XVs -> 4 XUVs
C:0 T:4 385 XUs and 316 XVs -> 12 XUVs
C:0 T:5 9669 XUs and 10549 XVs -> 461 XUVs
C:0 T:6 91897 XUs and 87034 XVs -> 3068 XUVs
C:0 T:7 636 XUs and 920 XVs -> 10 XUVs
4320 XUVs total
1346 collection wire objects
4320 potential space points
Neighbour search...
270046 tests to find 127028 neighbours
Iterating with no regularization...
Begin: 6.76871e+06
0 6.24776e+06
1 6.21427e+06
2 6.20928e+06
Now with regularization...
Begin: 5.91642e+06
0 5.91443e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

26-Sep-2024 13:04:26 CEST  Closed output file "np04hd_raw_run029081_0936_dataflow1_datawriter_0_20240905T073129_reco_stage1_reco_stage2_20240926T110426_keepup.root"
26-Sep-2024 13:04:26 CEST  Closed input file "np04hd_raw_run029081_0936_dataflow1_datawriter_0_20240905T073129_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              56.402        88.5996       123.371       85.2653       17.4755        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000626437    0.0026165    0.00566863    0.00259812    0.00141424       30     
produce:fembfilter:PDHDFEMBFilter                      0.536713      0.656559      0.918141      0.613941      0.118828        30     
produce:wclsdatahd:WireCellToolkit                      35.2794       41.8573       49.4836       42.5958       5.4858         30     
produce:gaushit:GausHitFinder                           0.26993      0.589275       1.06265      0.572627      0.183885        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000189586   0.000396082   0.000965664   0.000351004   0.000159716      30     
produce:reco3d:SpacePointSolver                        0.154501      0.744661       2.09306      0.642061      0.510174        30     
produce:hitpdune:DisambigFromSpacePoints               0.0917006     0.415775      0.893022      0.351535      0.212326        30     
produce:pandora:StandardPandora                         3.11718       10.4619       25.5489       9.19384       4.76749        30     
produce:pandoraWriter:StandardPandora                  0.054255      0.133434      0.242774      0.134042      0.0453435       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.286903      0.997547       2.07028      0.960752      0.414487        30     
produce:pandoraShower:LArPandoraShowerCreation         0.181639       1.07951       2.27006      0.916159      0.503405        30     
produce:pandoracalo:Calorimetry                         0.16853       0.52206      0.968248      0.513981       0.19316        30     
produce:pandoracalonosce:Calorimetry                   0.146021      0.464898      0.977023      0.469111      0.180759        30     
produce:pandorapid:Chi2ParticleID                     0.000813468   0.00186859    0.00526955    0.00175267    0.000809186      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.154336       1.02838       2.35828      0.910191      0.495621        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.154245       1.00645       2.32785      0.898801      0.495365        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0610584      0.13468      0.247172      0.126386      0.0479226       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0208287     0.0456147     0.0880989     0.0413758     0.0159942       30     
produce:emtrkmichelid:EmTrackMichelId                   12.7234       27.1227       43.6584       27.0788       7.32217        30     
[art]:TriggerResults:TriggerResultInserter            1.4788e-05    4.03718e-05   0.000113964   3.3999e-05    2.36075e-05      30     
end_path:out1:RootOutput                               7.635e-06    1.19766e-05    3.722e-05     1.062e-05    5.92134e-06      30     
end_path:michelremoving:michelremoving                 0.0145523     0.0565954     0.183512      0.0391755     0.0448003       30     
end_path:michelremovinggnocchi:michelremoving          0.0132314     0.0536462     0.200153      0.0325265     0.0485174       30     
end_path:calibanagnocchi:CalibAnaTree                  0.126674      0.379557      0.735075      0.409287      0.142257        30     
end_path:out1:RootOutput(write)                        0.352921      0.712861       1.16044      0.698316      0.186275        30     
========================================================================================================================================

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

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