Jobsub ID 47409.141@dunegpschedd01.fnal.gov

Jobsub ID	47409.141@dunegpschedd01.fnal.gov
Workflow ID	2740
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 08:45:00
Site	CERN
Entry	CMSHTPC_T2_CH_CERN_ce514
Last heartbeat	2025-09-17 10:06:31
From worker node	Hostname	b9p20p3034.cern.ch
	cpuinfo	Intel(R) Xeon(R) Silver 4216 CPU @ 2.10GHz
	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-17 08:53:48
Input files	vd-protodune:np02vd_raw_run039388_0570_df-s05-d1_dw_0_20250917T011226.hdf5
Jobscript	Exit code	0
	Real time	1h (4289s)
	CPU time	1h (4175s = 97%)
	Max RSS bytes	2992250880 (2853 MiB)
Outputting started	2025-09-17 10:05:18
Output files	vd-protodune-det-reco:np02vd_raw_run039388_0570_df-s05-d1_dw_0_20250917T011226_reco_stage1_20250917T100505_keepup.root
Finished	2025-09-17 10:06:31
Saved logs	justin-logs:47409.141-dunegpschedd01.fnal.gov.logs.tgz
List job events Cached HTCondor job logs

Jobscript log (last 10,000 characters)

d window for channel 9858
wclsFrameSaver: no samples within desired window for channel 9866
wclsFrameSaver: no samples within desired window for channel 9904
wclsFrameSaver: no samples within desired window for channel 9970
wclsFrameSaver: no samples within desired window for channel 9970
wclsFrameSaver: no samples within desired window for channel 9971
wclsFrameSaver: no samples within desired window for channel 9974
wclsFrameSaver: no samples within desired window for channel 10024
wclsFrameSaver: no samples within desired window for channel 10026
wclsFrameSaver: no samples within desired window for channel 10215
wclsFrameSaver: no samples within desired window for channel 10259
wclsFrameSaver: no samples within desired window for channel 10325
wclsFrameSaver: no samples within desired window for channel 10327
wclsFrameSaver: no samples within desired window for channel 10328
wclsFrameSaver: no samples within desired window for channel 10393
wclsFrameSaver: no samples within desired window for channel 10395
wclsFrameSaver: no samples within desired window for channel 10675
wclsFrameSaver: no samples within desired window for channel 10728
wclsFrameSaver: no samples within desired window for channel 10820
wclsFrameSaver: no samples within desired window for channel 10854
wclsFrameSaver: no samples within desired window for channel 10926
wclsFrameSaver: no samples within desired window for channel 10928
wclsFrameSaver: no samples within desired window for channel 10928
wclsFrameSaver: no samples within desired window for channel 11009
wclsFrameSaver: no samples within desired window for channel 11217
wclsFrameSaver: no samples within desired window for channel 11217
wclsFrameSaver: no samples within desired window for channel 11219
wclsFrameSaver: no samples within desired window for channel 11220
wclsFrameSaver: no samples within desired window for channel 11220
wclsFrameSaver: no samples within desired window for channel 11221
wclsFrameSaver: no samples within desired window for channel 11221
wclsFrameSaver: no samples within desired window for channel 11222
wclsFrameSaver: no samples within desired window for channel 11222
wclsFrameSaver: no samples within desired window for channel 12090
wclsFrameSaver: no samples within desired window for channel 12090
wclsFrameSaver: no samples within desired window for channel 12091
wclsFrameSaver: no samples within desired window for channel 12137
wclsFrameSaver: no samples within desired window for channel 12137
wclsFrameSaver: no samples within desired window for channel 12138
FrameSaver: q=6.11131e+06 n=805860 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 1163 XUs and 1289 XVs -> 61 XUVs
C:0 T:1 1067 XUs and 1274 XVs -> 43 XUVs
C:0 T:2 454 XUs and 599 XVs -> 25 XUVs
C:0 T:3 365 XUs and 412 XVs -> 20 XUVs
C:0 T:4 370 XUs and 277 XVs -> 17 XUVs
C:0 T:5 1365 XUs and 1553 XVs -> 30 XUVs
C:0 T:6 26 XUs and 30 XVs -> 1 XUVs
C:0 T:7 641 XUs and 655 XVs -> 22 XUVs
C:0 T:8 2170 XUs and 2349 XVs -> 129 XUVs
C:0 T:9 481 XUs and 530 XVs -> 47 XUVs
C:0 T:10 700 XUs and 969 XVs -> 62 XUVs
C:0 T:11 348 XUs and 589 XVs -> 32 XUVs
C:0 T:12 1285 XUs and 1161 XVs -> 133 XUVs
C:0 T:13 1070 XUs and 1212 XVs -> 71 XUVs
C:0 T:14 939 XUs and 551 XVs -> 55 XUVs
C:0 T:15 3311 XUs and 4119 XVs -> 184 XUVs
932 XUVs total
873 collection wire objects
932 potential space points
Neighbour search...
4740 tests to find 2308 neighbours
Iterating with no regularization...
Begin: 2.77018e+08
0 2.74482e+08
1 2.74478e+08
Now with regularization...
Begin: 2.70863e+08
0 2.70863e+08
17-Sep-2025 12:05:05 CEST  Closed output file "np02vd_raw_run039388_0570_df-s05-d1_dw_0_20250917T011226_reco_stage1_20250917T100505_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        39.3238       211.86        361.417       211.246       62.8158        20     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      5.8513e-05    9.02525e-05   0.000166286   8.7452e-05    2.77766e-05      20     
produce:tpcrawdecoder:PDVDTPCReader               2.09521       10.0387       14.2571       10.3389       2.12336        20     
produce:triggerrawdecoder:PDVDTriggerReader4    0.00560453     0.0088927     0.0220006    0.00699543    0.00427397       20     
produce:pdvddaphne:DAPHNEReaderPDVD             0.000367097   0.000530384   0.000759969   0.000535279   0.000106223      20     
produce:ophit:OpHitFinder                       0.000115181   0.000284138   0.00163797    0.000172849   0.000339642      20     
produce:opflash:OpFlashFinderVerticalDrift      4.6887e-05    8.69435e-05   0.000324648   7.4912e-05    5.84961e-05      20     
produce:wclsdatavd:WireCellToolkit                21.8854       104.306       129.124       108.599       21.9754        20     
produce:gaushit:GausHitFinder                    0.700074       1.79945       2.76804       1.82993      0.498071        20     
produce:nhitsfilter:NumberOfHitsFilter          0.000171863   0.000513495   0.000828354   0.000484765   0.00014993       20     
produce:reco3d:SpacePointSolver                   2.83668       15.856        27.0352       15.4768       5.70921        20     
produce:hitpdune:DisambigFromSpacePoints         0.122151      0.306473      0.737153      0.263574      0.135669        20     
produce:pandora:StandardPandora                   9.9252        71.8204       198.207       64.0826       42.792         20     
produce:pandoraTrack:LArPandoraTrackCreation     0.310796        1.762        5.02552       1.61266       1.05674        20     
produce:pandoraGnocalo:GnocchiCalorimetry       0.00720492     0.0378679     0.0679367     0.0375418     0.0131498       20     
[art]:TriggerResults:TriggerResultInserter       2.116e-05    4.22391e-05   9.8746e-05    3.89645e-05   1.46628e-05      20     
end_path:out1:RootOutput                         5.082e-06    9.51915e-06   3.1062e-05     8.121e-06    6.01879e-06      20     
end_path:out1:RootOutput(write)                   1.35635       5.88068       7.71311       5.93897       1.24339        20     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4938.95 MB
  Peak resident set size usage (VmHWM): 2992.25 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039388_0570_df-s05-d1_dw_0_20250917T011226_reco_stage1_20250917T100505_keepup.root
