justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID78788.3@justin-prod-sched02.dune.hep.ac.uk
Workflow ID3453
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 12:38:51
SiteES_PIC
EntryDUNE_T1_ES_PIC_ce14-multicore
Last heartbeat2024-09-26 14:03:10
From worker nodeHostnametds211.pic.es
cpuinfoIntel(R) Xeon(R) CPU E5-2640 v3 @ 2.60GHz
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 12:39:21
Input fileshd-protodune:np04hd_raw_run029107_3638_dataflow6_datawriter_0_20240909T043055.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 14:03:10
Saved logsjustin-logs:78788.3-justin-prod-sched02.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

TCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  0   event 873366 TrkackID 2 startx, y, z 38.1733 344.285 444.794  wireno, peakt tpcno 75 5 5 dist 0min T, max_T5 3631.12
no of hits closeby  0   event 873366 TrkackID 2 startx, y, z 38.1733 344.285 444.794  wireno, peakt tpcno 75 5 5 dist 0min T, max_T5 3631.12
Begin processing the 29th record. run: 29107 subRun: 1 event: 873374 at 26-Sep-2024 15:55:21 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 54716 traces, 54716 summary
Retagger: tagging trace set: gauss with 41629 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 41629 traces tagged "gauss"
FrameSaver: q=871344 n=784150 tag=gauss
wclsFrameSaver: saving 54716 traces tagged "wiener"
FrameSaver: q=929169 n=707833 tag=wiener
4171 hits in TPC 0
3060 hits in TPC 1
4145 hits in TPC 2
3633 hits in TPC 3
1627 hits in TPC 4
3314 hits in TPC 5
5338 hits in TPC 6
2612 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 1165 XUs and 1039 XVs -> 246 XUVs
C:0 T:2 17575 XUs and 14231 XVs -> 562 XUVs
C:0 T:3 248 XUs and 265 XVs -> 5 XUVs
C:0 T:4 33 XUs and 45 XVs -> 2 XUVs
C:0 T:5 6798 XUs and 4386 XVs -> 153 XUVs
C:0 T:6 11712 XUs and 13277 XVs -> 286 XUVs
C:0 T:7 129 XUs and 169 XVs -> 21 XUVs
1275 XUVs total
690 collection wire objects
1275 potential space points
Neighbour search...
26735 tests to find 16004 neighbours
Iterating with no regularization...
Begin: 3.44211e+06
0 3.22725e+06
1 3.21267e+06
2 3.21055e+06
Now with regularization...
Begin: 3.0156e+06
0 3.01512e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  0   event 873374 TrkackID 0 startx, y, z 73.4595 369.6 428.493  wireno, peakt tpcno 154 4151.2 6 dist 403.31min T, max_T2062.37 4171.72
no of hits closeby  1   event 873374 TrkackID 17 startx, y, z 21.0041 587.432 214.828  wireno, peakt tpcno 433 5509.36 2 dist 107.691min T, max_T5293.49 5570.94
no of hits closeby  0   event 873374 TrkackID 0 startx, y, z 73.4595 369.6 428.493  wireno, peakt tpcno 154 4151.2 6 dist 403.31min T, max_T2062.37 4171.72
no of hits closeby  1   event 873374 TrkackID 17 startx, y, z 21.0041 587.432 214.828  wireno, peakt tpcno 433 5509.36 2 dist 107.691min T, max_T5293.49 5570.94
Begin processing the 30th record. run: 29107 subRun: 1 event: 873382 at 26-Sep-2024 15:56:48 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 58903 traces, 58903 summary
Retagger: tagging trace set: gauss with 43033 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 43033 traces tagged "gauss"
FrameSaver: q=901109 n=778904 tag=gauss
wclsFrameSaver: saving 58903 traces tagged "wiener"
FrameSaver: q=1.0051e+06 n=711120 tag=wiener
3782 hits in TPC 0
2636 hits in TPC 1
4934 hits in TPC 2
3572 hits in TPC 3
1401 hits in TPC 4
2652 hits in TPC 5
6607 hits in TPC 6
4236 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 498 XUs and 509 XVs -> 21 XUVs
C:0 T:2 24974 XUs and 56649 XVs -> 2009 XUVs
C:0 T:3 38 XUs and 34 XVs -> 1 XUVs
C:0 T:4 68 XUs and 66 XVs -> 1 XUVs
C:0 T:5 11436 XUs and 54524 XVs -> 1707 XUVs
C:0 T:6 19694 XUs and 50012 XVs -> 852 XUVs
C:0 T:7 112 XUs and 187 XVs -> 1 XUVs
4592 XUVs total
1324 collection wire objects
4592 potential space points
Neighbour search...
302348 tests to find 145764 neighbours
Iterating with no regularization...
Begin: 2.0196e+07
0 1.81856e+07
1 1.80291e+07
2 1.80169e+07
Now with regularization...
Begin: 1.76546e+07
0 1.76504e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

