justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 47593.105@dunegpschedd01.fnal.gov

Jobsub ID47593.105@dunegpschedd01.fnal.gov
Workflow ID2815
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-17 14:47:21
SiteCZ_FZU
EntryNova_CZ_FZU_osgce2
Last heartbeat2025-09-17 17:14:29
From worker nodeHostnamemilan21.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 statestalled
Started2025-09-17 15:04:20
Input filesvd-protodune:np02vd_raw_run039388_0387_df-s05-d0_dw_0_20250916T212007.hdf5
Outputting started2025-09-17 15:50:44
Output files
Finished2025-09-17 18:10:49
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

18 
[17:48:54.280] D [  aux   ] <Resampler:resmp0> EOS at call=37 
[17:48:54.280] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=37 anode=0 
[17:48:54.280] D [  glue  ] <FrameFanin:nfsp> EOS at call=37 with 8 
[17:48:54.280] D [  glue  ] frame sink sees EOS
[17:48:54.280] D [ pgraph ] <Pgrapher:> graph execution complete 
[17:48:54.280] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.65 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.34 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.34 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 6.96 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 6.92 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 6.45 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 6.41 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.65 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::Aux::Resampler : 1.07 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.84 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.68 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.67 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.01 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:48:54.280] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[17:48:54.280] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[17:48:54.280] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[17:48:54.280] I [ timer  ] Timer: Total node execution : 58.040000315755606 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 55613 traces tagged "gauss"
FrameSaver: q=9.349e+06 n=1114398 tag=gauss
wclsFrameSaver: saving 67012 traces tagged "wiener"
FrameSaver: q=9.81614e+06 n=1071179 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 2236 XUs and 1982 XVs -> 76 XUVs
C:0 T:1 351 XUs and 391 XVs -> 21 XUVs
C:0 T:2 565 XUs and 604 XVs -> 47 XUVs
C:0 T:3 128 XUs and 162 XVs -> 9 XUVs
C:0 T:4 613 XUs and 880 XVs -> 29 XUVs
C:0 T:5 744 XUs and 856 XVs -> 29 XUVs
C:0 T:6 463 XUs and 752 XVs -> 46 XUVs
C:0 T:7 2221 XUs and 2769 XVs -> 168 XUVs
C:0 T:8 2064 XUs and 2265 XVs -> 125 XUVs
C:0 T:9 2333 XUs and 2116 XVs -> 108 XUVs
C:0 T:10 1764 XUs and 1671 XVs -> 107 XUVs
C:0 T:11 7523 XUs and 7607 XVs -> 428 XUVs
C:0 T:12 2153 XUs and 2375 XVs -> 85 XUVs
C:0 T:13 2076 XUs and 3806 XVs -> 179 XUVs
C:0 T:14 2190 XUs and 3061 XVs -> 82 XUVs
C:0 T:15 803 XUs and 1652 XVs -> 62 XUVs
1601 XUVs total
1347 collection wire objects
1601 potential space points
Neighbour search...
17545 tests to find 7162 neighbours
Iterating with no regularization...
Begin: 6.47725e+08
0 6.33353e+08
1 6.3301e+08
Now with regularization...
Begin: 6.19344e+08
0 6.19335e+08
17-Sep-2025 17:50:34 CEST  Closed output file "np02vd_raw_run039388_0387_df-s05-d0_dw_0_20250916T212007_reco_stage1_20250917T155034_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        100.124       143.896       200.63        137.204       25.8228        19     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                       6.691e-05    0.000135546   0.000766791    8.736e-05    0.000151827      19     
produce:tpcrawdecoder:PDVDTPCReader               13.4001       15.4432       17.6801       15.6836       1.10371        19     
produce:triggerrawdecoder:PDVDTriggerReader4     0.263312       0.26859      0.283493      0.265753     0.00559461       19     
produce:pdvddaphne:DAPHNEReaderPDVD             0.00029448    0.000359243   0.000728271    0.0003332    9.55088e-05      19     
produce:ophit:OpHitFinder                       0.00013391    0.000281595   0.000879741   0.00023341    0.000167388      19     
produce:opflash:OpFlashFinderVerticalDrift       4.97e-05     8.45391e-05   0.00035773     6.623e-05    6.54649e-05      19     
produce:wclsdatavd:WireCellToolkit                52.1668       63.1784       71.7791       64.5198       5.61819        19     
produce:gaushit:GausHitFinder                    0.654071       1.18087       1.74193       1.22366       0.26774        19     
produce:nhitsfilter:NumberOfHitsFilter          0.000175081   0.000269012   0.000610231   0.000240651   9.92037e-05      19     
produce:reco3d:SpacePointSolver                   7.1725        12.6459       21.3869       12.5918       3.51854        19     
produce:hitpdune:DisambigFromSpacePoints         0.0874844     0.180805      0.339254      0.165811      0.0559197       19     
produce:pandora:StandardPandora                   19.336        45.1065       84.3096       39.938        19.0735        19     
produce:pandoraTrack:LArPandoraTrackCreation     0.326132      0.773373       1.11073      0.827556      0.225906        19     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0143952     0.0296595     0.0537926     0.0335325     0.0111375       19     
[art]:TriggerResults:TriggerResultInserter       1.452e-05    2.22448e-05    6.939e-05     2.032e-05    1.14595e-05      19     
end_path:out1:RootOutput                         5.85e-06     8.76053e-06    3.678e-05     7.26e-06     6.72763e-06      19     
end_path:out1:RootOutput(write)                   4.07984       5.06359       6.0499        5.06093      0.518184        19     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4715.63 MB
  Peak resident set size usage (VmHWM): 2776.76 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039388_0387_df-s05-d0_dw_0_20250916T212007_reco_stage1_20250917T155034_keepup.root
\tHists: np02vd_raw_run039388_0387_df-s05-d0_dw_0_20250916T212007_reco_stage1_20250917T155034_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039388_0387_df-s05-d0_dw_0_20250916T212007_reco_stage1_20250917T155034_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run039388_0387_df-s05-d0_dw_0_20250916T212007_reco_stage1_20250917T155034_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": 1758124234.0,
    "core.end_time": 1758124234.0,
    "core.events": [
      29756,
      29760,
      29764,
      29768,
      29772,
      29776,
      29780,
      29784,
      29788,
      29792,
      29796,
      29800,
      29804,
      29808,
      29812,
      29816,
      29820,
      29824,
      29828
    ],
    "core.event_count": 19,
    "core.first_event_number": 29756,
    "core.last_event_number": 29828,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39388
    ],
    "core.runs_subruns": [
      3938800001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039388_0387_df-s05-d0_dw_0_20250916T212007.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run039388_0387_df-s05-d0_dw_0_20250916T212007_reco_stage1_20250917T155034_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": 1758124234.0,
    "core.end_time": 1758124234.0,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39388
    ],
    "core.runs_subruns": [
      3938800001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039388_0387_df-s05-d0_dw_0_20250916T212007.hdf5"
    }
  ]
}
justIN time: 2025-09-19 08:54:26 UTC       justIN version: 01.05.00