justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263269.187@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:51:00
SiteFR_CCIN2P3
EntryDUNE_FR_CCIN2P3_cccondorce01
Last heartbeat2024-09-26 11:05:23
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:52:55
Input fileshd-protodune:np04hd_raw_run029092_0447_dataflow7_datawriter_0_20240906T002649.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 11:05:23
Saved logsjustin-logs:263269.187-justin-prod-sched01.dune.hep.ac.uk.logs.tgz
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

oincidences...
C:0 T:1 1975 XUs and 3182 XVs -> 108 XUVs
C:0 T:2 90102 XUs and 107019 XVs -> 6327 XUVs
C:0 T:3 286 XUs and 263 XVs -> 0 XUVs
C:0 T:4 522 XUs and 890 XVs -> 55 XUVs
C:0 T:5 31326 XUs and 27504 XVs -> 1212 XUVs
C:0 T:6 54456 XUs and 72843 XVs -> 2000 XUVs
C:0 T:7 479 XUs and 435 XVs -> 15 XUVs
9717 XUVs total
2727 collection wire objects
9717 potential space points
Neighbour search...
796991 tests to find 428494 neighbours
Iterating with no regularization...
Begin: 3.48815e+07
0 2.99835e+07
1 2.96017e+07
2 2.95493e+07
3 2.95335e+07
Now with regularization...
Begin: 2.82366e+07
0 2.82241e+07
BdtBeamParticleIdTool::SliceFeatures::GetLArTPCIntercepts - inconsistent number of intercepts between a line and the LArTPC
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 29th record. run: 29092 subRun: 1 event: 107511 at 26-Sep-2024 12:53:38 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 50062 traces, 50062 summary
Retagger: tagging trace set: gauss with 38231 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 38231 traces tagged "gauss"
FrameSaver: q=782725 n=735672 tag=gauss
wclsFrameSaver: saving 50062 traces tagged "wiener"
FrameSaver: q=841549 n=672469 tag=wiener
3195 hits in TPC 0
3395 hits in TPC 1
5297 hits in TPC 2
3840 hits in TPC 3
1656 hits in TPC 4
3244 hits in TPC 5
4907 hits in TPC 6
2552 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 152 XUs and 158 XVs -> 7 XUVs
C:0 T:2 37800 XUs and 55497 XVs -> 2062 XUVs
C:0 T:3 38 XUs and 19 XVs -> 2 XUVs
C:0 T:4 61 XUs and 75 XVs -> 4 XUVs
C:0 T:5 13273 XUs and 14263 XVs -> 252 XUVs
C:0 T:6 42960 XUs and 53331 XVs -> 712 XUVs
C:0 T:7 144 XUs and 133 XVs -> 2 XUVs
3041 XUVs total
1122 collection wire objects
3041 potential space points
Neighbour search...
229475 tests to find 120274 neighbours
Iterating with no regularization...
Begin: 3.49143e+06
0 3.21136e+06
1 3.19412e+06
2 3.19167e+06
Now with regularization...
Begin: 3.0074e+06
0 3.00649e+06
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

