Jobsub ID 303501.2@dunegpschedd01.fnal.gov

Jobsub ID	303501.2@dunegpschedd01.fnal.gov
Workflow ID	12686
Stage ID	4
User name	usher@fnal.gov
Requested	Processors	1
	GPU	No
	RSS bytes	4194304000 (4000 MiB)
	Wall seconds limit	7200 (2 hours)
Submitted time	2026-02-03 16:24:06
Site	UK_RAL-PPD
Entry	CMSHTPC_T2_UK_SGrid_RALPP_hep208
Last heartbeat	2026-02-03 16:32:42
From worker node	Hostname	heplnc171.pp.rl.ac.uk
	cpuinfo	AMD EPYC 9534 64-Core Processor
	OS release	Scientific Linux release 7.9 (Nitrogen)
	Processors	1
	RSS bytes	4194304000 (4000 MiB)
	Wall seconds limit	257400 (71 hours)
	GPU
	Inner Apptainer?	True
Job state	finished
Started	2026-02-03 16:25:43
Input files	usertests:prod_monte-carlo-012686-000006_gen_g4_detsim.root
Jobscript	Exit code	0
	Real time	3m (211s)
	CPU time	2m (176s = 83%)
	Max RSS bytes	1773703168 (1691 MiB)
Outputting started	2026-02-03 16:29:14
Output files	usertests:prod_monte-carlo-012686-000006_gen_g4_detsim_reco.root usertests:prod_monte-carlo-012686-000006_gen_g4_detsim_reco_larcv.root
Finished	2026-02-03 16:32:42
Saved logs	justin-logs:303501.2-dunegpschedd01.fnal.gov.logs.tgz
List job events Cached HTCondor job logs

Jobscript log (last 10,000 characters)

