justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID78491.120@justin-prod-sched02.dune.hep.ac.uk
Workflow ID3447
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:11:15
SiteIT_CNAF
EntryCMSHTPC_T1_IT_CNAF_condor_ce02
Last heartbeat2024-09-26 12:21:09
From worker nodeHostnamecn-leo-145.cr.cnaf.infn.it
cpuinfoIntel(R) Xeon(R) Platinum 8480+
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4193255424 (3999 MiB)
Wall seconds limit216000 (60 hours)
Inner Apptainer?True
Job stateoutputting_failed
Allocator namejustin-allocator-pro.dune.hep.ac.uk
Started2024-09-26 10:43:22
Input fileshd-protodune:np04hd_raw_run029092_0400_dataflow6_datawriter_0_20240905T234037.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 12:21:09
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

557 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 4800 XUs and 3988 XVs -> 59 XUVs
C:0 T:2 20109 XUs and 16211 XVs -> 1099 XUVs
C:0 T:3 300 XUs and 332 XVs -> 7 XUVs
C:0 T:4 46 XUs and 74 XVs -> 1 XUVs
C:0 T:5 10918 XUs and 7537 XVs -> 302 XUVs
C:0 T:6 10689 XUs and 7195 XVs -> 261 XUVs
C:0 T:7 348 XUs and 404 XVs -> 5 XUVs
1734 XUVs total
738 collection wire objects
1734 potential space points
Neighbour search...
52794 tests to find 33272 neighbours
Iterating with no regularization...
Begin: 5.1369e+06
0 4.9078e+06
1 4.88606e+06
2 4.88178e+06
Now with regularization...
Begin: 4.5918e+06
0 4.59032e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 29th record. run: 29092 subRun: 1 event: 96254 at 26-Sep-2024 13:50:20 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 49494 traces, 49494 summary
Retagger: tagging trace set: gauss with 35898 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 35898 traces tagged "gauss"
FrameSaver: q=711461 n=638957 tag=gauss
wclsFrameSaver: saving 49494 traces tagged "wiener"
FrameSaver: q=766864 n=570472 tag=wiener
1969 hits in TPC 0
2451 hits in TPC 1
5424 hits in TPC 2
2938 hits in TPC 3
2385 hits in TPC 4
4068 hits in TPC 5
4581 hits in TPC 6
1317 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 6297 XUs and 5691 XVs -> 25 XUVs
C:0 T:2 23148 XUs and 29227 XVs -> 1206 XUVs
C:0 T:3 31 XUs and 43 XVs -> 2 XUVs
C:0 T:4 21 XUs and 24 XVs -> 2 XUVs
C:0 T:5 5707 XUs and 6976 XVs -> 684 XUVs
C:0 T:6 17672 XUs and 25857 XVs -> 1232 XUVs
C:0 T:7 16 XUs and 12 XVs -> 0 XUVs
3151 XUVs total
1116 collection wire objects
3151 potential space points
Neighbour search...
206679 tests to find 106334 neighbours
Iterating with no regularization...
Begin: 6.69757e+06
0 6.46458e+06
1 6.44464e+06
2 6.44203e+06
Now with regularization...
Begin: 6.05726e+06
0 6.05601e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 30th record. run: 29092 subRun: 1 event: 96262 at 26-Sep-2024 13:51:46 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 63269 traces, 63269 summary
Retagger: tagging trace set: gauss with 45175 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 45175 traces tagged "gauss"
FrameSaver: q=1.0359e+06 n=794783 tag=gauss
wclsFrameSaver: saving 63269 traces tagged "wiener"
FrameSaver: q=1.11248e+06 n=705693 tag=wiener
2377 hits in TPC 0
2027 hits in TPC 1
4389 hits in TPC 2
3380 hits in TPC 3
3862 hits in TPC 4
6598 hits in TPC 5
4662 hits in TPC 6
2506 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 81 XUs and 122 XVs -> 8 XUVs
C:0 T:2 13755 XUs and 12563 XVs -> 341 XUVs
C:0 T:3 15 XUs and 20 XVs -> 1 XUVs
C:0 T:4 345 XUs and 282 XVs -> 28 XUVs
C:0 T:5 20514 XUs and 22845 XVs -> 2143 XUVs
C:0 T:6 10329 XUs and 13329 XVs -> 401 XUVs
C:0 T:7 4 XUs and 6 XVs -> 0 XUVs
2922 XUVs total
1030 collection wire objects
2922 potential space points
Neighbour search...
124674 tests to find 77862 neighbours
Iterating with no regularization...
Begin: 7.24393e+06
0 6.69126e+06
1 6.64313e+06
2 6.634e+06
3 6.63098e+06
Now with regularization...
Begin: 6.11556e+06
0 6.11365e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLArTPCIntercepts - inconsistent number of intercepts between a line and the LArTPC
BdtBeamParticleIdTool::SliceFeatures::GetLArTPCIntercepts - inconsistent number of intercepts between a line and the LArTPC
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

