justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 235526.11@dunegpschedd02.fnal.gov

Jobsub ID235526.11@dunegpschedd02.fnal.gov
Workflow ID9489
Stage ID1
User nameykermaid@fnal.gov
HTCondor Groupgroup_dune.prod_mcsim
RequestedProcessors2
GPUNo
RSS bytes4193255424 (3999 MiB)
Wall seconds limit18000 (5 hours)
Submitted time2025-10-30 19:48:49
SiteUK_Lancaster
EntryUBoone_UK_Lancaster_HEC_grendel_ce02
Last heartbeat2025-10-31 00:44:34
From worker nodeHostnamecomp08-01
cpuinfoIntel(R) Xeon(R) Gold 6148 CPU @ 2.40GHz
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors2
RSS bytes4194304000 (4000 MiB)
Wall seconds limit257400 (71 hours)
GPU
Inner Apptainer?True
Job statefinished
Started2025-10-30 19:49:38
Input filesvd-protodune:np02vd_raw_run040330_0026_df-s04-d0_dw_0_20251030T191834.hdf5
JobscriptExit code0
Real time4h (17356s)
CPU time38m (2295s = 13%)
Max RSS bytes1979797504 (1888 MiB)
Outputting started2025-10-31 00:38:56
Output filesvd-protodune-det-reco:np02vd_raw_run040330_0026_df-s04-d0_dw_0_20251030T191834_reco_stage1_20251031T003841_keepup.root
Finished2025-10-31 00:44:34
Saved logsjustin-logs:235526.11-dunegpschedd02.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

] <Pgrapher:> graph execution complete 
[00:38:39.859] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::Aux::Resampler : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::Aux::Resampler : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::Gen::Retagger : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::Aux::Resampler : 0 sec
[00:38:39.859] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::Aux::Resampler : 0 sec
[00:38:39.859] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[00:38:39.859] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[00:38:39.859] I [ timer  ] Timer: Total node execution : 0 sec
wclsFrameSaver: saving empty frame to art::Event
PandoraContentApi::GetList(*this, m_inputHitListName, pCaloHitList) return STATUS_CODE_NOT_INITIALIZED
    in function: GetVolumeIdToHitListMap
    in file:     /scratch/workspace/build-larsoft/BUILDTYPE/prof/QUAL/s131-e26/label1/swarm/label2/ALMA9/build/larpandoracontent/v04_17_01-buildFW/src/larpandoracontent/LArControlFlow/MasterAlgorithm.cc line#: 271
this->GetVolumeIdToHitListMap(volumeIdToHitListMap) return STATUS_CODE_NOT_INITIALIZED
    in function: Run
    in file:     /scratch/workspace/build-larsoft/BUILDTYPE/prof/QUAL/s131-e26/label1/swarm/label2/ALMA9/build/larpandoracontent/v04_17_01-buildFW/src/larpandoracontent/LArControlFlow/MasterAlgorithm.cc line#: 165
iter->second->Run() throw STATUS_CODE_NOT_INITIALIZED
    in function: RunAlgorithm
    in file:     /scratch/workspace/build-lar-products/BUILDTYPE/prof/QUAL/e26/label1/swarm/label2/ALMA9/build/pandora/v04_16_02/src/pandora-v04-16-02/PandoraSDK-v04-00-02/src/Api/PandoraContentApiImpl.cc line#: 235