L][00m [0m [94m<SuperaMCParticleCluster::process>[00m Saved interaction count: 1
    [95m[NORMAL][00m [0m [94m<SuperaMCParticleCluster::process>[00m 0 particles have no ancestor, summed deposit energy = 0
Begin processing the 48th record. run: 210006 subRun: 0 event: 48 at 03-Feb-2026 16:29:05 GMT
++++>>>> total num hits: 1093, num free: 1093

Running Ophitfinder with InputDigiType = 'recob'
Found hits: 1553!
Filling voxel information...
   [93m[WARNING][00m [0m [94m<SuperaMCParticleCluster::AnalyzeSimChannel>[00m Total IDE ctr 32474
 ... due to missing track ID 0 from 0 track IDs
 ... due to invalid particle 0
 ... due to invalid 2d index 0
 ... due to invalid 3d index 3752

    [95m[NORMAL][00m [0m [94m<SuperaMCParticleCluster::process>[00m Saved interaction count: 1
    [95m[NORMAL][00m [0m [94m<SuperaMCParticleCluster::process>[00m 0 particles have no ancestor, summed deposit energy = 0
Begin processing the 49th record. run: 210006 subRun: 0 event: 49 at 03-Feb-2026 16:29:07 GMT
++++>>>> total num hits: 2972, num free: 2972

Running Ophitfinder with InputDigiType = 'recob'
Found hits: 1229!
Filling voxel information...
   [93m[WARNING][00m [0m [94m<SuperaMCParticleCluster::AnalyzeSimChannel>[00m Total IDE ctr 43102
 ... due to missing track ID 0 from 0 track IDs
 ... due to invalid particle 0
 ... due to invalid 2d index 0
 ... due to invalid 3d index 7116

    [95m[NORMAL][00m [0m [94m<SuperaMCParticleCluster::process>[00m Saved interaction count: 1
    [95m[NORMAL][00m [0m [94m<SuperaMCParticleCluster::process>[00m 0 particles have no ancestor, summed deposit energy = 0
Begin processing the 50th record. run: 210006 subRun: 0 event: 50 at 03-Feb-2026 16:29:08 GMT
++++>>>> total num hits: 22081, num free: 22081

Running Ophitfinder with InputDigiType = 'recob'
Found hits: 2734!
Filling voxel information...
   [93m[WARNING][00m [0m [94m<SuperaMCParticleCluster::AnalyzeSimChannel>[00m Total IDE ctr 68774
 ... due to missing track ID 0 from 0 track IDs
 ... due to invalid particle 0
 ... due to invalid 2d index 0
 ... due to invalid 3d index 9802

    [95m[NORMAL][00m [0m [94m<SuperaMCParticleCluster::process>[00m Saved interaction count: 1
    [95m[NORMAL][00m [0m [94m<SuperaMCParticleCluster::process>[00m 0 particles have no ancestor, summed deposit energy = 0
03-Feb-2026 16:29:13 GMT  Closed output file "prod_monte-carlo-012686-000006_gen_g4_detsim_reco.root"
03-Feb-2026 16:29:13 GMT  Closed input file "root://dune.dcache.nikhef.nl:1094/pnfs/nikhef.nl/data/dune/generic/rucio/usertests/88/be/prod_monte-carlo-012686-000006_gen_g4_detsim.root"
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing particle_mpv_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing neutrino_mpv_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing cluster3d_mcst_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing cluster3d_pcluster_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing cluster3d_sed_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing cluster3d_masked_true2reco_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_reco_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_pcluster_index_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_masked_true_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing cluster3d_pcluster_highE_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing cluster3d_pcluster_lowE_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_pcluster_leftover_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_pcluster_semantics_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing particle_pcluster_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing cluster3d_sed_at_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_reco_chi2_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_reco_occupancy_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_reco_nhits_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_reco_cryo_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_reco_tpc_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_reco_hit_charge0_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_reco_hit_charge1_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_reco_hit_charge2_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_reco_hit_time0_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_reco_hit_time1_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_reco_hit_time2_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_reco_hit_key0_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_reco_hit_key1_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_reco_hit_key2_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_pcluster_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_sed_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_pcluster_semantics_ghost_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing particle_corrected_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Writing sparse3d_reco_rescaled_tree with 50 entries
    [95m[NORMAL][00m [0m [94m<IOManager::finalize>[00m Closing output file

================================================================================================================================
TimeTracker printout (sec)                        Min           Avg           Max         Median          RMS         nEvts   
================================================================================================================================
Full event                                     0.239618       2.85177       30.5252       1.30923       5.41124        50     
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read)                        0.000395876   0.00199855     0.0101481     0.0006936    0.00315545       50     
reco:gaushit:GausHitFinder                     0.0434799     0.0879569     0.177166      0.0749729     0.0379528       50     
reco:cluster3d:Cluster3D                      0.00193297     0.710588       16.6353      0.0282559      2.81187        50     
reco:opdec:Deconvolution                       0.0312552     0.156662      0.292046      0.150018      0.0584256       50     
reco:ophitspe:OpHitFinderDeco                  0.0259474     0.0327665     0.0426521     0.0320842    0.00295298       50     
reco:opflash:OpFlashFinder                    0.000277474   0.00134953    0.00302124    0.00133542    0.000555264      50     
reco:opslicer:OpSlicer                        0.000143112    0.237714      0.733959      0.179788      0.193575        50     
reco:mcreco:MCReco                             0.0279976     0.263134       1.32246      0.141011       0.26942        50     
[art]:TriggerResults:TriggerResultInserter     1.618e-05    3.04991e-05   0.000102991   2.7266e-05    1.4223e-05       50     
end_path:superaMC:LArSoftSuperaDriver          0.0945557      1.21044       11.5483      0.563657       2.23981        50     
end_path:out1:RootOutput                       5.42e-06     1.09609e-05    2.794e-05    1.0175e-05    4.5818e-06       50     
end_path:out1:RootOutput(write)               0.00883058     0.148152       1.21668      0.0947079     0.217522        50     
================================================================================================================================

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

  Peak virtual memory usage (VmPeak)  : 37030.2 MB
  Peak resident set size usage (VmHWM): 1854.07 MB
====================================================================================================
Art has completed and will exit with status 0.
'larcv.root' -> 'prod_monte-carlo-012686-000006_gen_g4_detsim_reco_larcv.root'
Sandbox folder contains:
total 378M
-rw-r--r-- 1 pltdune04 pltdune   60 Feb  3 16:25 all-input-dids.txt
-rw-r--r-- 1 pltdune04 pltdune    0 Feb  3 16:26 debugprod.log
drwxr-xr-x 2 pltdune04 pltdune 4.0K Jan 17 01:52 fhicl
-rw-r--r-- 1 pltdune04 pltdune  12K Feb  3 16:25 fhicl_bundle.tgz
-rw-r--r-- 1 pltdune04 pltdune  90K Feb  3 16:29 jobscript.log
-rw-r--r-- 1 pltdune04 pltdune 319M Feb  3 16:29 prod_monte-carlo-012686-000006_gen_g4_detsim_reco.root
-rw-r--r-- 1 pltdune04 pltdune  59M Feb  3 16:29 prod_monte-carlo-012686-000006_gen_g4_detsim_reco_larcv.root
-rw-r--r-- 1 pltdune04 pltdune  519 Feb  3 16:29 supera_hist.root
Wrote processed DID: usertests:prod_monte-carlo-012686-000006_gen_g4_detsim.root
=== RECOjob completed successfully ===