justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263763.135@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 13:24:49
SiteUK_Sheffield
EntryDUNE_UK_Sheffield_lcgce1
Last heartbeat2024-09-26 14:51:56
From worker nodeHostnamewn082.hep
cpuinfoIntel(R) Xeon(R) CPU E5-2650 v2 @ 2.60GHz
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 13:26:16
Input fileshd-protodune:np04hd_raw_run029094_0071_dataflow3_datawriter_0_20240906T090751.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 14:51:56
Saved logsjustin-logs:263763.135-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

42625 traces tagged "wiener"
FrameSaver: q=693866 n=490739 tag=wiener
1934 hits in TPC 0
1435 hits in TPC 1
6226 hits in TPC 2
4137 hits in TPC 3
1285 hits in TPC 4
3303 hits in TPC 5
2029 hits in TPC 6
894 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 7 XUs and 5 XVs -> 2 XUVs
C:0 T:1 118 XUs and 381 XVs -> 4 XUVs
C:0 T:2 18982 XUs and 17532 XVs -> 470 XUVs
C:0 T:3 1042 XUs and 1386 XVs -> 20 XUVs
C:0 T:4 41 XUs and 67 XVs -> 3 XUVs
C:0 T:5 5487 XUs and 4837 XVs -> 209 XUVs
C:0 T:6 2893 XUs and 2227 XVs -> 26 XUVs
C:0 T:7 12 XUs and 15 XVs -> 0 XUVs
734 XUVs total
506 collection wire objects
734 potential space points
Neighbour search...
6138 tests to find 3380 neighbours
Iterating with no regularization...
Begin: 4.59494e+06
0 4.50996e+06
1 4.50823e+06
Now with regularization...
Begin: 4.26052e+06
0 4.26023e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 29th record. run: 29094 subRun: 1 event: 17267 at 26-Sep-2024 15:48:05 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 41192 traces, 41192 summary
Retagger: tagging trace set: gauss with 32326 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 32326 traces tagged "gauss"
FrameSaver: q=710106 n=646304 tag=gauss
wclsFrameSaver: saving 41192 traces tagged "wiener"
FrameSaver: q=745939 n=592952 tag=wiener
2714 hits in TPC 0
2433 hits in TPC 1
3500 hits in TPC 2
2237 hits in TPC 3
2857 hits in TPC 4
3204 hits in TPC 5
3237 hits in TPC 6
2288 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 50 XUs and 72 XVs -> 4 XUVs
C:0 T:2 8118 XUs and 25826 XVs -> 594 XUVs
C:0 T:3 118 XUs and 204 XVs -> 4 XUVs
C:0 T:4 91 XUs and 58 XVs -> 2 XUVs
C:0 T:5 4131 XUs and 5230 XVs -> 132 XUVs
C:0 T:6 5070 XUs and 8488 XVs -> 325 XUVs
C:0 T:7 452 XUs and 765 XVs -> 28 XUVs
1089 XUVs total
794 collection wire objects
1089 potential space points
Neighbour search...
15051 tests to find 9476 neighbours
Iterating with no regularization...
Begin: 3.95626e+06
0 3.88092e+06
1 3.8771e+06
Now with regularization...
Begin: 3.57934e+06
0 3.57865e+06
BdtBeamParticleIdTool::SliceFeatures::GetLArTPCIntercepts - inconsistent number of intercepts between a line and the LArTPC
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 30th record. run: 29094 subRun: 1 event: 17275 at 26-Sep-2024 15:49:32 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 67060 traces, 67060 summary
Retagger: tagging trace set: gauss with 50003 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 50003 traces tagged "gauss"
FrameSaver: q=1.07481e+06 n=940955 tag=gauss
wclsFrameSaver: saving 67060 traces tagged "wiener"
FrameSaver: q=1.16979e+06 n=855494 tag=wiener
5586 hits in TPC 0
3913 hits in TPC 1
7367 hits in TPC 2
2157 hits in TPC 3
3508 hits in TPC 4
5208 hits in TPC 5
5171 hits in TPC 6
2594 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 2302 XUs and 1716 XVs -> 59 XUVs
C:0 T:2 36597 XUs and 19524 XVs -> 563 XUVs
C:0 T:3 317 XUs and 232 XVs -> 11 XUVs
C:0 T:4 127 XUs and 63 XVs -> 3 XUVs
C:0 T:5 20881 XUs and 14439 XVs -> 1251 XUVs
C:0 T:6 20666 XUs and 45265 XVs -> 2507 XUVs
C:0 T:7 191 XUs and 191 XVs -> 9 XUVs
4403 XUVs total
1342 collection wire objects
4403 potential space points
Neighbour search...
404571 tests to find 185234 neighbours
Iterating with no regularization...
Begin: 6.23054e+06
0 5.17694e+06
1 5.11133e+06
2 5.10041e+06
3 5.09634e+06
Now with regularization...
Begin: 4.78785e+06
0 4.78506e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

