justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263235.152@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 09:30:31
SiteFR_CCIN2P3
EntryDUNE_FR_CCIN2P3_cccondorce03
Last heartbeat2024-09-26 10:32:56
From worker nodeHostnameccwcondor0595
cpuinfoAMD EPYC 7453 28-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:32:09
Input fileshd-protodune:np04hd_raw_run029092_0905_dataflow6_datawriter_0_20240906T072556.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 10:32:56
Saved logsjustin-logs:263235.152-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

rameSaver: saving 51952 traces tagged "gauss"
FrameSaver: q=1.10536e+06 n=920318 tag=gauss
wclsFrameSaver: saving 72194 traces tagged "wiener"
FrameSaver: q=1.18995e+06 n=822904 tag=wiener
2570 hits in TPC 0
2887 hits in TPC 1
9790 hits in TPC 2
5343 hits in TPC 3
2757 hits in TPC 4
4807 hits in TPC 5
5161 hits in TPC 6
3069 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 103 XUs and 120 XVs -> 0 XUVs
C:0 T:2 55985 XUs and 94976 XVs -> 2340 XUVs
C:0 T:3 659 XUs and 798 XVs -> 10 XUVs
C:0 T:4 42 XUs and 55 XVs -> 6 XUVs
C:0 T:5 32281 XUs and 36865 XVs -> 919 XUVs
C:0 T:6 29469 XUs and 38277 XVs -> 1968 XUVs
C:0 T:7 25 XUs and 27 XVs -> 0 XUVs
5243 XUVs total
1946 collection wire objects
5243 potential space points
Neighbour search...
307819 tests to find 173926 neighbours
Iterating with no regularization...
Begin: 1.55348e+07
0 1.42257e+07
1 1.41443e+07
2 1.41321e+07
Now with regularization...
Begin: 1.31602e+07
0 1.31553e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 29th record. run: 29092 subRun: 1 event: 217430 at 26-Sep-2024 12:29:36 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 70957 traces, 70957 summary
Retagger: tagging trace set: gauss with 54321 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 54321 traces tagged "gauss"
FrameSaver: q=1.19839e+06 n=1007626 tag=gauss
wclsFrameSaver: saving 70957 traces tagged "wiener"
FrameSaver: q=1.2732e+06 n=910233 tag=wiener
2862 hits in TPC 0
2377 hits in TPC 1
6125 hits in TPC 2
5099 hits in TPC 3
4363 hits in TPC 4
6604 hits in TPC 5
4929 hits in TPC 6
3527 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 130 XUs and 199 XVs -> 1 XUVs
C:0 T:2 17358 XUs and 22631 XVs -> 1193 XUVs
C:0 T:3 173 XUs and 253 XVs -> 22 XUVs
C:0 T:4 190 XUs and 145 XVs -> 5 XUVs
C:0 T:5 31522 XUs and 18915 XVs -> 574 XUVs
C:0 T:6 16550 XUs and 11535 XVs -> 371 XUVs
C:0 T:7 721 XUs and 560 XVs -> 44 XUVs
2210 XUVs total
1401 collection wire objects
2210 potential space points
Neighbour search...
41040 tests to find 25290 neighbours
Iterating with no regularization...
Begin: 1.02272e+07
0 9.84513e+06
1 9.80797e+06
2 9.8035e+06
Now with regularization...
Begin: 9.08174e+06
0 9.08027e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 30th record. run: 29092 subRun: 1 event: 217438 at 26-Sep-2024 12:31:07 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 56567 traces, 56567 summary
Retagger: tagging trace set: gauss with 41725 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 41725 traces tagged "gauss"
FrameSaver: q=880078 n=770740 tag=gauss
wclsFrameSaver: saving 56567 traces tagged "wiener"
FrameSaver: q=944536 n=698477 tag=wiener
2000 hits in TPC 0
2459 hits in TPC 1
4513 hits in TPC 2
4107 hits in TPC 3
1787 hits in TPC 4
2571 hits in TPC 5
8048 hits in TPC 6
5036 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 498 XUs and 613 XVs -> 30 XUVs
C:0 T:2 22022 XUs and 28897 XVs -> 1357 XUVs
C:0 T:3 589 XUs and 706 XVs -> 19 XUVs
C:0 T:4 30 XUs and 38 XVs -> 2 XUVs
C:0 T:5 2923 XUs and 8858 XVs -> 111 XUVs
C:0 T:6 38389 XUs and 35443 XVs -> 746 XUVs
C:0 T:7 1023 XUs and 659 XVs -> 16 XUVs
2281 XUVs total
1107 collection wire objects
2281 potential space points
Neighbour search...
83589 tests to find 51890 neighbours
Iterating with no regularization...
Begin: 4.98646e+06
0 4.70295e+06
1 4.68389e+06
2 4.68239e+06
Now with regularization...
Begin: 4.32092e+06
0 4.32023e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

