justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 49243.12@dunegpschedd01.fnal.gov

Jobsub ID49243.12@dunegpschedd01.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-19 00:47:04
SiteUK_RAL-Tier1
EntryLIGO_UK_RAL_arc_ce03
Last heartbeat2025-09-19 03:23:47
From worker nodeHostnamedune001-8709997.0-lcg2621.gridpp.rl.ac.uk
cpuinfoAMD EPYC 7763 64-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4194304000 (4000 MiB)
Wall seconds limit216000 (60 hours)
GPU
Inner Apptainer?True
Job statefinished
Started2025-09-19 02:22:37
Input filesvd-protodune:np02vd_raw_run039499_0159_df-s05-d4_dw_0_20250918T230138.hdf5
JobscriptExit code0
Real time59m (3557s)
CPU time51m (3100s = 87%)
Max RSS bytes3086290944 (2943 MiB)
Outputting started2025-09-19 03:21:56
Output filesvd-protodune-det-reco:np02vd_raw_run039499_0159_df-s05-d4_dw_0_20250918T230138_reco_stage1_20250919T032143_keepup.root
Finished2025-09-19 03:23:47
Saved logsjustin-logs:49243.12-dunegpschedd01.fnal.gov.logs.tgz
List job events     Cached HTCondor job logs

Jobscript log (last 10,000 characters)

glue  ] <ChannelSelector:chsel0> see EOS at call=39 
[03:20:41.917] D [  aux   ] <Resampler:resmp0> EOS at call=79 
[03:20:41.917] D [sigproc ] <OmnibusSigProc:anode0sigproc0> EOS at call=79 anode=0 
[03:20:41.917] D [  glue  ] <FrameFanin:nfsp> EOS at call=79 with 8 
[03:20:41.917] D [  glue  ] frame sink sees EOS
[03:20:41.917] D [ pgraph ] <Pgrapher:> graph execution complete 
[03:20:41.918] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 31.03 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 12.59 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 10.92 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 10.88 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.68 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.57 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.54 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.41 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.01 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[03:20:41.918] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[03:20:41.918] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 0 sec
[03:20:41.918] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[03:20:41.918] I [ timer  ] Timer: Total node execution : 67.67000104673207 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 25389 traces tagged "gauss"
FrameSaver: q=1.09221e+07 n=804338 tag=gauss
wclsFrameSaver: saving 32468 traces tagged "wiener"
FrameSaver: q=1.14947e+07 n=780919 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 2067 XUs and 1878 XVs -> 112 XUVs
C:0 T:1 102 XUs and 110 XVs -> 6 XUVs
C:0 T:2 610 XUs and 833 XVs -> 37 XUVs
C:0 T:3 882 XUs and 749 XVs -> 35 XUVs
C:0 T:4 1365 XUs and 2008 XVs -> 68 XUVs
C:0 T:5 1204 XUs and 1293 XVs -> 27 XUVs
C:0 T:6 1590 XUs and 1182 XVs -> 88 XUVs
C:0 T:7 29803 XUs and 83489 XVs -> 3127 XUVs
3500 XUVs total
1786 collection wire objects
3500 potential space points
Neighbour search...
100668 tests to find 21824 neighbours
Iterating with no regularization...
Begin: 1.97595e+11
0 1.9224e+11
1 1.91852e+11
2 1.91845e+11
Now with regularization...
Begin: 1.91357e+11
0 1.91356e+11
19-Sep-2025 03:21:43 UTC  Closed output file "np02vd_raw_run039499_0159_df-s05-d4_dw_0_20250918T230138_reco_stage1_20250919T032143_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        49.6151       86.5429       140.581       85.4192       22.749         40     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      8.4292e-05    0.000145258   0.000369991   0.000125384   6.03937e-05      40     
produce:tpcrawdecoder:PDVDTPCReader               7.09155       9.20348       44.0901       7.72625       5.78124        40     
produce:triggerrawdecoder:PDVDTriggerReader4     0.391185      0.451291      0.848069      0.395118      0.127984        40     
produce:pdvddaphne:DAPHNEReaderPDVD               2.84446       3.40627       4.31676       3.30374      0.375608        40     
produce:ophit:OpHitFinder                        0.0321305     0.050086      0.0723474     0.0506221    0.00795608       40     
produce:opflash:OpFlashFinderVerticalDrift      0.00518495      0.01387      0.0231619     0.0141505    0.00459426       40     
produce:wclsdatavd:WireCellToolkit                25.4027       40.3405       68.8042        38.75        11.552         40     
produce:gaushit:GausHitFinder                    0.321936      0.685594        1.008        0.64864      0.171317        40     
produce:nhitsfilter:NumberOfHitsFilter          0.000104113   0.000258636   0.000773244   0.000222742   0.000135587      40     
produce:reco3d:SpacePointSolver                   2.7191        6.13007       10.378        5.98762       1.93169        40     
produce:hitpdune:DisambigFromSpacePoints         0.0223038     0.0889824     0.295189      0.0714895     0.0567657       40     
produce:pandora:StandardPandora                   4.87149       23.0283       61.5168       20.0268       14.6198        40     
produce:pandoraTrack:LArPandoraTrackCreation     0.110315      0.327989      0.676833       0.27204      0.148049        40     
produce:pandoraGnocalo:GnocchiCalorimetry       0.00817557     0.0171652     0.0284422     0.0172736    0.00509774       40     
[art]:TriggerResults:TriggerResultInserter       2.199e-05    5.16426e-05   0.000129714   4.66965e-05   2.3724e-05       40     
end_path:out1:RootOutput                          4.6e-06     1.10818e-05   4.1541e-05    1.08355e-05   5.91039e-06      40     
end_path:out1:RootOutput(write)                   2.29969       2.77229       3.43933       2.77205      0.257612        40     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5229.54 MB
  Peak resident set size usage (VmHWM): 3129.28 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039499_0159_df-s05-d4_dw_0_20250918T230138_reco_stage1_20250919T032143_keepup.root
\tHists: np02vd_raw_run039499_0159_df-s05-d4_dw_0_20250918T230138_reco_stage1_20250919T032143_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039499_0159_df-s05-d4_dw_0_20250918T230138_reco_stage1_20250919T032143_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run039499_0159_df-s05-d4_dw_0_20250918T230138_reco_stage1_20250919T032143_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": 1758252104.0,
    "core.end_time": 1758252104.0,
    "core.events": [
      38824,
      38830,
      38836,
      38842,
      38848,
      38854,
      38860,
      38866,
      38872,
      38878,
      38884,
      38890,
      38896,
      38902,
      38908,
      38914,
      38920,
      38926,
      38932,
      38938,
      38944,
      38950,
      38956,
      38962,
      38968,
      38974,
      38980,
      38986,
      38992,
      38998,
      39004,
      39010,
      39016,
      39022,
      39028,
      39034,
      39040,
      39046,
      39052,
      39058
    ],
    "core.event_count": 40,
    "core.first_event_number": 38824,
    "core.last_event_number": 39058,
    "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_0159_df-s05-d4_dw_0_20250918T230138.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run039499_0159_df-s05-d4_dw_0_20250918T230138_reco_stage1_20250919T032143_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": 1758252104.0,
    "core.end_time": 1758252104.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_0159_df-s05-d4_dw_0_20250918T230138.hdf5"
    }
  ]
}
justIN time: 2025-09-19 08:53:22 UTC       justIN version: 01.05.00