Jobsub ID 40870.190@dunegpschedd02.fnal.gov

Jobsub ID	40870.190@dunegpschedd02.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:51:00
Site	CERN
Entry	CMSHTPC_T2_CH_CERN_ce514
Last heartbeat	2025-09-17 10:32:20
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 09:20:01
Input files	vd-protodune:np02vd_raw_run039388_0549_df-s05-d0_dw_0_20250917T004452.hdf5
Jobscript	Exit code	0
	Real time	1h (4263s)
	CPU time	1h (4071s = 95%)
	Max RSS bytes	2761256960 (2633 MiB)
Outputting started	2025-09-17 10:31:06
Output files	vd-protodune-det-reco:np02vd_raw_run039388_0549_df-s05-d0_dw_0_20250917T004452_reco_stage1_20250917T103053_keepup.root
Finished	2025-09-17 10:32:20
Saved logs	justin-logs:40870.190-dunegpschedd02.fnal.gov.logs.tgz
List job events Cached HTCondor job logs

Jobscript log (last 10,000 characters)

l 11023
wclsFrameSaver: no samples within desired window for channel 11024
wclsFrameSaver: no samples within desired window for channel 11024
wclsFrameSaver: no samples within desired window for channel 11079
wclsFrameSaver: no samples within desired window for channel 11124
wclsFrameSaver: no samples within desired window for channel 11125
wclsFrameSaver: no samples within desired window for channel 11125
wclsFrameSaver: no samples within desired window for channel 11129
wclsFrameSaver: no samples within desired window for channel 11447
wclsFrameSaver: no samples within desired window for channel 11455
wclsFrameSaver: no samples within desired window for channel 11491
wclsFrameSaver: no samples within desired window for channel 11491
wclsFrameSaver: no samples within desired window for channel 11491
wclsFrameSaver: no samples within desired window for channel 11638
wclsFrameSaver: no samples within desired window for channel 11641
wclsFrameSaver: no samples within desired window for channel 11642
wclsFrameSaver: no samples within desired window for channel 11643
wclsFrameSaver: no samples within desired window for channel 11643
wclsFrameSaver: no samples within desired window for channel 11644
wclsFrameSaver: no samples within desired window for channel 11807
wclsFrameSaver: no samples within desired window for channel 11807
wclsFrameSaver: no samples within desired window for channel 11808
wclsFrameSaver: no samples within desired window for channel 11808
wclsFrameSaver: no samples within desired window for channel 11808
wclsFrameSaver: no samples within desired window for channel 12040
wclsFrameSaver: no samples within desired window for channel 12041
wclsFrameSaver: no samples within desired window for channel 12042
wclsFrameSaver: no samples within desired window for channel 12043
wclsFrameSaver: no samples within desired window for channel 12043
wclsFrameSaver: no samples within desired window for channel 12043
wclsFrameSaver: no samples within desired window for channel 12044
wclsFrameSaver: no samples within desired window for channel 12045
wclsFrameSaver: no samples within desired window for channel 12045
wclsFrameSaver: no samples within desired window for channel 12046
wclsFrameSaver: no samples within desired window for channel 12046
wclsFrameSaver: no samples within desired window for channel 12147
wclsFrameSaver: no samples within desired window for channel 12147
wclsFrameSaver: no samples within desired window for channel 12147
wclsFrameSaver: no samples within desired window for channel 12147
FrameSaver: q=1.20698e+07 n=1237875 tag=wiener
0 X, 0 U, 0 V bad channels
Finding XUV coincidences...
C:0 T:0 377 XUs and 516 XVs -> 118 XUVs
C:0 T:1 1755 XUs and 2318 XVs -> 172 XUVs
C:0 T:2 285 XUs and 436 XVs -> 16 XUVs
C:0 T:3 920 XUs and 1624 XVs -> 36 XUVs
C:0 T:4 1627 XUs and 1879 XVs -> 141 XUVs
C:0 T:5 1899 XUs and 1802 XVs -> 152 XUVs
C:0 T:6 2194 XUs and 2443 XVs -> 115 XUVs
C:0 T:7 533 XUs and 578 XVs -> 26 XUVs
C:0 T:8 1826 XUs and 1914 XVs -> 78 XUVs
C:0 T:9 2156 XUs and 2102 XVs -> 75 XUVs
C:0 T:10 511 XUs and 361 XVs -> 18 XUVs
C:0 T:11 1989 XUs and 2470 XVs -> 97 XUVs
C:0 T:12 3414 XUs and 3685 XVs -> 151 XUVs
C:0 T:13 5735 XUs and 5375 XVs -> 205 XUVs
C:0 T:14 11425 XUs and 14308 XVs -> 659 XUVs
C:0 T:15 2198 XUs and 2668 XVs -> 144 XUVs
2203 XUVs total
1669 collection wire objects
2203 potential space points
Neighbour search...
24711 tests to find 8740 neighbours
Iterating with no regularization...
Begin: 4.36918e+09
0 4.27519e+09
1 4.27402e+09
Now with regularization...
Begin: 4.23427e+09
0 4.23422e+09
17-Sep-2025 12:30:53 CEST  Closed output file "np02vd_raw_run039388_0549_df-s05-d0_dw_0_20250917T004452_reco_stage1_20250917T103053_keepup.root"

