justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263757.98@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_ce3
Last heartbeat2024-09-26 15:10:02
From worker nodeHostnamen191.dur.scotgrid.ac.uk
cpuinfoIntel(R) Xeon(R) Gold 5220 CPU @ 2.20GHz
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:24:59
Input fileshd-protodune:np04hd_raw_run029097_0067_dataflow1_datawriter_0_20240906T114318.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 15:10:02
Saved logsjustin-logs:263757.98-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

0 T:3 593 XUs and 689 XVs -> 25 XUVs
C:0 T:4 162 XUs and 110 XVs -> 1 XUVs
C:0 T:5 58670 XUs and 63404 XVs -> 2192 XUVs
C:0 T:6 27449 XUs and 16474 XVs -> 503 XUVs
C:0 T:7 104 XUs and 179 XVs -> 1 XUVs
3621 XUVs total
1347 collection wire objects
3621 potential space points
Neighbour search...
229731 tests to find 123804 neighbours
Iterating with no regularization...
Begin: 1.8444e+07
0 1.7879e+07
1 1.78307e+07
2 1.78258e+07
Now with regularization...
Begin: 1.70896e+07
0 1.70877e+07
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 29th record. run: 29097 subRun: 1 event: 16305 at 26-Sep-2024 16:06:17 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 80500 traces, 80500 summary
Retagger: tagging trace set: gauss with 59402 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 59402 traces tagged "gauss"
FrameSaver: q=1.43577e+06 n=1079849 tag=gauss
wclsFrameSaver: saving 80500 traces tagged "wiener"
FrameSaver: q=1.54335e+06 n=976363 tag=wiener
6002 hits in TPC 0
5135 hits in TPC 1
7553 hits in TPC 2
3788 hits in TPC 3
5198 hits in TPC 4
7735 hits in TPC 5
5169 hits in TPC 6
2756 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 471 XUs and 263 XVs -> 5 XUVs
C:0 T:2 28538 XUs and 44866 XVs -> 2865 XUVs
C:0 T:3 1630 XUs and 1973 XVs -> 152 XUVs
C:0 T:4 369 XUs and 590 XVs -> 14 XUVs
C:0 T:5 36136 XUs and 26255 XVs -> 856 XUVs
C:0 T:6 13942 XUs and 36362 XVs -> 1013 XUVs
C:0 T:7 58 XUs and 124 XVs -> 4 XUVs
4909 XUVs total
1313 collection wire objects
4909 potential space points
Neighbour search...
180265 tests to find 109888 neighbours
Iterating with no regularization...
Begin: 4.18016e+07
0 3.66232e+07
1 3.62231e+07
2 3.61672e+07
3 3.61501e+07
Now with regularization...
Begin: 3.4031e+07
0 3.39558e+07
1 3.39383e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 30th record. run: 29097 subRun: 1 event: 16337 at 26-Sep-2024 16:08:10 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 60685 traces, 60685 summary
Retagger: tagging trace set: gauss with 42289 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 42289 traces tagged "gauss"
FrameSaver: q=950367 n=752805 tag=gauss
wclsFrameSaver: saving 60685 traces tagged "wiener"
FrameSaver: q=1.03881e+06 n=677368 tag=wiener
1877 hits in TPC 0
2729 hits in TPC 1
4151 hits in TPC 2
1231 hits in TPC 3
3750 hits in TPC 4
6693 hits in TPC 5
6229 hits in TPC 6
3293 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 0 XUs and 0 XVs -> 0 XUVs
C:0 T:1 231 XUs and 436 XVs -> 106 XUVs
C:0 T:2 14568 XUs and 29590 XVs -> 883 XUVs
C:0 T:3 100 XUs and 85 XVs -> 13 XUVs
C:0 T:4 94 XUs and 115 XVs -> 6 XUVs
C:0 T:5 12936 XUs and 24254 XVs -> 1341 XUVs
C:0 T:6 33171 XUs and 53818 XVs -> 3719 XUVs
C:0 T:7 265 XUs and 279 XVs -> 16 XUVs
6084 XUVs total
1497 collection wire objects
6084 potential space points
Neighbour search...
436984 tests to find 232688 neighbours
Iterating with no regularization...
Begin: 7.30764e+06
0 6.57897e+06
1 6.47277e+06
2 6.45967e+06
3 6.45588e+06
Now with regularization...
Begin: 5.92874e+06
0 5.92597e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  4   event 16337 TrkackID 14 startx, y, z -89.6599 592.65 357.696  wireno, peakt tpcno 446 4875 6 dist 1.25935min T, max_T3781.44 4915.82
no of hits closeby  4   event 16337 TrkackID 14 startx, y, z -89.6599 592.65 357.696  wireno, peakt tpcno 446 4875 6 dist 1.25935min T, max_T3781.44 4915.82
26-Sep-2024 16:09:36 BST  Closed output file "np04hd_raw_run029097_0067_dataflow1_datawriter_0_20240906T114318_reco_stage1_reco_stage2_20240926T150936_keepup.root"
26-Sep-2024 16:09:36 BST  Closed input file "np04hd_raw_run029097_0067_dataflow1_datawriter_0_20240906T114318_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              67.1477       108.648       157.606       110.087       22.7562        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000305228   0.00154164    0.00436843    0.00117363    0.00120076       30     
produce:fembfilter:PDHDFEMBFilter                      0.732807      0.943282       1.33688      0.807177      0.215245        30     
produce:wclsdatahd:WireCellToolkit                      44.6783       55.1311       74.1978       56.0012       8.24975        30     
produce:gaushit:GausHitFinder                          0.440298      0.813769       1.3689       0.787843      0.230964        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000224066   0.000389543   0.000585294   0.000379954   8.89918e-05      30     
produce:reco3d:SpacePointSolver                        0.169604       1.33881       7.53606      0.996556       1.32726        30     
produce:hitpdune:DisambigFromSpacePoints               0.173767      0.631582       1.5164       0.600563      0.294161        30     
produce:pandora:StandardPandora                         4.77876       14.4488       34.4204       12.892        7.02015        30     
produce:pandoraWriter:StandardPandora                  0.0836732     0.179724      0.353357      0.168943      0.0542404       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.741729       2.22941       6.50429       1.86474       1.20403        30     
produce:pandoraShower:LArPandoraShowerCreation         0.680845       2.36012       7.23356       2.23147       1.32941        30     
produce:pandoracalo:Calorimetry                         0.33991      0.942102       2.37783      0.825488      0.445255        30     
produce:pandoracalonosce:Calorimetry                   0.309878       0.88722       2.28789      0.759795      0.441113        30     
produce:pandorapid:Chi2ParticleID                     0.00124579    0.00209042     0.0035352    0.00182664    0.00063059       30     
produce:pandoraShowercalo:ShowerCalorimetry            0.574751       2.11334       6.67887       2.08452       1.1497         30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.565603       2.1448        6.51093       2.04454       1.19567        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0622661     0.153813      0.357534      0.139428      0.0579789       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0302871     0.0614083     0.136738      0.0534282     0.0231659       30     
produce:emtrkmichelid:EmTrackMichelId                   10.9732       22.0208       32.3508       21.6153       5.41289        30     
[art]:TriggerResults:TriggerResultInserter            3.1664e-05    4.90502e-05    0.0001017    4.76815e-05   1.37842e-05      30     
end_path:out1:RootOutput                               8.49e-06     1.42241e-05   3.0091e-05    1.36155e-05   4.81892e-06      30     
end_path:michelremoving:michelremoving                 0.0228986     0.115359      0.388956      0.0718932     0.0973994       30     
end_path:michelremovinggnocchi:michelremoving          0.0218988     0.111901      0.378868      0.0666454     0.096466        30     
end_path:calibanagnocchi:CalibAnaTree                  0.251486      0.821325       2.27977      0.716781      0.385388        30     
end_path:out1:RootOutput(write)                        0.458428      0.997666       1.92773       1.00164      0.311425        30     
========================================================================================================================================

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

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