Jobsub ID 239995.71@dunegpschedd01.fnal.gov

Jobsub ID	239995.71@dunegpschedd01.fnal.gov
Workflow ID	9374
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-10-29 08:08:13
Site	UK_Durham
Entry	DUNE_UK_SGridDurham_ce4
Last heartbeat	2025-10-29 12:29:42
From worker node	Hostname	n198.dur.scotgrid.ac.uk
	cpuinfo	Intel(R) Xeon(R) Gold 5220 CPU @ 2.20GHz
	OS release	Scientific Linux release 7.9 (Nitrogen)
	Processors	1
	RSS bytes	4194304000 (4000 MiB)
	Wall seconds limit	171000 (47 hours)
	GPU
	Inner Apptainer?	True
Job state	aborted
Started	2025-10-29 09:12:47
Input files	vd-protodune:np02vd_raw_run040266_0646_df-s03-d0_dw_0_20251025T151935.hdf5
Outputting started	2025-10-29 11:50:44
Output files	vd-protodune-det-reco:np02vd_raw_run040266_0646_df-s03-d0_dw_0_20251025T151935_reco_stage1_20251029T115034_keepup.root
Finished	2025-10-29 12:29:42
List job events Cached HTCondor job logs
Jobscript log (last 10,000 characters)

[11:49:19.915] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 15.72 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 14.89 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 14.61 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 14.36 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 14.31 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 13.16 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 12.98 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 12.9 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.46 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.46 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.45 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.45 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.04 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.02 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[11:49:19.915] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[11:49:19.915] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[11:49:19.915] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[11:49:19.915] I [ timer  ] Timer: Total node execution : 114.82999934256077 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 53172 traces tagged "gauss"
FrameSaver: q=1.43352e+07 n=1137266 tag=gauss
wclsFrameSaver: saving 65184 traces tagged "wiener"
FrameSaver: q=1.52326e+07 n=1089765 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 560 XUs and 620 XVs -> 26 XUVs
C:0 T:1 125 XUs and 190 XVs -> 5 XUVs
C:0 T:2 27 XUs and 36 XVs -> 0 XUVs
C:0 T:3 610 XUs and 652 XVs -> 22 XUVs
C:0 T:4 1572 XUs and 1674 XVs -> 87 XUVs
C:0 T:5 2626 XUs and 2771 XVs -> 128 XUVs
C:0 T:6 25976 XUs and 26894 XVs -> 2004 XUVs
C:0 T:7 1022 XUs and 1008 XVs -> 32 XUVs
C:0 T:8 274 XUs and 368 XVs -> 23 XUVs
C:0 T:9 584 XUs and 731 XVs -> 60 XUVs
C:0 T:10 1681 XUs and 2294 XVs -> 113 XUVs
C:0 T:11 401 XUs and 459 XVs -> 27 XUVs
C:0 T:12 2462 XUs and 3264 XVs -> 176 XUVs
C:0 T:13 1278 XUs and 1606 XVs -> 76 XUVs
C:0 T:14 1113 XUs and 1936 XVs -> 88 XUVs
C:0 T:15 1117 XUs and 1325 XVs -> 64 XUVs
2931 XUVs total
1782 collection wire objects
2931 potential space points
Neighbour search...
81125 tests to find 18046 neighbours
Iterating with no regularization...
Begin: 1.38501e+10
0 1.26752e+10
1 1.26209e+10
2 1.2619e+10
Now with regularization...
Begin: 1.24227e+10
0 1.24218e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
29-Oct-2025 11:50:34 GMT  Closed output file "np02vd_raw_run040266_0646_df-s03-d0_dw_0_20251025T151935_reco_stage1_20251029T115034_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        239.189       409.406       1330.58       338.875       221.341        23     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      5.8046e-05    8.3497e-05    0.000176348   7.2375e-05    2.75765e-05      23     
produce:tpcrawdecoder:PDVDTPCReader               109.565       183.96        291.23        180.433       53.8929        23     
produce:triggerrawdecoder:PDVDTriggerReader4     0.944388       1.39625       2.13053       1.27568      0.344162        23     
produce:pdvddaphne:DAPHNEReaderPDVD             0.000414528   0.000494485   0.00111782    0.000443819   0.00015475       23     
produce:ophit:OpHitFinder                       6.1984e-05    0.000260401   0.00400627    9.1875e-05    0.000798898      23     
produce:opflash:OpFlashFinderVerticalDrift      4.9337e-05    7.45446e-05   0.000344587   6.2021e-05    5.83121e-05      23     
produce:wclsdatavd:WireCellToolkit                71.8391       106.111       188.477       104.696       33.4548        23     
produce:gaushit:GausHitFinder                    0.810445       1.74274       4.18292       1.59222       0.72077        23     
produce:nhitsfilter:NumberOfHitsFilter          0.000236071   0.000412041    0.0010904    0.00036292    0.000173386      23     
produce:reco3d:SpacePointSolver                   6.24702       16.2811       48.4949       13.6684       9.42976        23     
produce:hitpdune:DisambigFromSpacePoints         0.112124      0.345397       1.44999      0.255627      0.298298        23     
produce:pandora:StandardPandora                   13.3846       91.871        901.541       33.4956       182.323        23     
produce:pandoraTrack:LArPandoraTrackCreation     0.533994       2.59517       16.446        1.39256       3.70595        23     
produce:pandoraGnocalo:GnocchiCalorimetry        0.024357      0.0385839     0.0851798     0.0365896     0.0133911       23     
[art]:TriggerResults:TriggerResultInserter      2.2539e-05    2.9972e-05     8.134e-05    2.6546e-05    1.1597e-05       23     
end_path:out1:RootOutput                         4.191e-06    8.23317e-06    2.506e-05     7.745e-06    3.94531e-06      23     
end_path:out1:RootOutput(write)                   4.36615       5.01126       6.67092       4.89388      0.576522        23     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 6447.69 MB
  Peak resident set size usage (VmHWM): 4178.13 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040266_0646_df-s03-d0_dw_0_20251025T151935_reco_stage1_20251029T115034_keepup.root
\tHists: np02vd_raw_run040266_0646_df-s03-d0_dw_0_20251025T151935_reco_stage1_20251029T115035_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040266_0646_df-s03-d0_dw_0_20251025T151935_reco_stage1_20251029T115034_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run040266_0646_df-s03-d0_dw_0_20251025T151935_reco_stage1_20251029T115034_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": 1761738636.0,
    "core.end_time": 1761738636.0,
    "core.events": [
      119200,
      119208,
      119216,
      119224,
      119232,
      119240,
      119248,
      119256,
      119264,
      119272,
      119280,
      119288,
      119296,
      119304,
      119312,
      119320,
      119328,
      119336,
      119344,
      119352,
      119360,
      119368,
      119376
    ],
    "core.event_count": 23,
    "core.first_event_number": 119200,
    "core.last_event_number": 119376,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      40266
    ],
    "core.runs_subruns": [
      4026600001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run040266_0646_df-s03-d0_dw_0_20251025T151935.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run040266_0646_df-s03-d0_dw_0_20251025T151935_reco_stage1_20251029T115035_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": 1761738636.0,
    "core.end_time": 1761738636.0,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      40266
    ],
    "core.runs_subruns": [
      4026600001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run040266_0646_df-s03-d0_dw_0_20251025T151935.hdf5"
    }
  ]
}