Jobsub ID 236147.50@dunegpschedd01.fnal.gov

Jobsub ID	236147.50@dunegpschedd01.fnal.gov
Workflow ID	9143
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-18 21:31:46
Site	CZ_FZU
Entry	Nova_CZ_FZU_osgce1
Last heartbeat	2025-10-18 23:01:57
From worker node	Hostname	lupin22.farm.particle.cz
	cpuinfo	AMD EPYC 7551 32-Core Processor
	OS release	Scientific Linux release 7.9 (Nitrogen)
	Processors	1
	RSS bytes	4194304000 (4000 MiB)
	Wall seconds limit	172800 (48 hours)
	GPU
	Inner Apptainer?	True
Job state	aborted
Started	2025-10-18 21:52:04
Input files	vd-protodune:np02vd_raw_run040140_0973_df-s03-d2_dw_0_20251018T191824.hdf5
Outputting started	2025-10-18 22:48:11
Output files	vd-protodune-det-reco:np02vd_raw_run040140_0973_df-s03-d2_dw_0_20251018T191824_reco_stage1_20251018T224802_keepup.root
Finished	2025-10-18 23:01:57
List job events Cached HTCondor job logs
Jobscript log (last 10,000 characters)

plete 
[00:46:11.088] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 12.85 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 12.59 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.66 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 11.19 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 10.89 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 10.7 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 9.91 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::SigProc::OmnibusSigProc : 9.09 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.45 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.42 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.42 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::Aux::Resampler : 0.41 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::Gen::FrameFanin : 0.03 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0.01 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::Gen::DumpFrames : 0 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::SigProc::ChannelSelector : 0 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::Gen::Retagger : 0 sec
[00:46:11.088] I [ timer  ] Timer: WireCell::Gen::FrameFanout : 0 sec
[00:46:11.088] I [ timer  ] Timer: wcls::RawFrameSource : 0 sec
[00:46:11.088] I [ timer  ] Timer: wcls::FrameSaver : 0 sec
[00:46:11.088] I [ timer  ] Timer: Total node execution : 90.64000007137656 sec
wclsFrameSaver saving cooked to 10000 ticks
wclsFrameSaver: saving 66904 traces tagged "gauss"
FrameSaver: q=1.78828e+07 n=1383562 tag=gauss
wclsFrameSaver: saving 82324 traces tagged "wiener"
FrameSaver: q=1.92597e+07 n=1327155 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 268 XUs and 431 XVs -> 22 XUVs
C:0 T:1 74 XUs and 134 XVs -> 0 XUVs
C:0 T:2 795 XUs and 877 XVs -> 21 XUVs
C:0 T:3 117 XUs and 144 XVs -> 3 XUVs
C:0 T:4 2210 XUs and 2119 XVs -> 84 XUVs
C:0 T:5 21063 XUs and 20520 XVs -> 1636 XUVs
C:0 T:6 12561 XUs and 15892 XVs -> 796 XUVs
C:0 T:7 5487 XUs and 5140 XVs -> 369 XUVs
C:0 T:8 1019 XUs and 1131 XVs -> 57 XUVs
C:0 T:9 2033 XUs and 2732 XVs -> 175 XUVs
C:0 T:10 1451 XUs and 1670 XVs -> 111 XUVs
C:0 T:11 1366 XUs and 1656 XVs -> 101 XUVs
C:0 T:12 1688 XUs and 1932 XVs -> 159 XUVs
C:0 T:13 1274 XUs and 1378 XVs -> 95 XUVs
C:0 T:14 684 XUs and 816 XVs -> 33 XUVs
C:0 T:15 2882 XUs and 2893 XVs -> 104 XUVs
3766 XUVs total
2161 collection wire objects
3766 potential space points
Neighbour search...
119756 tests to find 28568 neighbours
Iterating with no regularization...
Begin: 2.15628e+10
0 2.0541e+10
1 2.05107e+10
2 2.051e+10
Now with regularization...
Begin: 1.9882e+10
0 1.98804e+10
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
19-Oct-2025 00:48:02 CEST  Closed output file "np02vd_raw_run040140_0973_df-s03-d2_dw_0_20251018T191824_reco_stage1_20251018T224802_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        93.525        150.633       237.831       152.199       35.7496        22     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                       5.309e-05    7.81996e-05   0.000199921   7.48255e-05   2.90983e-05      22     
produce:tpcrawdecoder:PDVDTPCReader               13.0753       13.9323       16.0484       13.6396       0.85727        22     
produce:triggerrawdecoder:PDVDTriggerReader4      0.26767      0.280482      0.318294      0.274416      0.0123682       22     
produce:pdvddaphne:DAPHNEReaderPDVD               2.13637       2.39635        2.801        2.40055      0.187761        22     
produce:ophit:OpHitFinder                        0.029563      0.0371503     0.052051      0.0369301    0.00530553       22     
produce:opflash:OpFlashFinderVerticalDrift      0.000728462   0.00372058     0.0113366    0.00249456    0.00289025       22     
produce:wclsdatavd:WireCellToolkit                45.0787       73.1705       97.6615       73.182        15.1737        22     
produce:gaushit:GausHitFinder                    0.908843       1.23462       1.79931       1.16713      0.251142        22     
produce:nhitsfilter:NumberOfHitsFilter          0.000246691   0.000398274   0.000576782   0.000399997   8.58071e-05      22     
produce:reco3d:SpacePointSolver                   9.03985       13.3701       22.9334       12.6382       3.4241         22     
produce:hitpdune:DisambigFromSpacePoints          0.11915      0.203523      0.387667      0.198965      0.0673326       22     
produce:pandora:StandardPandora                   13.3647       37.7448       88.1716       36.5748       18.7657        22     
produce:pandoraTrack:LArPandoraTrackCreation     0.561874       1.2426        2.99063       1.08001      0.587337        22     
produce:pandoraGnocalo:GnocchiCalorimetry        0.022219      0.0346868     0.056248      0.032906     0.00755618       22     
[art]:TriggerResults:TriggerResultInserter       1.594e-05    3.15633e-05   0.00010511    2.2025e-05    2.12075e-05      22     
end_path:out1:RootOutput                         4.86e-06      8.665e-06     2.672e-05     7.28e-06     4.41284e-06      22     
end_path:out1:RootOutput(write)                   5.07023       6.92199       10.3326       6.86954       1.26031        22     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5237.26 MB
  Peak resident set size usage (VmHWM): 3352.76 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run040140_0973_df-s03-d2_dw_0_20251018T191824_reco_stage1_20251018T224802_keepup.root
\tHists: np02vd_raw_run040140_0973_df-s03-d2_dw_0_20251018T191824_reco_stage1_20251018T224802_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run040140_0973_df-s03-d2_dw_0_20251018T191824_reco_stage1_20251018T224802_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run040140_0973_df-s03-d2_dw_0_20251018T191824_reco_stage1_20251018T224802_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": 1760827683.0,
    "core.end_time": 1760827683.0,
    "core.events": [
      170946,
      170954,
      170962,
      170970,
      170978,
      170986,
      170994,
      171002,
      171010,
      171018,
      171026,
      171034,
      171042,
      171050,
      171058,
      171066,
      171074,
      171082,
      171090,
      171098,
      171106,
      171114
    ],
    "core.event_count": 22,
    "core.first_event_number": 170946,
    "core.last_event_number": 171114,
    "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_0973_df-s03-d2_dw_0_20251018T191824.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run040140_0973_df-s03-d2_dw_0_20251018T191824_reco_stage1_20251018T224802_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": 1760827683.0,
    "core.end_time": 1760827683.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_0973_df-s03-d2_dw_0_20251018T191824.hdf5"
    }
  ]
}