Begin processing the 30th record. run: 29092 subRun: 1 event: 107519 at 26-Sep-2024 12:54:48 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 51993 traces, 51993 summary
Retagger: tagging trace set: gauss with 39340 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 39340 traces tagged "gauss"
FrameSaver: q=893304 n=816063 tag=gauss
wclsFrameSaver: saving 51993 traces tagged "wiener"
FrameSaver: q=982224 n=757693 tag=wiener
4714 hits in TPC 0
5354 hits in TPC 1
1937 hits in TPC 2
1485 hits in TPC 3
3390 hits in TPC 4
5609 hits in TPC 5
6005 hits in TPC 6
3800 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 1624 XUs and 1166 XVs -> 170 XUVs
C:0 T:2 4237 XUs and 2329 XVs -> 139 XUVs
C:0 T:3 15 XUs and 18 XVs -> 1 XUVs
C:0 T:4 377 XUs and 401 XVs -> 24 XUVs
C:0 T:5 21904 XUs and 23790 XVs -> 585 XUVs
C:0 T:6 38841 XUs and 17105 XVs -> 1946 XUVs
C:0 T:7 209 XUs and 325 XVs -> 10 XUVs
2875 XUVs total
1016 collection wire objects
2875 potential space points
Neighbour search...
265855 tests to find 128588 neighbours
Iterating with no regularization...
Begin: 7.045e+06
0 6.40272e+06
1 6.34981e+06
2 6.34028e+06
3 6.3373e+06
Now with regularization...
Begin: 6.05919e+06
0 6.05739e+06
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  0   event 107519 TrkackID 0 startx, y, z 133.132 585.17 431.955  wireno, peakt tpcno 147 4681.58 6 dist 437.047min T, max_T2350 4709.5
no of hits closeby  0   event 107519 TrkackID 0 startx, y, z 133.132 585.17 431.955  wireno, peakt tpcno 147 4681.58 6 dist 437.047min T, max_T2350 4709.5
26-Sep-2024 12:56:13 CEST  Closed output file "np04hd_raw_run029092_0447_dataflow7_datawriter_0_20240906T002649_reco_stage1_reco_stage2_20240926T105613_keepup.root"
26-Sep-2024 12:56:13 CEST  Closed input file "np04hd_raw_run029092_0447_dataflow7_datawriter_0_20240906T002649_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              65.6853       90.3008       161.599       84.6316       22.3854        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.000602098   0.00331481     0.010131     0.00317869    0.00180596       30     
produce:fembfilter:PDHDFEMBFilter                      0.565404      0.699003      0.895946      0.702213      0.0757514       30     
produce:wclsdatahd:WireCellToolkit                      32.0015       38.4976       46.4287       38.4813       2.83868        30     
produce:gaushit:GausHitFinder                          0.456722      0.739811       1.30835       0.73532      0.196803        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000225012   0.000352163   0.000730069   0.000334181   0.000112586      30     
produce:reco3d:SpacePointSolver                        0.274371       1.11962       3.18235      0.857377      0.896563        30     
produce:hitpdune:DisambigFromSpacePoints               0.139729      0.550345       2.13307      0.424038      0.394848        30     
produce:pandora:StandardPandora                         5.17453       12.2311       33.378        11.145        6.06663        30     
produce:pandoraWriter:StandardPandora                  0.0787999     0.158543      0.279959      0.151737      0.0504872       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.627259       1.8593        7.58062       1.45991       1.31486        30     
produce:pandoraShower:LArPandoraShowerCreation         0.664249       2.39265       7.93136       1.58092       1.75295        30     
produce:pandoracalo:Calorimetry                        0.303908      0.889664       3.04696       0.73067      0.530549        30     
produce:pandoracalonosce:Calorimetry                   0.270195      0.858246       3.09259      0.700201      0.574025        30     
produce:pandorapid:Chi2ParticleID                     0.00104837     0.0021047    0.00540016    0.00200525    0.000833665      30     
produce:pandoraShowercalo:ShowerCalorimetry            0.554646       2.07789       8.5332        1.4688        1.61904        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.552874       2.05058       7.94654       1.3526        1.56569        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0663122     0.147654       0.27541      0.132582      0.0614372       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0227174     0.0515469     0.100816      0.0427635     0.0218913       30     
produce:emtrkmichelid:EmTrackMichelId                   15.2139       24.2438       38.8197       23.8976       6.18831        30     
[art]:TriggerResults:TriggerResultInserter            4.4574e-05    7.1803e-05    0.000157035   6.58835e-05   1.98597e-05      30     
end_path:out1:RootOutput                               1.085e-05    2.19925e-05   6.0904e-05    2.2036e-05    8.32247e-06      30     
end_path:michelremoving:michelremoving                 0.0187469     0.0728761     0.265947      0.0494294     0.0646129       30     
end_path:michelremovinggnocchi:michelremoving          0.019612      0.0686932     0.313576      0.0475421     0.0692521       30     
end_path:calibanagnocchi:CalibAnaTree                  0.230456      0.643266       1.86384      0.561749      0.344129        30     
end_path:out1:RootOutput(write)                        0.473605      0.721212       1.23277       0.66242      0.195237        30     
========================================================================================================================================

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

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