Jobsub ID 40956.1@dunegpschedd02.fnal.gov

Jobsub ID	40956.1@dunegpschedd02.fnal.gov
Workflow ID	2744
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-17 12:03:10
Site	NL_NIKHEF
Entry	VIRGO_NL_NIKHEF_juk
Last heartbeat	2025-09-17 14:19:46
From worker node	Hostname	wn-pep-012.farm.nikhef.nl
	cpuinfo	Intel(R) Xeon(R) Gold 6148 CPU @ 2.40GHz
	OS release	Scientific Linux release 7.9 (Nitrogen)
	Processors	1
	RSS bytes	4194304000 (4000 MiB)
	Wall seconds limit	129600 (36 hours)
	GPU
	Inner Apptainer?	True
Job state	finished
Started	2025-09-17 12:03:44
Input files	vd-protodune:np02vd_raw_run039275_0524_df-s02-d3_dw_0_20250902T082848.hdf5
Jobscript	Exit code	0
	Real time	2h (8107s)
	CPU time	1h (4514s = 55%)
	Max RSS bytes	3639857152 (3471 MiB)
Outputting started	2025-09-17 14:18:52
Output files	vd-protodune-det-reco:np02vd_raw_run039275_0524_df-s02-d3_dw_0_20250902T082848_reco_stage1_20250917T141845_keepup.root
Finished	2025-09-17 14:19:46
Saved logs	justin-logs:40956.1-dunegpschedd02.fnal.gov.logs.tgz
List job events Cached HTCondor job logs

Jobscript log (last 10,000 characters)