26-Sep-2024 15:58:18 CEST  Closed output file "np04hd_raw_run029107_3638_dataflow6_datawriter_0_20240909T043055_reco_stage1_reco_stage2_20240926T135818_keepup.root"
26-Sep-2024 15:58:18 CEST  Closed input file "np04hd_raw_run029107_3638_dataflow6_datawriter_0_20240909T043055_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              61.6975       88.7756       123.055       86.5805       11.7745        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000432598   0.00201779    0.00305671    0.00212993    0.000730593      30     
produce:fembfilter:PDHDFEMBFilter                      0.564658      0.597281      0.644908      0.592962      0.0181297       30     
produce:wclsdatahd:WireCellToolkit                      40.4085       45.1101       54.0272       44.2193       3.42679        30     
produce:gaushit:GausHitFinder                          0.350201       0.68339      0.906916      0.668446      0.124314        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000180433   0.000351913   0.000648553   0.000350748   8.94283e-05      30     
produce:reco3d:SpacePointSolver                        0.136725       0.7076        1.74352      0.585226      0.420246        30     
produce:hitpdune:DisambigFromSpacePoints               0.121973      0.450521      0.764676      0.444114      0.161285        30     
produce:pandora:StandardPandora                         3.24118       11.278        21.8337       10.672        3.90652        30     
produce:pandoraWriter:StandardPandora                  0.0818408     0.133217      0.235678      0.125171      0.0295204       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.381299       1.27633       3.32275       1.14591      0.562908        30     
produce:pandoraShower:LArPandoraShowerCreation         0.287948       1.43015       5.22928       1.20021      0.866425        30     
produce:pandoracalo:Calorimetry                        0.224977      0.634679       1.42531      0.575899      0.225574        30     
produce:pandoracalonosce:Calorimetry                   0.195364      0.581313       1.4982        0.53972      0.245663        30     
produce:pandorapid:Chi2ParticleID                     0.00120414    0.00170496    0.00295583    0.00164355    0.000377941      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.226594       1.30229       4.46206       1.08752      0.741541        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.222757       1.26937       3.80243       1.08431       0.65918        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0713173     0.125348       0.20811      0.120562      0.0256414       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.025258      0.0445494     0.0773351     0.0437572    0.00974471       30     
produce:emtrkmichelid:EmTrackMichelId                   11.8295       21.7314       34.5805       21.288        4.07613        30     
[art]:TriggerResults:TriggerResultInserter            1.9257e-05    3.15045e-05   7.8217e-05    2.6409e-05    1.38189e-05      30     
end_path:out1:RootOutput                               7.439e-06    1.03102e-05   2.5202e-05    9.5795e-06    3.36282e-06      30     
end_path:michelremoving:michelremoving                 0.0222952     0.0955664     0.474571      0.0365753     0.104395        30     
end_path:michelremovinggnocchi:michelremoving          0.0214781     0.0916496     0.436574      0.0336769     0.0993512       30     
end_path:calibanagnocchi:CalibAnaTree                  0.179448      0.471534       1.15083      0.422668      0.188627        30     
end_path:out1:RootOutput(write)                        0.370042      0.674422       1.00656      0.665762      0.125231        30     
========================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4924.69 MB
  Peak resident set size usage (VmHWM): 2580.95 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run029107_3638_dataflow6_datawriter_0_20240909T043055_reco_stage1_reco_stage2_20240926T135818_keepup.root
\tHists: np04hd_raw_run029107_3638_dataflow6_datawriter_0_20240909T043055_reco_stage1_reco_stage2_20240926T135818_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:13:05 UTC       justIN version: 01.01.09