justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 42777.81@dunegpschedd02.fnal.gov

Jobsub ID42777.81@dunegpschedd02.fnal.gov
Workflow ID2973
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-18 23:45:01
SiteCZ_FZU
EntryNova_CZ_FZU_osgce1
Last heartbeat2025-09-19 05:08:31
From worker nodeHostnamemilan25.farm.particle.cz
cpuinfoAMD EPYC 7543 32-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4194304000 (4000 MiB)
Wall seconds limit172800 (48 hours)
GPU
Inner Apptainer?True
Job statefinished
Started2025-09-19 04:00:15
Input filesvd-protodune:np02vd_raw_run039499_0193_df-s04-d1_dw_0_20250919T000949.hdf5
JobscriptExit code0
Real time1h (3987s)
CPU time59m (3541s = 88%)
Max RSS bytes4012433408 (3826 MiB)
Outputting started2025-09-19 05:06:43
Output filesvd-protodune-det-reco:np02vd_raw_run039499_0193_df-s04-d1_dw_0_20250919T000949_reco_stage1_20250919T050630_keepup.root
Finished2025-09-19 05:08:31
Saved logsjustin-logs:42777.81-dunegpschedd02.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

[07:05:54.820] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=39 
[07:05:54.820] D [  aux   ] <Resampler:resmp0> EOS at call=79 
[07:05:54.820] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=79 anode=0 
[07:05:54.820] D [  glue  ] <FrameFanin:nfsp> EOS at call=79 with 8 
[07:05:54.820] D [  glue  ] frame sink sees EOS
[07:05:54.820] D [ pgraph ] <Pgrapher:> graph execution complete 
[07:05:54.820] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.75 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.4 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.32 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 6.7 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.28 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.27 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.27 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.23 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::Gen::Retagger : 0 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[07:05:54.820] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[07:05:54.820] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[07:05:54.820] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[07:05:54.820] I [ timer  ] Timer: Total node execution : 30.230000102892518 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 21942 traces tagged "gauss"
FrameSaver: q=3.59767e+06 n=512301 tag=gauss
wclsFrameSaver: saving 26940 traces tagged "wiener"
FrameSaver: q=3.85078e+06 n=499524 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 910 XUs and 1159 XVs -> 34 XUVs
C:0 T:1 1131 XUs and 1278 XVs -> 30 XUVs
C:0 T:2 1407 XUs and 1505 XVs -> 78 XUVs
C:0 T:3 177 XUs and 289 XVs -> 13 XUVs
C:0 T:4 1707 XUs and 2052 XVs -> 75 XUVs
C:0 T:5 119 XUs and 118 XVs -> 5 XUVs
C:0 T:6 447 XUs and 473 XVs -> 17 XUVs
C:0 T:7 9717 XUs and 9821 XVs -> 945 XUVs
1197 XUVs total
736 collection wire objects
1197 potential space points
Neighbour search...
49483 tests to find 11766 neighbours
Iterating with no regularization...
Begin: 1.45285e+09
0 1.4026e+09
1 1.40114e+09
2 1.40111e+09
Now with regularization...
Begin: 1.3813e+09
0 1.38129e+09
19-Sep-2025 07:06:30 CEST  Closed output file "np02vd_raw_run039499_0193_df-s04-d1_dw_0_20250919T000949_reco_stage1_20250919T050630_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        55.3879       98.0561       566.198       77.8156       91.4748        40     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                       6.786e-05    0.000110257    0.0002381     9.489e-05    4.05345e-05      40     
produce:tpcrawdecoder:PDVDTPCReader               5.81836       7.70567       12.5464       7.39219       1.27846        40     
produce:triggerrawdecoder:PDVDTriggerReader4     0.266608      0.271854      0.318546      0.269799     0.00820893       40     
produce:pdvddaphne:DAPHNEReaderPDVD               2.86123       3.45523       4.23676       3.46229      0.263357        40     
produce:ophit:OpHitFinder                        0.0425966     0.0533165     0.0679512     0.052226     0.00579693       40     
produce:opflash:OpFlashFinderVerticalDrift      0.00547327     0.0127596     0.0278324      0.01297     0.00376673       40     
produce:wclsdatavd:WireCellToolkit                26.3309       37.356        75.7063       32.7142       11.3144        40     
produce:gaushit:GausHitFinder                    0.410149      0.738579       1.53718       0.70334      0.211447        40     
produce:nhitsfilter:NumberOfHitsFilter          0.00010224    0.00016668    0.000358741   0.00015177    4.61649e-05      40     
produce:reco3d:SpacePointSolver                   2.30297       6.37712       20.0207       5.65107       3.0236         40     
produce:hitpdune:DisambigFromSpacePoints         0.0232891     0.0953772     0.442119      0.0784888     0.0727068       40     
produce:pandora:StandardPandora                   6.09669       38.5267       451.819       20.2468       80.6706        40     
produce:pandoraTrack:LArPandoraTrackCreation      0.11363      0.335991       1.3349       0.298017      0.210876        40     
produce:pandoraGnocalo:GnocchiCalorimetry       0.00983629     0.0182609     0.0298518     0.0169275    0.00463201       40     
[art]:TriggerResults:TriggerResultInserter       1.838e-05    2.69726e-05   6.7651e-05    2.5545e-05    7.65425e-06      40     
end_path:out1:RootOutput                         3.99e-06     9.04625e-06    2.81e-05      8.61e-06     3.65983e-06      40     
end_path:out1:RootOutput(write)                   2.70675       3.08788       3.95568       3.04271      0.251906        40     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5924.01 MB
  Peak resident set size usage (VmHWM): 4012.43 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0193_df-s04-d1_dw_0_20250919T000949_reco_stage1_20250919T050630_keepup.root