26-Sep-2024 13:53:19 CEST  Closed output file "np04hd_raw_run029092_0400_dataflow6_datawriter_0_20240905T234037_reco_stage1_reco_stage2_20240926T115319_keepup.root"
26-Sep-2024 13:53:19 CEST  Closed input file "np04hd_raw_run029092_0400_dataflow6_datawriter_0_20240905T234037_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              78.6306       99.6072       123.055       97.6994       11.3398        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000544615   0.00190729    0.00522766    0.00137647    0.00132743       30     
produce:fembfilter:PDHDFEMBFilter                      0.691213      0.724795      0.759831      0.726577      0.018789        30     
produce:wclsdatahd:WireCellToolkit                      55.942        60.6071       64.4284       60.6119       1.87985        30     
produce:gaushit:GausHitFinder                          0.356008      0.542617      0.804684      0.540185      0.114428        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000343236   0.000503216   0.000805943   0.000491696   0.000102158      30     
produce:reco3d:SpacePointSolver                         0.16811      0.921872       2.16845      0.696182       0.59053        30     
produce:hitpdune:DisambigFromSpacePoints               0.135026      0.321126       0.59324      0.306978      0.109537        30     
produce:pandora:StandardPandora                         4.57327       9.20032       15.1189       8.73665       3.07767        30     
produce:pandoraWriter:StandardPandora                  0.0885033     0.154387       0.22504      0.147136      0.0320585       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.965982       2.07532       3.67708       1.94436      0.759239        30     
produce:pandoraShower:LArPandoraShowerCreation          0.73627       2.65411       6.01472       2.34994       1.2174         30     
produce:pandoracalo:Calorimetry                        0.400397      0.843157       1.37484      0.806259      0.261106        30     
produce:pandoracalonosce:Calorimetry                   0.368413      0.791382       1.36699      0.753341      0.251473        30     
produce:pandorapid:Chi2ParticleID                     0.00107916     0.0016748    0.00460121    0.00150423    0.000660095      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.519201       2.08164       4.45406       1.93894      0.881403        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.487597       2.05373       4.35911       1.82201      0.879015        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0667625     0.0974008     0.134218      0.0970931     0.0172062       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0273146     0.0433849     0.0688739     0.0413397    0.00965093       30     
produce:emtrkmichelid:EmTrackMichelId                   9.83601       14.7811       25.052        14.4315       3.18261        30     
[art]:TriggerResults:TriggerResultInserter            4.4083e-05    6.79102e-05   0.000103453   6.67725e-05   1.54129e-05      30     
end_path:out1:RootOutput                              1.1791e-05    1.67108e-05   2.9043e-05    1.59665e-05   3.52571e-06      30     
end_path:michelremoving:michelremoving                 0.0244844     0.0704053     0.222653      0.0551562     0.0421572       30     
end_path:michelremovinggnocchi:michelremoving          0.020751      0.0682471     0.214496       0.0539       0.0415734       30     
end_path:calibanagnocchi:CalibAnaTree                  0.263187       0.60991       1.15267      0.560497      0.209352        30     
end_path:out1:RootOutput(write)                        0.454867      0.618588      0.848469      0.589997      0.115532        30     
========================================================================================================================================

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

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