Jobsub ID 262144.92@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
AFM g4 jobscript.
Input PFN = root://xrootd.pic.es:1094/pnfs/pic.es/data/dune/RSE/usertests/05/43/000682_reco_data_2025-12-01T_121111Z_reco_data_2025-12-01T_171801Z.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:53:13 CET run: 20000031 subRun: 0 event: 1 MCTruthEventAction.cc:112
Generating 1 particles
%MSG
%MSG-i ParticleListActionService: larg4Main:largeant@BeginModule 02-Dec-2025 12:53:13 CET run: 20000031 subRun: 0 event: 1
Not Stored Process summary:
Pair : 20
conv : 112
compt : 5210
phot : 1802
annihil : 132
Brem : 1734
Ion : 3004
%MSG
%MSG-i endOfEventAction: larg4Main:largeant@BeginModule 02-Dec-2025 12:53:13 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:53:13 CET run: 20000031 subRun: 0 event: 1 ParticleListAction.cc:827
mclistHandle Size: 1
%MSG
%MSG-i endOfEventAction: larg4Main:largeant@BeginModule 02-Dec-2025 12:53:13 CET run: 20000031 subRun: 0 event: 1 ParticleListAction.cc:830
Found 1 particles
%MSG
%MSG-i NuRandomService: IonAndScint:IonAndScint@BeginModule 02-Dec-2025 12:53:13 CET run: 20000031 subRun: 0 event: 1
Random seed for this event, engine 'IonAndScint.ISCalcAlg': 836146576
%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:53:13 CET Opened output file with pattern "000682_reco_data_2025-12-01T_121111Z_reco_data_2025-12-01T_171801Z_reco_data_2025-12-02T_114235Z.root"
%MSG-i Root_Information: PostProcessEvent TClass::Init() 02-Dec-2025 12:53:13 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:53:13 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:53:14 CET Closed output file "000682_reco_data_2025-12-01T_121111Z_reco_data_2025-12-01T_171801Z_reco_data_2025-12-02T_114235Z.root"
02-Dec-2025 12:53:14 CET Closed input file "root://xrootd.pic.es:1094/pnfs/pic.es/data/dune/RSE/usertests/05/43/000682_reco_data_2025-12-01T_121111Z_reco_data_2025-12-01T_171801Z.root"
IonAndScint endJob.
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 0.209003 0.209003 0.209003 0.209003 0 1
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.00107349 0.00107349 0.00107349 0.00107349 0 1
simulate:rns:RandomNumberSaver 0.000190686 0.000190686 0.000190686 0.000190686 0 1
simulate:largeant:larg4Main 0.19 0.19 0.19 0.19 0 1
simulate:IonAndScint:IonAndScint 0.0165803 0.0165803 0.0165803 0.0165803 0 1
simulate:muonfilter:LArG4ParticleFilter 0.000184115 0.000184115 0.000184115 0.000184115 0 1
[art]:TriggerResults:TriggerResultInserter 3.9932e-05 3.9932e-05 3.9932e-05 3.9932e-05 0 1
end_path:out1:RootOutput 0.000175475 0.000175475 0.000175475 0.000175475 0 1
end_path:out1:RootOutput(write) 3.2551e-05 3.2551e-05 3.2551e-05 3.2551e-05 0 1
================================================================================================================================
%MSG-i NuRandomService: RootOutput:out1@EndJob 02-Dec-2025 12:53:14 CET ModuleEndJob
Summary of seeds computed by the NuRandomService
Random policy: 'perEvent'
algorithm version: EventTimestamp_v1
Configured value Last value ModuleLabel.InstanceName
(per event) 836146576 IonAndScint.ISCalcAlg
(per event) 340576173 largeant
%MSG
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 1758.15 MB
Peak resident set size usage (VmHWM): 988.57 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 = 14.804165 Real = 382.031880
MemReport ---------- Memory summary [base-10 MB] ------
MemReport VmPeak = 1758.15 VmHWM = 988.57
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.w10935s1",
"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": 1764676394.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": 1764676393.0
},
"parents": [
{
"did": "usertests:000682_reco_data_2025-12-01T_121111Z_reco_data_2025-12-01T_171801Z.root"
}
]
}
===================
.:
total 212
-rw-r--r-- 1 dune001 dune 113300 Dec 2 12:53 000682_reco_data_2025-12-01T_121111Z_reco_data_2025-12-01T_171801Z_reco_data_2025-12-02T_114235Z.root
-rw-r--r-- 1 dune001 dune 67652 Dec 2 12:53 000682_reco_data_2025-12-01T_121111Z_reco_data_2025-12-01T_171801Z_reco_2025-12-02T_114235Z.log
-rw-r--r-- 1 dune001 dune 8159 Dec 2 12:55 jobscript.log
-rw-r--r-- 1 dune001 dune 1130 Dec 2 12:55 000682_reco_data_2025-12-01T_121111Z_reco_data_2025-12-01T_171801Z_reco_data_2025-12-02T_114235Z.root.json
-rw-r--r-- 1 dune001 dune 941 Dec 2 12:55 000682_reco_data_2025-12-01T_121111Z_reco_data_2025-12-01T_171801Z_reco_data_2025-12-02T_114235Z.root.ext.json
-rw-r--r-- 1 dune001 dune 519 Dec 2 12:53 g4_hist.root
-rw-r--r-- 1 dune001 dune 164 Dec 2 12:40 all-input-dids.txt
-rw-r--r-- 1 dune001 dune 140 Dec 2 12:55 justin-processed-pfns.txt 