justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

Jobsub ID 242849.84@dunegpschedd01.fnal.gov

Jobsub ID242849.84@dunegpschedd01.fnal.gov
Workflow ID9583
Stage ID1
User nameykermaid@fnal.gov
HTCondor Groupgroup_dune.prod_mcsim
RequestedProcessors1
GPUNo
RSS bytes4193255424 (3999 MiB)
Wall seconds limit18000 (5 hours)
Submitted time2025-11-04 08:47:54
SiteCERN
EntryCMSHTPC_T2_CH_CERN_ce511
Last heartbeat2025-11-04 11:01:45
From worker nodeHostnameb9p05p8732.cern.ch
cpuinfoAMD EPYC 7543 32-Core Processor
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4194304000 (4000 MiB)
Wall seconds limit343800 (95 hours)
GPU
Inner Apptainer?True
Job statefinished
Started2025-11-04 10:10:42
Input filesvd-protodune:np02vd_raw_run040348_0181_df-s04-d3_dw_0_20251103T213829.hdf5
JobscriptExit code0
Real time48m (2910s)
CPU time46m (2789s = 95%)
Max RSS bytes3052183552 (2910 MiB)
Outputting started2025-11-04 10:59:13
Output filesvd-protodune-det-reco:np02vd_raw_run040348_0181_df-s04-d3_dw_0_20251103T213829_reco_stage1_20251104T105900_keepup.root
Finished2025-11-04 11:01:45
Saved logsjustin-logs:242849.84-dunegpschedd01.fnal.gov.logs.tgz
List job events     (HTCondor job logs unavailable)

Jobscript log (last 10,000 characters)