\tHists: np02vd_raw_run039388_0570_df-s05-d1_dw_0_20250917T011226_reco_stage1_20250917T100505_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039388_0570_df-s05-d1_dw_0_20250917T011226_reco_stage1_20250917T100505_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run039388_0570_df-s05-d1_dw_0_20250917T011226_reco_stage1_20250917T100505_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": 1758103506.0,
    "core.end_time": 1758103506.0,
    "core.events": [
      43893,
      43897,
      43901,
      43905,
      43909,
      43913,
      43917,
      43921,
      43925,
      43929,
      43933,
      43937,
      43941,
      43945,
      43949,
      43953,
      43957,
      43961,
      43965,
      43969
    ],
    "core.event_count": 20,
    "core.first_event_number": 43893,
    "core.last_event_number": 43969,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39388
    ],
    "core.runs_subruns": [
      3938800001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039388_0570_df-s05-d1_dw_0_20250917T011226.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run039388_0570_df-s05-d1_dw_0_20250917T011226_reco_stage1_20250917T100505_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": 1758103506.0,
    "core.end_time": 1758103506.0,
    "core.data_stream": "cosmics",
    "core.file_content_status": "good",
    "core.file_type": "detector",
    "core.run_type": "vd-protodune",
    "core.runs": [
      39388
    ],
    "core.runs_subruns": [
      3938800001
    ],
    "dune.daq_test": false,
    "retention.status": "active",
    "retention.class": "physics"
  },
  "parents": [
    {
      "did": "vd-protodune:np02vd_raw_run039388_0570_df-s05-d1_dw_0_20250917T011226.hdf5"
    }
  ]
}