justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 32473.8@dunegpschedd02.fnal.gov

Jobsub ID32473.8@dunegpschedd02.fnal.gov
Workflow ID2135
Stage ID1
User nameykermaid@fnal.gov
HTCondor Groupgroup_dune.prod.mcsim
RequestedProcessors1
GPUNo
RSS bytes4193255424 (3999 MiB)
Wall seconds limit18000 (5 hours)
Submitted time2025-09-02 21:47:08
SiteUK_RAL-PPD
EntryCMSHTPC_T2_UK_SGrid_RALPP_hep206
Last heartbeat2025-09-02 23:12:39
From worker nodeHostnameheplnc153.pp.rl.ac.uk
cpuinfoAMD EPYC 7763 64-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4194304000 (4000 MiB)
Wall seconds limit257400 (71 hours)
GPU
Inner Apptainer?True
Job statejobscript_error
Started2025-09-02 21:50:45
Input filesvd-protodune:np02vd_raw_run039275_0447_df-s03-d2_dw_0_20250902T052648.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Max RSS bytes0 (0 MiB)
Outputting started 
Output files
Finished2025-09-02 23:12:39
Saved logsjustin-logs:32473.8-dunegpschedd02.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

trace sets:[ "gauss7":9037 [0] "mp2_roi7":8659 [0] "mp3_roi7":2946 [0] "wiener7":10955 [10955] ] cmm:[ ] output: frame: ident=161268 time=111 tick=500 with 208316 traces.  frame tags:[ "framefanin" ] 16 tagged trace sets:[ "gauss0":5968 [0] "gauss1":2714 [0] "gauss2":4760 [0] "gauss3":2967 [0] "gauss4":10910 [0] "gauss5":7353 [0] "gauss6":13563 [0] "gauss7":9037 [0] "wiener0":7598 [7598] "wiener1":3617 [3617] "wiener2":5739 [5739] "wiener3":3823 [3823] "wiener4":13291 [13291] "wiener5":9004 [9004] "wiener6":16825 [16825] "wiener7":10955 [10955] ] cmm:[ ] 
Retagger: tagging trace set: wiener with 70852 traces, 70852 summary
Retagger: tagging trace set: gauss with 57272 traces, 0 summary
[00:08:13.710] D [  glue  ] sink frame: #161268 @0.000111 with 208316 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[00:08:13.711] D [  glue  ] <FrameFanout:nfsp> call=35: see EOS 
[00:08:13.711] D [  glue  ] <ChannelSelector:chsel7> see EOS at call=17 
[00:08:13.711] D [sigproc ] <OmnibusSigProc:anode7sigproc7> EOS at call=35 anode=7 
[00:08:13.711] D [  glue  ] <ChannelSelector:chsel6> see EOS at call=17 
[00:08:13.711] D [sigproc ] <OmnibusSigProc:anode6sigproc6> EOS at call=35 anode=6 
[00:08:13.711] D [  glue  ] <ChannelSelector:chsel5> see EOS at call=17 
[00:08:13.711] D [sigproc ] <OmnibusSigProc:anode5sigproc5> EOS at call=35 anode=5 
[00:08:13.711] D [  glue  ] <ChannelSelector:chsel4> see EOS at call=17 
[00:08:13.711] D [sigproc ] <OmnibusSigProc:anode4sigproc4> EOS at call=35 anode=4 
[00:08:13.711] D [  glue  ] <ChannelSelector:chsel3> see EOS at call=17 
[00:08:13.711] D [  aux   ] <Resampler:resmp3> EOS at call=35 
[00:08:13.711] D [sigproc ] <OmnibusSigProc:anode3sigproc3> EOS at call=35 anode=3 
[00:08:13.711] D [  glue  ] <ChannelSelector:chsel2> see EOS at call=17 
[00:08:13.711] D [  aux   ] <Resampler:resmp2> EOS at call=35 
[00:08:13.711] D [sigproc ] <OmnibusSigProc:anode2sigproc2> EOS at call=35 anode=2 
[00:08:13.711] D [  glue  ] <ChannelSelector:chsel1> see EOS at call=17 
[00:08:13.711] D [  aux   ] <Resampler:resmp1> EOS at call=35 
[00:08:13.711] D [sigproc ] <OmnibusSigProc:anode1sigproc1> EOS at call=35 anode=1 
[00:08:13.711] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=17 
[00:08:13.711] D [  aux   ] <Resampler:resmp0> EOS at call=35 
[00:08:13.711] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=35 anode=0 
[00:08:13.711] D [  glue  ] <FrameFanin:nfsp> EOS at call=35 with 8 
[00:08:13.711] D [  glue  ] frame sink sees EOS
[00:08:13.711] D [ pgraph ] <Pgrapher:> graph execution complete 
[00:08:13.712] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 15.93 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 13.74 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 13.29 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 12.49 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 9.65 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 9.41 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.62 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.61 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.22 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.13 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.12 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.05 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.04 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.01 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0.01 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[00:08:13.712] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:08:13.712] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[00:08:13.712] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[00:08:13.712] I [ timer  ] Timer: Total node execution : 96.3499987963587 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 57272 traces tagged "gauss"
FrameSaver: q=1.20869e+07 n=1375854 tag=gauss
wclsFrameSaver: saving 70852 traces tagged "wiener"
FrameSaver: q=1.30995e+07 n=1323514 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 778 XUs and 674 XVs -> 34 XUVs
C:0 T:1 2343 XUs and 2792 XVs -> 122 XUVs
C:0 T:2 266 XUs and 173 XVs -> 8 XUVs
C:0 T:3 1615 XUs and 2457 XVs -> 130 XUVs
C:0 T:4 625 XUs and 665 XVs -> 36 XUVs
C:0 T:5 620 XUs and 545 XVs -> 23 XUVs
C:0 T:6 1062 XUs and 943 XVs -> 56 XUVs
C:0 T:7 108 XUs and 116 XVs -> 10 XUVs
C:0 T:8 5309 XUs and 7565 XVs -> 527 XUVs
C:0 T:9 8705 XUs and 18085 XVs -> 2254 XUVs
C:0 T:10 2426 XUs and 6237 XVs -> 852 XUVs
C:0 T:11 15456 XUs and 32128 XVs -> 3272 XUVs
C:0 T:12 21499 XUs and 51390 XVs -> 4240 XUVs
C:0 T:13 4291 XUs and 8465 XVs -> 997 XUVs
C:0 T:14 2175 XUs and 5093 XVs -> 398 XUVs
C:0 T:15 20021 XUs and 36025 XVs -> 3632 XUVs
16591 XUVs total
2835 collection wire objects
16591 potential space points
Neighbour search...
1549965 tests to find 648868 neighbours
Iterating with no regularization...
Begin: 1.07018e+09
0 9.42319e+08
1 9.3265e+08
2 9.30998e+08
3 9.30419e+08
Now with regularization...
Begin: 9.10858e+08
0 9.10398e+08
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
03-Sep-2025 00:12:12 BST  Closed output file "np02vd_raw_run039275_0447_df-s03-d2_dw_0_20250902T052648_reco_stage1_20250902T231212_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        173.763       268.227       421.322       241.281       78.2487        18     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      0.000103414   0.000569419   0.00733324    0.000160475   0.00164164       18     
produce:tpcrawdecoder:PDVDTPCReader               15.5515       16.369        19.162        16.175       0.762731        18     
produce:triggerrawdecoder:PDVDTriggerReader4     0.0357053     0.036693      0.0425904     0.0362593    0.00153213       18     
produce:pdvddaphne:DAPHNEReaderPDVD               4.81413       5.51944       6.11268       5.57201      0.348345        18     
produce:ophit:OpHitFinder                        0.0588411     0.0832516     0.103213      0.0835464     0.0116813       18     
produce:opflash:OpFlashFinderVerticalDrift      0.00655079     0.0185841     0.0293361     0.0188187    0.00574014       18     
produce:wclsdatavd:WireCellToolkit                84.848        98.4981       113.667       99.1293       6.78212        18     
produce:gaushit:GausHitFinder                    0.992622       2.03058       3.23071       2.01086      0.613908        18     
produce:nhitsfilter:NumberOfHitsFilter          0.000327264   0.00057794    0.00116414    0.000531025   0.000206416      18     
produce:reco3d:SpacePointSolver                   12.096        23.2743       39.0614       21.4518       8.34378        18     
produce:hitpdune:DisambigFromSpacePoints         0.159907      0.401124      0.750574       0.36687      0.177052        18     
produce:pandora:StandardPandora                   34.4772       112.268       241.976       93.4512       66.7236        18     
produce:pandoraTrack:LArPandoraTrackCreation     0.599395       1.97372       3.70263       1.80302      0.830649        18     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0209737     0.0545369     0.094033      0.0538804     0.016245        18     
[art]:TriggerResults:TriggerResultInserter      2.9025e-05    5.12046e-05   0.000124142   4.13275e-05   2.5184e-05       18     
end_path:out1:RootOutput                         5.831e-06    1.28984e-05   4.5024e-05    1.12555e-05   8.04338e-06      18     
end_path:out1:RootOutput(write)                   5.76202       7.62457       8.51834       7.62694      0.636793        18     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5051.72 MB
  Peak resident set size usage (VmHWM): 3086.65 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039275_0447_df-s03-d2_dw_0_20250902T052648_reco_stage1_20250902T231212_keepup.root
\tHists: np02vd_raw_run039275_0447_df-s03-d2_dw_0_20250902T052648_reco_stage1_20250902T231212_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: 2025-11-05 14:41:27 UTC       justIN version: 01.05.01