justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 47593.122@dunegpschedd01.fnal.gov

Jobsub ID47593.122@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_osgce1
Last heartbeat2025-09-17 17:17:04
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:06:55
Input filesvd-protodune:np02vd_raw_run039388_0398_df-s05-d1_dw_0_20250916T213507.hdf5
Outputting started2025-09-17 15:59:07
Output files
Finished2025-09-17 18:10:49
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

[  glue  ] <FrameFanin:nfsp> EOS at call=37 with 8 
[17:57:47.457] D [  glue  ] frame sink sees EOS
[17:57:47.457] D [ pgraph ] <Pgrapher:> graph execution complete 
[17:57:47.457] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.35 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 6.73 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 6.39 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.85 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.63 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.62 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.23 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 4.96 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.62 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.61 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.61 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.59 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.01 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[17:57:47.457] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[17:57:47.457] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[17:57:47.457] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[17:57:47.457] I [ timer  ] Timer: Total node execution : 51.230000235140324 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 46661 traces tagged "gauss"
FrameSaver: q=9.57109e+06 n=1159697 tag=gauss
wclsFrameSaver: saving 56840 traces tagged "wiener"
FrameSaver: q=1.00926e+07 n=1121640 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 22 XUs and 23 XVs -> 1 XUVs
C:0 T:1 251 XUs and 214 XVs -> 5 XUVs
C:0 T:2 783 XUs and 556 XVs -> 22 XUVs
C:0 T:3 345 XUs and 430 XVs -> 20 XUVs
C:0 T:4 273 XUs and 472 XVs -> 12 XUVs
C:0 T:5 719 XUs and 1178 XVs -> 44 XUVs
C:0 T:6 465 XUs and 602 XVs -> 27 XUVs
C:0 T:7 327 XUs and 455 XVs -> 14 XUVs
C:0 T:8 17797 XUs and 29190 XVs -> 4389 XUVs
C:0 T:9 7066 XUs and 9431 XVs -> 952 XUVs
C:0 T:10 4180 XUs and 5981 XVs -> 1112 XUVs
C:0 T:11 752 XUs and 1118 XVs -> 32 XUVs
C:0 T:12 4820 XUs and 3498 XVs -> 226 XUVs
C:0 T:13 1272 XUs and 872 XVs -> 59 XUVs
C:0 T:14 3710 XUs and 3529 XVs -> 252 XUVs
C:0 T:15 3409 XUs and 2629 XVs -> 134 XUVs
7301 XUVs total
1553 collection wire objects
7301 potential space points
Neighbour search...
1012851 tests to find 424086 neighbours
Iterating with no regularization...
Begin: 1.1532e+09
0 1.08903e+09
1 1.08475e+09
2 1.08424e+09
Now with regularization...
Begin: 1.06e+09
0 1.05975e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
17-Sep-2025 17:59:00 CEST  Closed output file "np02vd_raw_run039388_0398_df-s05-d1_dw_0_20250916T213507_reco_stage1_20250917T155900_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        114.587       162.504       257.87        148.558       40.0477        19     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      6.3381e-05    9.58564e-05   0.00017677     8.316e-05    3.07099e-05      19     
produce:tpcrawdecoder:PDVDTPCReader               13.2306       15.1566       17.9418       14.1207       1.50163        19     
produce:triggerrawdecoder:PDVDTriggerReader4     0.264985      0.269046      0.283722      0.267681     0.00434713       19     
produce:pdvddaphne:DAPHNEReaderPDVD             0.000236901   0.000373831   0.000923561   0.00035187    0.000136348      19     
produce:ophit:OpHitFinder                        0.0001555    0.000301415   0.00108886    0.00023763    0.000196147      19     
produce:opflash:OpFlashFinderVerticalDrift       5.028e-05    8.52491e-05   0.000395231    6.546e-05    7.39297e-05      19     
produce:wclsdatavd:WireCellToolkit                52.4525       64.9499       82.1618       63.1406       8.14312        19     
produce:gaushit:GausHitFinder                    0.884773       1.3501        2.07839       1.26257      0.368298        19     
produce:nhitsfilter:NumberOfHitsFilter          0.000197221   0.000327258   0.000582091   0.000328611   0.00010464       19     
produce:reco3d:SpacePointSolver                   10.5182       15.3507       24.7272       14.7515       3.97264        19     
produce:hitpdune:DisambigFromSpacePoints         0.122554      0.238311      0.520122      0.206845      0.110411        19     
produce:pandora:StandardPandora                   22.825        59.0224       149.529       51.1821       32.4774        19     
produce:pandoraTrack:LArPandoraTrackCreation     0.629015      0.956978       1.66287      0.919344      0.273113        19     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0171283     0.0280568     0.0491399     0.0252113    0.00849063       19     
[art]:TriggerResults:TriggerResultInserter       1.318e-05    2.00643e-05    4.87e-05      1.811e-05    7.25897e-06      19     
end_path:out1:RootOutput                         3.45e-06     7.27842e-06    2.599e-05     6.12e-06     4.62774e-06      19     
end_path:out1:RootOutput(write)                   3.8422        5.15037        6.512        5.19873      0.770011        19     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4914.77 MB
  Peak resident set size usage (VmHWM): 2964.4 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039388_0398_df-s05-d1_dw_0_20250916T213507_reco_stage1_20250917T155900_keepup.root
\tHists: np02vd_raw_run039388_0398_df-s05-d1_dw_0_20250916T213507_reco_stage1_20250917T155900_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039388_0398_df-s05-d1_dw_0_20250916T213507_reco_stage1_20250917T155900_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run039388_0398_df-s05-d1_dw_0_20250916T213507_reco_stage1_20250917T155900_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": 1758124740.0,
    "core.end_time": 1758124740.0,
    "core.events": [
      30669,
      30673,
      30677,
      30681,
      30685,
      30689,
      30693,
      30697,
      30701,
      30705,
      30709,
      30713,
      30717,
      30721,
      30725,
      30729,
      30733,
      30737,
      30741
    ],
    "core.event_count": 19,
    "core.first_event_number": 30669,
    "core.last_event_number": 30741,
    "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_0398_df-s05-d1_dw_0_20250916T213507.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run039388_0398_df-s05-d1_dw_0_20250916T213507_reco_stage1_20250917T155900_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": 1758124740.0,
    "core.end_time": 1758124740.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_0398_df-s05-d1_dw_0_20250916T213507.hdf5"
    }
  ]
}
justIN time: 2025-09-19 08:48:16 UTC       justIN version: 01.05.00