26-Sep-2024 15:51:33 BST  Closed output file "np04hd_raw_run029094_0071_dataflow3_datawriter_0_20240906T090751_reco_stage1_reco_stage2_20240926T145133_keepup.root"
26-Sep-2024 15:51:33 BST  Closed input file "np04hd_raw_run029094_0071_dataflow3_datawriter_0_20240906T090751_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              81.2461       112.665       151.361       110.54        20.0143        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000469949   0.00207911    0.00410787    0.00214566    0.000909678      30     
produce:fembfilter:PDHDFEMBFilter                      0.643127      0.660128      0.700906      0.658345      0.011448        30     
produce:wclsdatahd:WireCellToolkit                      42.7747       44.0505       46.3425       43.9498      0.921823        30     
produce:gaushit:GausHitFinder                          0.440478      0.795341       1.58502       0.75022      0.243139        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000231706   0.000380236   0.000562513   0.000380004   8.27937e-05      30     
produce:reco3d:SpacePointSolver                        0.149587       0.88446       4.24866      0.588829      0.871673        30     
produce:hitpdune:DisambigFromSpacePoints               0.214753      0.536898       1.06925      0.524695      0.259387        30     
produce:pandora:StandardPandora                         5.12213       12.5244       22.7699       11.7434       5.2413         30     
produce:pandoraWriter:StandardPandora                  0.0975481     0.146439      0.203458      0.144072      0.0342054       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.546762       1.27743       2.44772       1.28415      0.518548        30     
produce:pandoraShower:LArPandoraShowerCreation         0.505741       1.4536        3.35727       1.36149      0.792162        30     
produce:pandoracalo:Calorimetry                        0.284476      0.635802       1.12793      0.631441      0.235979        30     
produce:pandoracalonosce:Calorimetry                   0.243617      0.559052      0.968486      0.577991      0.200128        30     
produce:pandorapid:Chi2ParticleID                     0.00121647    0.00194339    0.00338267    0.00188018    0.000429579      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.450903       1.3091        2.82088       1.24363      0.685022        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.447571       1.28279       2.86112       1.22642       0.65904        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0728017     0.132834      0.199338      0.133857      0.0331456       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0262749     0.0443891     0.0679007     0.0441102     0.0107779       30     
produce:emtrkmichelid:EmTrackMichelId                   27.1238       44.9421       63.5553       43.6465       10.3556        30     
[art]:TriggerResults:TriggerResultInserter            2.3173e-05    3.43838e-05   7.8906e-05    2.87745e-05   1.48198e-05      30     
end_path:out1:RootOutput                               7.293e-06    1.04996e-05   2.7094e-05     8.804e-06    4.29491e-06      30     
end_path:michelremoving:michelremoving                 0.0196184     0.0821959     0.341752      0.0391613     0.0799739       30     
end_path:michelremovinggnocchi:michelremoving          0.0181588     0.0788034     0.338428      0.0338263     0.079075        30     
end_path:calibanagnocchi:CalibAnaTree                   0.20852      0.456035      0.907295      0.434176       0.18253        30     
end_path:out1:RootOutput(write)                        0.435601      0.720098       1.04948      0.696086      0.171381        30     
========================================================================================================================================

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

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