Jobsub ID 236429.11@dunegpschedd01.fnal.gov

Jobsub ID	236429.11@dunegpschedd01.fnal.gov
Workflow ID	9152
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-19 13:40:38
Site	UK_RAL-Tier1
Entry	LIGO_UK_RAL_arc_ce01
Last heartbeat	2025-10-19 14:36:57
From worker node	Hostname	dune004-9633831.0-lcg2701.gridpp.rl.ac.uk
	cpuinfo	AMD EPYC 9654 96-Core Processor
	OS release	Scientific Linux release 7.9 (Nitrogen)
	Processors	1
	RSS bytes	4194304000 (4000 MiB)
	Wall seconds limit	216000 (60 hours)
	GPU
	Inner Apptainer?	True
Job state	aborted
Started	2025-10-19 13:43:42
Input files	vd-protodune:np02vd_raw_run040140_1550_df-s04-d0_dw_0_20251019T105731.hdf5
Outputting started	2025-10-19 14:34:00
Output files	vd-protodune-det-reco:np02vd_raw_run040140_1550_df-s04-d0_dw_0_20251019T105731_reco_stage1_20251019T143344_keepup.root
Finished	2025-10-19 14:36:57
List job events Cached HTCondor job logs
Jobscript log (last 10,000 characters)

: 12.22 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.92 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.87 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.58 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.57 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.45 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 10.43 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.52 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.5 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.49 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.47 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.02 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.02 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::Gen::Retagger : 0.01 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[14:32:56.658] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[14:32:56.658] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[14:32:56.658] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[14:32:56.658] I [ timer  ] Timer: Total node execution : 94.0699999500066 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 50397 traces tagged "gauss"
FrameSaver: q=1.29902e+07 n=1022400 tag=gauss
wclsFrameSaver: saving 62146 traces tagged "wiener"
FrameSaver: q=1.39656e+07 n=979052 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 8952 XUs and 10520 XVs -> 883 XUVs
C:0 T:1 1376 XUs and 1713 XVs -> 82 XUVs
C:0 T:2 1695 XUs and 2585 XVs -> 113 XUVs
C:0 T:3 848 XUs and 1137 XVs -> 30 XUVs
C:0 T:4 460 XUs and 648 XVs -> 25 XUVs
C:0 T:5 1169 XUs and 1340 XVs -> 26 XUVs
C:0 T:6 482 XUs and 385 XVs -> 9 XUVs
C:0 T:7 381 XUs and 554 XVs -> 18 XUVs
C:0 T:8 869 XUs and 846 XVs -> 66 XUVs
C:0 T:9 1414 XUs and 1857 XVs -> 97 XUVs
C:0 T:10 3386 XUs and 4201 XVs -> 201 XUVs
C:0 T:11 389 XUs and 486 XVs -> 30 XUVs
C:0 T:12 522 XUs and 531 XVs -> 37 XUVs
C:0 T:13 412 XUs and 517 XVs -> 32 XUVs
C:0 T:14 539 XUs and 476 XVs -> 31 XUVs
C:0 T:15 1582 XUs and 1375 XVs -> 79 XUVs
1759 XUVs total
1164 collection wire objects
1759 potential space points
Neighbour search...
48659 tests to find 12544 neighbours
Iterating with no regularization...
Begin: 7.47656e+09
0 6.79152e+09
1 6.75568e+09
2 6.75528e+09
Now with regularization...
Begin: 6.59739e+09
0 6.59709e+09
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
19-Oct-2025 14:33:44 UTC  Closed output file "np02vd_raw_run040140_1550_df-s04-d0_dw_0_20251019T105731_reco_stage1_20251019T143344_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        87.0518       127.308       200.091       112.685       34.4182        23     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      8.3546e-05    0.00012908    0.000419812   9.8348e-05    7.89192e-05      23     
produce:tpcrawdecoder:PDVDTPCReader               15.8036       17.5689       19.9235       17.3725       1.19772        23     
produce:triggerrawdecoder:PDVDTriggerReader4     0.392883      0.418417      0.603941      0.405992      0.0422104       23     
produce:pdvddaphne:DAPHNEReaderPDVD               2.14442       2.64461       3.17507       2.67213      0.278022        23     
produce:ophit:OpHitFinder                        0.0254491     0.0391956     0.0589777     0.0399563    0.00636454       23     
produce:opflash:OpFlashFinderVerticalDrift      0.000499832   0.00386352     0.010951     0.00313619    0.00281194       23     
produce:wclsdatavd:WireCellToolkit                42.3939       59.2404       96.8397       53.9798       13.6767        23     
produce:gaushit:GausHitFinder                    0.556867       1.16742       1.88301       1.08819      0.290174        23     
produce:nhitsfilter:NumberOfHitsFilter          0.000139149   0.000323326   0.00155428     0.0002592    0.000275286      23     
produce:reco3d:SpacePointSolver                   6.70451       10.3336       18.9828       10.6106       2.93609        23     
produce:hitpdune:DisambigFromSpacePoints         0.107257      0.187354      0.439267      0.153628      0.0833288       23     
produce:pandora:StandardPandora                   11.1834       29.9637       84.3875       21.3746       22.7474        23     
produce:pandoraTrack:LArPandoraTrackCreation     0.392535       0.95446       2.7137       0.823267      0.493533        23     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0225422     0.0353767     0.0551483     0.0355318    0.00841012       23     
[art]:TriggerResults:TriggerResultInserter      3.0165e-05    5.52641e-05   0.000142975   4.8202e-05    2.3822e-05       23     
end_path:out1:RootOutput                         5.098e-06    1.44288e-05   4.1973e-05    1.1547e-05    8.73236e-06      23     
end_path:out1:RootOutput(write)                   3.6416        4.66596       5.47577       4.6996       0.439907        23     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5684.98 MB
  Peak resident set size usage (VmHWM): 3552.51 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040140_1550_df-s04-d0_dw_0_20251019T105731_reco_stage1_20251019T143344_keepup.root
\tHists: np02vd_raw_run040140_1550_df-s04-d0_dw_0_20251019T105731_reco_stage1_20251019T143344_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040140_1550_df-s04-d0_dw_0_20251019T105731_reco_stage1_20251019T143344_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run040140_1550_df-s04-d0_dw_0_20251019T105731_reco_stage1_20251019T143344_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": 1760884425.0,
    "core.end_time": 1760884425.0,
    "core.events": [
      272020,
      272028,
      272036,
      272044,
      272052,
      272060,
      272068,
      272076,
      272084,
      272092,
      272100,
      272108,
      272116,
      272124,
      272132,
      272140,
      272148,
      272156,
      272164,
      272172,
      272180,
      272188,
      272196
    ],
    "core.event_count": 23,
    "core.first_event_number": 272020,
    "core.last_event_number": 272196,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      40140
    ],
    "core.runs_subruns": [
      4014000001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run040140_1550_df-s04-d0_dw_0_20251019T105731.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run040140_1550_df-s04-d0_dw_0_20251019T105731_reco_stage1_20251019T143344_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": 1760884425.0,
    "core.end_time": 1760884425.0,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      40140
    ],
    "core.runs_subruns": [
      4014000001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run040140_1550_df-s04-d0_dw_0_20251019T105731.hdf5"
    }
  ]
}