justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID78461.152@justin-prod-sched02.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 09:58:29
SiteFR_CCIN2P3
EntryDUNE_FR_CCIN2P3_cccondorce02
Last heartbeat2024-09-26 11:15:13
From worker nodeHostnameccwcondor0559
cpuinfoAMD EPYC 7453 28-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4193255424 (3999 MiB)
Wall seconds limit106200 (29 hours)
Inner Apptainer?True
Job statejobscript_error
Allocator namejustin-allocator-pro.dune.hep.ac.uk
Started2024-09-26 10:13:54
Input fileshd-protodune:np04hd_raw_run029107_2277_dataflow1_datawriter_0_20240908T044528.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 11:15:13
Saved logsjustin-logs:78461.152-justin-prod-sched02.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

060 hits in TPC 3
875 hits in TPC 4
4039 hits in TPC 5
1895 hits in TPC 6
1304 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 342 XUs and 462 XVs -> 18 XUVs
C:0 T:2 12176 XUs and 10635 XVs -> 489 XUVs
C:0 T:3 324 XUs and 403 XVs -> 25 XUVs
C:0 T:4 7 XUs and 5 XVs -> 0 XUVs
C:0 T:5 14887 XUs and 10556 XVs -> 272 XUVs
C:0 T:6 1369 XUs and 2462 XVs -> 69 XUVs
C:0 T:7 68 XUs and 78 XVs -> 4 XUVs
877 XUVs total
573 collection wire objects
877 potential space points
Neighbour search...
9501 tests to find 6452 neighbours
Iterating with no regularization...
Begin: 2.67085e+06
0 2.60161e+06
1 2.5971e+06
2 2.59676e+06
Now with regularization...
Begin: 2.40454e+06
0 2.40441e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
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 29th record. run: 29107 subRun: 1 event: 546761 at 26-Sep-2024 13:11:47 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 43222 traces, 43222 summary
Retagger: tagging trace set: gauss with 34831 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 34831 traces tagged "gauss"
FrameSaver: q=701811 n=702323 tag=gauss
wclsFrameSaver: saving 43222 traces tagged "wiener"
FrameSaver: q=745311 n=645659 tag=wiener
1878 hits in TPC 0
3316 hits in TPC 1
1523 hits in TPC 2
868 hits in TPC 3
4022 hits in TPC 4
7014 hits in TPC 5
2901 hits in TPC 6
3216 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 67 XUs and 61 XVs -> 6 XUVs
C:0 T:2 1802 XUs and 1497 XVs -> 116 XUVs
C:0 T:3 0 XUs and 0 XVs -> 0 XUVs
C:0 T:4 67 XUs and 73 XVs -> 3 XUVs
C:0 T:5 40745 XUs and 40648 XVs -> 2577 XUVs
C:0 T:6 5623 XUs and 4611 XVs -> 128 XUVs
C:0 T:7 909 XUs and 553 XVs -> 64 XUVs
2894 XUVs total
988 collection wire objects
2894 potential space points
Neighbour search...
232258 tests to find 111208 neighbours
Iterating with no regularization...
Begin: 4.18011e+06
0 3.8296e+06
1 3.79783e+06
2 3.79283e+06
3 3.79124e+06
Now with regularization...
Begin: 3.57757e+06
0 3.57638e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 30th record. run: 29107 subRun: 1 event: 546769 at 26-Sep-2024 13:12:49 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 51070 traces, 51070 summary
Retagger: tagging trace set: gauss with 37945 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 37945 traces tagged "gauss"
FrameSaver: q=825721 n=721074 tag=gauss
wclsFrameSaver: saving 51070 traces tagged "wiener"
FrameSaver: q=892257 n=648311 tag=wiener
2659 hits in TPC 0
3435 hits in TPC 1
5799 hits in TPC 2
1966 hits in TPC 3
2583 hits in TPC 4
5533 hits in TPC 5
4651 hits in TPC 6
1673 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 17 XUs and 1 XVs -> 0 XUVs
C:0 T:1 936 XUs and 976 XVs -> 111 XUVs
C:0 T:2 34360 XUs and 32009 XVs -> 2993 XUVs
C:0 T:3 33 XUs and 41 XVs -> 1 XUVs
C:0 T:4 37 XUs and 70 XVs -> 3 XUVs
C:0 T:5 15778 XUs and 25726 XVs -> 1046 XUVs
C:0 T:6 20951 XUs and 54355 XVs -> 914 XUVs
C:0 T:7 68 XUs and 30 XVs -> 0 XUVs
5068 XUVs total
1190 collection wire objects
5068 potential space points
Neighbour search...
781538 tests to find 362644 neighbours
Iterating with no regularization...
Begin: 6.11182e+06
0 5.60347e+06
1 5.57924e+06
2 5.57592e+06
Now with regularization...
Begin: 5.27552e+06
0 5.27348e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

