justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 32023.99@dunegpschedd02.fnal.gov

Jobsub ID32023.99@dunegpschedd02.fnal.gov
Workflow ID2131
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 07:52:28
SiteFR_CCIN2P3
EntryDUNE_FR_CCIN2P3_cccondorce01
Last heartbeat2025-09-02 09:41:27
From worker nodeHostnameccwcondor0564
cpuinfoAMD EPYC 7453 28-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4194304000 (4000 MiB)
Wall seconds limit106200 (29 hours)
GPU
Inner Apptainer?True
Job statejobscript_error
Started2025-09-02 07:54:23
Input filesvd-protodune:np02vd_raw_run039275_0104_df-s01-d3_dw_0_20250901T182252.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Max RSS bytes0 (0 MiB)
Outputting started 
Output files
Finished2025-09-02 09:41:27
Saved logsjustin-logs:32023.99-dunegpschedd02.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

=37781 time=70 tick=512 with 28984 traces.  frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss7":8358 [0] "mp2_roi7":8309 [0] "mp3_roi7":2235 [0] "wiener7":10082 [10082] ] cmm:[ ] output: frame: ident=37781 time=70 tick=500 with 202005 traces.  frame tags:[ "framefanin" ] 16 tagged trace sets:[ "gauss0":6896 [0] "gauss1":5040 [0] "gauss2":847 [0] "gauss3":3735 [0] "gauss4":13409 [0] "gauss5":9283 [0] "gauss6":7848 [0] "gauss7":8358 [0] "wiener0":8371 [8371] "wiener1":6442 [6442] "wiener2":1031 [1031] "wiener3":4508 [4508] "wiener4":16896 [16896] "wiener5":11363 [11363] "wiener6":9380 [9380] "wiener7":10082 [10082] ] cmm:[ ] 
Retagger: tagging trace set: wiener with 68073 traces, 68073 summary
Retagger: tagging trace set: gauss with 55416 traces, 0 summary
[11:37:36.920] D [  glue  ] sink frame: #37781 @7e-05 with 202005 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[11:37:36.920] D [  glue  ] <FrameFanout:nfsp> call=35: see EOS 
[11:37:36.920] D [  glue  ] <ChannelSelector:chsel7> see EOS at call=17 
[11:37:36.920] D [sigproc ] <OmnibusSigProc:anode7sigproc7> EOS at call=35 anode=7 
[11:37:36.920] D [  glue  ] <ChannelSelector:chsel6> see EOS at call=17 
[11:37:36.920] D [sigproc ] <OmnibusSigProc:anode6sigproc6> EOS at call=35 anode=6 
[11:37:36.920] D [  glue  ] <ChannelSelector:chsel5> see EOS at call=17 
[11:37:36.920] D [sigproc ] <OmnibusSigProc:anode5sigproc5> EOS at call=35 anode=5 
[11:37:36.920] D [  glue  ] <ChannelSelector:chsel4> see EOS at call=17 
[11:37:36.920] D [sigproc ] <OmnibusSigProc:anode4sigproc4> EOS at call=35 anode=4 
[11:37:36.920] D [  glue  ] <ChannelSelector:chsel3> see EOS at call=17 
[11:37:36.920] D [  aux   ] <Resampler:resmp3> EOS at call=35 
[11:37:36.920] D [sigproc ] <OmnibusSigProc:anode3sigproc3> EOS at call=35 anode=3 
[11:37:36.920] D [  glue  ] <ChannelSelector:chsel2> see EOS at call=17 
[11:37:36.921] D [  aux   ] <Resampler:resmp2> EOS at call=35 
[11:37:36.921] D [sigproc ] <OmnibusSigProc:anode2sigproc2> EOS at call=35 anode=2 
[11:37:36.921] D [  glue  ] <ChannelSelector:chsel1> see EOS at call=17 
[11:37:36.921] D [  aux   ] <Resampler:resmp1> EOS at call=35 
[11:37:36.921] D [sigproc ] <OmnibusSigProc:anode1sigproc1> EOS at call=35 anode=1 
[11:37:36.921] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=17 
[11:37:36.921] D [  aux   ] <Resampler:resmp0> EOS at call=35 
[11:37:36.921] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=35 anode=0 
[11:37:36.921] D [  glue  ] <FrameFanin:nfsp> EOS at call=35 with 8 
[11:37:36.921] D [  glue  ] frame sink sees EOS
[11:37:36.921] D [ pgraph ] <Pgrapher:> graph execution complete 
[11:37:36.921] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 17.94 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 12.43 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 12.41 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 12.02 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.3 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 10.36 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.54 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.05 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.38 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.11 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.08 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.07 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.04 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.01 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:37:36.921] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:37:36.921] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[11:37:36.921] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[11:37:36.921] I [ timer  ] Timer: Total node execution : 97.80000124685466 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 55416 traces tagged "gauss"
FrameSaver: q=9.80341e+06 n=1145553 tag=gauss
wclsFrameSaver: saving 68073 traces tagged "wiener"
FrameSaver: q=1.03227e+07 n=1100820 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1236 XUs and 1279 XVs -> 58 XUVs
C:0 T:1 1031 XUs and 1288 XVs -> 46 XUVs
C:0 T:2 1379 XUs and 1165 XVs -> 48 XUVs
C:0 T:3 570 XUs and 974 XVs -> 73 XUVs
C:0 T:4 157 XUs and 114 XVs -> 4 XUVs
C:0 T:5 24 XUs and 19 XVs -> 0 XUVs
C:0 T:6 484 XUs and 562 XVs -> 12 XUVs
C:0 T:7 322 XUs and 532 XVs -> 15 XUVs
C:0 T:8 18062 XUs and 31134 XVs -> 2812 XUVs
C:0 T:9 5707 XUs and 9416 XVs -> 1058 XUVs
C:0 T:10 4406 XUs and 6915 XVs -> 883 XUVs
C:0 T:11 1774 XUs and 1549 XVs -> 131 XUVs
C:0 T:12 3900 XUs and 3338 XVs -> 269 XUVs
C:0 T:13 1658 XUs and 1513 XVs -> 118 XUVs
C:0 T:14 2321 XUs and 2680 XVs -> 169 XUVs
C:0 T:15 1813 XUs and 1592 XVs -> 100 XUVs
5796 XUVs total
1955 collection wire objects
5796 potential space points
Neighbour search...
404338 tests to find 199836 neighbours
Iterating with no regularization...
Begin: 6.52241e+08
0 5.81079e+08
1 5.76042e+08
2 5.75278e+08
3 5.75042e+08
Now with regularization...
Begin: 5.6106e+08
0 5.60917e+08
PcaShowerParticleBuildingAlgorithm::OpeningAngle - principal eigenvalue less than or equal to 0.
02-Sep-2025 11:40:48 CEST  Closed output file "np02vd_raw_run039275_0104_df-s01-d3_dw_0_20250901T182252_reco_stage1_20250902T094048_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        203.532       349.409       545.334       319.227       93.4999        18     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      0.000114184   0.00015532    0.000266608   0.000144335   3.86717e-05      18     
produce:tpcrawdecoder:PDVDTPCReader               10.3437       11.1058       14.0319       10.8664      0.892601        18     
produce:triggerrawdecoder:PDVDTriggerReader4    0.00837126     0.0144817     0.0446739     0.0118484     0.0089507       18     
produce:pdvddaphne:DAPHNEReaderPDVD               6.3721        7.31245       8.54611       7.22642      0.634861        18     
produce:ophit:OpHitFinder                        0.078424      0.0928353     0.116315      0.0910562    0.00958712       18     
produce:opflash:OpFlashFinderVerticalDrift       0.0153826     0.0245086     0.0366235     0.0232737     0.0063059       18     
produce:wclsdatavd:WireCellToolkit                95.7394       116.737       142.611       116.992       11.2263        18     
produce:gaushit:GausHitFinder                     1.63363       2.55534       3.90026       2.49603       0.52992        18     
produce:nhitsfilter:NumberOfHitsFilter          0.000441666   0.000990972   0.00219242    0.000990106   0.00047265       18     
produce:reco3d:SpacePointSolver                   14.1233       27.5439       42.6307       26.3561       7.72514        18     
produce:hitpdune:DisambigFromSpacePoints         0.244772       0.54824      0.906528       0.53374      0.196574        18     
produce:pandora:StandardPandora                   61.2716       170.009       346.793       145.753       80.1313        18     
produce:pandoraTrack:LArPandoraTrackCreation      2.88662       5.55607       7.5417        5.75739       1.27634        18     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0531734     0.0765006     0.119305      0.0672234     0.0193878       18     
[art]:TriggerResults:TriggerResultInserter      6.7656e-05    0.00012757    0.000316862   0.00011265    5.44762e-05      18     
end_path:out1:RootOutput                        1.5869e-05    3.72557e-05   0.00012332    3.23755e-05   2.33587e-05      18     
end_path:out1:RootOutput(write)                   7.00819       7.67771       8.31493       7.72286      0.377954        18     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5410.94 MB
  Peak resident set size usage (VmHWM): 3461.87 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039275_0104_df-s01-d3_dw_0_20250901T182252_reco_stage1_20250902T094048_keepup.root
\tHists: np02vd_raw_run039275_0104_df-s01-d3_dw_0_20250901T182252_reco_stage1_20250902T094049_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-09-19 04:24:53 UTC       justIN version: 01.05.00