justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID78830.69@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 13:07:16
SiteUK_Durham
EntryDUNE_UK_SGridDurham_ce4
Last heartbeat2024-09-26 14:45:01
From worker nodeHostnamen204.dur.scotgrid.ac.uk
cpuinfoIntel(R) Xeon(R) Gold 5220 CPU @ 2.20GHz
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 13:08:20
Input fileshd-protodune:np04hd_raw_run029107_0752_dataflow7_datawriter_0_20240907T031834.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 14:45:01
Saved logsjustin-logs:78830.69-justin-prod-sched02.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

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

no of hits closeby  12   event 180719 TrkackID 0 startx, y, z 332.934 608.891 143.279  wireno, peakt tpcno 461 2278.94 5 dist 0min T, max_T150.101 4400.62
no of hits closeby  0   event 180719 TrkackID 16 startx, y, z -365.583 579.343 392.241  wireno, peakt tpcno 182 5854.58 5 dist 0.917679min T, max_T5058 5855.17
no of hits closeby  12   event 180719 TrkackID 0 startx, y, z 332.934 608.891 143.279  wireno, peakt tpcno 461 2278.94 5 dist 0min T, max_T150.101 4400.62
no of hits closeby  0   event 180719 TrkackID 16 startx, y, z -365.583 579.343 392.241  wireno, peakt tpcno 182 5854.58 5 dist 0.917679min T, max_T5058 5855.17
Begin processing the 29th record. run: 29107 subRun: 1 event: 180727 at 26-Sep-2024 15:42:09 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 56252 traces, 56252 summary
Retagger: tagging trace set: gauss with 41565 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 41565 traces tagged "gauss"
FrameSaver: q=933554 n=748190 tag=gauss
wclsFrameSaver: saving 56252 traces tagged "wiener"
FrameSaver: q=1.01381e+06 n=681037 tag=wiener
1557 hits in TPC 0
2805 hits in TPC 1
3182 hits in TPC 2
2156 hits in TPC 3
4868 hits in TPC 4
6223 hits in TPC 5
5816 hits in TPC 6
2709 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 175 XUs and 88 XVs -> 21 XUVs
C:0 T:2 8601 XUs and 19439 XVs -> 903 XUVs
C:0 T:3 16 XUs and 32 XVs -> 0 XUVs
C:0 T:4 166 XUs and 189 XVs -> 3 XUVs
C:0 T:5 29004 XUs and 35295 XVs -> 1180 XUVs
C:0 T:6 9934 XUs and 14433 XVs -> 1190 XUVs
C:0 T:7 175 XUs and 246 XVs -> 8 XUVs
3305 XUVs total
1624 collection wire objects
3305 potential space points
Neighbour search...
99131 tests to find 48618 neighbours
Iterating with no regularization...
Begin: 9.58778e+06
0 8.63423e+06
1 8.58464e+06
2 8.57909e+06
Now with regularization...
Begin: 7.84436e+06
0 7.84213e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  0   event 180727 TrkackID 4 startx, y, z 16.8045 592.782 354.201  wireno, peakt tpcno 97 8.89023 5 dist 0min T, max_T5.4043 1416.16
no of hits closeby  0   event 180727 TrkackID 6 startx, y, z 151.076 147.298 459.703  wireno, peakt tpcno 326 2996.75 5 dist 0min T, max_T2550.05 4458.5
no of hits closeby  0   event 180727 TrkackID 4 startx, y, z 16.8045 592.782 354.201  wireno, peakt tpcno 97 8.89023 5 dist 0min T, max_T5.4043 1416.16
no of hits closeby  0   event 180727 TrkackID 6 startx, y, z 151.076 147.298 459.703  wireno, peakt tpcno 326 2996.75 5 dist 0min T, max_T2550.05 4458.5
Begin processing the 30th record. run: 29107 subRun: 1 event: 180735 at 26-Sep-2024 15:43:30 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 26792 traces, 26792 summary
Retagger: tagging trace set: gauss with 19632 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 19632 traces tagged "gauss"
FrameSaver: q=439516 n=372352 tag=gauss
wclsFrameSaver: saving 26792 traces tagged "wiener"
FrameSaver: q=475017 n=334550 tag=wiener
1628 hits in TPC 0
2167 hits in TPC 1
3264 hits in TPC 2
2387 hits in TPC 3
618 hits in TPC 4
590 hits in TPC 5
2995 hits in TPC 6
1897 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 2 XUs and 3 XVs -> 0 XUVs
C:0 T:2 14709 XUs and 29971 XVs -> 895 XUVs
C:0 T:3 9 XUs and 14 XVs -> 1 XUVs
C:0 T:4 85 XUs and 51 XVs -> 6 XUVs
C:0 T:5 237 XUs and 152 XVs -> 3 XUVs
C:0 T:6 17596 XUs and 44810 XVs -> 1307 XUVs
C:0 T:7 2 XUs and 1 XVs -> 0 XUVs
2212 XUVs total
764 collection wire objects
2212 potential space points
Neighbour search...
100902 tests to find 60014 neighbours
Iterating with no regularization...
Begin: 4.3532e+06
0 3.76099e+06
1 3.70944e+06
2 3.70126e+06
3 3.69888e+06
Now with regularization...
Begin: 3.51129e+06
0 3.50988e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

