justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263920.21@justin-prod-sched01.dune.hep.ac.uk
Workflow ID3451
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:19:16
SiteIT_CNAF
EntryCMSHTPC_T1_IT_CNAF_condor_ce02
Last heartbeat2024-09-26 15:11:14
From worker nodeHostnamewn-200-04-07-04-a.cr.cnaf.infn.it
cpuinfoAMD EPYC 7313 16-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4193255424 (3999 MiB)
Wall seconds limit216000 (60 hours)
Inner Apptainer?True
Job statejobscript_error
Allocator namejustin-allocator-pro.dune.hep.ac.uk
Started2024-09-26 14:21:09
Input fileshd-protodune:np04hd_raw_run029107_1982_dataflow0_datawriter_0_20240908T000357.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 15:11:14
Saved logsjustin-logs:263920.21-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

---------------------------------------------------------------------------------------------

no of hits closeby  2   event 475928 TrkackID 5 startx, y, z 245.426 524.177 393.772  wireno, peakt tpcno 117 2651.69 5 dist 1.22079min T, max_T6.1296 3133.3
no of hits closeby  3   event 475928 TrkackID 14 startx, y, z 50.2657 592.574 423.792  wireno, peakt tpcno 387 3697 5 dist 0min T, max_T3687 4627
no of hits closeby  2   event 475928 TrkackID 5 startx, y, z 245.426 524.177 393.772  wireno, peakt tpcno 117 2651.69 5 dist 1.22079min T, max_T6.1296 3133.3
no of hits closeby  3   event 475928 TrkackID 14 startx, y, z 50.2657 592.574 423.792  wireno, peakt tpcno 387 3697 5 dist 0min T, max_T3687 4627
Begin processing the 29th record. run: 29107 subRun: 1 event: 475976 at 26-Sep-2024 17:08:43 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 44048 traces, 44048 summary
Retagger: tagging trace set: gauss with 32845 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 32845 traces tagged "gauss"
FrameSaver: q=639919 n=559011 tag=gauss
wclsFrameSaver: saving 44048 traces tagged "wiener"
FrameSaver: q=696375 n=500544 tag=wiener
4528 hits in TPC 0
3647 hits in TPC 1
3348 hits in TPC 2
2104 hits in TPC 3
1923 hits in TPC 4
3906 hits in TPC 5
967 hits in TPC 6
713 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 3974 XUs and 5151 XVs -> 617 XUVs
C:0 T:2 11720 XUs and 4302 XVs -> 117 XUVs
C:0 T:3 42 XUs and 31 XVs -> 0 XUVs
C:0 T:4 47 XUs and 46 XVs -> 1 XUVs
C:0 T:5 5507 XUs and 4305 XVs -> 190 XUVs
C:0 T:6 1760 XUs and 561 XVs -> 14 XUVs
C:0 T:7 62 XUs and 83 XVs -> 3 XUVs
942 XUVs total
327 collection wire objects
942 potential space points
Neighbour search...
48810 tests to find 31204 neighbours
Iterating with no regularization...
Begin: 2.1255e+06
0 2.05834e+06
1 2.05553e+06
2 2.05536e+06
Now with regularization...
Begin: 1.9639e+06
0 1.96385e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 30th record. run: 29107 subRun: 1 event: 475984 at 26-Sep-2024 17:09:32 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 77699 traces, 77699 summary
Retagger: tagging trace set: gauss with 58472 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 58472 traces tagged "gauss"
FrameSaver: q=1.2798e+06 n=1146083 tag=gauss
wclsFrameSaver: saving 77699 traces tagged "wiener"
FrameSaver: q=1.38599e+06 n=1049788 tag=wiener
5907 hits in TPC 0
5599 hits in TPC 1
6299 hits in TPC 2
3091 hits in TPC 3
5851 hits in TPC 4
6859 hits in TPC 5
6029 hits in TPC 6
3612 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 3136 XUs and 2890 XVs -> 111 XUVs
C:0 T:2 28701 XUs and 36403 XVs -> 1784 XUVs
C:0 T:3 530 XUs and 596 XVs -> 56 XUVs
C:0 T:4 185 XUs and 162 XVs -> 8 XUVs
C:0 T:5 28294 XUs and 46266 XVs -> 2304 XUVs
C:0 T:6 35000 XUs and 31816 XVs -> 2082 XUVs
C:0 T:7 423 XUs and 282 XVs -> 9 XUVs
6354 XUVs total
1790 collection wire objects
6354 potential space points
Neighbour search...
476688 tests to find 243500 neighbours
Iterating with no regularization...
Begin: 9.04784e+06
0 7.24052e+06
1 7.062e+06
2 7.04401e+06
3 7.03894e+06
Now with regularization...
Begin: 6.63955e+06
0 6.63671e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  1   event 475984 TrkackID 2 startx, y, z 55.9604 284.259 449.835  wireno, peakt tpcno 63 5781.27 6 dist 289.46min T, max_T4923.81 5792.94
no of hits closeby  1   event 475984 TrkackID 2 startx, y, z 55.9604 284.259 449.835  wireno, peakt tpcno 63 5781.27 6 dist 289.46min T, max_T4923.81 5792.94
26-Sep-2024 17:10:53 CEST  Closed output file "np04hd_raw_run029107_1982_dataflow0_datawriter_0_20240908T000357_reco_stage1_reco_stage2_20240926T151053_keepup.root"
26-Sep-2024 17:10:53 CEST  Closed input file "np04hd_raw_run029107_1982_dataflow0_datawriter_0_20240908T000357_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              49.0517       69.5213       96.3841       70.2899       11.1171        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000340719   0.00150241    0.00390807    0.00131824    0.000901512      30     
produce:fembfilter:PDHDFEMBFilter                      0.397578      0.483899      0.644698      0.446646      0.0769394       30     
produce:wclsdatahd:WireCellToolkit                      26.3123       29.8083       34.8483       29.4876       2.11053        30     
produce:gaushit:GausHitFinder                          0.228738      0.492159      0.820231       0.46426      0.152178        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000161162   0.000254776   0.000511299   0.000240402   6.7786e-05       30     
produce:reco3d:SpacePointSolver                        0.0874574     0.747036       3.7533       0.595187      0.674577        30     
produce:hitpdune:DisambigFromSpacePoints               0.0897511     0.427823       1.00599      0.344947      0.264531        30     
produce:pandora:StandardPandora                         3.66196       9.12859       15.0731       8.61054       3.29454        30     
produce:pandoraWriter:StandardPandora                  0.0536701     0.0991763     0.209118      0.096979      0.0329817       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.304156      0.795391       1.60354       0.89213      0.305519        30     
produce:pandoraShower:LArPandoraShowerCreation         0.354486      0.972904       2.32432      0.989051      0.428988        30     
produce:pandoracalo:Calorimetry                        0.178776      0.440207      0.999981      0.439555      0.166771        30     
produce:pandoracalonosce:Calorimetry                   0.159178      0.411039      0.897276      0.405189       0.16871        30     
produce:pandorapid:Chi2ParticleID                     0.00066074    0.00148666    0.00512771    0.00127084    0.000818012      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.353059      0.967859       2.50983      0.888702      0.462375        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.344629      0.933798       2.49813      0.896094      0.442329        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.045637      0.091833      0.232685      0.083551      0.0378896       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0153611     0.0307619     0.0719816     0.0280443     0.0110982       30     
produce:emtrkmichelid:EmTrackMichelId                   12.3411       22.5818       31.9331       22.5255       4.98355        30     
[art]:TriggerResults:TriggerResultInserter            1.2313e-05    2.29505e-05   5.9612e-05    2.20365e-05   9.16126e-06      30     
end_path:out1:RootOutput                               3.807e-06    7.95763e-06   1.9436e-05     6.903e-06    3.59569e-06      30     
end_path:michelremoving:michelremoving                 0.0104659     0.0709845     0.308091      0.0316283     0.0681269       30     
end_path:michelremovinggnocchi:michelremoving         0.00995349     0.0669995     0.260989      0.028303      0.0608892       30     
end_path:calibanagnocchi:CalibAnaTree                  0.0883061     0.330988       0.5748       0.318796      0.113378        30     
end_path:out1:RootOutput(write)                        0.283639      0.558286      0.978413      0.538773      0.138783        30     
========================================================================================================================================

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

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