justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263940.103@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 14:25:09
SiteUK_Sheffield
EntryDUNE_UK_Sheffield_lcgce1
Last heartbeat2024-09-26 15:37:11
From worker nodeHostnamewn031.hep
cpuinfoIntel(R) Xeon(R) Gold 6226 CPU @ 2.70GHz
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 14:27:07
Input fileshd-protodune:np04hd_raw_run029097_0265_dataflow2_datawriter_0_20240906T155022.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 15:37:09
Saved logsjustin-logs:263940.103-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

ces...
C:0 T:1 278 XUs and 119 XVs -> 5 XUVs
C:0 T:2 21682 XUs and 78805 XVs -> 2601 XUVs
C:0 T:3 54 XUs and 91 XVs -> 3 XUVs
C:0 T:4 51 XUs and 85 XVs -> 7 XUVs
C:0 T:5 19952 XUs and 18775 XVs -> 378 XUVs
C:0 T:6 33303 XUs and 56907 XVs -> 3565 XUVs
C:0 T:7 116 XUs and 312 XVs -> 9 XUVs
6568 XUVs total
1545 collection wire objects
6568 potential space points
Neighbour search...
739832 tests to find 347430 neighbours
Iterating with no regularization...
Begin: 9.87604e+06
0 8.61072e+06
1 8.49184e+06
2 8.47755e+06
3 8.47353e+06
Now with regularization...
Begin: 8.18933e+06
0 8.18716e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  1   event 63818 TrkackID 1 startx, y, z 17.8158 473.541 9.9385  wireno, peakt tpcno 450 283.465 5 dist 0min T, max_T175.967 418.472
no of hits closeby  1   event 63818 TrkackID 1 startx, y, z 17.8158 473.541 9.9385  wireno, peakt tpcno 450 283.465 5 dist 0min T, max_T175.967 418.472
Begin processing the 29th record. run: 29097 subRun: 1 event: 63826 at 26-Sep-2024 16:33:12 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 56730 traces, 56730 summary
Retagger: tagging trace set: gauss with 41892 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 41892 traces tagged "gauss"
FrameSaver: q=900004 n=749448 tag=gauss
wclsFrameSaver: saving 56730 traces tagged "wiener"
FrameSaver: q=966253 n=672827 tag=wiener
2672 hits in TPC 0
2232 hits in TPC 1
4967 hits in TPC 2
3377 hits in TPC 3
2576 hits in TPC 4
5452 hits in TPC 5
5061 hits in TPC 6
1963 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 332 XUs and 203 XVs -> 2 XUVs
C:0 T:2 14656 XUs and 24167 XVs -> 1186 XUVs
C:0 T:3 771 XUs and 1306 XVs -> 36 XUVs
C:0 T:4 274 XUs and 471 XVs -> 31 XUVs
C:0 T:5 14048 XUs and 14380 XVs -> 258 XUVs
C:0 T:6 7158 XUs and 22185 XVs -> 300 XUVs
C:0 T:7 66 XUs and 80 XVs -> 1 XUVs
1814 XUVs total
995 collection wire objects
1814 potential space points
Neighbour search...
36814 tests to find 20352 neighbours
Iterating with no regularization...
Begin: 4.23939e+06
0 3.85486e+06
1 3.82499e+06
2 3.82234e+06
Now with regularization...
Begin: 3.4506e+06
0 3.44995e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 30th record. run: 29097 subRun: 1 event: 63834 at 26-Sep-2024 16:34:31 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 69889 traces, 69889 summary
Retagger: tagging trace set: gauss with 53482 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 53482 traces tagged "gauss"
FrameSaver: q=1.20534e+06 n=1093607 tag=gauss
wclsFrameSaver: saving 69889 traces tagged "wiener"
FrameSaver: q=1.28801e+06 n=1001282 tag=wiener
4144 hits in TPC 0
4071 hits in TPC 1
8552 hits in TPC 2
6536 hits in TPC 3
2246 hits in TPC 4
3600 hits in TPC 5
9747 hits in TPC 6
4034 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 100 XUs and 52 XVs -> 0 XUVs
C:0 T:2 32946 XUs and 72075 XVs -> 2085 XUVs
C:0 T:3 513 XUs and 647 XVs -> 17 XUVs
C:0 T:4 59 XUs and 122 XVs -> 9 XUVs
C:0 T:5 5717 XUs and 6098 XVs -> 181 XUVs
C:0 T:6 37283 XUs and 38391 XVs -> 1035 XUVs
C:0 T:7 538 XUs and 582 XVs -> 26 XUVs
3353 XUVs total
1772 collection wire objects
3353 potential space points
Neighbour search...
143679 tests to find 65176 neighbours
Iterating with no regularization...
Begin: 1.40472e+07
0 1.25542e+07
1 1.24309e+07
2 1.24242e+07
Now with regularization...
Begin: 1.17814e+07
0 1.17783e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

