justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 38834.0@dunegpschedd01.fnal.gov

Jobsub ID38834.0@dunegpschedd01.fnal.gov
Workflow ID2128
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 10:22:34
SiteFR_CCIN2P3
EntryDUNE_FR_CCIN2P3_cccondorce01
Last heartbeat2025-09-02 11:56:58
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 10:23:40
Input filesvd-protodune:np02vd_raw_run039273_1522_df-s05-d5_dw_0_20250901T064255.hdf5
JobscriptExit code1
Real time0m (0s)
CPU time0m (0s = 0%)
Max RSS bytes0 (0 MiB)
Outputting started 
Output files
Finished2025-09-02 11:56:58
Saved logsjustin-logs:38834.0-dunegpschedd01.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

=31 tick=512 with 53321 traces.  frame tags:[ "sigproc" ] 4 tagged trace sets:[ "gauss7":13120 [0] "mp2_roi7":17997 [0] "mp3_roi7":5286 [0] "wiener7":16918 [16918] ] cmm:[ ] output: frame: ident=548279 time=31 tick=500 with 229477 traces.  frame tags:[ "framefanin" ] 16 tagged trace sets:[ "gauss0":3813 [0] "gauss1":2983 [0] "gauss2":4558 [0] "gauss3":5711 [0] "gauss4":15032 [0] "gauss5":7814 [0] "gauss6":9274 [0] "gauss7":13120 [0] "wiener0":4658 [4658] "wiener1":3573 [3573] "wiener2":5545 [5545] "wiener3":6900 [6900] "wiener4":19593 [19593] "wiener5":9571 [9571] "wiener6":11976 [11976] "wiener7":16918 [16918] ] cmm:[ ] 
Retagger: tagging trace set: wiener with 78734 traces, 78734 summary
Retagger: tagging trace set: gauss with 62305 traces, 0 summary
[13:51:39.930] D [  glue  ] sink frame: #548279 @3.1e-05 with 229477 traces, frame tags:[retagger], trace tags:[gauss, wiener]
[13:51:39.930] D [  glue  ] <FrameFanout:nfsp> call=35: see EOS 
[13:51:39.930] D [  glue  ] <ChannelSelector:chsel7> see EOS at call=17 
[13:51:39.930] D [sigproc ] <OmnibusSigProc:anode7sigproc7> EOS at call=35 anode=7 
[13:51:39.930] D [  glue  ] <ChannelSelector:chsel6> see EOS at call=17 
[13:51:39.930] D [sigproc ] <OmnibusSigProc:anode6sigproc6> EOS at call=35 anode=6 
[13:51:39.930] D [  glue  ] <ChannelSelector:chsel5> see EOS at call=17 
[13:51:39.930] D [sigproc ] <OmnibusSigProc:anode5sigproc5> EOS at call=35 anode=5 
[13:51:39.930] D [  glue  ] <ChannelSelector:chsel4> see EOS at call=17 
[13:51:39.930] D [sigproc ] <OmnibusSigProc:anode4sigproc4> EOS at call=35 anode=4 
[13:51:39.930] D [  glue  ] <ChannelSelector:chsel3> see EOS at call=17 
[13:51:39.930] D [  aux   ] <Resampler:resmp3> EOS at call=35 
[13:51:39.930] D [sigproc ] <OmnibusSigProc:anode3sigproc3> EOS at call=35 anode=3 
[13:51:39.930] D [  glue  ] <ChannelSelector:chsel2> see EOS at call=17 
[13:51:39.930] D [  aux   ] <Resampler:resmp2> EOS at call=35 
[13:51:39.930] D [sigproc ] <OmnibusSigProc:anode2sigproc2> EOS at call=35 anode=2 
[13:51:39.931] D [  glue  ] <ChannelSelector:chsel1> see EOS at call=17 
[13:51:39.931] D [  aux   ] <Resampler:resmp1> EOS at call=35 
[13:51:39.931] D [sigproc ] <OmnibusSigProc:anode1sigproc1> EOS at call=35 anode=1 
[13:51:39.931] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=17 
[13:51:39.931] D [  aux   ] <Resampler:resmp0> EOS at call=35 
[13:51:39.931] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=35 anode=0 
[13:51:39.931] D [  glue  ] <FrameFanin:nfsp> EOS at call=35 with 8 
[13:51:39.931] D [  glue  ] frame sink sees EOS
[13:51:39.932] D [ pgraph ] <Pgrapher:> graph execution complete 
[13:51:39.932] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 22.5 sec
[13:51:39.932] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 16.28 sec
[13:51:39.932] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.73 sec
[13:51:39.932] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 10.87 sec
[13:51:39.932] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 9.79 sec
[13:51:39.932] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 9.6 sec
[13:51:39.932] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 9.33 sec
[13:51:39.932] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.95 sec
[13:51:39.932] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.13 sec
[13:51:39.932] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.12 sec
[13:51:39.932] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.09 sec
[13:51:39.932] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.08 sec
[13:51:39.932] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.05 sec
[13:51:39.932] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[13:51:39.932] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:51:39.933] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:51:39.933] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:51:39.933] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:51:39.933] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[13:51:39.933] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:51:39.933] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[13:51:39.933] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[13:51:39.933] I [ timer  ] Timer: WireCell::Gen::Retagger : 0 sec
[13:51:39.933] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[13:51:39.933] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[13:51:39.933] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[13:51:39.933] I [ timer  ] Timer: Total node execution : 103.59000026620924 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 62305 traces tagged "gauss"
FrameSaver: q=1.39119e+07 n=1541437 tag=gauss
wclsFrameSaver: saving 78734 traces tagged "wiener"
FrameSaver: q=1.51844e+07 n=1478162 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 336 XUs and 473 XVs -> 13 XUVs
C:0 T:1 347 XUs and 567 XVs -> 22 XUVs
C:0 T:2 146 XUs and 146 XVs -> 6 XUVs
C:0 T:3 477 XUs and 600 XVs -> 33 XUVs
C:0 T:4 396 XUs and 463 XVs -> 18 XUVs
C:0 T:5 813 XUs and 1088 XVs -> 46 XUVs
C:0 T:6 1035 XUs and 1084 XVs -> 35 XUVs
C:0 T:7 1090 XUs and 1177 XVs -> 38 XUVs
C:0 T:8 40449 XUs and 91257 XVs -> 14166 XUVs
C:0 T:9 10176 XUs and 23299 XVs -> 1917 XUVs
C:0 T:10 8408 XUs and 11672 XVs -> 3156 XUVs
C:0 T:11 8251 XUs and 13795 XVs -> 2673 XUVs
C:0 T:12 2962 XUs and 4670 XVs -> 316 XUVs
C:0 T:13 18777 XUs and 33888 XVs -> 5032 XUVs
C:0 T:14 28727 XUs and 63459 XVs -> 10517 XUVs
C:0 T:15 9234 XUs and 18975 XVs -> 2558 XUVs
40546 XUVs total
3897 collection wire objects
40546 potential space points
Neighbour search...
6137930 tests to find 2552434 neighbours
Iterating with no regularization...
Begin: 1.04446e+09
0 9.09642e+08
1 8.99218e+08
2 8.97356e+08
3 8.96681e+08
Now with regularization...
Begin: 8.77734e+08
0 8.77149e+08
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
02-Sep-2025 13:56:27 CEST  Closed output file "np02vd_raw_run039273_1522_df-s05-d5_dw_0_20250901T064255_reco_stage1_20250902T115627_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        210.827       305.961       418.606       291.145       68.7453        18     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      9.8414e-05    0.000136642   0.000318585   0.000122129   4.92015e-05      18     
produce:tpcrawdecoder:PDVDTPCReader               8.65063       9.78859       10.465        9.85333      0.502054        18     
produce:triggerrawdecoder:PDVDTriggerReader4    0.00941658     0.0115857     0.0181766    0.00970605    0.00317548       18     
produce:pdvddaphne:DAPHNEReaderPDVD               5.24957       6.1466        7.01012       6.16657      0.414344        18     
produce:ophit:OpHitFinder                        0.0551148     0.0698771     0.0985526     0.0693079    0.00922574       18     
produce:opflash:OpFlashFinderVerticalDrift       0.012384      0.0207674     0.0311325     0.0217255    0.00457829       18     
produce:wclsdatavd:WireCellToolkit                88.4426       98.705        121.913       97.7698       9.51486        18     
produce:gaushit:GausHitFinder                     1.49481       2.26804       3.09473       2.24792      0.364438        18     
produce:nhitsfilter:NumberOfHitsFilter          0.000517086   0.00094794    0.00198946    0.000904285   0.000370155      18     
produce:reco3d:SpacePointSolver                   13.4917       24.907        38.7869       24.5523       6.40504        18     
produce:hitpdune:DisambigFromSpacePoints          0.20699      0.499682      0.832984      0.487224      0.173293        18     
produce:pandora:StandardPandora                   71.9462       152.432       242.08        138.052       57.3906        18     
produce:pandoraTrack:LArPandoraTrackCreation      1.25213       3.84507       6.30437       3.60988       1.38533        18     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0409663     0.0652763     0.0841106     0.0629879     0.0137935       18     
[art]:TriggerResults:TriggerResultInserter      4.4143e-05    8.66332e-05   0.000178373   8.39975e-05   2.64813e-05      18     
end_path:out1:RootOutput                        1.2955e-05    2.36002e-05   5.7768e-05    2.0278e-05    1.13874e-05      18     
end_path:out1:RootOutput(write)                   6.38596        7.098        8.01539       7.0927       0.366094        18     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5330.85 MB
  Peak resident set size usage (VmHWM): 3405.37 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039273_1522_df-s05-d5_dw_0_20250901T064255_reco_stage1_20250902T115627_keepup.root
\tHists: np02vd_raw_run039273_1522_df-s05-d5_dw_0_20250901T064255_reco_stage1_20250902T115628_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 07:20:17 UTC       justIN version: 01.05.01