justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263765.12@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 13:24:52
SiteUK_Durham
EntryDUNE_UK_SGridDurham_ce3
Last heartbeat2024-09-26 15:03:39
From worker nodeHostnamen172.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:26:30
Input fileshd-protodune:np04hd_raw_run029107_1982_dataflow6_datawriter_0_20240908T000357.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 15:03:39
Saved logsjustin-logs:263765.12-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 29th record. run: 29107 subRun: 1 event: 475982 at 26-Sep-2024 15:59:45 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 66738 traces, 66738 summary
Retagger: tagging trace set: gauss with 49268 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 49268 traces tagged "gauss"
FrameSaver: q=1.04992e+06 n=933247 tag=gauss
wclsFrameSaver: saving 66738 traces tagged "wiener"
FrameSaver: q=1.13723e+06 n=849095 tag=wiener
3784 hits in TPC 0
3721 hits in TPC 1
8044 hits in TPC 2
3489 hits in TPC 3
4325 hits in TPC 4
7827 hits in TPC 5
4639 hits in TPC 6
1387 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 32 XUs and 21 XVs -> 1 XUVs
C:0 T:2 23837 XUs and 71617 XVs -> 1074 XUVs
C:0 T:3 201 XUs and 375 XVs -> 30 XUVs
C:0 T:4 507 XUs and 430 XVs -> 5 XUVs
C:0 T:5 61284 XUs and 37329 XVs -> 511 XUVs
C:0 T:6 25907 XUs and 57403 XVs -> 3032 XUVs
C:0 T:7 9 XUs and 23 XVs -> 1 XUVs
4654 XUVs total
1214 collection wire objects
4654 potential space points
Neighbour search...
398050 tests to find 160728 neighbours
Iterating with no regularization...
Begin: 6.99708e+06
0 6.36419e+06
1 6.33243e+06
2 6.32744e+06
Now with regularization...
Begin: 6.05796e+06
0 6.05544e+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 475982 TrkackID 7 startx, y, z 48.8271 228.554 16.8677  wireno, peakt tpcno 210 3053.11 2 dist 218.468min T, max_T2048.92 3080.24
no of hits closeby  0   event 475982 TrkackID 7 startx, y, z 48.8271 228.554 16.8677  wireno, peakt tpcno 210 3053.11 2 dist 218.468min T, max_T2048.92 3080.24
Begin processing the 30th record. run: 29107 subRun: 1 event: 475990 at 26-Sep-2024 16:01:39 BST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 75352 traces, 75352 summary
Retagger: tagging trace set: gauss with 57191 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 57191 traces tagged "gauss"
FrameSaver: q=1.24294e+06 n=1045101 tag=gauss
wclsFrameSaver: saving 75352 traces tagged "wiener"
FrameSaver: q=1.32207e+06 n=938891 tag=wiener
1651 hits in TPC 0
2931 hits in TPC 1
6447 hits in TPC 2
3721 hits in TPC 3
6075 hits in TPC 4
9307 hits in TPC 5
5719 hits in TPC 6
2982 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 214 XUs and 121 XVs -> 1 XUVs
C:0 T:2 16193 XUs and 33202 XVs -> 462 XUVs
C:0 T:3 98 XUs and 146 XVs -> 4 XUVs
C:0 T:4 419 XUs and 190 XVs -> 4 XUVs
C:0 T:5 52075 XUs and 32228 XVs -> 857 XUVs
C:0 T:6 12450 XUs and 14485 XVs -> 501 XUVs
C:0 T:7 172 XUs and 136 XVs -> 7 XUVs
1836 XUVs total
1109 collection wire objects
1836 potential space points
Neighbour search...
31724 tests to find 19514 neighbours
Iterating with no regularization...
Begin: 7.09104e+06
0 6.56911e+06
1 6.53619e+06
2 6.53032e+06
Now with regularization...
Begin: 6.18321e+06
0 6.18069e+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
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  0   event 475990 TrkackID 7 startx, y, z 343.898 559.349 69.8634  wireno, peakt tpcno 20 5132.07 6 dist 94.0678min T, max_T781 5132.07
no of hits closeby  0   event 475990 TrkackID 7 startx, y, z 343.898 559.349 69.8634  wireno, peakt tpcno 20 5132.07 6 dist 94.0678min T, max_T781 5132.07
26-Sep-2024 16:03:15 BST  Closed output file "np04hd_raw_run029107_1982_dataflow6_datawriter_0_20240908T000357_reco_stage1_reco_stage2_20240926T150315_keepup.root"
26-Sep-2024 16:03:15 BST  Closed input file "np04hd_raw_run029107_1982_dataflow6_datawriter_0_20240908T000357_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              63.0421       100.001       162.687       96.1847       19.8984        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000300649   0.00117209    0.00362983     0.0010161    0.000848863      30     
produce:fembfilter:PDHDFEMBFilter                      0.711034      0.820273       1.15266      0.791664      0.101226        30     
produce:wclsdatahd:WireCellToolkit                      44.136        48.5913       55.4539       47.7748       3.22773        30     
produce:gaushit:GausHitFinder                          0.368627      0.813865       1.26789      0.810279      0.204075        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000242994   0.000374183   0.000536971   0.000366983   7.21269e-05      30     
produce:reco3d:SpacePointSolver                        0.0747815      1.12947       3.79534      0.846917      0.889096        30     
produce:hitpdune:DisambigFromSpacePoints               0.120785      0.609882       1.41544      0.548308      0.301556        30     
produce:pandora:StandardPandora                         3.74337       14.1301       36.7711       12.5807       6.73834        30     
produce:pandoraWriter:StandardPandora                  0.0929858     0.171608      0.256221      0.169882      0.0377315       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.641106       2.33569       5.78653       2.02931       1.16835        30     
produce:pandoraShower:LArPandoraShowerCreation         0.382601       2.68137       6.22079       2.41564       1.42268        30     
produce:pandoracalo:Calorimetry                        0.292233       0.96408       2.49083       0.83712      0.442905        30     
produce:pandoracalonosce:Calorimetry                   0.256948      0.890653       2.30726      0.779026      0.417218        30     
produce:pandorapid:Chi2ParticleID                     0.00122418    0.00196064    0.00285495    0.00191236    0.000376533      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.290469       2.34748       5.74187       2.22024       1.23487        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.282296       2.32559       5.59392       2.06852       1.27186        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0684443     0.138705      0.256799      0.138985      0.0416677       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.027077      0.0561675     0.105391      0.0562144     0.0162265       30     
produce:emtrkmichelid:EmTrackMichelId                   10.1851       20.0146       31.6219       19.4704       4.85055        30     
[art]:TriggerResults:TriggerResultInserter            2.4708e-05    4.20269e-05   0.000134082   3.84705e-05   1.81071e-05      30     
end_path:out1:RootOutput                               7.579e-06    1.20921e-05    4.083e-05    1.03495e-05   5.85607e-06      30     
end_path:michelremoving:michelremoving                 0.0203747     0.0910115      0.31324      0.0603221     0.0778528       30     
end_path:michelremovinggnocchi:michelremoving          0.0199735     0.0860369     0.300893      0.0532764     0.0753513       30     
end_path:calibanagnocchi:CalibAnaTree                  0.186698      0.739636       1.84064      0.719365      0.349264        30     
end_path:out1:RootOutput(write)                        0.422645      0.870681       1.55799      0.834311      0.244661        30     
========================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5846.59 MB
  Peak resident set size usage (VmHWM): 2927.49 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run029107_1982_dataflow6_datawriter_0_20240908T000357_reco_stage1_reco_stage2_20240926T150315_keepup.root
\tHists: np04hd_raw_run029107_1982_dataflow6_datawriter_0_20240908T000357_reco_stage1_reco_stage2_20240926T150315_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-11-17 03:15:39 UTC       justIN version: 01.01.09