26-Sep-2024 16:36:48 BST  Closed output file "np04hd_raw_run029097_0265_dataflow2_datawriter_0_20240906T155022_reco_stage1_reco_stage2_20240926T153648_keepup.root"
26-Sep-2024 16:36:48 BST  Closed input file "np04hd_raw_run029097_0265_dataflow2_datawriter_0_20240906T155022_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              68.9265       89.3754       136.458       83.7663       16.4017        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000337817   0.00127141    0.00376439    0.00103567    0.000874264      30     
produce:fembfilter:PDHDFEMBFilter                      0.598936      0.621913       0.63966       0.62112      0.0104708       30     
produce:wclsdatahd:WireCellToolkit                      44.5227       48.0275       50.7037       48.1546       1.60278        30     
produce:gaushit:GausHitFinder                          0.331849      0.655478       1.02733      0.676366      0.163966        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000245945   0.000399005   0.000583129   0.00038083    7.83294e-05      30     
produce:reco3d:SpacePointSolver                        0.174567      0.872878       2.91027      0.611328      0.641165        30     
produce:hitpdune:DisambigFromSpacePoints               0.198207      0.500509       1.09574      0.438805      0.237206        30     
produce:pandora:StandardPandora                         5.19801       11.8985       26.1415       10.6984       5.37801        30     
produce:pandoraWriter:StandardPandora                  0.101209      0.149723      0.258215      0.141222      0.0362613       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.853411       1.97963       5.83728       1.7127        1.04304        30     
produce:pandoraShower:LArPandoraShowerCreation         0.852686       2.27953       7.31308       1.73896       1.50364        30     
produce:pandoracalo:Calorimetry                        0.361912      0.828445       2.30399       0.75919      0.389778        30     
produce:pandoracalonosce:Calorimetry                   0.337476      0.776504       2.24199      0.702396      0.380855        30     
produce:pandorapid:Chi2ParticleID                     0.00116666    0.00164233    0.00292662    0.00158746    0.000372161      30     
produce:pandoraShowercalo:ShowerCalorimetry             0.66646       1.94538       5.27025       1.64884       1.15891        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.668485       1.9189        5.15717       1.56885       1.14033        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.072416      0.110027       0.15647      0.104708      0.0229747       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0294117     0.0447128     0.0655205     0.042903     0.00950753       30     
produce:emtrkmichelid:EmTrackMichelId                   9.4261        15.1974       26.0816       14.275        4.00519        30     
[art]:TriggerResults:TriggerResultInserter            2.6455e-05    4.10613e-05   8.0302e-05    3.9909e-05    1.06409e-05      30     
end_path:out1:RootOutput                               6.586e-06    9.79877e-06   2.3732e-05    9.3615e-06    3.17052e-06      30     
end_path:michelremoving:michelremoving                 0.0226393     0.0546892     0.165964      0.0382329     0.036335        30     
end_path:michelremovinggnocchi:michelremoving          0.0209773     0.0517992     0.164954      0.0355805     0.0368518       30     
end_path:calibanagnocchi:CalibAnaTree                  0.273961      0.594517       1.62973      0.495389      0.289685        30     
end_path:out1:RootOutput(write)                        0.430647      0.667568       1.01941      0.652819       0.15275        30     
========================================================================================================================================

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

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