Jobsub ID 261703.0@dunegpschedd01.fnal.gov
Jobscript log (last 10,000 characters)
AFM gen filter jobscript.
Input PFN = root://lcg-dunese1.sfu.computecanada.ca:1094//dune/RSE/usertests/f6/96/000938_reco_data_2025-12-01T_121329Z.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 ===
Reading stepLimit(s) from the configuration file, for volume(s):
%MSG
%MSG-i LArG4DetectorService::Ctr: Early 01-Dec-2025 13:42:47 AST JobSetup
Volume: volTPCActiveInner0, stepLimit: 0.4
%MSG
%MSG-i LArG4DetectorService::Ctr: Early 01-Dec-2025 13:42:47 AST JobSetup
Volume: volTPCActiveInner1, stepLimit: 0.4
%MSG
%MSG-i LArG4DetectorService::Ctr: Early 01-Dec-2025 13:42:47 AST JobSetup
Volume: volTPCActiveOuter, stepLimit: 0.4
%MSG
registering to primaryGeneratorActionsMap_
registering to eventActionsMap_
registering to trackingActionsMap_
registering to steppingActionsMap_
%MSG-i ParticleListActionService: Early 01-Dec-2025 13:42:47 AST JobSetup
The full tracking information will not be stored for particles resulting from the following processes:
{ "conv" "LowEnConversion" "Pair" "compt" "Compt" "Brem" "phot" "Photo" "Ion" "annihil" }
%MSG
%MSG-i ParticleListActionService: Early 01-Dec-2025 13:42:47 AST JobSetup
Trajectory sparsification enabled with SparsifyMargin : 0.015
%MSG
%MSG-i SignalShapingServiceDUNE: Early 01-Dec-2025 13:42:47 AST JobSetup
Getting Filter from .fcl file
%MSG
%MSG-i SignalShapingServiceDUNE: Early 01-Dec-2025 13:42:47 AST JobSetup
using the field response provided from a .root file
%MSG
Warning in <TFile::Append>: Replacing existing TH1: FieldResponse_U (Potential memory leak).
Warning in <TFile::Append>: Replacing existing TH1: FieldResponse_V (Potential memory leak).
Warning in <TFile::Append>: Replacing existing TH1: FieldResponse_Y (Potential memory leak).
01-Dec-2025 13:42:47 AST Initiating request to open input file "root://lcg-dunese1.sfu.computecanada.ca:1094//dune/RSE/usertests/f6/96/000938_reco_data_2025-12-01T_121329Z.root"
01-Dec-2025 13:42:52 AST Opened input file "root://lcg-dunese1.sfu.computecanada.ca:1094//dune/RSE/usertests/f6/96/000938_reco_data_2025-12-01T_121329Z.root"
Begin processing the 1st record. run: 20000031 subRun: 0 event: 1 at 01-Dec-2025 13:42:56 AST
%MSG-w ParticleInventory: ProcessEvent 01-Dec-2025 13:42:57 AST run: 20000031 subRun: 0 event: 1
Rebuild failed to get the MCParticles. This is expected when running on a generation or simulation step.
%MSG
%MSG-w ParticleInventory: ProcessEvent 01-Dec-2025 13:42:57 AST run: 20000031 subRun: 0 event: 1
Rebuild failed to get the MCParticles. This is expected when running on a generation or simulation step.
%MSG
%MSG-w ParticleInventory: ProcessEvent 01-Dec-2025 13:42:57 AST run: 20000031 subRun: 0 event: 1
Rebuild failed to get the MCParticles. This is expected when running on a generation or simulation step.
%MSG
%MSG-w BackTrackerService: ProcessEvent 01-Dec-2025 13:42:57 AST run: 20000031 subRun: 0 event: 1
Rebuild failed to get the SimChannels. This is expected when running on a generation or simulation step.
%MSG
%MSG-w PhotonBackTrackerService: ProcessEvent 01-Dec-2025 13:42:57 AST run: 20000031 subRun: 0 event: 1
Rebuild failed to get the OpDetBTRs. This is expected when running on a generation or simulation step.
%MSG
01-Dec-2025 13:42:57 AST Opened output file with pattern "000938_reco_data_2025-12-01T_121329Z_reco_data_2025-12-01T_174238Z.root"
%MSG-w FastCloning: PostProcessEvent 01-Dec-2025 13:42:57 AST run: 20000031 subRun: 0 event: 1
Fast cloning has been deactivated for the following reasons:
- Event-selection has been specified in the RootOutput configuration.
- There are fewer events to process than are present in the event tree.
%MSG
01-Dec-2025 13:42:59 AST Closed output file "000938_reco_data_2025-12-01T_121329Z_reco_data_2025-12-01T_174238Z.root"
01-Dec-2025 13:42:59 AST Closed input file "root://lcg-dunese1.sfu.computecanada.ca:1094//dune/RSE/usertests/f6/96/000938_reco_data_2025-12-01T_121329Z.root"
================================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
================================================================================================================================
Full event 1.20888 1.20888 1.20888 1.20888 0 1
--------------------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.120667 0.120667 0.120667 0.120667 0 1
fpath:cosmicfilter:CosmicFilter 0.36455 0.36455 0.36455 0.36455 0 1
[art]:TriggerResults:TriggerResultInserter 9.9391e-05 9.9391e-05 9.9391e-05 9.9391e-05 0 1
end_path:out1:RootOutput 0.000261895 0.000261895 0.000261895 0.000261895 0 1
end_path:out1:RootOutput(write) 0.722789 0.722789 0.722789 0.722789 0 1
================================================================================================================================
%MSG-i NuRandomService: RootOutput:out1@EndJob 01-Dec-2025 13:42:59 AST ModuleEndJob
Summary of seeds computed by the NuRandomService
Random policy: 'perEvent'
algorithm version: EventTimestamp_v1
%MSG
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 1361.87 MB
Peak resident set size usage (VmHWM): 414.007 MB
====================================================================================================
TrigReport ---------- Event summary -------------
TrigReport Events total = 1 passed = 1 failed = 0
TrigReport ---------- Modules in End-path ----------
TrigReport Run Success Error Name
TrigReport 1 1 0 out1
TimeReport ---------- Time summary [sec] -------
TimeReport CPU = 0.791365 Real = 3.918164
MemReport ---------- Memory summary [base-10 MB] ------
MemReport VmPeak = 1361.87 VmHWM = 414.036
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.w10919s1",
"art.file_format_era": "ART_2011a",
"art.file_format_version": 15,
"art.first_event": 1,
"art.last_event": 1,
"art.process_name": "MUSUNFilter",
"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": "generated",
"core.end_time": 1764610978.0,
"core.event_count": 1,
"core.file_format": "artroot",
"core.file_type": "mc",
"core.first_event_number": 1,
"core.last_event_number": 1,
"core.run_type": "physics",
"core.runs": [
20000031
],
"core.runs_subruns": [
2000003100000
],
"core.start_time": 1764610977.0
},
"parents": [
{
"did": "usertests:000938_reco_data_2025-12-01T_121329Z.root"
}
]
}
===================
.:
total 136
-rw-r--r-- 1 dunepilot gridusers 95876 Dec 1 13:42 000938_reco_data_2025-12-01T_121329Z_reco_data_2025-12-01T_174238Z.root
-rw-r--r-- 1 dunepilot gridusers 8736 Dec 1 13:42 000938_reco_data_2025-12-01T_121329Z_reco_2025-12-01T_174238Z.log
-rw-r--r-- 1 dunepilot gridusers 7910 Dec 1 13:43 jobscript.log
-rw-r--r-- 1 dunepilot gridusers 1073 Dec 1 13:43 000938_reco_data_2025-12-01T_121329Z_reco_data_2025-12-01T_174238Z.root.json
-rw-r--r-- 1 dunepilot gridusers 853 Dec 1 13:43 000938_reco_data_2025-12-01T_121329Z_reco_data_2025-12-01T_174238Z.root.ext.json
-rw-r--r-- 1 dunepilot gridusers 519 Dec 1 13:42 MUSUN_filter_hist.root
-rw-r--r-- 1 dunepilot gridusers 113 Dec 1 13:43 justin-processed-pfns.txt
-rw-r--r-- 1 dunepilot gridusers 104 Dec 1 13:42 all-input-dids.txt 