justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263368.1@justin-prod-sched01.dune.hep.ac.uk
Workflow ID3461
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:19:14
SiteUK_Durham
EntryDUNE_UK_SGridDurham_ce4
Last heartbeat2024-09-26 12:22:29
From worker nodeHostnamen255.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 10:52:26
Input fileshd-protodune:np04hd_raw_run027298_0092_dataflow2_datawriter_0_20240619T143430.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 12:10:08
Saved logsjustin-logs:263368.1-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

agged "gauss"
FrameSaver: q=1.65706e+06 n=805619 tag=gauss
wclsFrameSaver: saving 59639 traces tagged "wiener"
FrameSaver: q=1.76799e+06 n=722130 tag=wiener
2746 hits in TPC 0
3182 hits in TPC 1
3722 hits in TPC 2
1961 hits in TPC 3
4184 hits in TPC 4
7870 hits in TPC 5
5081 hits in TPC 6
3093 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 427 XUs and 455 XVs -> 12 XUVs
C:0 T:2 10413 XUs and 5150 XVs -> 584 XUVs
C:0 T:3 477 XUs and 296 XVs -> 12 XUVs
C:0 T:4 134 XUs and 111 XVs -> 5 XUVs
C:0 T:5 22349 XUs and 25508 XVs -> 1059 XUVs
C:0 T:6 10015 XUs and 9680 XVs -> 576 XUVs
C:0 T:7 808 XUs and 572 XVs -> 13 XUVs
2261 XUVs total
1136 collection wire objects
2261 potential space points
Neighbour search...
32513 tests to find 19840 neighbours
Iterating with no regularization...
Begin: 3.32387e+07
0 3.22056e+07
1 3.21667e+07
2 3.21642e+07
Now with regularization...
Begin: 2.9924e+07
0 2.99226e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 34th record. run: 27298 subRun: 1 event: 13014 at 26-Sep-2024 13:08:23 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 43581 traces, 43581 summary
Retagger: tagging trace set: gauss with 32795 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 32795 traces tagged "gauss"
FrameSaver: q=1.23704e+06 n=606415 tag=gauss
wclsFrameSaver: saving 43581 traces tagged "wiener"
FrameSaver: q=1.30523e+06 n=544615 tag=wiener
2787 hits in TPC 0
1981 hits in TPC 1
3102 hits in TPC 2
2387 hits in TPC 3
3025 hits in TPC 4
3932 hits in TPC 5
4084 hits in TPC 6
2208 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 1207 XUs and 2195 XVs -> 9 XUVs
C:0 T:2 4937 XUs and 4225 XVs -> 237 XUVs
C:0 T:3 132 XUs and 173 XVs -> 14 XUVs
C:0 T:4 80 XUs and 128 XVs -> 4 XUVs
C:0 T:5 6992 XUs and 12663 XVs -> 362 XUVs
C:0 T:6 13946 XUs and 10185 XVs -> 374 XUVs
C:0 T:7 214 XUs and 617 XVs -> 23 XUVs
1023 XUVs total
718 collection wire objects
1023 potential space points
Neighbour search...
9875 tests to find 6332 neighbours
Iterating with no regularization...
Begin: 9.9107e+06
0 9.514e+06
1 9.50429e+06
2 9.5035e+06
Now with regularization...
Begin: 8.89756e+06
0 8.89709e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 35th record. run: 27298 subRun: 1 event: 13018 at 26-Sep-2024 13:09:08 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 58003 traces, 58003 summary
Retagger: tagging trace set: gauss with 44954 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 44954 traces tagged "gauss"
FrameSaver: q=1.66205e+06 n=857239 tag=gauss
wclsFrameSaver: saving 58003 traces tagged "wiener"
FrameSaver: q=1.77931e+06 n=786306 tag=wiener
3227 hits in TPC 0
4837 hits in TPC 1
5464 hits in TPC 2
4422 hits in TPC 3
3590 hits in TPC 4
5253 hits in TPC 5
4306 hits in TPC 6
3217 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 6 XUs and 5 XVs -> 0 XUVs
C:0 T:1 3957 XUs and 3917 XVs -> 187 XUVs
C:0 T:2 12606 XUs and 15943 XVs -> 485 XUVs
C:0 T:3 1268 XUs and 1124 XVs -> 84 XUVs
C:0 T:4 125 XUs and 126 XVs -> 4 XUVs
C:0 T:5 14893 XUs and 9126 XVs -> 659 XUVs
C:0 T:6 11391 XUs and 16889 XVs -> 535 XUVs
C:0 T:7 43 XUs and 53 XVs -> 3 XUVs
1957 XUVs total
1048 collection wire objects
1957 potential space points
Neighbour search...
51397 tests to find 30104 neighbours
Iterating with no regularization...
Begin: 2.30868e+07
0 2.22008e+07
1 2.21631e+07
2 2.21574e+07
Now with regularization...
Begin: 2.13742e+07
0 2.13721e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