26-Sep-2024 12:32:19 CEST  Closed output file "np04hd_raw_run029092_0905_dataflow6_datawriter_0_20240906T072556_reco_stage1_reco_stage2_20240926T103219_keepup.root"
26-Sep-2024 12:32:19 CEST  Closed input file "np04hd_raw_run029092_0905_dataflow6_datawriter_0_20240906T072556_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              67.7507       86.9582       121.394       84.2448       14.5896        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000711374   0.00258801    0.00427644     0.0026857    0.00110153       30     
produce:fembfilter:PDHDFEMBFilter                      0.497285      0.560294      0.726773       0.51655      0.0726202       30     
produce:wclsdatahd:WireCellToolkit                      33.5272       36.4458       43.2229       36.0185       2.19789        30     
produce:gaushit:GausHitFinder                          0.375606      0.587774       1.19856      0.583436      0.165311        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000227176    0.0003024    0.000496691   0.000289041   5.83871e-05      30     
produce:reco3d:SpacePointSolver                        0.239506       1.24528       4.27973      0.681327       1.11647        30     
produce:hitpdune:DisambigFromSpacePoints               0.207632      0.420035       1.23333      0.341195      0.226628        30     
produce:pandora:StandardPandora                         4.80271       10.7413       33.5631       8.9258        5.53371        30     
produce:pandoraWriter:StandardPandora                  0.0808964     0.127031      0.185015      0.125741      0.027425        30     
produce:pandoraTrack:LArPandoraTrackCreation           0.680912       1.5339        3.00323       1.47055      0.645917        30     
produce:pandoraShower:LArPandoraShowerCreation         0.624177       1.79504       4.29366       1.82025      0.850084        30     
produce:pandoracalo:Calorimetry                        0.327856       0.72225       1.38127       0.71946      0.261898        30     
produce:pandoracalonosce:Calorimetry                   0.309344      0.650657       1.44513      0.581518      0.253904        30     
produce:pandorapid:Chi2ParticleID                     0.00128338    0.00194046    0.00487965    0.00173262    0.000713035      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.603428       1.50315       3.2428        1.55206      0.647007        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.598477       1.45926       3.40325       1.32264      0.644221        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0679832      0.11448      0.236351      0.107213      0.0395652       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0259911     0.0423507     0.0851531     0.0387612     0.0137999       30     
produce:emtrkmichelid:EmTrackMichelId                   19.155        27.5013       44.0303       25.788        5.59177        30     
[art]:TriggerResults:TriggerResultInserter            3.8772e-05    6.42174e-05   0.000144962   5.79385e-05   2.15583e-05      30     
end_path:out1:RootOutput                               9.889e-06    1.88086e-05   4.7289e-05    1.72475e-05   7.5447e-06       30     
end_path:michelremoving:michelremoving                 0.0221183     0.0747219     0.261811      0.0431084     0.0630317       30     
end_path:michelremovinggnocchi:michelremoving          0.0195291     0.0748805     0.333465      0.0461151     0.072559        30     
end_path:calibanagnocchi:CalibAnaTree                  0.293945      0.514045       1.14706      0.451583      0.187626        30     
end_path:out1:RootOutput(write)                         0.43465      0.642169       1.18309      0.632631      0.167064        30     
========================================================================================================================================

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

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