ow for channel 10150
wclsFrameSaver: no samples within desired window for channel 10370
wclsFrameSaver: no samples within desired window for channel 10370
wclsFrameSaver: no samples within desired window for channel 10370
wclsFrameSaver: no samples within desired window for channel 10373
wclsFrameSaver: no samples within desired window for channel 10373
wclsFrameSaver: no samples within desired window for channel 10374
wclsFrameSaver: no samples within desired window for channel 10374
wclsFrameSaver: no samples within desired window for channel 10374
wclsFrameSaver: no samples within desired window for channel 10678
wclsFrameSaver: no samples within desired window for channel 10706
wclsFrameSaver: no samples within desired window for channel 10744
wclsFrameSaver: no samples within desired window for channel 11031
wclsFrameSaver: no samples within desired window for channel 11345
wclsFrameSaver: no samples within desired window for channel 11346
wclsFrameSaver: no samples within desired window for channel 11347
wclsFrameSaver: no samples within desired window for channel 11349
wclsFrameSaver: no samples within desired window for channel 11349
wclsFrameSaver: no samples within desired window for channel 11350
wclsFrameSaver: no samples within desired window for channel 11351
wclsFrameSaver: no samples within desired window for channel 11351
wclsFrameSaver: no samples within desired window for channel 11351
wclsFrameSaver: no samples within desired window for channel 11352
wclsFrameSaver: no samples within desired window for channel 11353
wclsFrameSaver: no samples within desired window for channel 11353
wclsFrameSaver: no samples within desired window for channel 11353
wclsFrameSaver: no samples within desired window for channel 11354
wclsFrameSaver: no samples within desired window for channel 11354
wclsFrameSaver: no samples within desired window for channel 11355
wclsFrameSaver: no samples within desired window for channel 11356
wclsFrameSaver: no samples within desired window for channel 11356
wclsFrameSaver: no samples within desired window for channel 11357
wclsFrameSaver: no samples within desired window for channel 11357
wclsFrameSaver: no samples within desired window for channel 11357
wclsFrameSaver: no samples within desired window for channel 11358
wclsFrameSaver: no samples within desired window for channel 11358
wclsFrameSaver: no samples within desired window for channel 11359
wclsFrameSaver: no samples within desired window for channel 11654
FrameSaver: q=1.48031e+07 n=1514734 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 690 XUs and 785 XVs -> 23 XUVs
C:0 T:1 2174 XUs and 1621 XVs -> 128 XUVs
C:0 T:2 872 XUs and 1078 XVs -> 22 XUVs
C:0 T:3 2287 XUs and 1943 XVs -> 89 XUVs
C:0 T:4 796 XUs and 655 XVs -> 41 XUVs
C:0 T:5 1153 XUs and 1220 XVs -> 60 XUVs
C:0 T:6 206 XUs and 241 XVs -> 10 XUVs
C:0 T:7 1069 XUs and 1095 XVs -> 47 XUVs
C:0 T:8 46386 XUs and 130494 XVs -> 14749 XUVs
C:0 T:9 23453 XUs and 33390 XVs -> 3771 XUVs
C:0 T:10 13180 XUs and 20984 XVs -> 5182 XUVs
C:0 T:11 762 XUs and 1076 XVs -> 36 XUVs
C:0 T:12 2997 XUs and 3095 XVs -> 398 XUVs
C:0 T:13 1456 XUs and 2369 XVs -> 463 XUVs
C:0 T:14 2866 XUs and 5377 XVs -> 499 XUVs
C:0 T:15 10978 XUs and 18818 XVs -> 2312 XUVs
27830 XUVs total
3446 collection wire objects
27830 potential space points
Neighbour search...
3745552 tests to find 1580852 neighbours
Iterating with no regularization...
Begin: 9.65226e+08
0 8.09064e+08
1 7.98554e+08
2 7.96705e+08
3 7.96032e+08
Now with regularization...
Begin: 7.79627e+08
0 7.79066e+08
17-Sep-2025 16:18:45 CEST  Closed output file "np02vd_raw_run039275_0524_df-s02-d3_dw_0_20250902T082848_reco_stage1_20250917T141845_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        325.641         448         808.378       422.529       103.956        18     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      4.9626e-05    6.84399e-05   0.00016038    6.06805e-05   2.6009e-05       18     
produce:tpcrawdecoder:PDVDTPCReader               143.792         186         252.947       190.857       25.7172        18     
produce:triggerrawdecoder:PDVDTriggerReader4     0.0340491     0.0378614     0.0509608     0.0344362    0.00603366       18     
produce:pdvddaphne:DAPHNEReaderPDVD               21.2177       24.3788       27.7045       24.7197       1.91517        18     
produce:ophit:OpHitFinder                        0.0476585     0.0563292     0.0616678     0.0568106    0.00306194       18     
produce:opflash:OpFlashFinderVerticalDrift      0.00719819     0.0147598     0.0224696     0.0148605    0.00372613       18     
produce:wclsdatavd:WireCellToolkit                82.3862       95.1362       108.784       95.1041       7.07505        18     
produce:gaushit:GausHitFinder                     1.11287       1.84687       3.28013       1.78187      0.494608        18     
produce:nhitsfilter:NumberOfHitsFilter          0.000353729   0.000518021   0.000799605   0.000491202   0.000124871      18     
produce:reco3d:SpacePointSolver                   11.3974       20.6667       43.4244       18.9515       7.84266        18     
produce:hitpdune:DisambigFromSpacePoints          0.17956      0.413414      0.977133      0.408924      0.197992        18     
produce:pandora:StandardPandora                   33.3413       112.258       453.269       98.7599       92.5746        18     
produce:pandoraTrack:LArPandoraTrackCreation     0.844237       1.75345       4.81047       1.62337      0.962387        18     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0248981     0.0352898     0.0497305     0.0343869    0.00778772       18     
[art]:TriggerResults:TriggerResultInserter      1.7813e-05    2.71861e-05   6.3236e-05    2.5852e-05    9.92769e-06      18     
end_path:out1:RootOutput                         6.696e-06    8.50617e-06   2.5888e-05     7.501e-06    4.24769e-06      18     
end_path:out1:RootOutput(write)                   4.94557       5.36451       5.9938        5.36827      0.297634        18     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 5510.93 MB
  Peak resident set size usage (VmHWM): 3639.86 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039275_0524_df-s02-d3_dw_0_20250902T082848_reco_stage1_20250917T141845_keepup.root
\tHists: np02vd_raw_run039275_0524_df-s02-d3_dw_0_20250902T082848_reco_stage1_20250917T141845_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039275_0524_df-s02-d3_dw_0_20250902T082848_reco_stage1_20250917T141845_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run039275_0524_df-s02-d3_dw_0_20250902T082848_reco_stage1_20250917T141845_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": 1758118725.0,
    "core.end_time": 1758118725.0,
    "core.events": [
      188645,
      188665,
      188685,
      188705,
      188725,
      188745,
      188765,
      188785,
      188805,
      188825,
      188845,
      188865,
      188885,
      188905,
      188925,
      188945,
      188965,
      188985
    ],
    "core.event_count": 18,
    "core.first_event_number": 188645,
    "core.last_event_number": 188985,
    "core.data_stream": "physics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39275
    ],
    "core.runs_subruns": [
      3927500001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039275_0524_df-s02-d3_dw_0_20250902T082848.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run039275_0524_df-s02-d3_dw_0_20250902T082848_reco_stage1_20250917T141845_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": 1758118725.0,
    "core.end_time": 1758118725.0,
    "core.data_stream": "physics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39275
    ],
    "core.runs_subruns": [
      3927500001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039275_0524_df-s02-d3_dw_0_20250902T082848.hdf5"
    }
  ]
}