justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 42563.176@dunegpschedd02.fnal.gov

Jobsub ID42563.176@dunegpschedd02.fnal.gov
Workflow ID2920
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 19:06:47
SiteCZ_FZU
EntryNova_CZ_FZU_osgce1
Last heartbeat2025-09-18 23:52: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-18 21:57:32
Input filesvd-protodune:np02vd_raw_run039499_0071_df-s04-d2_dw_0_20250918T200613.hdf5
JobscriptExit code0
Real time1h (6801s)
CPU time1h (6376s = 93%)
Max RSS bytes5111947264 (4875 MiB)
Outputting started2025-09-18 23:50:55
Output filesvd-protodune-det-reco:np02vd_raw_run039499_0071_df-s04-d2_dw_0_20250918T200613_reco_stage1_20250918T235041_keepup.root
Finished2025-09-18 23:52:31
Saved logsjustin-logs:42563.176-dunegpschedd02.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

1 
[01:49:54.025] D [  glue  ] <ChannelSelector:chsel0> see EOS at call=40 
[01:49:54.025] D [  aux   ] <Resampler:resmp0> EOS at call=81 
[01:49:54.025] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=81 anode=0 
[01:49:54.025] D [  glue  ] <FrameFanin:nfsp> EOS at call=81 with 8 
[01:49:54.025] D [  glue  ] frame sink sees EOS
[01:49:54.025] D [ pgraph ] <Pgrapher:> graph execution complete 
[01:49:54.025] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 13.6 sec
[01:49:54.025] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 10.66 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 10.64 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 10.48 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.36 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.36 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.36 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.36 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::Gen::Retagger : 0 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[01:49:54.026] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[01:49:54.026] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[01:49:54.026] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[01:49:54.026] I [ timer  ] Timer: Total node execution : 46.82000017166138 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 21364 traces tagged "gauss"
FrameSaver: q=4.15188e+06 n=493067 tag=gauss
wclsFrameSaver: saving 26733 traces tagged "wiener"
FrameSaver: q=4.42804e+06 n=468696 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 83 XUs and 91 XVs -> 4 XUVs
C:0 T:1 936 XUs and 1007 XVs -> 57 XUVs
C:0 T:2 5708 XUs and 5075 XVs -> 262 XUVs
C:0 T:3 15062 XUs and 15619 XVs -> 1287 XUVs
C:0 T:4 82 XUs and 100 XVs -> 6 XUVs
C:0 T:5 929 XUs and 852 XVs -> 52 XUVs
C:0 T:6 233 XUs and 242 XVs -> 8 XUVs
C:0 T:7 612 XUs and 586 XVs -> 32 XUVs
1708 XUVs total
1123 collection wire objects
1708 potential space points
Neighbour search...
41820 tests to find 10390 neighbours
Iterating with no regularization...
Begin: 4.96583e+10
0 4.95738e+10
1 4.95513e+10
Now with regularization...
Begin: 4.86555e+10
0 4.86554e+10
19-Sep-2025 01:50:41 CEST  Closed output file "np02vd_raw_run039499_0071_df-s04-d2_dw_0_20250918T200613_reco_stage1_20250918T235041_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        43.9565       164.192       1535.2        77.0724       297.444        41     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                       4.645e-05    0.000147072   0.00105088    0.00012019    0.00016724       41     
produce:tpcrawdecoder:PDVDTPCReader               5.28237       6.48712       8.45263       6.09946      0.937894        41     
produce:triggerrawdecoder:PDVDTriggerReader4     0.265268      0.275001      0.326996       0.26959      0.0125428       41     
produce:pdvddaphne:DAPHNEReaderPDVD               2.69006       3.35554       3.78169       3.36899      0.260103        41     
produce:ophit:OpHitFinder                        0.0339085     0.0500779     0.0641359     0.0513544    0.00666013       41     
produce:opflash:OpFlashFinderVerticalDrift      0.00433794     0.0122087      0.0289       0.0120408    0.00423829       41     
produce:wclsdatavd:WireCellToolkit                21.8107       40.5708       79.6782       34.4781       12.4699        41     
produce:gaushit:GausHitFinder                    0.311311       0.82514       1.82962      0.778111      0.314879        41     
produce:nhitsfilter:NumberOfHitsFilter          7.9891e-05    0.000179162   0.00044959    0.00017002    6.24129e-05      41     
produce:reco3d:SpacePointSolver                   2.41321       7.98804       31.2257       6.54457       5.1589         41     
produce:hitpdune:DisambigFromSpacePoints         0.0281722     0.133548      0.704481      0.104292      0.127584        41     
produce:pandora:StandardPandora                   5.14902       100.899       1408.05       19.4983       284.914        41     
produce:pandoraTrack:LArPandoraTrackCreation     0.0953991     0.479355       2.29524      0.390134      0.411442        41     
produce:pandoraGnocalo:GnocchiCalorimetry       0.00666256     0.0206168     0.0535571     0.0188957    0.00811889       41     
[art]:TriggerResults:TriggerResultInserter       1.332e-05    2.82983e-05    6.342e-05      2.5e-05     9.71388e-06      41     
end_path:out1:RootOutput                         3.94e-06      8.591e-06     3.064e-05      7.4e-06     5.11278e-06      41     
end_path:out1:RootOutput(write)                   1.98547       3.07576       4.14225       3.11627      0.454571        41     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 7022.63 MB
  Peak resident set size usage (VmHWM): 5111.95 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0071_df-s04-d2_dw_0_20250918T200613_reco_stage1_20250918T235041_keepup.root
\tHists: np02vd_raw_run039499_0071_df-s04-d2_dw_0_20250918T200613_reco_stage1_20250918T235041_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0071_df-s04-d2_dw_0_20250918T200613_reco_stage1_20250918T235041_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run039499_0071_df-s04-d2_dw_0_20250918T200613_reco_stage1_20250918T235041_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": 1758239442.0,
    "core.end_time": 1758239442.0,
    "core.events": [
      17329,
      17335,
      17341,
      17347,
      17353,
      17359,
      17365,
      17371,
      17377,
      17383,
      17389,
      17395,
      17401,
      17407,
      17413,
      17419,
      17425,
      17431,
      17437,
      17443,
      17449,
      17455,
      17461,
      17467,
      17473,
      17479,
      17485,
      17491,
      17497,
      17503,
      17509,
      17515,
      17521,
      17527,
      17533,
      17539,
      17545,
      17551,
      17557,
      17563,
      17569
    ],
    "core.event_count": 41,
    "core.first_event_number": 17329,
    "core.last_event_number": 17569,
    "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_0071_df-s04-d2_dw_0_20250918T200613.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run039499_0071_df-s04-d2_dw_0_20250918T200613_reco_stage1_20250918T235041_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": 1758239442.0,
    "core.end_time": 1758239442.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_0071_df-s04-d2_dw_0_20250918T200613.hdf5"
    }
  ]
}
justIN time: 2025-09-19 08:48:39 UTC       justIN version: 01.05.00