justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 32473.3@dunegpschedd02.fnal.gov

Jobsub ID32473.3@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:10:38
From worker nodeHostnameheplnc154.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:04
Input filesvd-protodune:np02vd_raw_run039275_0449_df-s05-d5_dw_0_20250902T053127.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Max RSS bytes0 (0 MiB)
Outputting started 
Output files
Finished2025-09-02 23:10:38
Saved logsjustin-logs:32473.3-dunegpschedd02.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

:[ "gauss6":11063 [0] "mp2_roi6":14538 [0] "mp3_roi6":2849 [0] "wiener6":13308 [13308] ] cmm:[ ] input 7: frame: ident=162019 time=119 tick=512 with 36497 traces.  frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss7":10076 [0] "mp2_roi7":11536 [0] "mp3_roi7":2783 [0] "wiener7":12102 [12102] ] cmm:[ ] output: frame: ident=162019 time=119 tick=500 with 179286 traces.  frame tags:[ "framefanin" ] 16 tagged trace sets:[ "gauss0":2481 [0] "gauss1":6186 [0] "gauss2":3124 [0] "gauss3":6493 [0] "gauss4":7918 [0] "gauss5":3867 [0] "gauss6":11063 [0] "gauss7":10076 [0] "wiener0":3005 [3005] "wiener1":7418 [7418] "wiener2":3753 [3753] "wiener3":8070 [8070] "wiener4":9528 [9528] "wiener5":4411 [4411] "wiener6":13308 [13308] "wiener7":12102 [12102] ] cmm:[ ] 
Retagger: tagging trace set: wiener with 61595 traces, 61595 summary
Retagger: tagging trace set: gauss with 51208 traces, 0 summary
[00:07:59.168] D [  glue  ] sink frame: #162019 @0.000119 with 179286 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[00:07:59.168] D [  glue  ] <FrameFanout:nfsp> call=35: see EOS 
[00:07:59.168] D [  glue  ] <ChannelSelector:chsel7> see EOS at call=17 
[00:07:59.168] D [sigproc ] <OmnibusSigProc:anode7sigproc7> EOS at call=35 anode=7 
[00:07:59.168] D [  glue  ] <ChannelSelector:chsel6> see EOS at call=17 
[00:07:59.168] D [sigproc ] <OmnibusSigProc:anode6sigproc6> EOS at call=35 anode=6 
[00:07:59.169] D [  glue  ] <ChannelSelector:chsel5> see EOS at call=17 
[00:07:59.169] D [sigproc ] <OmnibusSigProc:anode5sigproc5> EOS at call=35 anode=5 
[00:07:59.169] D [  glue  ] <ChannelSelector:chsel4> see EOS at call=17 
[00:07:59.169] D [sigproc ] <OmnibusSigProc:anode4sigproc4> EOS at call=35 anode=4 
[00:07:59.169] D [  glue  ] <ChannelSelector:chsel3> see EOS at call=17 
[00:07:59.169] D [  aux   ] <Resampler:resmp3> EOS at call=35 
[00:07:59.169] D [sigproc ] <OmnibusSigProc:anode3sigproc3> EOS at call=35 anode=3 
[00:07:59.169] D [  glue  ] <ChannelSelector:chsel2> see EOS at call=17 
[00:07:59.169] D [  aux   ] <Resampler:resmp2> EOS at call=35 
[00:07:59.169] D [sigproc ] <OmnibusSigProc:anode2sigproc2> EOS at call=35 anode=2 
[00:07:59.169] D [  glue  ] <ChannelSelector:chsel1> see EOS at call=17 
[00:07:59.169] D [  aux   ] <Resampler:resmp1> EOS at call=35 
[00:07:59.169] D [sigproc ] <OmnibusSigProc:anode1sigproc1> EOS at call=35 anode=1 
[00:07:59.169] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=17 
[00:07:59.169] D [  aux   ] <Resampler:resmp0> EOS at call=35 
[00:07:59.169] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=35 anode=0 
[00:07:59.169] D [  glue  ] <FrameFanin:nfsp> EOS at call=35 with 8 
[00:07:59.169] D [  glue  ] frame sink sees EOS
[00:07:59.169] D [ pgraph ] <Pgrapher:> graph execution complete 
[00:07:59.169] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 12.21 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.18 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 10.69 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 10.34 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 9.62 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.9 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.84 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.65 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.21 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.19 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.18 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.13 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.01 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0.01 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:07:59.169] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:07:59.170] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[00:07:59.170] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:07:59.170] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[00:07:59.170] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[00:07:59.170] I [ timer  ] Timer: Total node execution : 85.2199993878603 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 51208 traces tagged "gauss"
FrameSaver: q=8.46675e+06 n=1095145 tag=gauss
wclsFrameSaver: saving 61595 traces tagged "wiener"
FrameSaver: q=8.90709e+06 n=1061105 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 237 XUs and 283 XVs -> 13 XUVs
C:0 T:1 226 XUs and 275 XVs -> 28 XUVs
C:0 T:2 678 XUs and 853 XVs -> 24 XUVs
C:0 T:3 666 XUs and 741 XVs -> 55 XUVs
C:0 T:4 531 XUs and 736 XVs -> 21 XUVs
C:0 T:5 261 XUs and 334 XVs -> 5 XUVs
C:0 T:6 1302 XUs and 1376 XVs -> 67 XUVs
C:0 T:7 640 XUs and 831 XVs -> 24 XUVs
C:0 T:8 14113 XUs and 28389 XVs -> 5798 XUVs
C:0 T:9 2817 XUs and 6250 XVs -> 478 XUVs
C:0 T:10 289 XUs and 444 XVs -> 31 XUVs
C:0 T:11 922 XUs and 957 XVs -> 61 XUVs
C:0 T:12 3813 XUs and 4556 XVs -> 226 XUVs
C:0 T:13 3488 XUs and 3433 XVs -> 148 XUVs
C:0 T:14 2140 XUs and 2677 XVs -> 139 XUVs
C:0 T:15 3079 XUs and 3299 XVs -> 189 XUVs
7307 XUVs total
1247 collection wire objects
7307 potential space points
Neighbour search...
1045845 tests to find 427892 neighbours
Iterating with no regularization...
Begin: 1.10359e+09
0 1.0866e+09
1 1.08551e+09
2 1.08536e+09
Now with regularization...
Begin: 1.07167e+09
0 1.07158e+09
03-Sep-2025 00:10:07 BST  Closed output file "np02vd_raw_run039275_0449_df-s05-d5_dw_0_20250902T053127_reco_stage1_20250902T231007_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        191.732       260.093       438.616       234.861       68.6918        18     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      8.6693e-05    0.00012259    0.000348364   0.000103855   5.85106e-05      18     
produce:tpcrawdecoder:PDVDTPCReader               15.3851       21.2102       31.7415       20.9045       3.71045        18     
produce:triggerrawdecoder:PDVDTriggerReader4     0.0355489     0.0376666     0.0436459     0.0361973    0.00284799       18     
produce:pdvddaphne:DAPHNEReaderPDVD               5.08639       5.81752       7.32095       5.72831      0.537894        18     
produce:ophit:OpHitFinder                        0.0727684     0.0809695     0.0898199     0.0807149    0.00447041       18     
produce:opflash:OpFlashFinderVerticalDrift       0.0123424     0.0195195     0.0264886     0.0188711    0.00352959       18     
produce:wclsdatavd:WireCellToolkit                83.9122       95.5105       116.169       96.5654       8.45077        18     
produce:gaushit:GausHitFinder                     1.51155       2.22613       3.50705       2.11247      0.536612        18     
produce:nhitsfilter:NumberOfHitsFilter          0.000426682   0.000533812   0.000962558   0.000472382   0.000139534      18     
produce:reco3d:SpacePointSolver                   15.0155       24.159        44.1646       22.1613       7.93977        18     
produce:hitpdune:DisambigFromSpacePoints         0.214166      0.428625      0.829768      0.377474      0.183474        18     
produce:pandora:StandardPandora                   47.5705       100.765       249.924       84.7501       54.8591        18     
produce:pandoraTrack:LArPandoraTrackCreation     0.973111       1.96285       4.07644       1.80932      0.834631        18     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0433287     0.0556698     0.0741518     0.0511719    0.00946616       18     
[art]:TriggerResults:TriggerResultInserter      3.9484e-05    5.70761e-05   0.000142138   5.17425e-05   2.16996e-05      18     
end_path:out1:RootOutput                         6.462e-06    1.30011e-05   4.5806e-05    1.1141e-05    8.17105e-06      18     
end_path:out1:RootOutput(write)                   7.29825       7.75311       8.32405       7.69192      0.250187        18     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5150.65 MB
  Peak resident set size usage (VmHWM): 3188 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039275_0449_df-s05-d5_dw_0_20250902T053127_reco_stage1_20250902T231007_keepup.root
\tHists: np02vd_raw_run039275_0449_df-s05-d5_dw_0_20250902T053127_reco_stage1_20250902T231007_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:42 UTC       justIN version: 01.05.01