Jobsub ID 37940.93@dunegpschedd02.fnal.gov

Jobsub ID	37940.93@dunegpschedd02.fnal.gov
Workflow ID	2482
Stage ID	1
User name	ykermaid@fnal.gov
HTCondor Group	group_dune.prod_mcsim
Requested	Processors	1
	GPU	No
	RSS bytes	4193255424 (3999 MiB)
	Wall seconds limit	18000 (5 hours)
Submitted time	2025-09-11 00:49:59
Site	CERN
Entry	CMSHTPC_T2_CH_CERN_ce508
Last heartbeat	2025-09-11 03:05:21
From worker node	Hostname	b9p07p9037.cern.ch
	cpuinfo	AMD EPYC 7543 32-Core Processor
	OS release	Scientific Linux release 7.9 (Nitrogen)
	Processors	1
	RSS bytes	4194304000 (4000 MiB)
	Wall seconds limit	343800 (95 hours)
	GPU
	Inner Apptainer?	True
Job state	aborted
Started	2025-09-11 01:57:28
Input files	vd-protodune:np02vd_raw_run039255_1144_df-s05-d0_dw_0_20250830T224452.hdf5
Outputting started	2025-09-11 03:00:32
Output files	vd-protodune-det-reco:np02vd_raw_run039255_1144_df-s05-d0_dw_0_20250830T224452_reco_stage1_20250911T030010_keepup.root
Finished	2025-09-11 03:05:21
List job events Cached HTCondor job logs
Jobscript log (last 10,000 characters)

