justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263757.112@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:23:33
SiteUK_Durham
EntryDUNE_UK_SGridDurham_ce4
Last heartbeat2024-09-26 14:48:11
From worker nodeHostnamen255.dur.scotgrid.ac.uk
cpuinfoAMD EPYC 7763 64-Core Processor
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 13:25:14
Input fileshd-protodune:np04hd_raw_run029092_0925_dataflow1_datawriter_0_20240906T074917.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 14:48:11
Saved logsjustin-logs:263757.112-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

gularization...
Begin: 3.51037e+07
0 2.96763e+07
1 2.88808e+07
2 2.88398e+07
3 2.88346e+07
Now with regularization...
Begin: 2.7791e+07
0 2.77801e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  0   event 222217 TrkackID 14 startx, y, z 61.3673 175.627 452.308  wireno, peakt tpcno 310 5025.92 5 dist 0min T, max_T4602.07 5365.21
no of hits closeby  0   event 222217 TrkackID 14 startx, y, z 61.3673 175.627 452.308  wireno, peakt tpcno 310 5025.92 5 dist 0min T, max_T4602.07 5365.21
Begin processing the 29th record. run: 29092 subRun: 1 event: 222233 at 26-Sep-2024 15:44:55 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 94702 traces, 94702 summary
Retagger: tagging trace set: gauss with 68051 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 68051 traces tagged "gauss"
FrameSaver: q=1.50114e+06 n=1213684 tag=gauss
wclsFrameSaver: saving 94702 traces tagged "wiener"
FrameSaver: q=1.662e+06 n=1092421 tag=wiener
5172 hits in TPC 0
6176 hits in TPC 1
8106 hits in TPC 2
3663 hits in TPC 3
4981 hits in TPC 4
10905 hits in TPC 5
7774 hits in TPC 6
2846 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 5236 XUs and 2251 XVs -> 405 XUVs
C:0 T:2 50030 XUs and 48223 XVs -> 3599 XUVs
C:0 T:3 2996 XUs and 1646 XVs -> 83 XUVs
C:0 T:4 23 XUs and 29 XVs -> 1 XUVs
C:0 T:5 82099 XUs and 98452 XVs -> 5018 XUVs
C:0 T:6 47809 XUs and 52143 XVs -> 750 XUVs
C:0 T:7 28536 XUs and 21007 XVs -> 2241 XUVs
12097 XUVs total
3150 collection wire objects
12097 potential space points
Neighbour search...
820229 tests to find 406660 neighbours
Iterating with no regularization...
Begin: 1.93423e+07
0 1.71285e+07
1 1.69578e+07
2 1.69275e+07
3 1.69162e+07
Now with regularization...
Begin: 1.58675e+07
0 1.58608e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  0   event 222233 TrkackID 8 startx, y, z 78.8023 592.202 347.386  wireno, peakt tpcno 93 1790.88 6 dist 299.77min T, max_T6.73997 1806.47
no of hits closeby  0   event 222233 TrkackID 8 startx, y, z 78.8023 592.202 347.386  wireno, peakt tpcno 93 1790.88 6 dist 299.77min T, max_T6.73997 1806.47
Begin processing the 30th record. run: 29092 subRun: 1 event: 222241 at 26-Sep-2024 15:46:53 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 34760 traces, 34760 summary
Retagger: tagging trace set: gauss with 26438 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 26438 traces tagged "gauss"
FrameSaver: q=549191 n=476264 tag=gauss
wclsFrameSaver: saving 34760 traces tagged "wiener"
FrameSaver: q=590549 n=430560 tag=wiener
1918 hits in TPC 0
2048 hits in TPC 1
3537 hits in TPC 2
2745 hits in TPC 3
1157 hits in TPC 4
2184 hits in TPC 5
3366 hits in TPC 6
1444 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 2079 XUs and 849 XVs -> 23 XUVs
C:0 T:2 17581 XUs and 13355 XVs -> 382 XUVs
C:0 T:3 572 XUs and 835 XVs -> 35 XUVs
C:0 T:4 1 XUs and 5 XVs -> 0 XUVs
C:0 T:5 1323 XUs and 2554 XVs -> 245 XUVs
C:0 T:6 4457 XUs and 5317 XVs -> 719 XUVs
C:0 T:7 244 XUs and 469 XVs -> 12 XUVs
1416 XUVs total
722 collection wire objects
1416 potential space points
Neighbour search...
32176 tests to find 19742 neighbours
Iterating with no regularization...
Begin: 2.76806e+06
0 2.49651e+06
1 2.46641e+06
2 2.46231e+06
3 2.46133e+06
Now with regularization...
Begin: 2.1917e+06
0 2.19113e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