==================================================================================================================================
TimeTracker printout (sec)                          Min           Avg           Max         Median          RMS         nEvts   
==================================================================================================================================
Full event                                        160.379       221.429       324.886       220.931       39.0435        19     
----------------------------------------------------------------------------------------------------------------------------------
source:HDF5RawInput3(read)                      5.9733e-05    0.000100522   0.000229621   9.2246e-05    3.70154e-05      19     
produce:tpcrawdecoder:PDVDTPCReader               9.13874       14.5363       49.6223       10.6038        11.7          19     
produce:triggerrawdecoder:PDVDTriggerReader4    0.00570007     0.0158027     0.0826497    0.00775394     0.0189374       19     
produce:pdvddaphne:DAPHNEReaderPDVD             0.000347797   0.000497294   0.000766527   0.000496797   8.97122e-05      19     
produce:ophit:OpHitFinder                       0.000115311   0.000227936   0.000782503   0.000167017   0.000147456      19     
produce:opflash:OpFlashFinderVerticalDrift      4.4224e-05    8.6018e-05    0.000290017   7.7352e-05    4.98221e-05      19     
produce:wclsdatavd:WireCellToolkit                89.3791       107.855       145.488       102.896       14.9082        19     
produce:gaushit:GausHitFinder                     1.10231       1.84005       2.68038       1.84481      0.385601        19     
produce:nhitsfilter:NumberOfHitsFilter          0.000351139    0.0005175    0.000705853   0.000522205   0.000109002      19     
produce:reco3d:SpacePointSolver                   7.74294       17.764        29.6276       17.298        5.00153        19     
produce:hitpdune:DisambigFromSpacePoints         0.124451      0.309918      0.480012      0.319878      0.0884338       19     
produce:pandora:StandardPandora                   34.0435       70.8811       137.223       64.4798       26.296         19     
produce:pandoraTrack:LArPandoraTrackCreation      0.54296       1.97165       4.40477       1.67325       1.0041         19     
produce:pandoraGnocalo:GnocchiCalorimetry        0.0192549     0.0411789     0.0830743     0.0430062     0.0130787       19     
[art]:TriggerResults:TriggerResultInserter      2.9343e-05    3.87936e-05   7.2687e-05    3.5093e-05    1.0917e-05       19     
end_path:out1:RootOutput                         5.042e-06    8.67168e-06   2.3048e-05     7.775e-06    3.62139e-06      19     
end_path:out1:RootOutput(write)                   5.35073       6.16394       8.66235       5.83953      0.794651        19     
==================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 4712.12 MB
  Peak resident set size usage (VmHWM): 2761.26 MB
  Details saved in: 'mem.db'
====================================================================================================
Art has completed and will exit with status 0.
Output files:
\tReco: np02vd_raw_run039388_0549_df-s05-d0_dw_0_20250917T004452_reco_stage1_20250917T103053_keepup.root
\tHists: np02vd_raw_run039388_0549_df-s05-d0_dw_0_20250917T004452_reco_stage1_20250917T103053_keepup_hists.root
Forming reco metadata
Successfully opened file np02vd_raw_run039388_0549_df-s05-d0_dw_0_20250917T004452_reco_stage1_20250917T103053_keepup.root
Ran successfully
{
  "name": "np02vd_raw_run039388_0549_df-s05-d0_dw_0_20250917T004452_reco_stage1_20250917T103053_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": 1758105054.0,
    "core.end_time": 1758105054.0,
    "core.events": [
      42232,
      42236,
      42240,
      42244,
      42248,
      42252,
      42256,
      42260,
      42264,
      42268,
      42272,
      42276,
      42280,
      42284,
      42288,
      42292,
      42296,
      42300,
      42304
    ],
    "core.event_count": 19,
    "core.first_event_number": 42232,
    "core.last_event_number": 42304,
    "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_0549_df-s05-d0_dw_0_20250917T004452.hdf5"
    }
  ]
}Forming hist metadata
formed
{
  "name": "np02vd_raw_run039388_0549_df-s05-d0_dw_0_20250917T004452_reco_stage1_20250917T103053_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": 1758105054.0,
    "core.end_time": 1758105054.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_0549_df-s05-d0_dw_0_20250917T004452.hdf5"
    }
  ]
}