justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 32473.11@dunegpschedd02.fnal.gov

Jobsub ID32473.11@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:11:13
From worker nodeHostnameheplnc160.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:51:08
Input filesvd-protodune:np02vd_raw_run039275_0449_df-s04-d2_dw_0_20250902T053104.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Max RSS bytes0 (0 MiB)
Outputting started 
Output files
Finished2025-09-02 23:11:11
Saved logsjustin-logs:32473.11-dunegpschedd02.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

gauss6":8922 [0] "mp2_roi6":9380 [0] "mp3_roi6":2572 [0] "wiener6":11375 [11375] ] cmm:[ ] input 7: frame: ident=161992 time=140 tick=512 with 41501 traces.  frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss7":10932 [0] "mp2_roi7":13152 [0] "mp3_roi7":3498 [0] "wiener7":13919 [13919] ] cmm:[ ] output: frame: ident=161992 time=140 tick=500 with 164101 traces.  frame tags:[ "framefanin" ] 16 tagged trace sets:[ "gauss0":3274 [0] "gauss1":3049 [0] "gauss2":1165 [0] "gauss3":5075 [0] "gauss4":6223 [0] "gauss5":7692 [0] "gauss6":8922 [0] "gauss7":10932 [0] "wiener0":4227 [4227] "wiener1":4185 [4185] "wiener2":1401 [1401] "wiener3":6060 [6060] "wiener4":7579 [7579] "wiener5":9262 [9262] "wiener6":11375 [11375] "wiener7":13919 [13919] ] cmm:[ ] 
Retagger: tagging trace set: wiener with 58008 traces, 58008 summary
Retagger: tagging trace set: gauss with 46332 traces, 0 summary
[00:08:59.737] D [  glue  ] sink frame: #161992 @0.00014 with 164101 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[00:08:59.737] D [  glue  ] <FrameFanout:nfsp> call=35: see EOS 
[00:08:59.737] D [  glue  ] <ChannelSelector:chsel7> see EOS at call=17 
[00:08:59.737] D [sigproc ] <OmnibusSigProc:anode7sigproc7> EOS at call=35 anode=7 
[00:08:59.737] D [  glue  ] <ChannelSelector:chsel6> see EOS at call=17 
[00:08:59.737] D [sigproc ] <OmnibusSigProc:anode6sigproc6> EOS at call=35 anode=6 
[00:08:59.737] D [  glue  ] <ChannelSelector:chsel5> see EOS at call=17 
[00:08:59.737] D [sigproc ] <OmnibusSigProc:anode5sigproc5> EOS at call=35 anode=5 
[00:08:59.737] D [  glue  ] <ChannelSelector:chsel4> see EOS at call=17 
[00:08:59.737] D [sigproc ] <OmnibusSigProc:anode4sigproc4> EOS at call=35 anode=4 
[00:08:59.737] D [  glue  ] <ChannelSelector:chsel3> see EOS at call=17 
[00:08:59.737] D [  aux   ] <Resampler:resmp3> EOS at call=35 
[00:08:59.738] D [sigproc ] <OmnibusSigProc:anode3sigproc3> EOS at call=35 anode=3 
[00:08:59.738] D [  glue  ] <ChannelSelector:chsel2> see EOS at call=17 
[00:08:59.738] D [  aux   ] <Resampler:resmp2> EOS at call=35 
[00:08:59.738] D [sigproc ] <OmnibusSigProc:anode2sigproc2> EOS at call=35 anode=2 
[00:08:59.738] D [  glue  ] <ChannelSelector:chsel1> see EOS at call=17 
[00:08:59.738] D [  aux   ] <Resampler:resmp1> EOS at call=35 
[00:08:59.738] D [sigproc ] <OmnibusSigProc:anode1sigproc1> EOS at call=35 anode=1 
[00:08:59.738] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=17 
[00:08:59.738] D [  aux   ] <Resampler:resmp0> EOS at call=35 
[00:08:59.738] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=35 anode=0 
[00:08:59.738] D [  glue  ] <FrameFanin:nfsp> EOS at call=35 with 8 
[00:08:59.738] D [  glue  ] frame sink sees EOS
[00:08:59.738] D [ pgraph ] <Pgrapher:> graph execution complete 
[00:08:59.738] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.3 sec
[00:08:59.738] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 9.61 sec
[00:08:59.738] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 9.6 sec
[00:08:59.738] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 9.48 sec
[00:08:59.738] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.52 sec
[00:08:59.738] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.2 sec
[00:08:59.738] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.17 sec
[00:08:59.738] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.49 sec
[00:08:59.738] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.19 sec
[00:08:59.738] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.1 sec
[00:08:59.738] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.05 sec
[00:08:59.738] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.86 sec
[00:08:59.738] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[00:08:59.738] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.03 sec
[00:08:59.738] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.01 sec
[00:08:59.738] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:08:59.742] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:08:59.742] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:08:59.742] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:08:59.742] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:08:59.742] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[00:08:59.742] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:08:59.742] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:08:59.742] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[00:08:59.742] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[00:08:59.742] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[00:08:59.742] I [ timer  ] Timer: Total node execution : 76.63999993167818 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 46332 traces tagged "gauss"
FrameSaver: q=7.69673e+06 n=920795 tag=gauss
wclsFrameSaver: saving 58008 traces tagged "wiener"
FrameSaver: q=8.26202e+06 n=875626 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 261 XUs and 201 XVs -> 13 XUVs
C:0 T:1 1075 XUs and 653 XVs -> 36 XUVs
C:0 T:2 1483 XUs and 792 XVs -> 76 XUVs
C:0 T:3 150 XUs and 132 XVs -> 5 XUVs
C:0 T:4 150 XUs and 162 XVs -> 6 XUVs
C:0 T:5 88 XUs and 117 XVs -> 4 XUVs
C:0 T:6 528 XUs and 773 XVs -> 31 XUVs
C:0 T:7 1045 XUs and 1253 XVs -> 33 XUVs
C:0 T:8 4683 XUs and 10558 XVs -> 1451 XUVs
C:0 T:9 1945 XUs and 1562 XVs -> 145 XUVs
C:0 T:10 1702 XUs and 1764 XVs -> 103 XUVs
C:0 T:11 2082 XUs and 2355 XVs -> 248 XUVs
C:0 T:12 2071 XUs and 1765 XVs -> 120 XUVs
C:0 T:13 5149 XUs and 7677 XVs -> 1033 XUVs
C:0 T:14 9499 XUs and 6879 XVs -> 755 XUVs
C:0 T:15 11305 XUs and 18842 XVs -> 2032 XUVs
6091 XUVs total
1789 collection wire objects
6091 potential space points
Neighbour search...
467553 tests to find 219726 neighbours
Iterating with no regularization...
Begin: 4.00865e+08
0 3.63079e+08
1 3.60518e+08
2 3.60022e+08
3 3.5985e+08
Now with regularization...
Begin: 3.50445e+08
0 3.50328e+08
03-Sep-2025 00:10:40 BST  Closed output file "np02vd_raw_run039275_0449_df-s04-d2_dw_0_20250902T053104_reco_stage1_20250902T231040_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        187.259       261.961       367.044       260.785        56.73         18     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      8.7274e-05    0.00015273    0.000315019   0.000133319   5.98074e-05      18     
produce:tpcrawdecoder:PDVDTPCReader               15.7502       16.4218       17.3518       16.3676      0.460452        18     
produce:triggerrawdecoder:PDVDTriggerReader4     0.0358702     0.0362916     0.0383114     0.0361424    0.000589423      18     
produce:pdvddaphne:DAPHNEReaderPDVD               4.78011       5.6513        6.32895       5.64115       0.40548        18     
produce:ophit:OpHitFinder                        0.0705804     0.0791352     0.0913239     0.0786667    0.00607027       18     
produce:opflash:OpFlashFinderVerticalDrift       0.014581      0.0215073     0.0293267     0.021075     0.00376466       18     
produce:wclsdatavd:WireCellToolkit                79.8959       95.2931       108.95        97.1641       8.1002         18     
produce:gaushit:GausHitFinder                     1.65835       2.16839       2.87512       2.19357      0.385145        18     
produce:nhitsfilter:NumberOfHitsFilter          0.000373289   0.000524448   0.000867944   0.00050506    0.000147162      18     
produce:reco3d:SpacePointSolver                   16.7382       24.3251       38.8931       23.9419       6.3808         18     
produce:hitpdune:DisambigFromSpacePoints         0.274818      0.456931      0.910244      0.426561      0.163163        18     
produce:pandora:StandardPandora                   53.9435       107.944       187.303       100.026       46.8722        18     
produce:pandoraTrack:LArPandoraTrackCreation      1.05428       1.78332       2.56423       1.7568       0.440068        18     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0397854     0.0544215     0.0691124     0.054572     0.00769127       18     
[art]:TriggerResults:TriggerResultInserter      3.0357e-05    4.23491e-05   9.1642e-05    3.87725e-05   1.31241e-05      18     
end_path:out1:RootOutput                         8.496e-06    1.11809e-05   3.0858e-05    1.01485e-05   4.87342e-06      18     
end_path:out1:RootOutput(write)                   7.18075       7.63655       8.16218       7.60602      0.271266        18     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5455.31 MB
  Peak resident set size usage (VmHWM): 3501.53 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039275_0449_df-s04-d2_dw_0_20250902T053104_reco_stage1_20250902T231040_keepup.root
\tHists: np02vd_raw_run039275_0449_df-s04-d2_dw_0_20250902T053104_reco_stage1_20250902T231040_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:39:41 UTC       justIN version: 01.05.01