justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263757.126@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:37:03
From worker nodeHostnamen249.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:22
Input fileshd-protodune:np04hd_raw_run029097_0072_dataflow1_datawriter_0_20240906T114904.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 14:37:01
Saved logsjustin-logs:263757.126-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

8e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  0   event 17497 TrkackID 1 startx, y, z 241.422 605.76 361.939  wireno, peakt tpcno 0 2978.99 5 dist 15.8747min T, max_T1918.31 4993
no of hits closeby  0   event 17497 TrkackID 1 startx, y, z 241.422 605.76 361.939  wireno, peakt tpcno 0 2978.99 5 dist 15.8747min T, max_T1918.31 4993
Begin processing the 29th record. run: 29097 subRun: 1 event: 17505 at 26-Sep-2024 15:32:27 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 68937 traces, 68937 summary
Retagger: tagging trace set: gauss with 54493 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 54493 traces tagged "gauss"
FrameSaver: q=1.40187e+06 n=1144323 tag=gauss
wclsFrameSaver: saving 68937 traces tagged "wiener"
FrameSaver: q=1.51025e+06 n=1063919 tag=wiener
4109 hits in TPC 0
3512 hits in TPC 1
14387 hits in TPC 2
9894 hits in TPC 3
2225 hits in TPC 4
4791 hits in TPC 5
3166 hits in TPC 6
3351 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 55 XUs and 77 XVs -> 3 XUVs
C:0 T:2 112042 XUs and 129233 XVs -> 8385 XUVs
C:0 T:3 14420 XUs and 20554 XVs -> 958 XUVs
C:0 T:4 38 XUs and 42 XVs -> 9 XUVs
C:0 T:5 10632 XUs and 12671 XVs -> 249 XUVs
C:0 T:6 5807 XUs and 8053 XVs -> 407 XUVs
C:0 T:7 875 XUs and 1201 XVs -> 55 XUVs
10066 XUVs total
3890 collection wire objects
10066 potential space points
Neighbour search...
999076 tests to find 255774 neighbours
Iterating with no regularization...
Begin: 2.5545e+07
0 1.92123e+07
1 1.86425e+07
2 1.85675e+07
3 1.85516e+07
Now with regularization...
Begin: 1.70419e+07
0 1.70203e+07
1 1.70151e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  1   event 17505 TrkackID 5 startx, y, z -78.0465 590.272 313.99  wireno, peakt tpcno 399 2987.03 6 dist 0.0196881min T, max_T2982.94 4264.67
no of hits closeby  1   event 17505 TrkackID 5 startx, y, z -78.0465 590.272 313.99  wireno, peakt tpcno 399 2987.03 6 dist 0.0196881min T, max_T2982.94 4264.67
Begin processing the 30th record. run: 29097 subRun: 1 event: 17513 at 26-Sep-2024 15:34:18 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 52173 traces, 52173 summary
Retagger: tagging trace set: gauss with 41031 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 41031 traces tagged "gauss"
FrameSaver: q=995961 n=841422 tag=gauss
wclsFrameSaver: saving 52173 traces tagged "wiener"
FrameSaver: q=1.05745e+06 n=773821 tag=wiener
1750 hits in TPC 0
2541 hits in TPC 1
4588 hits in TPC 2
2178 hits in TPC 3
3400 hits in TPC 4
3723 hits in TPC 5
7146 hits in TPC 6
5329 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 5 XUs and 5 XVs -> 0 XUVs
C:0 T:2 10511 XUs and 7970 XVs -> 562 XUVs
C:0 T:3 504 XUs and 804 XVs -> 18 XUVs
C:0 T:4 310 XUs and 579 XVs -> 4 XUVs
C:0 T:5 6415 XUs and 8780 XVs -> 574 XUVs
C:0 T:6 25385 XUs and 30042 XVs -> 1196 XUVs
C:0 T:7 36 XUs and 57 XVs -> 1 XUVs
2355 XUVs total
1449 collection wire objects
2355 potential space points
Neighbour search...
54107 tests to find 25858 neighbours
Iterating with no regularization...
Begin: 8.48628e+06
0 7.83701e+06
1 7.80012e+06
2 7.79592e+06
Now with regularization...
Begin: 7.09585e+06
0 7.09395e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  2   event 17513 TrkackID 2 startx, y, z 149.145 599.809 262.544  wireno, peakt tpcno 443 1323.81 5 dist 0min T, max_T542.609 2506.53
no of hits closeby  2   event 17513 TrkackID 2 startx, y, z 149.145 599.809 262.544  wireno, peakt tpcno 443 1323.81 5 dist 0min T, max_T542.609 2506.53
26-Sep-2024 15:35:20 BST  Closed output file "np04hd_raw_run029097_0072_dataflow1_datawriter_0_20240906T114904_reco_stage1_reco_stage2_20240926T143520_keepup.root"
26-Sep-2024 15:35:20 BST  Closed input file "np04hd_raw_run029097_0072_dataflow1_datawriter_0_20240906T114904_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              44.2434       66.4775       111.262       63.419        14.8004        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000320528   0.00114047    0.00212532    0.00115627    0.00049537       30     
produce:fembfilter:PDHDFEMBFilter                      0.442954       0.52862      0.692472      0.456155      0.104118        30     
produce:wclsdatahd:WireCellToolkit                      24.5905       28.758        34.8785       27.1351       3.75042        30     
produce:gaushit:GausHitFinder                          0.292032      0.561001      0.978043      0.514896      0.193194        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000138523   0.000257558   0.000400619   0.000253596   5.61181e-05      30     
produce:reco3d:SpacePointSolver                        0.093342      0.720611       2.90484      0.453119      0.670567        30     
produce:hitpdune:DisambigFromSpacePoints               0.120548      0.424165       0.98502      0.327559      0.231638        30     
produce:pandora:StandardPandora                         3.03761       10.0632       39.3753       8.54612       6.68236        30     
produce:pandoraWriter:StandardPandora                  0.0537506      0.10417      0.226711      0.100519      0.0387017       30     
produce:pandoraTrack:LArPandoraTrackCreation            0.27032      0.752831       2.57936      0.669367       0.43079        30     
produce:pandoraShower:LArPandoraShowerCreation         0.365333      0.911638       2.05138      0.850899      0.446548        30     
produce:pandoracalo:Calorimetry                        0.178797      0.424356       1.22614      0.391012      0.197034        30     
produce:pandoracalonosce:Calorimetry                   0.149729       0.38123       1.16033      0.353338      0.184544        30     
produce:pandorapid:Chi2ParticleID                     0.000788304   0.00149258    0.00443603    0.00133472    0.000722051      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.320599      0.885087       1.89225      0.861286      0.400567        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.285143      0.850061       1.77819      0.834841      0.385992        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0574554     0.102376      0.199445      0.0893486     0.0412559       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0186121     0.0342049     0.0667605     0.0307546     0.0132067       30     
produce:emtrkmichelid:EmTrackMichelId                   11.9582       19.9068       33.9624       18.9393       5.20284        30     
[art]:TriggerResults:TriggerResultInserter            1.5339e-05    2.17352e-05   5.5796e-05    1.82295e-05   8.11854e-06      30     
end_path:out1:RootOutput                               6.562e-06    8.39523e-06   1.8735e-05    7.3495e-06    2.52521e-06      30     
end_path:michelremoving:michelremoving                 0.0121669     0.0374059     0.106957      0.0206306     0.0307913       30     
end_path:michelremovinggnocchi:michelremoving         0.00866117     0.0365857     0.123413      0.0173497     0.0350563       30     
end_path:calibanagnocchi:CalibAnaTree                  0.0998748     0.308315      0.816915      0.283167      0.138364        30     
end_path:out1:RootOutput(write)                        0.367038      0.613986       1.04594      0.620439      0.159779        30     
========================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4940.45 MB
  Peak resident set size usage (VmHWM): 2606.32 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run029097_0072_dataflow1_datawriter_0_20240906T114904_reco_stage1_reco_stage2_20240926T143520_keepup.root
\tHists: np04hd_raw_run029097_0072_dataflow1_datawriter_0_20240906T114904_reco_stage1_reco_stage2_20240926T143520_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:11:43 UTC       justIN version: 01.01.09