justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 78490.0@justin-prod-sched02.dune.hep.ac.uk

Jobsub ID78490.0@justin-prod-sched02.dune.hep.ac.uk
Workflow ID3442
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 10:11:10
SiteUK_Sheffield
EntryDUNE_UK_Sheffield_lcgce1
Last heartbeat2024-09-26 12:26:59
From worker nodeHostnamewn032.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 stateoutputting_failed
Allocator namejustin-allocator-pro.dune.hep.ac.uk
Started2024-09-26 10:42:18
Input fileshd-protodune:np04hd_raw_run029072_0020_dataflow6_datawriter_0_20240903T150453.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 12:26:59
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

10 startx, y, z 182.208 406.891 101.833  wireno, peakt tpcno 266 2288.7 2 dist 146.393min T, max_T5 2327.37
Begin processing the 29th record. run: 29072 subRun: 1 event: 5054 at 26-Sep-2024 12:56:18 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 92659 traces, 92659 summary
Retagger: tagging trace set: gauss with 68355 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 68355 traces tagged "gauss"
FrameSaver: q=1.54515e+06 n=1274504 tag=gauss
wclsFrameSaver: saving 92659 traces tagged "wiener"
FrameSaver: q=1.68059e+06 n=1159279 tag=wiener
6637 hits in TPC 0
5738 hits in TPC 1
6091 hits in TPC 2
3310 hits in TPC 3
7689 hits in TPC 4
11564 hits in TPC 5
5644 hits in TPC 6
3243 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 2872 XUs and 2445 XVs -> 71 XUVs
C:0 T:2 32799 XUs and 31316 XVs -> 824 XUVs
C:0 T:3 237 XUs and 294 XVs -> 18 XUVs
C:0 T:4 312 XUs and 426 XVs -> 17 XUVs
C:0 T:5 88911 XUs and 103954 XVs -> 5455 XUVs
C:0 T:6 44221 XUs and 36049 XVs -> 804 XUVs
C:0 T:7 0 XUs and 0 XVs -> 0 XUVs
7189 XUVs total
2494 collection wire objects
7189 potential space points
Neighbour search...
295823 tests to find 177822 neighbours
Iterating with no regularization...
Begin: 1.2053e+07
0 1.09942e+07
1 1.08929e+07
2 1.08737e+07
3 1.08672e+07
Now with regularization...
Begin: 1.01653e+07
0 1.01611e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  5   event 5054 TrkackID 0 startx, y, z 19.7071 561.746 1.06898  wireno, peakt tpcno 472 5664.05 6 dist 65.0157min T, max_T5525.68 5855.42
no of hits closeby  2   event 5054 TrkackID 10 startx, y, z 34.6986 292.333 441.397  wireno, peakt tpcno 27 3155.15 5 dist 0min T, max_T3154.42 3754.57
no of hits closeby  5   event 5054 TrkackID 0 startx, y, z 19.7071 561.746 1.06898  wireno, peakt tpcno 472 5664.05 6 dist 65.0157min T, max_T5525.68 5855.42
no of hits closeby  2   event 5054 TrkackID 10 startx, y, z 34.6986 292.333 441.397  wireno, peakt tpcno 27 3155.15 5 dist 0min T, max_T3154.42 3754.57
Begin processing the 30th record. run: 29072 subRun: 1 event: 5062 at 26-Sep-2024 12:58:34 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 91287 traces, 91287 summary
Retagger: tagging trace set: gauss with 67511 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 67511 traces tagged "gauss"
FrameSaver: q=1.537e+06 n=1230601 tag=gauss
wclsFrameSaver: saving 91287 traces tagged "wiener"
FrameSaver: q=1.70746e+06 n=1127006 tag=wiener
5051 hits in TPC 0
6831 hits in TPC 1
7168 hits in TPC 2
3626 hits in TPC 3
4857 hits in TPC 4
8016 hits in TPC 5
10402 hits in TPC 6
4546 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 2928 XUs and 1443 XVs -> 75 XUVs
C:0 T:2 26654 XUs and 81664 XVs -> 1417 XUVs
C:0 T:3 518 XUs and 779 XVs -> 12 XUVs
C:0 T:4 262 XUs and 545 XVs -> 3 XUVs
C:0 T:5 68363 XUs and 74314 XVs -> 2415 XUVs
C:0 T:6 73492 XUs and 161708 XVs -> 5418 XUVs
C:0 T:7 188 XUs and 296 XVs -> 4 XUVs
9344 XUVs total
2743 collection wire objects
9344 potential space points
Neighbour search...
749888 tests to find 313486 neighbours
Iterating with no regularization...
Begin: 3.082e+07
0 2.57778e+07
1 2.5316e+07
2 2.52376e+07
3 2.52087e+07
4 2.51946e+07
Now with regularization...
Begin: 2.44167e+07
0 2.4406e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  3   event 5062 TrkackID 16 startx, y, z -32.1254 586.986 268.437  wireno, peakt tpcno 378 3317.47 2 dist 0.67407min T, max_T3297.78 4149.24
no of hits closeby  3   event 5062 TrkackID 16 startx, y, z -32.1254 586.986 268.437  wireno, peakt tpcno 378 3317.47 2 dist 0.67407min T, max_T3297.78 4149.24
26-Sep-2024 13:00:43 BST  Closed output file "np04hd_raw_run029072_0020_dataflow6_datawriter_0_20240903T150453_reco_stage1_reco_stage2_20240926T120043_keepup.root"
26-Sep-2024 13:00:43 BST  Closed input file "np04hd_raw_run029072_0020_dataflow6_datawriter_0_20240903T150453_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              67.1387       96.7201       139.931       88.9377       21.7742        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000445358   0.00157994     0.0041326    0.00120489    0.00106613       30     
produce:fembfilter:PDHDFEMBFilter                      0.609709      0.675423       1.00266       0.64855      0.0882914       30     
produce:wclsdatahd:WireCellToolkit                      45.3121       49.8579       57.0712       50.1748       2.57589        30     
produce:gaushit:GausHitFinder                          0.405718      0.729364       1.0687       0.688733      0.196952        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000311204   0.000469365   0.000768354   0.00043225    0.000124513      30     
produce:reco3d:SpacePointSolver                        0.229493       1.29842       4.72064      0.818605       1.05186        30     
produce:hitpdune:DisambigFromSpacePoints               0.199409      0.600689       1.37791      0.485217      0.322002        30     
produce:pandora:StandardPandora                         4.69643       13.9775       31.2584       11.6797       7.33774        30     
produce:pandoraWriter:StandardPandora                  0.108515       0.17385       0.30918       0.16104      0.0494277       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.871297        2.816        7.58408       2.63549       1.55869        30     
produce:pandoraShower:LArPandoraShowerCreation         0.603149       3.38685       8.13084       2.80385       2.14335        30     
produce:pandoracalo:Calorimetry                        0.364005       1.11284       2.76369       1.02559      0.616039        30     
produce:pandoracalonosce:Calorimetry                   0.346515       1.10366       3.1316       0.958886       0.6494         30     
produce:pandorapid:Chi2ParticleID                     0.00116743    0.00189662    0.00559484    0.00178102    0.000810162      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.498261       3.03206       7.59507       2.48994       1.95197        30     
produce:pandoraShowercalonosce:ShowerCalorimetry        0.42915       2.95424       7.72297       2.52428       1.80325        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0750473     0.123313      0.203446      0.117977      0.0340664       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0301992     0.0502157     0.0795867     0.0494743     0.0128684       30     
produce:emtrkmichelid:EmTrackMichelId                   7.86384       12.7788       22.2591       12.0233       3.36893        30     
[art]:TriggerResults:TriggerResultInserter            3.0655e-05    5.05316e-05   7.9747e-05    5.0675e-05    1.06604e-05      30     
end_path:out1:RootOutput                               9.504e-06    1.64729e-05   4.7635e-05    1.57415e-05   6.74145e-06      30     
end_path:michelremoving:michelremoving                 0.0235386     0.0962519      0.31236      0.0673769     0.0743236       30     
end_path:michelremovinggnocchi:michelremoving          0.0214058     0.0907681     0.315883      0.0590416     0.0754654       30     
end_path:calibanagnocchi:CalibAnaTree                  0.210392      0.811912       1.86481      0.694639      0.410643        30     
end_path:out1:RootOutput(write)                        0.419124      0.729888       1.27944      0.706617      0.204689        30     
========================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5876.1 MB
  Peak resident set size usage (VmHWM): 2713.53 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run029072_0020_dataflow6_datawriter_0_20240903T150453_reco_stage1_reco_stage2_20240926T120043_keepup.root
\tHists: np04hd_raw_run029072_0020_dataflow6_datawriter_0_20240903T150453_reco_stage1_reco_stage2_20240926T120043_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 11:17:24 UTC       justIN version: 01.01.08