Jobsub ID 256318.2@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
AFM g4 jobscript.
Input PFN = root://xrootd.echo.stfc.ac.uk:1094/dune:/protodune/RSE/usertests/f2/84/000161_reco_data_2025-12-01T_120532Z_reco_data_2025-12-01T_172339Z.root
Setting up larsoft UPS area... /cvmfs/larsoft.opensciencegrid.org
Setting up DUNE UPS area... /cvmfs/dune.opensciencegrid.org/products/dune/
/cvmfs/larsoft.opensciencegrid.org/products/xrootd/v5_5_5a/Linux64bit+3.10-2.17-e26-p3915-prof/lib/libXrdPosixPreload.so
=== Start last 100 lines of lar log file ===
%MSG-i generatePrimaries: larg4Main:largeant@BeginModule 02-Dec-2025 12:08:10 CET run: 20000031 subRun: 0 event: 1 MCTruthEventAction.cc:112
Generating 1 particles
%MSG
%MSG-i ParticleListActionService: larg4Main:largeant@BeginModule 02-Dec-2025 12:08:10 CET run: 20000031 subRun: 0 event: 1
Not Stored Process summary:
conv : 232
Pair : 2
Ion : 2516
compt : 5720
phot : 2051
Brem : 1931
annihil : 236
%MSG
%MSG-i endOfEventAction: larg4Main:largeant@BeginModule 02-Dec-2025 12:08:10 CET run: 20000031 subRun: 0 event: 1 ParticleListAction.cc:817
MCTruth Handles Size: 1
%MSG
%MSG-i endOfEventAction: larg4Main:largeant@BeginModule 02-Dec-2025 12:08:10 CET run: 20000031 subRun: 0 event: 1 ParticleListAction.cc:827
mclistHandle Size: 1
%MSG
%MSG-i endOfEventAction: larg4Main:largeant@BeginModule 02-Dec-2025 12:08:10 CET run: 20000031 subRun: 0 event: 1 ParticleListAction.cc:830
Found 1 particles
%MSG
%MSG-i NuRandomService: IonAndScint:IonAndScint@BeginModule 02-Dec-2025 12:08:10 CET run: 20000031 subRun: 0 event: 1
Random seed for this event, engine 'IonAndScint.ISCalcAlg': 192086854
%MSG
IonAndScint Module Producer
SimEnergyDeposit input module: largeant, instance name: LArG4DetectorServicevolTPCPlaneVInner0
SimEnergyDeposit input module: largeant, instance name: LArG4DetectorServicevolTPCPlaneZInner0
Skip SimEnergyDeposit in: LArG4DetectorServicevolTPCOuter
SimEnergyDeposit input module: largeant, instance name: LArG4DetectorServicevolTPCInner1
Skip SimEnergyDeposit in: LArG4DetectorServicevolTPCInner0
Skip SimEnergyDeposit in: LArG4DetectorServicevolTPCPlaneZOuter
SimEnergyDeposit input module: largeant, instance name: LArG4DetectorServicevolTPCPlaneZInner1
SimEnergyDeposit input module: largeant, instance name: LArG4DetectorServicevolTPCPlaneVInner1
Skip SimEnergyDeposit in: LArG4DetectorServicevolTPCPlaneVOuter
SimEnergyDeposit input module: largeant, instance name: LArG4DetectorServicevolTPCActiveInner0
SimEnergyDeposit input module: largeant, instance name: LArG4DetectorServicevolTPCActiveInner1
SimEnergyDeposit input module: largeant, instance name: LArG4DetectorServicevolTPCPlaneUInner0
SimEnergyDeposit input module: largeant, instance name: LArG4DetectorServicevolTPCPlaneUInner1
Skip SimEnergyDeposit in: LArG4DetectorServicevolTPCPlaneUOuter
SimEnergyDeposit input module: largeant, instance name: LArG4DetectorServicevolTPCActiveOuter
02-Dec-2025 12:08:10 CET Opened output file with pattern "000161_reco_data_2025-12-01T_120532Z_reco_data_2025-12-01T_172339Z_reco_data_2025-12-02T_110731Z.root"
%MSG-i Root_Information: PostProcessEvent TClass::Init() 02-Dec-2025 12:08:10 CET run: 20000031 subRun: 0 event: 1
no dictionary for class sim::IDE is available
ROOT severity: 2000
%MSG
%MSG-w FastCloning: PostProcessEvent 02-Dec-2025 12:08:10 CET run: 20000031 subRun: 0 event: 1
Fast cloning has been deactivated for the following reasons:
- Event-selection has been specified in the RootOutput configuration.
%MSG
02-Dec-2025 12:08:12 CET Closed output file "000161_reco_data_2025-12-01T_120532Z_reco_data_2025-12-01T_172339Z_reco_data_2025-12-02T_110731Z.root"
02-Dec-2025 12:08:12 CET Closed input file "root://xrootd.echo.stfc.ac.uk:1094/dune:/protodune/RSE/usertests/f2/84/000161_reco_data_2025-12-01T_120532Z_reco_data_2025-12-01T_172339Z.root"
IonAndScint endJob.
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 0.569863 0.569863 0.569863 0.569863 0 1
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.017403 0.017403 0.017403 0.017403 0 1
simulate:rns:RandomNumberSaver 0.000294959 0.000294959 0.000294959 0.000294959 0 1
simulate:largeant:larg4Main 0.485585 0.485585 0.485585 0.485585 0 1
simulate:IonAndScint:IonAndScint 0.0646147 0.0646147 0.0646147 0.0646147 0 1
simulate:muonfilter:LArG4ParticleFilter 0.00036885 0.00036885 0.00036885 0.00036885 0 1
[art]:TriggerResults:TriggerResultInserter 7.5752e-05 7.5752e-05 7.5752e-05 7.5752e-05 0 1
end_path:out1:RootOutput 0.000258315 0.000258315 0.000258315 0.000258315 0 1
end_path:out1:RootOutput(write) 5.5542e-05 5.5542e-05 5.5542e-05 5.5542e-05 0 1
================================================================================================================================
%MSG-i NuRandomService: RootOutput:out1@EndJob 02-Dec-2025 12:08:12 CET ModuleEndJob
Summary of seeds computed by the NuRandomService
Random policy: 'perEvent'
algorithm version: EventTimestamp_v1
Configured value Last value ModuleLabel.InstanceName
(per event) 192086854 IonAndScint.ISCalcAlg
(per event) 840848967 largeant
%MSG
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 1775.6 MB
Peak resident set size usage (VmHWM): 998.441 MB
====================================================================================================
TrigReport ---------- Event summary -------------
TrigReport Events total = 1 passed = 0 failed = 1
TrigReport ---------- Modules in End-path ----------
TrigReport Run Success Error Name
TrigReport 0 0 0 out1
TimeReport ---------- Time summary [sec] -------
TimeReport CPU = 20.748762 Real = 21.728867
MemReport ---------- Memory summary [base-10 MB] ------
MemReport VmPeak = 1775.6 VmHWM = 998.441
Art has completed and will exit with status 0.
=== End last 100 lines of lar log file ===
lar exit code 0
extractor_prod.py exit code 0
pdjson2metadata exit code 0
Metadata extraction succeeds
===Metadata JSON===
{
"metadata": {
"DUNE.campaign": "justIN.w10933s1",
"art.file_format_era": "ART_2011a",
"art.file_format_version": 15,
"art.first_event": 4294967295,
"art.last_event": 4294967295,
"art.process_name": "G4",
"art.run_type": "physics",
"core.application": "art.reco",
"core.application.family": "art",
"core.application.name": "reco",
"core.application.version": "v10_11_00d01",
"core.data_stream": "out1",
"core.data_tier": "simulated",
"core.end_time": 1764673692.0,
"core.event_count": 0,
"core.file_format": "artroot",
"core.file_type": "mc",
"core.first_event_number": 4294967295,
"core.last_event_number": 4294967295,
"core.run_type": "physics",
"core.runs": [
20000031
],
"core.runs_subruns": [
2000003100000
],
"core.start_time": 1764673690.0
},
"parents": [
{
"did": "usertests:000161_reco_data_2025-12-01T_120532Z_reco_data_2025-12-01T_172339Z.root"
}
]
}
===================
.:
total 208
-rw-r--r--. 1 dune009 dune 113295 Dec 2 12:08 000161_reco_data_2025-12-01T_120532Z_reco_data_2025-12-01T_172339Z_reco_data_2025-12-02T_110731Z.root
-rw-r--r--. 1 dune009 dune 67659 Dec 2 12:08 000161_reco_data_2025-12-01T_120532Z_reco_data_2025-12-01T_172339Z_reco_2025-12-02T_110731Z.log
-rw-r--r--. 1 dune009 dune 8163 Dec 2 12:08 jobscript.log
-rw-r--r--. 1 dune009 dune 1130 Dec 2 12:08 000161_reco_data_2025-12-01T_120532Z_reco_data_2025-12-01T_172339Z_reco_data_2025-12-02T_110731Z.root.json
-rw-r--r--. 1 dune009 dune 941 Dec 2 12:08 000161_reco_data_2025-12-01T_120532Z_reco_data_2025-12-01T_172339Z_reco_data_2025-12-02T_110731Z.root.ext.json
-rw-r--r--. 1 dune009 dune 519 Dec 2 12:08 g4_hist.root
-rw-r--r--. 1 dune009 dune 164 Dec 2 12:07 all-input-dids.txt
-rw-r--r--. 1 dune009 dune 143 Dec 2 12:08 justin-processed-pfns.txt 