26-Sep-2024 15:47:36 BST  Closed output file "np04hd_raw_run029092_0925_dataflow1_datawriter_0_20240906T074917_reco_stage1_reco_stage2_20240926T144736_keepup.root"
26-Sep-2024 15:47:36 BST  Closed input file "np04hd_raw_run029092_0925_dataflow1_datawriter_0_20240906T074917_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              42.6855       76.9088       117.085       73.7293       18.1013        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000553269   0.00194906    0.00416495    0.00178884    0.000926612      30     
produce:fembfilter:PDHDFEMBFilter                      0.451612      0.606667      0.693663      0.636675      0.084273        30     
produce:wclsdatahd:WireCellToolkit                      25.3243       32.1377       36.8002       34.1576       4.0196         30     
produce:gaushit:GausHitFinder                          0.241837      0.634423       1.13081      0.564719      0.220111        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000200891   0.000327522   0.000634994   0.000298711   9.8137e-05       30     
produce:reco3d:SpacePointSolver                        0.132458       1.02635        4.653       0.626166       1.01342        30     
produce:hitpdune:DisambigFromSpacePoints               0.0973867     0.511152       1.38106      0.364956       0.33374        30     
produce:pandora:StandardPandora                         2.46506       11.8394       31.9531       9.7014        6.79814        30     
produce:pandoraWriter:StandardPandora                  0.0559985     0.144632      0.222581      0.140803      0.0421641       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.281858       1.08817       2.37796      0.967273       0.54608        30     
produce:pandoraShower:LArPandoraShowerCreation         0.225958       1.2895        3.40529       1.17737      0.792735        30     
produce:pandoracalo:Calorimetry                        0.150384       0.56234       1.15385      0.513725      0.241341        30     
produce:pandoracalonosce:Calorimetry                   0.129796      0.502899       1.04653      0.477034      0.223899        30     
produce:pandorapid:Chi2ParticleID                     0.000909324   0.00177362    0.00283132    0.00173607    0.000471018      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.193883       1.15506       3.45927      0.987616       0.73772        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.191649       1.12271       3.46046      0.962344       0.72198        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0455043     0.130834      0.232603      0.120019      0.0425074       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0161717     0.0434316     0.0740603     0.0381903     0.0133336       30     
produce:emtrkmichelid:EmTrackMichelId                   10.1267       22.6749       36.1662       22.6298       6.40216        30     
[art]:TriggerResults:TriggerResultInserter            1.4998e-05    3.28971e-05   8.8347e-05    3.07935e-05   1.39562e-05      30     
end_path:out1:RootOutput                               7.915e-06    1.84526e-05   0.000213145   1.1437e-05    3.62989e-05      30     
end_path:michelremoving:michelremoving                 0.0155721     0.0755649     0.227738      0.0323792     0.0678405       30     
end_path:michelremovinggnocchi:michelremoving          0.0146502     0.0727812     0.222901      0.0271226     0.0671831       30     
end_path:calibanagnocchi:CalibAnaTree                  0.170568      0.421818      0.945334       0.35954      0.186559        30     
end_path:out1:RootOutput(write)                        0.432299      0.736227       1.19154      0.661404      0.213338        30     
========================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4913.35 MB
  Peak resident set size usage (VmHWM): 2587.04 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run029092_0925_dataflow1_datawriter_0_20240906T074917_reco_stage1_reco_stage2_20240926T144736_keepup.root
\tHists: np04hd_raw_run029092_0925_dataflow1_datawriter_0_20240906T074917_reco_stage1_reco_stage2_20240926T144736_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 03:12:28 UTC       justIN version: 01.01.09