busSigProc : 14.03 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 12.01 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.66 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.28 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.76 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 7.03 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 6.79 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.49 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.46 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.43 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.31 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.01 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:57:45.739] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:57:45.739] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[11:57:45.739] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[11:57:45.739] I [ timer  ] Timer: Total node execution : 84.60999993048608 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 65408 traces tagged "gauss"
FrameSaver: q=1.81057e+07 n=1447004 tag=gauss
wclsFrameSaver: saving 80836 traces tagged "wiener"
FrameSaver: q=1.93061e+07 n=1391467 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 866 XUs and 1053 XVs -> 53 XUVs
C:0 T:1 520 XUs and 385 XVs -> 3 XUVs
C:0 T:2 3495 XUs and 4376 XVs -> 96 XUVs
C:0 T:3 534 XUs and 604 XVs -> 25 XUVs
C:0 T:4 2345 XUs and 2857 XVs -> 145 XUVs
C:0 T:5 691 XUs and 801 XVs -> 28 XUVs
C:0 T:6 604 XUs and 816 XVs -> 31 XUVs
C:0 T:7 24893 XUs and 33239 XVs -> 2132 XUVs
C:0 T:8 687 XUs and 663 XVs -> 44 XUVs
C:0 T:9 2485 XUs and 2276 XVs -> 129 XUVs
C:0 T:10 1668 XUs and 2133 XVs -> 92 XUVs
C:0 T:11 2684 XUs and 3489 XVs -> 130 XUVs
C:0 T:12 3199 XUs and 2224 XVs -> 185 XUVs
C:0 T:13 236 XUs and 284 XVs -> 11 XUVs
C:0 T:14 2374 XUs and 1588 XVs -> 123 XUVs
C:0 T:15 1281 XUs and 1350 XVs -> 66 XUVs
3293 XUVs total
1919 collection wire objects
3293 potential space points
Neighbour search...
83013 tests to find 19502 neighbours
Iterating with no regularization...
Begin: 3.03511e+10
0 2.96469e+10
1 2.96056e+10
2 2.96053e+10
Now with regularization...
Begin: 2.91059e+10
0 2.91051e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
04-Nov-2025 11:59:00 CET  Closed output file "np02vd_raw_run040348_0181_df-s04-d3_dw_0_20251103T213829_reco_stage1_20251104T105900_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        78.1252       123.914       310.831       113.557       48.4503        23     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      6.4972e-05    0.000118243   0.000324019   0.000111244   5.4763e-05       23     
produce:tpcrawdecoder:PDVDTPCReader               5.12671       5.97929       7.47419       5.68389      0.677576        23     
produce:triggerrawdecoder:PDVDTriggerReader4     0.0147375      0.02127      0.0497576     0.0182771    0.00885862       23     
produce:pdvddaphne:DAPHNEReaderPDVD             0.000304868   0.000418697   0.000911436   0.00037122    0.000125281      23     
produce:ophit:OpHitFinder                       6.3602e-05    0.000148031   0.000832074   0.000119354   0.00014772       23     
produce:opflash:OpFlashFinderVerticalDrift      5.3942e-05    9.03278e-05   0.000436972   7.1422e-05    7.47599e-05      23     
produce:wclsdatavd:WireCellToolkit                45.402        63.971        95.8804       59.0728       14.1366        23     
produce:gaushit:GausHitFinder                    0.698383       1.35407       1.87338       1.35028      0.331236        23     
produce:nhitsfilter:NumberOfHitsFilter          0.000184655   0.000297344   0.000600188   0.000279428   8.44283e-05      23     
produce:reco3d:SpacePointSolver                   5.1217        12.865        32.7619       11.9192       5.54144        23     
produce:hitpdune:DisambigFromSpacePoints         0.0780498      0.20055       0.45078      0.176779      0.102019        23     
produce:pandora:StandardPandora                   11.2025       33.7439       166.874       22.5694       31.5401        23     
produce:pandoraTrack:LArPandoraTrackCreation     0.304172      0.935266       2.59353      0.877528      0.466305        23     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0123476     0.0306419     0.0500966     0.0297396     0.0101318       23     
[art]:TriggerResults:TriggerResultInserter      1.2271e-05    2.28163e-05   4.7431e-05     2.112e-05    8.0991e-06       23     
end_path:out1:RootOutput                         3.76e-06     8.78896e-06   2.7831e-05     7.211e-06    5.3008e-06       23     
end_path:out1:RootOutput(write)                   3.3852        4.7709        5.94584       4.88848      0.635015        23     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4978.03 MB
  Peak resident set size usage (VmHWM): 3052.18 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040348_0181_df-s04-d3_dw_0_20251103T213829_reco_stage1_20251104T105900_keepup.root
\tHists: np02vd_raw_run040348_0181_df-s04-d3_dw_0_20251103T213829_reco_stage1_20251104T105900_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040348_0181_df-s04-d3_dw_0_20251103T213829_reco_stage1_20251104T105900_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run040348_0181_df-s04-d3_dw_0_20251103T213829_reco_stage1_20251104T105900_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": 1762253940.0,
    "core.end_time": 1762253940.0,
    "core.events": [
      16743,
      16747,
      16751,
      16755,
      16759,
      16763,
      16767,
      16771,
      16775,
      16779,
      16783,
      16787,
      16791,
      16795,
      16799,
      16803,
      16807,
      16811,
      16815,
      16819,
      16823,
      16827,
      16831
    ],
    "core.event_count": 23,
    "core.first_event_number": 16743,
    "core.last_event_number": 16831,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      40348
    ],
    "core.runs_subruns": [
      4034800001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run040348_0181_df-s04-d3_dw_0_20251103T213829.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run040348_0181_df-s04-d3_dw_0_20251103T213829_reco_stage1_20251104T105900_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": 1762253940.0,
    "core.end_time": 1762253940.0,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      40348
    ],
    "core.runs_subruns": [
      4034800001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run040348_0181_df-s04-d3_dw_0_20251103T213829.hdf5"
    }
  ]
}
justIN time: 2025-11-05 07:21:31 UTC       justIN version: 01.05.01