Jobsub ID 40372.174@dunegpschedd02.fnal.gov

Jobsub ID	40372.174@dunegpschedd02.fnal.gov
Workflow ID	2641
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-16 08:50:46
Site	CERN
Entry	CMSHTPC_T2_CH_CERN_ce504
Last heartbeat	2025-09-16 09:56:18
From worker node	Hostname	b9p12p2405.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	finished
Started	2025-09-16 08:52:27
Input files	vd-protodune:np02vd_raw_run039353_0835_df-s05-d1_dw_0_20250916T083424.hdf5
Jobscript	Exit code	0
	Real time	1h (3759s)
	CPU time	59m (3597s = 95%)
	Max RSS bytes	2862637056 (2730 MiB)
Outputting started	2025-09-16 09:55:07
Output files	vd-protodune-det-reco:np02vd_raw_run039353_0835_df-s05-d1_dw_0_20250916T083424_reco_stage1_20250916T095456_keepup.root
Finished	2025-09-16 09:56:18
Saved logs	justin-logs:40372.174-dunegpschedd02.fnal.gov.logs.tgz
List job events Cached HTCondor job logs

Jobscript log (last 10,000 characters)

10891
wclsFrameSaver: no samples within desired window for channel 10917
wclsFrameSaver: no samples within desired window for channel 10924
wclsFrameSaver: no samples within desired window for channel 11006
wclsFrameSaver: no samples within desired window for channel 11441
wclsFrameSaver: no samples within desired window for channel 11443
wclsFrameSaver: no samples within desired window for channel 11444
wclsFrameSaver: no samples within desired window for channel 11444
wclsFrameSaver: no samples within desired window for channel 11444
wclsFrameSaver: no samples within desired window for channel 11444
wclsFrameSaver: no samples within desired window for channel 11445
wclsFrameSaver: no samples within desired window for channel 11570
wclsFrameSaver: no samples within desired window for channel 11571
wclsFrameSaver: no samples within desired window for channel 11571
wclsFrameSaver: no samples within desired window for channel 11572
wclsFrameSaver: no samples within desired window for channel 12154
wclsFrameSaver: no samples within desired window for channel 12154
wclsFrameSaver: no samples within desired window for channel 12154
wclsFrameSaver: no samples within desired window for channel 12219
wclsFrameSaver: no samples within desired window for channel 12219
wclsFrameSaver: no samples within desired window for channel 12220
wclsFrameSaver: no samples within desired window for channel 12220
wclsFrameSaver: no samples within desired window for channel 12221
wclsFrameSaver: no samples within desired window for channel 12222
wclsFrameSaver: no samples within desired window for channel 12223
wclsFrameSaver: no samples within desired window for channel 12223
wclsFrameSaver: no samples within desired window for channel 12243
wclsFrameSaver: no samples within desired window for channel 12244
wclsFrameSaver: no samples within desired window for channel 12244
wclsFrameSaver: no samples within desired window for channel 12245
wclsFrameSaver: no samples within desired window for channel 12245
wclsFrameSaver: no samples within desired window for channel 12246
wclsFrameSaver: no samples within desired window for channel 12247
wclsFrameSaver: no samples within desired window for channel 12247
wclsFrameSaver: no samples within desired window for channel 12248
wclsFrameSaver: no samples within desired window for channel 12248
wclsFrameSaver: no samples within desired window for channel 12249
wclsFrameSaver: no samples within desired window for channel 12249
FrameSaver: q=8.37088e+06 n=906927 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 150 XUs and 161 XVs -> 5 XUVs
C:0 T:1 996 XUs and 1123 XVs -> 46 XUVs
C:0 T:2 1921 XUs and 1782 XVs -> 108 XUVs
C:0 T:3 592 XUs and 911 XVs -> 47 XUVs
C:0 T:4 195 XUs and 204 XVs -> 11 XUVs
C:0 T:5 947 XUs and 891 XVs -> 30 XUVs
C:0 T:6 134 XUs and 138 XVs -> 3 XUVs
C:0 T:7 336 XUs and 473 XVs -> 30 XUVs
C:0 T:8 2466 XUs and 2615 XVs -> 125 XUVs
C:0 T:9 4715 XUs and 4518 XVs -> 231 XUVs
C:0 T:10 997 XUs and 924 XVs -> 64 XUVs
C:0 T:11 2656 XUs and 2893 XVs -> 131 XUVs
C:0 T:12 514 XUs and 519 XVs -> 40 XUVs
C:0 T:13 1773 XUs and 1584 XVs -> 98 XUVs
C:0 T:14 397 XUs and 488 XVs -> 15 XUVs
C:0 T:15 1580 XUs and 2048 XVs -> 70 XUVs
1054 XUVs total
945 collection wire objects
1054 potential space points
Neighbour search...
5198 tests to find 2568 neighbours
Iterating with no regularization...
Begin: 3.11052e+08
0 3.06493e+08
1 3.06476e+08
Now with regularization...
Begin: 3.0229e+08
0 3.0229e+08
BdtBeamParticleIdTool::SliceFeatures::GetLeadingCaloHits - empty calo hit list
16-Sep-2025 11:54:56 CEST  Closed output file "np02vd_raw_run039353_0835_df-s05-d1_dw_0_20250916T083424_reco_stage1_20250916T095456_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        107.607       185.68        962.099       138.329       180.959        20     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      5.3291e-05    0.000101259   0.000157791   0.000107246   3.10386e-05      20     
produce:tpcrawdecoder:PDVDTPCReader               2.01646       8.91308       26.5526       7.97895       4.56406        20     
produce:triggerrawdecoder:PDVDTriggerReader4    0.00588768    0.00877109     0.036625     0.00713503    0.00647932       20     
produce:pdvddaphne:DAPHNEReaderPDVD             0.000186992   0.000330709   0.000864947   0.000309837   0.000131089      20     
produce:ophit:OpHitFinder                       0.000113191   0.000272934   0.00116833    0.000223307   0.000220437      20     
produce:opflash:OpFlashFinderVerticalDrift      4.0931e-05    8.11881e-05   0.000462494   6.10905e-05   8.80864e-05      20     
produce:wclsdatavd:WireCellToolkit                29.8268       61.3731       87.1659       62.5601       10.9881        20     
produce:gaushit:GausHitFinder                    0.726801       1.23145       2.14757       1.23753      0.308196        20     
produce:nhitsfilter:NumberOfHitsFilter          0.000200612   0.000286456   0.000605415   0.000262017   9.05966e-05      20     
produce:reco3d:SpacePointSolver                   8.21738       13.4324       22.3898       13.5492       3.29038        20     
produce:hitpdune:DisambigFromSpacePoints         0.114618      0.199227      0.449822       0.18032      0.0890091       20     
produce:pandora:StandardPandora                   24.1105       94.8335       908.852       49.9912       187.933        20     
produce:pandoraTrack:LArPandoraTrackCreation     0.613096       1.2172        4.99417       0.92615      0.922748        20     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0168222     0.0282855     0.0410746     0.027398      0.0071661       20     
[art]:TriggerResults:TriggerResultInserter       1.655e-05    2.71881e-05    5.193e-05    2.3765e-05    1.05471e-05      20     
end_path:out1:RootOutput                         4.31e-06     9.00605e-06    3.017e-05     8.02e-06     5.24841e-06      20     
end_path:out1:RootOutput(write)                   1.6654        4.40352       6.28404       4.13697      0.954072        20     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4825.74 MB
  Peak resident set size usage (VmHWM): 2862.64 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039353_0835_df-s05-d1_dw_0_20250916T083424_reco_stage1_20250916T095456_keepup.root
\tHists: np02vd_raw_run039353_0835_df-s05-d1_dw_0_20250916T083424_reco_stage1_20250916T095456_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039353_0835_df-s05-d1_dw_0_20250916T083424_reco_stage1_20250916T095456_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run039353_0835_df-s05-d1_dw_0_20250916T083424_reco_stage1_20250916T095456_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": 1758016497.0,
    "core.end_time": 1758016497.0,
    "core.events": [
      64321,
      64325,
      64329,
      64333,
      64337,
      64341,
      64345,
      64349,
      64353,
      64357,
      64361,
      64365,
      64369,
      64373,
      64377,
      64381,
      64385,
      64389,
      64393,
      64397
    ],
    "core.event_count": 20,
    "core.first_event_number": 64321,
    "core.last_event_number": 64397,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39353
    ],
    "core.runs_subruns": [
      3935300001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039353_0835_df-s05-d1_dw_0_20250916T083424.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run039353_0835_df-s05-d1_dw_0_20250916T083424_reco_stage1_20250916T095456_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": 1758016497.0,
    "core.end_time": 1758016497.0,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39353
    ],
    "core.runs_subruns": [
      3935300001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039353_0835_df-s05-d1_dw_0_20250916T083424.hdf5"
    }
  ]
}