26-Sep-2024 15:44:36 BST  Closed output file "np04hd_raw_run029107_0752_dataflow7_datawriter_0_20240907T031834_reco_stage1_reco_stage2_20240926T144436_keepup.root"
26-Sep-2024 15:44:36 BST  Closed input file "np04hd_raw_run029107_0752_dataflow7_datawriter_0_20240907T031834_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              65.4632       88.2312       128.759       85.0953       13.8077        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000333508   0.000924548   0.00235968    0.000786615   0.000538484      30     
produce:fembfilter:PDHDFEMBFilter                      0.690834      0.706002      0.751237      0.704878      0.011292        30     
produce:wclsdatahd:WireCellToolkit                      42.079        44.3314       49.0245       44.3007       1.19374        30     
produce:gaushit:GausHitFinder                          0.321741      0.759473       1.09101      0.717041      0.178377        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000199687   0.000353566   0.000488188   0.000346997   6.90101e-05      30     
produce:reco3d:SpacePointSolver                        0.249849      0.880528       4.01563      0.541477      0.771011        30     
produce:hitpdune:DisambigFromSpacePoints               0.139667       0.58123       1.42047      0.489559      0.272047        30     
produce:pandora:StandardPandora                         6.66722       13.3093       30.0476       11.6115       5.46336        30     
produce:pandoraWriter:StandardPandora                  0.102336       0.15487       0.23573      0.155629      0.0310036       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.365716       1.49368       3.15676       1.39312       0.62482        30     
produce:pandoraShower:LArPandoraShowerCreation         0.442074       1.70577       4.00297       1.54967      0.826669        30     
produce:pandoracalo:Calorimetry                         0.19664      0.691665       1.29822      0.679881      0.247443        30     
produce:pandoracalonosce:Calorimetry                   0.181316      0.625446       1.21066      0.608431      0.225826        30     
produce:pandorapid:Chi2ParticleID                     0.000949875   0.00184599    0.00416755    0.00178856    0.000543671      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.336225       1.46421       3.05515       1.22416      0.684512        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.334204       1.45305       3.02915       1.20868      0.688383        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0422677     0.129755      0.206968      0.123916      0.0330051       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0218765     0.0497319     0.0753799     0.0498391     0.0114035       30     
produce:emtrkmichelid:EmTrackMichelId                   11.8288       18.2243       27.1144       18.484        3.4892         30     
[art]:TriggerResults:TriggerResultInserter            2.5121e-05    3.22416e-05   7.0125e-05    3.0312e-05    7.81786e-06      30     
end_path:out1:RootOutput                               6.172e-06    1.35486e-05   0.000177651   7.3955e-06    3.05742e-05      30     
end_path:michelremoving:michelremoving                 0.0173713     0.0946519     0.355718      0.040354      0.0906421       30     
end_path:michelremovinggnocchi:michelremoving          0.0162138     0.0918825     0.351635      0.0367804     0.0909547       30     
end_path:calibanagnocchi:CalibAnaTree                  0.132848      0.527326       1.11362      0.494241      0.211144        30     
end_path:out1:RootOutput(write)                        0.382109      0.805042       1.25794      0.775037      0.184655        30     
========================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5835.3 MB
  Peak resident set size usage (VmHWM): 2959.97 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run029107_0752_dataflow7_datawriter_0_20240907T031834_reco_stage1_reco_stage2_20240926T144436_keepup.root
\tHists: np04hd_raw_run029107_0752_dataflow7_datawriter_0_20240907T031834_reco_stage1_reco_stage2_20240926T144436_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 11:16:02 UTC       justIN version: 01.01.08