: 9.76 sec
[04:56:50.864] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 9.25 sec
[04:56:50.864] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 9.1 sec
[04:56:50.864] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 8.51 sec
[04:56:50.864] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 6.21 sec
[04:56:50.864] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.74 sec
[04:56:50.864] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.57 sec
[04:56:50.864] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 5.53 sec
[04:56:50.864] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.67 sec
[04:56:50.864] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.64 sec
[04:56:50.864] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.64 sec
[04:56:50.864] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.62 sec
[04:56:50.864] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.01 sec
[04:56:50.865] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[04:56:50.865] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[04:56:50.865] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.01 sec
[04:56:50.865] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:56:50.865] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:56:50.865] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:56:50.865] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:56:50.865] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[04:56:50.865] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:56:50.865] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[04:56:50.865] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[04:56:50.865] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[04:56:50.865] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[04:56:50.865] I [ timer  ] Timer: Total node execution : 62.280001021921635 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 61126 traces tagged "gauss"
FrameSaver: q=1.22895e+07 n=1365222 tag=gauss
wclsFrameSaver: saving 76669 traces tagged "wiener"
FrameSaver: q=1.32303e+07 n=1300041 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 65 XUs and 105 XVs -> 8 XUVs
C:0 T:1 285 XUs and 270 XVs -> 14 XUVs
C:0 T:2 706 XUs and 664 XVs -> 45 XUVs
C:0 T:3 341 XUs and 395 XVs -> 21 XUVs
C:0 T:4 594 XUs and 668 XVs -> 21 XUVs
C:0 T:5 704 XUs and 470 XVs -> 39 XUVs
C:0 T:6 1284 XUs and 1047 XVs -> 56 XUVs
C:0 T:7 109 XUs and 134 XVs -> 6 XUVs
C:0 T:8 18453 XUs and 40077 XVs -> 7854 XUVs
C:0 T:9 6241 XUs and 9085 XVs -> 1264 XUVs
C:0 T:10 5680 XUs and 7521 XVs -> 939 XUVs
C:0 T:11 15956 XUs and 25837 XVs -> 3772 XUVs
C:0 T:12 19079 XUs and 32130 XVs -> 3268 XUVs
C:0 T:13 6832 XUs and 5519 XVs -> 720 XUVs
C:0 T:14 3847 XUs and 5038 XVs -> 521 XUVs
C:0 T:15 29070 XUs and 41644 XVs -> 7854 XUVs
26402 XUVs total
3307 collection wire objects
26402 potential space points
Neighbour search...
4385178 tests to find 1763012 neighbours
Iterating with no regularization...
Begin: 1.78622e+09
0 1.66611e+09
1 1.65791e+09
2 1.65662e+09
Now with regularization...
Begin: 1.60899e+09
0 1.60829e+09
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLArTPCIntercepts - inconsistent number of intercepts between a line and the LArTPC
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
11-Sep-2025 05:00:10 CEST  Closed output file "np02vd_raw_run039255_1144_df-s05-d0_dw_0_20250830T224452_reco_stage1_20250911T030010_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        138.015       207.034       352.581       190.401       59.4521        18     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      4.8241e-05    9.99323e-05   0.000164041   0.000103501   3.20764e-05      18     
produce:tpcrawdecoder:PDVDTPCReader               5.90978       6.32431       6.70203       6.33121      0.228731        18     
produce:triggerrawdecoder:PDVDTriggerReader4    0.000970186   0.00290639     0.0143686    0.00152432    0.00336809       18     
produce:pdvddaphne:DAPHNEReaderPDVD               3.61571       4.07318       4.54163       4.01671      0.283624        18     
produce:ophit:OpHitFinder                        0.0287024     0.0470886     0.0620253     0.0476385    0.00786929       18     
produce:opflash:OpFlashFinderVerticalDrift      0.00618734     0.0137659     0.0219121     0.0138251    0.00400366       18     
produce:wclsdatavd:WireCellToolkit                55.0184       68.5002       93.2099       68.7072       9.10485        18     
produce:gaushit:GausHitFinder                     1.01794       1.56459       2.15101       1.59881      0.361316        18     
produce:nhitsfilter:NumberOfHitsFilter          0.000270352   0.000394174   0.000583813   0.000382962   8.87985e-05      18     
produce:reco3d:SpacePointSolver                   13.2693       20.7416       31.0988       20.275        5.34068        18     
produce:hitpdune:DisambigFromSpacePoints         0.195481      0.354537      0.650047      0.336682       0.12794        18     
produce:pandora:StandardPandora                   50.6642       98.6482       229.366       82.0388       49.9915        18     
produce:pandoraTrack:LArPandoraTrackCreation     0.925593       1.48664       2.63506       1.40546      0.449791        18     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0216924     0.0380968     0.0723818     0.036703      0.0129888       18     
[art]:TriggerResults:TriggerResultInserter       1.52e-05     2.55807e-05    4.913e-05    2.3395e-05    8.27187e-06      18     
end_path:out1:RootOutput                         5.89e-06     8.8135e-06    2.3761e-05     7.96e-06     3.74747e-06      18     
end_path:out1:RootOutput(write)                   4.2703        5.20301       7.01925       4.9702       0.702495        18     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5213.67 MB
  Peak resident set size usage (VmHWM): 3254.54 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039255_1144_df-s05-d0_dw_0_20250830T224452_reco_stage1_20250911T030010_keepup.root
\tHists: np02vd_raw_run039255_1144_df-s05-d0_dw_0_20250830T224452_reco_stage1_20250911T030010_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039255_1144_df-s05-d0_dw_0_20250830T224452_reco_stage1_20250911T030010_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run039255_1144_df-s05-d0_dw_0_20250830T224452_reco_stage1_20250911T030010_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": 1757559611.0,
    "core.end_time": 1757559611.0,
    "core.events": [
      411847,
      411867,
      411887,
      411907,
      411927,
      411947,
      411967,
      411987,
      412007,
      412027,
      412047,
      412067,
      412087,
      412107,
      412127,
      412147,
      412167,
      412187
    ],
    "core.event_count": 18,
    "core.first_event_number": 411847,
    "core.last_event_number": 412187,
    "core.data_stream": "physics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39255
    ],
    "core.runs_subruns": [
      3925500001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039255_1144_df-s05-d0_dw_0_20250830T224452.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run039255_1144_df-s05-d0_dw_0_20250830T224452_reco_stage1_20250911T030010_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": 1757559611.0,
    "core.end_time": 1757559611.0,
    "core.data_stream": "physics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39255
    ],
    "core.runs_subruns": [
      3925500001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039255_1144_df-s05-d0_dw_0_20250830T224452.hdf5"
    }
  ]
}