justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263947.2@justin-prod-sched01.dune.hep.ac.uk
Workflow ID3460
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:26:25
SiteUK_Manchester
EntryUBoone_T2_UK_Manchester_ce02
Last heartbeat2024-09-26 15:50:59
From worker nodeHostnamewn1904240.tier2.hep.manchester.ac.uk
cpuinfoAMD EPYC 7513 32-Core Processor
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:28
Input fileshd-protodune:np04hd_raw_run029218_0097_dataflow2_datawriter_0_20240917T165136.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 15:50:59
Saved logsjustin-logs:263947.2-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

0 V bad channels
Finding XUV coincidences...
C:0 T:1 207 XUs and 77 XVs -> 0 XUVs
C:0 T:2 12647 XUs and 11474 XVs -> 441 XUVs
C:0 T:3 391 XUs and 383 XVs -> 29 XUVs
C:0 T:4 125 XUs and 204 XVs -> 2 XUVs
C:0 T:5 7178 XUs and 10410 XVs -> 360 XUVs
C:0 T:6 22818 XUs and 14336 XVs -> 1223 XUVs
C:0 T:7 175 XUs and 312 XVs -> 9 XUVs
2064 XUVs total
1252 collection wire objects
2064 potential space points
Neighbour search...
35214 tests to find 19906 neighbours
Iterating with no regularization...
Begin: 3.96714e+06
0 3.70103e+06
1 3.68226e+06
2 3.68017e+06
Now with regularization...
Begin: 3.27097e+06
0 3.27047e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 30th record. run: 29218 subRun: 1 event: 24290 at 26-Sep-2024 16:47:19 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 46374 traces, 46374 summary
Retagger: tagging trace set: gauss with 35988 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 35988 traces tagged "gauss"
FrameSaver: q=741755 n=725383 tag=gauss
wclsFrameSaver: saving 46374 traces tagged "wiener"
FrameSaver: q=786224 n=664886 tag=wiener
3526 hits in TPC 0
2079 hits in TPC 1
5426 hits in TPC 2
4069 hits in TPC 3
2434 hits in TPC 4
3100 hits in TPC 5
3506 hits in TPC 6
3039 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 6 XUs and 7 XVs -> 0 XUVs
C:0 T:2 26505 XUs and 76025 XVs -> 948 XUVs
C:0 T:3 4141 XUs and 10983 XVs -> 689 XUVs
C:0 T:4 8 XUs and 2 XVs -> 0 XUVs
C:0 T:5 4903 XUs and 4521 XVs -> 215 XUVs
C:0 T:6 25844 XUs and 61088 XVs -> 532 XUVs
C:0 T:7 71 XUs and 113 XVs -> 1 XUVs
2385 XUVs total
1017 collection wire objects
2385 potential space points
Neighbour search...
87955 tests to find 49340 neighbours
Iterating with no regularization...
Begin: 4.71576e+06
0 3.90082e+06
1 3.86286e+06
2 3.85845e+06
3 3.85645e+06
Now with regularization...
Begin: 3.66462e+06
0 3.66332e+06
BdtBeamParticleIdTool::SliceFeatures::GetLArTPCIntercepts - inconsistent number of intercepts between a line and the LArTPC
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 31st record. run: 29218 subRun: 1 event: 24298 at 26-Sep-2024 16:48:55 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 49316 traces, 49316 summary
Retagger: tagging trace set: gauss with 36341 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 36341 traces tagged "gauss"
FrameSaver: q=789547 n=686619 tag=gauss
wclsFrameSaver: saving 49316 traces tagged "wiener"
FrameSaver: q=846652 n=618980 tag=wiener
4040 hits in TPC 0
2412 hits in TPC 1
4659 hits in TPC 2
3758 hits in TPC 3
1656 hits in TPC 4
2752 hits in TPC 5
3428 hits in TPC 6
1949 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 17 XUs and 4 XVs -> 0 XUVs
C:0 T:2 16643 XUs and 23073 XVs -> 1597 XUVs
C:0 T:3 39 XUs and 32 XVs -> 2 XUVs
C:0 T:4 110 XUs and 220 XVs -> 8 XUVs
C:0 T:5 5674 XUs and 2809 XVs -> 60 XUVs
C:0 T:6 7802 XUs and 12042 XVs -> 721 XUVs
C:0 T:7 36 XUs and 30 XVs -> 2 XUVs
2390 XUVs total
815 collection wire objects
2390 potential space points
Neighbour search...
90080 tests to find 49390 neighbours
Iterating with no regularization...
Begin: 4.16098e+06
0 3.37317e+06
1 3.30718e+06
2 3.29668e+06
3 3.29381e+06
Now with regularization...
Begin: 2.94671e+06
0 2.94553e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  0   event 24298 TrkackID 8 startx, y, z 149.419 458.931 35.029  wireno, peakt tpcno 132 1845.08 2 dist 185.81min T, max_T4.30234 1888.42
no of hits closeby  0   event 24298 TrkackID 8 startx, y, z 149.419 458.931 35.029  wireno, peakt tpcno 132 1845.08 2 dist 185.81min T, max_T4.30234 1888.42
26-Sep-2024 16:50:29 BST  Closed output file "np04hd_raw_run029218_0097_dataflow2_datawriter_0_20240917T165136_reco_stage1_reco_stage2_20240926T155029_keepup.root"
26-Sep-2024 16:50:29 BST  Closed input file "np04hd_raw_run029218_0097_dataflow2_datawriter_0_20240917T165136_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              69.5448       113.134       160.323       111.18        22.2188        31     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.00104478    0.00355337     0.0060405    0.00366268    0.00125046       31     
produce:fembfilter:PDHDFEMBFilter                       0.55799      0.800359      0.934011       0.81026      0.0799157       31     
produce:wclsdatahd:WireCellToolkit                      40.0021       49.0736       57.8197       50.7928       4.87727        31     
produce:gaushit:GausHitFinder                          0.393689      0.875649       1.35048      0.790566       0.25195        31     
produce:nhitsfilter:NumberOfHitsFilter                0.000262604   0.000432382   0.000641556   0.000398168   9.80271e-05      31     
produce:reco3d:SpacePointSolver                        0.061161      0.848408       2.14788      0.691223      0.505178        31     
produce:hitpdune:DisambigFromSpacePoints               0.130748      0.717024       1.52974      0.677465      0.374522        31     
produce:pandora:StandardPandora                         2.65187       15.1663        35.97        13.8072       7.12109        31     
produce:pandoraWriter:StandardPandora                  0.0897931     0.234732      0.386191      0.246161      0.0621437       31     
produce:pandoraTrack:LArPandoraTrackCreation           0.301674       1.55939       3.68332       1.38144      0.777223        31     
produce:pandoraShower:LArPandoraShowerCreation         0.188228       1.84363       4.62345       1.67173       1.06364        31     
produce:pandoracalo:Calorimetry                        0.210864      0.810409       1.64644      0.781482      0.338127        31     
produce:pandoracalonosce:Calorimetry                   0.181379      0.712249       1.53034      0.670885      0.303057        31     
produce:pandorapid:Chi2ParticleID                     0.00136975    0.00241869    0.00551264     0.0022684    0.000776269      31     
produce:pandoraShowercalo:ShowerCalorimetry            0.186645       1.68985       4.09176       1.61213      0.929544        31     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.153201       1.63581       3.82509       1.48186      0.901101        31     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0563835     0.184329      0.288515      0.175785        0.051         31     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0180367     0.0599891     0.0932506     0.0603709     0.0159087       31     
produce:emtrkmichelid:EmTrackMichelId                   14.0951       34.9948       53.616        34.3791       8.72714        31     
[art]:TriggerResults:TriggerResultInserter            3.1229e-05    7.21699e-05   0.000160962   6.7537e-05    2.42493e-05      31     
end_path:out1:RootOutput                              1.0039e-05    1.97231e-05   8.8526e-05    1.6281e-05    1.37839e-05      31     
end_path:michelremoving:michelremoving                 0.012673      0.0764924     0.227385      0.048074      0.0597231       31     
end_path:michelremovinggnocchi:michelremoving          0.0108323     0.0715225     0.216824      0.0394029     0.0608485       31     
end_path:calibanagnocchi:CalibAnaTree                  0.092014      0.551658       1.05171      0.533122      0.228376        31     
end_path:out1:RootOutput(write)                        0.294819       1.03529       1.60008      0.963788       0.27583        31     
========================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4928.52 MB
  Peak resident set size usage (VmHWM): 2562.68 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run029218_0097_dataflow2_datawriter_0_20240917T165136_reco_stage1_reco_stage2_20240926T155029_keepup.root
\tHists: np04hd_raw_run029218_0097_dataflow2_datawriter_0_20240917T165136_reco_stage1_reco_stage2_20240926T155029_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 08:48:21 UTC       justIN version: 01.01.09