justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID263269.159@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_cccondorce02
Last heartbeat2024-09-26 11:11:53
From worker nodeHostnameccwcondor0636
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:00
Input fileshd-protodune:np04hd_raw_run029092_0417_dataflow1_datawriter_0_20240906T000233.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Outputting started 
Output files
Finished2024-09-26 11:11:53
Saved logsjustin-logs:263269.159-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: 29092 subRun: 1 event: 100345 at 26-Sep-2024 12:58:21 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 84090 traces, 84090 summary
Retagger: tagging trace set: gauss with 64213 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 64213 traces tagged "gauss"
FrameSaver: q=1.65077e+06 n=1254705 tag=gauss
wclsFrameSaver: saving 84090 traces tagged "wiener"
FrameSaver: q=1.77585e+06 n=1148289 tag=wiener
4119 hits in TPC 0
3616 hits in TPC 1
8942 hits in TPC 2
5829 hits in TPC 3
3740 hits in TPC 4
5191 hits in TPC 5
10706 hits in TPC 6
6772 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 332 XUs and 219 XVs -> 25 XUVs
C:0 T:2 41435 XUs and 41212 XVs -> 2039 XUVs
C:0 T:3 405 XUs and 495 XVs -> 14 XUVs
C:0 T:4 267 XUs and 301 XVs -> 9 XUVs
C:0 T:5 13775 XUs and 11789 XVs -> 308 XUVs
C:0 T:6 67114 XUs and 64892 XVs -> 2121 XUVs
C:0 T:7 4768 XUs and 5501 XVs -> 313 XUVs
4829 XUVs total
2447 collection wire objects
4829 potential space points
Neighbour search...
301469 tests to find 103214 neighbours
Iterating with no regularization...
Begin: 3.38032e+07
0 2.9087e+07
1 2.87801e+07
2 2.87637e+07
Now with regularization...
Begin: 2.71593e+07
0 2.71498e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLArTPCIntercepts - inconsistent number of intercepts between a line and the LArTPC
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  27   event 100345 TrkackID 1 startx, y, z -54.798 510.409 8.7904  wireno, peakt tpcno 405 1764.43 6 dist 0min T, max_T1563.5 5854.83
no of hits closeby  0   event 100345 TrkackID 20 startx, y, z 66.34 405.917 26.6558  wireno, peakt tpcno 221 798.38 2 dist 150.407min T, max_T5 841.443
no of hits closeby  27   event 100345 TrkackID 1 startx, y, z -54.798 510.409 8.7904  wireno, peakt tpcno 405 1764.43 6 dist 0min T, max_T1563.5 5854.83
no of hits closeby  0   event 100345 TrkackID 20 startx, y, z 66.34 405.917 26.6558  wireno, peakt tpcno 221 798.38 2 dist 150.407min T, max_T5 841.443
Begin processing the 30th record. run: 29092 subRun: 1 event: 100353 at 26-Sep-2024 13:00:22 CEST
RawFrameSource: got 10240 raw::RawDigit objects
	input nticks=5859 keeping as is
Retagger: tagging trace set: wiener with 93128 traces, 93128 summary
Retagger: tagging trace set: gauss with 67640 traces, 0 summary
wclsFrameSaver saving cooked to 5859 ticks
wclsFrameSaver: saving 67640 traces tagged "gauss"
FrameSaver: q=1.51584e+06 n=1238502 tag=gauss
wclsFrameSaver: saving 93128 traces tagged "wiener"
FrameSaver: q=1.64123e+06 n=1120897 tag=wiener
5269 hits in TPC 0
4189 hits in TPC 1
9655 hits in TPC 2
5899 hits in TPC 3
3585 hits in TPC 4
7215 hits in TPC 5
7275 hits in TPC 6
5131 hits in TPC 7
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:1 4284 XUs and 4364 XVs -> 451 XUVs
C:0 T:2 72277 XUs and 87303 XVs -> 4097 XUVs
C:0 T:3 403 XUs and 504 XVs -> 17 XUVs
C:0 T:4 186 XUs and 238 XVs -> 9 XUVs
C:0 T:5 18150 XUs and 20627 XVs -> 1097 XUVs
C:0 T:6 26763 XUs and 48597 XVs -> 1474 XUVs
C:0 T:7 1942 XUs and 3625 XVs -> 61 XUVs
7206 XUVs total
2784 collection wire objects
7206 potential space points
Neighbour search...
609392 tests to find 285178 neighbours
Iterating with no regularization...
Begin: 2.5141e+07
0 2.37807e+07
1 2.37027e+07
2 2.3694e+07
Now with regularization...
Begin: 2.25606e+07
0 2.25576e+07
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
---INTERPRETED-MATCHING-OUTPUT------------------------------------------------------------------
---SUMMARY--------------------------------------------------------------------------------------
------------------------------------------------------------------------------------------------