Failure in algorithm Alg0003, LArMaster, STATUS_CODE_NOT_INITIALIZED
31-Oct-2025 00:38:41 GMT  Closed output file "np02vd_raw_run040330_0026_df-s04-d0_dw_0_20251030T191834_reco_stage1_20251031T003841_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        9.05321       276.375       727.802       345.622       198.85         61     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      4.0015e-05    5.3307e-05    0.00010585    5.2192e-05    7.65616e-06      61     
produce:tpcrawdecoder:PDVDTPCReader               8.06022       234.882       470.971       290.377       169.592        61     
produce:triggerrawdecoder:PDVDTriggerReader4     0.769782       1.51938       3.8129        1.38165      0.571905        61     
produce:pdvddaphne:DAPHNEReaderPDVD             0.000355757   0.000419449   0.000621302   0.000416593   3.36491e-05      61     
produce:ophit:OpHitFinder                        4.23e-05     5.61335e-05   0.000402493   4.7815e-05    4.58718e-05      61     
produce:opflash:OpFlashFinderVerticalDrift      3.1229e-05    4.10972e-05   0.000244182   3.5057e-05    2.92893e-05      61     
produce:wclsdatavd:WireCellToolkit              0.000396851     26.3833       56.6495       36.9416       18.2266        61     
produce:gaushit:GausHitFinder                   3.1764e-05     0.341743       0.81187       0.42428      0.264803        61     
produce:nhitsfilter:NumberOfHitsFilter           8.667e-06    0.00010086    0.000340972   0.000119736   7.45986e-05      61     
produce:reco3d:SpacePointSolver                  5.768e-05      2.62681       6.96376       3.22722       2.11708        61     
produce:hitpdune:DisambigFromSpacePoints        0.000123369    0.0240571     0.0761083     0.0270744     0.0200515       61     
produce:pandora:StandardPandora                 0.00054721      9.12401       275.676       4.84154       34.6833        61     
produce:pandoraTrack:LArPandoraTrackCreation      9.5e-05      0.111374      0.413719       0.12641      0.0964163       61     
produce:pandoraGnocalo:GnocchiCalorimetry       9.1166e-05     0.0074232     0.0152454     0.0094089    0.00561708       61     
[art]:TriggerResults:TriggerResultInserter       9.179e-06    1.51022e-05   3.9636e-05    1.3732e-05    5.30514e-06      61     
end_path:out1:RootOutput                         2.638e-06    6.3727e-06     1.774e-05     7.053e-06    2.6315e-06       61     
end_path:out1:RootOutput(write)                 0.00884587      1.33355       2.27499       1.98108      0.958624        61     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4018.02 MB
  Peak resident set size usage (VmHWM): 1979.8 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040330_0026_df-s04-d0_dw_0_20251030T191834_reco_stage1_20251031T003841_keepup.root
\tHists: np02vd_raw_run040330_0026_df-s04-d0_dw_0_20251030T191834_reco_stage1_20251031T003845_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040330_0026_df-s04-d0_dw_0_20251030T191834_reco_stage1_20251031T003841_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run040330_0026_df-s04-d0_dw_0_20251030T191834_reco_stage1_20251031T003841_keepup.root",
  "namespace": "vd-protodune-det-reco",
  "metadata": {
    "core.file_format": "artroot",
    "core.application.name": "reco",
    "core.application.family": "dunesw",
    "core.application.version": "v10_11_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": 1761871126.0,
    "core.end_time": 1761871126.0,
    "core.events": [
      1547,
      1548,
      1549,
      1550,
      1551,
      1552,
      1553,
      1554,
      1555,
      1556,
      1557,
      1558,
      1559,
      1560,
      1561,
      1562,
      1563,
      1564,
      1565,
      1566,
      1567,
      1568,
      1569,
      1570,
      1571,
      1572,
      1573,
      1574,
      1575,
      1576,
      1577,
      1578,
      1579,
      1580,
      1581,
      1582,
      1583,
      1584,
      1585,
      1586,
      1587,
      1588,
      1589,
      1590,
      1591,
      1592,
      1593,
      1594,
      1595,
      1596,
      1597,
      1598,
      1599,
      1600,
      1601,
      1602,
      1603,
      1604,
      1605,
      1606,
      1607
    ],
    "core.event_count": 61,
    "core.first_event_number": 1547,
    "core.last_event_number": 1607,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      40330
    ],
    "core.runs_subruns": [
      4033000001
    ],
    "dune.daq_test": true,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run040330_0026_df-s04-d0_dw_0_20251030T191834.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run040330_0026_df-s04-d0_dw_0_20251030T191834_reco_stage1_20251031T003845_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_11_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": 1761871126.0,
    "core.end_time": 1761871126.0,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      40330
    ],
    "core.runs_subruns": [
      4033000001
    ],
    "dune.daq_test": true,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run040330_0026_df-s04-d0_dw_0_20251030T191834.hdf5"
    }
  ]
}
justIN time: 2025-11-04 07:19:28 UTC       justIN version: 01.05.01