26-Sep-2024 13:10:07 BST  Closed output file "np04hd_raw_run027298_0092_dataflow2_datawriter_0_20240619T143430_reco_stage1_reco_stage2_20240926T121007_keepup.root"
26-Sep-2024 13:10:07 BST  Closed input file "np04hd_raw_run027298_0092_dataflow2_datawriter_0_20240619T143430_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              45.2197       67.3599       100.362       63.0715       13.5807        35     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000291673   0.00175284    0.00364618    0.00182461    0.000819518      35     
produce:fembfilter:PDHDFEMBFilter                      0.493718      0.584102      0.811692       0.59846      0.0933019       35     
produce:wclsdatahd:WireCellToolkit                      24.7762       27.4665       35.5038       25.707        3.14533        35     
produce:gaushit:GausHitFinder                          0.333078      0.555916       1.12125      0.513496       0.17469        35     
produce:nhitsfilter:NumberOfHitsFilter                0.000195451   0.000286361   0.00048012    0.000271465   6.25733e-05      35     
produce:reco3d:SpacePointSolver                        0.122012      0.640415       3.31188      0.501224      0.617393        35     
produce:hitpdune:DisambigFromSpacePoints               0.122846      0.410196      0.905915      0.353206      0.192467        35     
produce:pandora:StandardPandora                         3.25249       10.7761       24.2716       10.8142       4.67889        35     
produce:pandoraWriter:StandardPandora                  0.0686068     0.116694       0.23756      0.108102      0.0332846       35     
produce:pandoraTrack:LArPandoraTrackCreation           0.513026       0.95724       2.48987      0.853272      0.409726        35     
produce:pandoraShower:LArPandoraShowerCreation          0.47457       1.22756       3.4611        1.02814      0.633943        35     
produce:pandoracalo:Calorimetry                        0.266711      0.506436      0.985078      0.444425      0.178375        35     
produce:pandoracalonosce:Calorimetry                   0.236926      0.458131      0.913472      0.416654      0.158663        35     
produce:pandorapid:Chi2ParticleID                     0.00101627    0.00150633    0.00289165    0.00131694    0.000433463      35     
produce:pandoraShowercalo:ShowerCalorimetry            0.425279       1.12772       2.97062      0.963525      0.536143        35     
produce:pandoraShowercalonosce:ShowerCalorimetry        0.40447       1.07917       2.59647      0.907439      0.492804        35     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.058377      0.0947985     0.195348      0.0821382     0.0346122       35     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0203234     0.0321994     0.0645303     0.0279066     0.0109848       35     
produce:emtrkmichelid:EmTrackMichelId                   12.2791       20.1367       33.7746       19.156        5.05352        35     
[art]:TriggerResults:TriggerResultInserter            1.8755e-05    2.80086e-05   8.0032e-05    2.3736e-05    1.19092e-05      35     
end_path:out1:RootOutput                               6.422e-06    1.00175e-05   2.4296e-05     9.218e-06    3.61782e-06      35     
end_path:michelremoving:michelremoving                 0.014546      0.0494657     0.232318      0.0213012     0.052705        35     
end_path:michelremovinggnocchi:michelremoving          0.0124235     0.0462673      0.20629      0.0179678     0.0508054       35     
end_path:calibanagnocchi:CalibAnaTree                  0.192687      0.373017       0.83227      0.343832      0.135187        35     
end_path:out1:RootOutput(write)                        0.377287      0.605428       1.02798      0.573836       0.14597        35     
========================================================================================================================================

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

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