justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263270.148@justin-prod-sched01.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 09:52:09
SiteFR_CCIN2P3
EntryDUNE_FR_CCIN2P3_cccondorce01
Last heartbeat2024-09-26 11:09:46
From worker nodeHostnameccwcondor0577
cpuinfoAMD EPYC 7453 28-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4193255424 (3999 MiB)
Wall seconds limit106200 (29 hours)
Inner Apptainer?True
Job statejobscript_error
Allocator namejustin-allocator-pro.dune.hep.ac.uk
Started2024-09-26 09:53:03
Input fileshd-protodune:np04hd_raw_run029081_0863_dataflow6_datawriter_0_20240905T062327.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 11:09:46
Saved logsjustin-logs:263270.148-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

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

Begin processing the 29th record. run: 29081 subRun: 1 event: 207350 at 26-Sep-2024 12:56:56 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 69193 traces, 69193 summary
Retagger: tagging trace set: gauss with 51207 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 51207 traces tagged "gauss"
FrameSaver: q=1.1838e+06 n=959418 tag=gauss
wclsFrameSaver: saving 69193 traces tagged "wiener"
FrameSaver: q=1.26026e+06 n=866283 tag=wiener
1991 hits in TPC 0
2068 hits in TPC 1
7861 hits in TPC 2
4671 hits in TPC 3
3392 hits in TPC 4
6258 hits in TPC 5
4906 hits in TPC 6
3595 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 292 XUs and 350 XVs -> 4 XUVs
C:0 T:2 32759 XUs and 26164 XVs -> 993 XUVs
C:0 T:3 293 XUs and 434 XVs -> 7 XUVs
C:0 T:4 128 XUs and 206 XVs -> 11 XUVs
C:0 T:5 34774 XUs and 30197 XVs -> 663 XUVs
C:0 T:6 11760 XUs and 12362 XVs -> 364 XUVs
C:0 T:7 288 XUs and 543 XVs -> 69 XUVs
2111 XUVs total
1221 collection wire objects
2111 potential space points
Neighbour search...
33909 tests to find 20790 neighbours
Iterating with no regularization...
Begin: 8.14586e+06
0 7.72562e+06
1 7.70824e+06
2 7.70617e+06
Now with regularization...
Begin: 7.1756e+06
0 7.17465e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  0   event 207350 TrkackID 6 startx, y, z 210.827 404.532 460.541  wireno, peakt tpcno 268 4705.58 5 dist 0min T, max_T3009.26 5675.23
no of hits closeby  0   event 207350 TrkackID 13 startx, y, z -198.237 174.112 454.231  wireno, peakt tpcno 297 2384.44 6 dist 0min T, max_T9.04773 2560
no of hits closeby  0   event 207350 TrkackID 6 startx, y, z 210.827 404.532 460.541  wireno, peakt tpcno 268 4705.58 5 dist 0min T, max_T3009.26 5675.23
no of hits closeby  0   event 207350 TrkackID 13 startx, y, z -198.237 174.112 454.231  wireno, peakt tpcno 297 2384.44 6 dist 0min T, max_T9.04773 2560
Begin processing the 30th record. run: 29081 subRun: 1 event: 207406 at 26-Sep-2024 12:58:15 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 43035 traces, 43035 summary
Retagger: tagging trace set: gauss with 32055 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 32055 traces tagged "gauss"
FrameSaver: q=612334 n=561330 tag=gauss
wclsFrameSaver: saving 43035 traces tagged "wiener"
FrameSaver: q=669065 n=506524 tag=wiener
4325 hits in TPC 0
2796 hits in TPC 1
4027 hits in TPC 2
1824 hits in TPC 3
2935 hits in TPC 4
5072 hits in TPC 5
627 hits in TPC 6
177 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 1008 XUs and 589 XVs -> 45 XUVs
C:0 T:2 14453 XUs and 13716 XVs -> 493 XUVs
C:0 T:3 42 XUs and 29 XVs -> 0 XUVs
C:0 T:4 189 XUs and 329 XVs -> 9 XUVs
C:0 T:5 13787 XUs and 15808 XVs -> 264 XUVs
C:0 T:6 242 XUs and 393 XVs -> 54 XUVs
C:0 T:7 26 XUs and 58 XVs -> 2 XUVs
867 XUVs total
478 collection wire objects
867 potential space points
Neighbour search...
12789 tests to find 7940 neighbours
Iterating with no regularization...
Begin: 2.94685e+06
0 2.82288e+06
1 2.81417e+06
2 2.81201e+06
Now with regularization...
Begin: 2.67957e+06
0 2.67845e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  0   event 207406 TrkackID 5 startx, y, z 32.5006 594.993 114.716  wireno, peakt tpcno 263 1007.35 2 dist 285.09min T, max_T4.85969 1080.33
no of hits closeby  0   event 207406 TrkackID 7 startx, y, z 184.062 604.701 77.6402  wireno, peakt tpcno 314 3491.51 2 dist 35.3109min T, max_T1182.01 3531.6
no of hits closeby  0   event 207406 TrkackID 5 startx, y, z 32.5006 594.993 114.716  wireno, peakt tpcno 263 1007.35 2 dist 285.09min T, max_T4.85969 1080.33
no of hits closeby  0   event 207406 TrkackID 7 startx, y, z 184.062 604.701 77.6402  wireno, peakt tpcno 314 3491.51 2 dist 35.3109min T, max_T1182.01 3531.6
26-Sep-2024 12:59:12 CEST  Closed output file "np04hd_raw_run029081_0863_dataflow6_datawriter_0_20240905T062327_reco_stage1_reco_stage2_20240926T105912_keepup.root"
26-Sep-2024 12:59:13 CEST  Closed input file "np04hd_raw_run029081_0863_dataflow6_datawriter_0_20240905T062327_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              57.0272       95.9482       142.04        91.1657       19.1032        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000524212   0.00296995     0.0052669    0.00295169    0.00121585       30     
produce:fembfilter:PDHDFEMBFilter                      0.504171       1.05479       8.41366      0.731074       1.41428        30     
produce:wclsdatahd:WireCellToolkit                      31.546        40.266        46.8563       40.4076       3.55635        30     
produce:gaushit:GausHitFinder                          0.286796      0.665468      0.967444      0.652134      0.176278        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000195807   0.000320734   0.000662702   0.000308018   8.34892e-05      30     
produce:reco3d:SpacePointSolver                        0.119878       1.1272        2.99991      0.802135       0.83199        30     
produce:hitpdune:DisambigFromSpacePoints                0.1126       0.481874      0.980969      0.463852       0.24465        30     
produce:pandora:StandardPandora                         3.66026        11.64        24.0931       10.6348       5.10785        30     
produce:pandoraWriter:StandardPandora                  0.0744997     0.149689      0.260227      0.147811      0.046241        30     
produce:pandoraTrack:LArPandoraTrackCreation           0.512907       1.83076       4.45146       1.63702      0.818438        30     
produce:pandoraShower:LArPandoraShowerCreation         0.366306       2.11936       6.02812       1.76334       1.16911        30     
produce:pandoracalo:Calorimetry                        0.243908      0.894333       2.25061      0.841297      0.391057        30     
produce:pandoracalonosce:Calorimetry                   0.217493      0.851286       2.20122      0.824315      0.369696        30     
produce:pandorapid:Chi2ParticleID                     0.00109664    0.00220574    0.00531801    0.00206563    0.000760139      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.336784       1.93331       4.80115       1.57262       1.11317        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.298751        1.777        4.17959       1.52059       1.01187        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0525536     0.138843      0.283104      0.140646      0.0507212       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0205188     0.0492769     0.0811799     0.0466343     0.0163994       30     
produce:emtrkmichelid:EmTrackMichelId                   15.8441       29.2507       44.6468       28.3055       7.19572        30     
[art]:TriggerResults:TriggerResultInserter            4.3091e-05    6.5288e-05    9.1742e-05    6.4596e-05    1.08593e-05      30     
end_path:out1:RootOutput                              1.2112e-05    1.98712e-05   7.1384e-05    1.77635e-05   1.08787e-05      30     
end_path:michelremoving:michelremoving                 0.0232527     0.100282      0.368206      0.0590049     0.0812414       30     
end_path:michelremovinggnocchi:michelremoving          0.0225133     0.0971227     0.320584      0.0504035     0.0785205       30     
end_path:calibanagnocchi:CalibAnaTree                  0.231704      0.602271       1.44982      0.538004      0.283565        30     
end_path:out1:RootOutput(write)                        0.353361      0.693457       1.00037      0.716224      0.178653        30     
========================================================================================================================================

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

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