no of hits closeby  0   event 100353 TrkackID 5 startx, y, z 139.426 592.016 306.256  wireno, peakt tpcno 244 0.504107 5 dist 0min T, max_T0.504107 1691.55
no of hits closeby  0   event 100353 TrkackID 5 startx, y, z 139.426 592.016 306.256  wireno, peakt tpcno 244 0.504107 5 dist 0min T, max_T0.504107 1691.55
26-Sep-2024 13:02:25 CEST  Closed output file "np04hd_raw_run029092_0417_dataflow1_datawriter_0_20240906T000233_reco_stage1_reco_stage2_20240926T110225_keepup.root"
26-Sep-2024 13:02:26 CEST  Closed input file "np04hd_raw_run029092_0417_dataflow1_datawriter_0_20240906T000233_reco_stage1.root"

========================================================================================================================================
TimeTracker printout (sec)                                Min           Avg           Max         Median          RMS         nEvts   
========================================================================================================================================
Full event                                              75.8376       105.353       156.754       98.2042       24.5998        30     
----------------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                                0.00074302    0.00297563    0.00530928    0.00296616    0.00121465       30     
produce:fembfilter:PDHDFEMBFilter                      0.517465       1.24118       8.01346      0.793379       1.66105        30     
produce:wclsdatahd:WireCellToolkit                      33.7282       40.6485       48.048        41.0686       4.38232        30     
produce:gaushit:GausHitFinder                          0.380544      0.707239       1.15454      0.726565       0.20147        30     
produce:nhitsfilter:NumberOfHitsFilter                0.000247162   0.000370792   0.000727131   0.00034954    9.85493e-05      30     
produce:reco3d:SpacePointSolver                        0.197968       1.43204       3.50536       1.36838       1.03752        30     
produce:hitpdune:DisambigFromSpacePoints               0.141882      0.599858       1.55591      0.535173      0.333053        30     
produce:pandora:StandardPandora                         5.38143       14.9528       45.7084       12.696        8.44733        30     
produce:pandoraWriter:StandardPandora                  0.0893266      0.16288      0.276499      0.152411      0.0445619       30     
produce:pandoraTrack:LArPandoraTrackCreation           0.777078       2.08103       3.82275       1.89448      0.819052        30     
produce:pandoraShower:LArPandoraShowerCreation         0.880322       2.77358       6.82161       2.30213       1.65616        30     
produce:pandoracalo:Calorimetry                        0.404372      0.994388       2.03267      0.817256      0.432753        30     
produce:pandoracalonosce:Calorimetry                   0.398618      0.959444       1.82022      0.890263      0.375227        30     
produce:pandorapid:Chi2ParticleID                     0.00122485    0.00223594    0.00467566    0.00226646    0.00064198       30     
produce:pandoraShowercalo:ShowerCalorimetry            0.600588       2.32759       5.78141       1.82239       1.29008        30     
produce:pandoraShowercalonosce:ShowerCalorimetry       0.729333       2.19915       5.24388       1.74528       1.16163        30     
produce:pandoraGnocchiCalo:GnocchiCalorimetry          0.0814545     0.155269      0.244448      0.149089      0.0439085       30     
produce:pandoraGnocchiCalonosce:GnocchiCalorimetry     0.0283962     0.0544476     0.0827339     0.0551698     0.0142224       30     
produce:emtrkmichelid:EmTrackMichelId                   22.4336       32.1491       47.787        31.3571       7.40769        30     
[art]:TriggerResults:TriggerResultInserter            3.5517e-05    6.61729e-05   9.5288e-05    6.89735e-05   1.59214e-05      30     
end_path:out1:RootOutput                              1.1351e-05    2.09185e-05   4.3862e-05    1.9972e-05    7.58213e-06      30     
end_path:michelremoving:michelremoving                 0.0221002     0.105399       0.33349      0.0575291     0.0885318       30     
end_path:michelremovinggnocchi:michelremoving          0.0181546     0.102571      0.362607      0.0592784     0.088364        30     
end_path:calibanagnocchi:CalibAnaTree                  0.250916      0.679923       1.20616       0.57858       0.25502        30     
end_path:out1:RootOutput(write)                         0.48683      0.790335       1.21743      0.790861      0.186478        30     
========================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4927.94 MB
  Peak resident set size usage (VmHWM): 2331.36 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np04hd_raw_run029092_0417_dataflow1_datawriter_0_20240906T000233_reco_stage1_reco_stage2_20240926T110225_keepup.root
\tHists: np04hd_raw_run029092_0417_dataflow1_datawriter_0_20240906T000233_reco_stage1_reco_stage2_20240926T110225_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 06:04:23 UTC       justIN version: 01.01.09