\tHists: np02vd_raw_run039499_0193_df-s04-d1_dw_0_20250919T000949_reco_stage1_20250919T050630_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0193_df-s04-d1_dw_0_20250919T000949_reco_stage1_20250919T050630_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run039499_0193_df-s04-d1_dw_0_20250919T000949_reco_stage1_20250919T050630_keepup.root",
  "namespace": "vd-protodune-det-reco",
  "metadata": {
    "core.file_format": "artroot",
    "core.application.name": "reco",
    "core.application.family": "dunesw",
    "core.application.version": "v10_10_00d00",
    "core.data_tier": "full-reconstructed",
    "dune.config_file": "standard_reco_stage1_protodunevd_keepup_all.fcl",
    "dune.campaign": "vd-protodune-reco-keepup-v0",
    "core.start_time": 1758258391.0,
    "core.end_time": 1758258391.0,
    "core.events": [
      47064,
      47070,
      47076,
      47082,
      47088,
      47094,
      47100,
      47106,
      47112,
      47118,
      47124,
      47130,
      47136,
      47142,
      47148,
      47154,
      47160,
      47166,
      47172,
      47178,
      47184,
      47190,
      47196,
      47202,
      47208,
      47214,
      47220,
      47226,
      47232,
      47238,
      47244,
      47250,
      47256,
      47262,
      47268,
      47274,
      47280,
      47286,
      47292,
      47298
    ],
    "core.event_count": 40,
    "core.first_event_number": 47064,
    "core.last_event_number": 47298,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39499
    ],
    "core.runs_subruns": [
      3949900001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039499_0193_df-s04-d1_dw_0_20250919T000949.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run039499_0193_df-s04-d1_dw_0_20250919T000949_reco_stage1_20250919T050630_keepup_hists.root",
  "namespace": "vd-protodune-det-reco",
  "metadata": {
    "core.file_format": "root",
    "core.application.name": "reco",
    "core.application.family": "dunesw",
    "core.application.version": "v10_10_00d00",
    "core.data_tier": "root-tuple-virtual",
    "dune.config_file": "standard_reco_stage1_protodunevd_keepup_all.fcl",
    "dune.campaign": "vd-protodune-reco-keepup-v0",
    "core.start_time": 1758258391.0,
    "core.end_time": 1758258391.0,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39499
    ],
    "core.runs_subruns": [
      3949900001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039499_0193_df-s04-d1_dw_0_20250919T000949.hdf5"
    }
  ]
}
justIN time: 2025-09-19 08:54:14 UTC       justIN version: 01.05.00