26-Sep-2024 13:14:02 CEST  Closed output file "np04hd_raw_run029107_2277_dataflow1_datawriter_0_20240908T044528_reco_stage1_reco_stage2_20240926T111402_keepup.root"
26-Sep-2024 13:14:02 CEST  Closed input file "np04hd_raw_run029107_2277_dataflow1_datawriter_0_20240908T044528_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              47.9988       84.0145       156.299       76.0298       23.6879        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000353519   0.00221224    0.00421992    0.00228798    0.00100505       30     
produce:fembfilter:PDHDFEMBFilter                      0.496939      0.597166      0.791601      0.559205      0.0894732       30     
produce:wclsdatahd:WireCellToolkit                      30.0975       35.7812       42.4678       35.8002       3.14657        30     
produce:gaushit:GausHitFinder                           0.27988      0.574283       1.03816      0.557347       0.20257        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000180577   0.000292725   0.000522846   0.000279662   8.57624e-05      30     
produce:reco3d:SpacePointSolver                        0.0679642      0.86083       3.23229      0.774416      0.683225        30     
produce:hitpdune:DisambigFromSpacePoints               0.0766754     0.420806      0.973219      0.292585      0.262405        30     
produce:pandora:StandardPandora                         2.29628       10.312        27.2993       8.77083       5.81539        30     
produce:pandoraWriter:StandardPandora                  0.0605641     0.130005       0.24398      0.120855      0.0453107       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.293112       1.38058       4.34138       1.00808      0.958873        30     
produce:pandoraShower:LArPandoraShowerCreation         0.317063       1.72637       7.05546      0.982277       1.58092        30     
produce:pandoracalo:Calorimetry                        0.180604      0.739626       2.99614      0.567998      0.536169        30     
produce:pandoracalonosce:Calorimetry                   0.151698      0.651386       2.2123        0.50886      0.423204        30     
produce:pandorapid:Chi2ParticleID                     0.000930066   0.00182017    0.00396522    0.00171516    0.000647852      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.267507       1.59365       7.42947       1.10503       1.51456        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.260291       1.55608       7.23223       1.01868       1.45398        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0564507     0.115696      0.219922      0.116474      0.0431913       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0222567     0.0422057     0.086934      0.0387026     0.0157156       30     
produce:emtrkmichelid:EmTrackMichelId                   12.0456       26.1177       50.7228       23.0272       8.75649        30     
[art]:TriggerResults:TriggerResultInserter            2.5938e-05    4.96035e-05   9.1972e-05    4.90215e-05   1.61531e-05      30     
end_path:out1:RootOutput                               9.197e-06    1.49986e-05   3.3512e-05    1.38115e-05   5.62818e-06      30     
end_path:michelremoving:michelremoving                 0.0164547     0.0723158     0.259873      0.0345499     0.0725869       30     
end_path:michelremovinggnocchi:michelremoving          0.0137225     0.0693456     0.244382      0.0332416     0.0728994       30     
end_path:calibanagnocchi:CalibAnaTree                  0.0985945     0.467511       1.54029      0.379462      0.292324        30     
end_path:out1:RootOutput(write)                         0.30273      0.632919       1.13638      0.564368      0.229879        30     
========================================================================================================================================

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

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