justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID79031.1@justin-prod-sched02.dune.hep.ac.uk
Workflow ID3449
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:02:34
SiteIT_CNAF
EntryCMSHTPC_T1_IT_CNAF_condor_ce02
Last heartbeat2024-09-26 15:48:08
From worker nodeHostnamecn-608-05-08.cr.cnaf.infn.it
cpuinfoIntel(R) Xeon(R) CPU E5-2697 v4 @ 2.30GHz
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:03:18
Input fileshd-protodune:np04hd_raw_run029107_0710_dataflow2_datawriter_0_20240907T024456.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 15:48:08
Saved logsjustin-logs:79031.1-justin-prod-sched02.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

6788 neighbours
Iterating with no regularization...
Begin: 6.24998e+06
0 6.05446e+06
1 6.04938e+06
Now with regularization...
Begin: 5.65351e+06
0 5.65286e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  4   event 170642 TrkackID 15 startx, y, z -72.9251 78.1152 274.733  wireno, peakt tpcno 131 543.969 6 dist 1.48619min T, max_T5 913
no of hits closeby  4   event 170642 TrkackID 15 startx, y, z -72.9251 78.1152 274.733  wireno, peakt tpcno 131 543.969 6 dist 1.48619min T, max_T5 913
Begin processing the 29th record. run: 29107 subRun: 1 event: 170650 at 26-Sep-2024 17:42:10 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 38267 traces, 38267 summary
Retagger: tagging trace set: gauss with 29333 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 29333 traces tagged "gauss"
FrameSaver: q=703130 n=587323 tag=gauss
wclsFrameSaver: saving 38267 traces tagged "wiener"
FrameSaver: q=770065 n=538075 tag=wiener
3454 hits in TPC 0
2342 hits in TPC 1
4648 hits in TPC 2
1994 hits in TPC 3
2923 hits in TPC 4
4557 hits in TPC 5
1698 hits in TPC 6
1215 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 4733 XUs and 6653 XVs -> 934 XUVs
C:0 T:2 8441 XUs and 6860 XVs -> 361 XUVs
C:0 T:3 1430 XUs and 3655 XVs -> 212 XUVs
C:0 T:4 186 XUs and 220 XVs -> 16 XUVs
C:0 T:5 12890 XUs and 17711 XVs -> 693 XUVs
C:0 T:6 2155 XUs and 1497 XVs -> 91 XUVs
C:0 T:7 14 XUs and 8 XVs -> 0 XUVs
2307 XUVs total
615 collection wire objects
2307 potential space points
Neighbour search...
107263 tests to find 64238 neighbours
Iterating with no regularization...
Begin: 5.31141e+06
0 4.34123e+06
1 4.27753e+06
2 4.27137e+06
3 4.26972e+06
Now with regularization...
Begin: 4.11129e+06
0 4.10989e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 30th record. run: 29107 subRun: 1 event: 170690 at 26-Sep-2024 17:44:30 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 85348 traces, 85348 summary
Retagger: tagging trace set: gauss with 62190 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 62190 traces tagged "gauss"
FrameSaver: q=1.29545e+06 n=1117299 tag=gauss
wclsFrameSaver: saving 85348 traces tagged "wiener"
FrameSaver: q=1.39446e+06 n=1002883 tag=wiener
7318 hits in TPC 0
5396 hits in TPC 1
6845 hits in TPC 2
4065 hits in TPC 3
3937 hits in TPC 4
6622 hits in TPC 5
7433 hits in TPC 6
2735 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 1520 XUs and 805 XVs -> 39 XUVs
C:0 T:2 24741 XUs and 42660 XVs -> 3733 XUVs
C:0 T:3 19 XUs and 14 XVs -> 0 XUVs
C:0 T:4 160 XUs and 206 XVs -> 9 XUVs
C:0 T:5 36736 XUs and 35628 XVs -> 1070 XUVs
C:0 T:6 23565 XUs and 30971 XVs -> 1323 XUVs
C:0 T:7 717 XUs and 1570 XVs -> 34 XUVs
6208 XUVs total
1891 collection wire objects
6208 potential space points
Neighbour search...
429634 tests to find 256828 neighbours
Iterating with no regularization...
Begin: 8.34542e+06
0 7.63209e+06
1 7.57626e+06
2 7.5652e+06
3 7.56122e+06
Now with regularization...
Begin: 6.9747e+06
0 6.97198e+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--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  5   event 170690 TrkackID 11 startx, y, z 32.3265 600.002 455.095  wireno, peakt tpcno 325 15 5 dist 0.915694min T, max_T6.19508 2215.07
no of hits closeby  5   event 170690 TrkackID 11 startx, y, z 32.3265 600.002 455.095  wireno, peakt tpcno 325 15 5 dist 0.915694min T, max_T6.19508 2215.07
26-Sep-2024 17:47:40 CEST  Closed output file "np04hd_raw_run029107_0710_dataflow2_datawriter_0_20240907T024456_reco_stage1_reco_stage2_20240926T154740_keepup.root"
26-Sep-2024 17:47:40 CEST  Closed input file "np04hd_raw_run029107_0710_dataflow2_datawriter_0_20240907T024456_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              98.4193       160.071       253.609       152.375       38.3238        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000591445    0.0027307    0.00464345    0.00262882    0.00127663       30     
produce:fembfilter:PDHDFEMBFilter                      0.681541      0.924667       1.08401       1.05695      0.172037        30     
produce:wclsdatahd:WireCellToolkit                      53.9112       70.4988       84.9547       70.8993       8.75147        30     
produce:gaushit:GausHitFinder                          0.525259       1.08634       1.81821       1.03515      0.353965        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000237828   0.000473442   0.000709576   0.000473674   0.000110644      30     
produce:reco3d:SpacePointSolver                        0.218873       1.72475       10.0242       1.1266        1.94146        30     
produce:hitpdune:DisambigFromSpacePoints               0.238702       1.09202       3.1452       0.886166      0.831419        30     
produce:pandora:StandardPandora                         7.65344       21.418        51.9159       18.6248       10.9855        30     
produce:pandoraWriter:StandardPandora                  0.117027      0.216613      0.416808      0.204214      0.0742329       30     
produce:pandoraTrack:LArPandoraTrackCreation            0.97748       2.8204        9.62479        2.837        1.69291        30     
produce:pandoraShower:LArPandoraShowerCreation         0.914389       3.09708       8.38711       2.65174       1.79159        30     
produce:pandoracalo:Calorimetry                        0.541873       1.26811       3.26048       1.03341      0.643089        30     
produce:pandoracalonosce:Calorimetry                    0.48859       1.20414       2.81951      0.978383      0.626191        30     
produce:pandorapid:Chi2ParticleID                     0.00140941    0.00284898    0.00520095    0.00264557    0.000967648      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.854061       2.85861       7.94474       2.22203       1.7519         30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.820867       2.75361       7.46661       2.34458       1.63846        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.107875      0.187387      0.325091      0.168128      0.0637666       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0390419     0.0725911     0.129398      0.0663298     0.0256179       30     
produce:emtrkmichelid:EmTrackMichelId                   23.4751       46.3128       76.043        43.2135       12.3999        30     
[art]:TriggerResults:TriggerResultInserter            2.7527e-05    5.78763e-05   8.9284e-05    5.61775e-05   1.60669e-05      30     
end_path:out1:RootOutput                               8.344e-06    1.3691e-05    2.9911e-05    1.16455e-05   4.92048e-06      30     
end_path:michelremoving:michelremoving                 0.0282473     0.116245      0.355996      0.0672271     0.0931151       30     
end_path:michelremovinggnocchi:michelremoving          0.0328903     0.110479       0.34553      0.0640176     0.0907373       30     
end_path:calibanagnocchi:CalibAnaTree                  0.395621      0.999635       3.0951       0.844277      0.600215        30     
end_path:out1:RootOutput(write)                        0.571315       1.1563        2.02682       1.07583      0.384363        30     
========================================================================================================================================

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

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