Jobsub ID 253058.48@dunegpschedd02.fnal.gov
Jobscript log (last 10,000 characters)
nfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/e7/40/np02vd_raw_run040890_0042_df-s04-d0_dw_0_20251125T220526_reco_stage1_20251126T000427_keepup.root
did: vd-protodune-det-reco:np02vd_raw_run040890_0042_df-s04-d0_dw_0_20251125T220526_reco_stage1_20251126T000427_keepup.root
output_file: np02vd_raw_run040890_0042_df-s04-d0_dw_0_20251125T220526_reco_stage1_20251126T000427_keepup_20251126T124942Z_singlehit.root
Running reco stage1
%MSG-i MF_INIT_OK: Early 26-Nov-2025 06:49:50 CST JobSetup
Messagelogger initialization complete.
%MSG
Info in <TGeoManager::Import>: Reading geometry from file: /cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_12_01d00/gdml/protodunevd_v5_ggd.gdml
Info in <TGeoManager::TGeoManager>: Geometry GDMLImport, Geometry imported from GDML created
Error: Unsupported GDML Tag Used :gdml_simple_extension. Please Check Geometry/Schema.
Info in <TGeoManager::SetTopVolume>: Top volume is volWorld. Master volume is volWorld
Info in <TGeoNavigator::BuildCache>: --- Maximum geometry depth set to 100
Info in <TGeoManager::CheckGeometry>: Fixing runtime shapes...
Info in <TGeoManager::CheckGeometry>: ...Nothing to fix
Info in <TGeoManager::CloseGeometry>: Counting nodes...
Info in <TGeoManager::Voxelize>: Voxelizing...
Info in <TGeoManager::CloseGeometry>: Building cache...
Info in <TGeoManager::CountLevels>: max level = 5, max placements = 292
Info in <TGeoManager::CloseGeometry>: 16484 nodes/ 2469 volume UID's in Geometry imported from GDML
Info in <TGeoManager::CloseGeometry>: ----------------modeler ready----------------
%MSG-i AuxDetGeometryCore: Early 26-Nov-2025 06:50:04 CST JobSetup
New detector geometry loaded from
/cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_12_01d00/gdml/protodunevd_v5_ggd.gdml
%MSG
%MSG-i GeometryCore: Early 26-Nov-2025 06:50:04 CST JobSetup
Sorting volumes...
%MSG
%MSG-i GeometryCore: Early 26-Nov-2025 06:50:04 CST JobSetup
New detector geometry loaded from
/cvmfs/dune.opensciencegrid.org/products/dune/dunecore/v10_12_01d00/gdml/protodunevd_v5_ggd.gdml
%MSG
%MSG-i CRPWireReadoutGeom: Early 26-Nov-2025 06:50:04 CST JobSetup
Initializing CRPWireReadoutGeom channel mapping algorithm.
%MSG
%MSG-i CRPWireReadoutGeom: Early 26-Nov-2025 06:50:04 CST JobSetup
Build readout planes for 1 16 3
%MSG
%MSG-i CRPWireReadoutGeom: Early 26-Nov-2025 06:50:04 CST JobSetup
Counted 12288 channels.
%MSG
GeoApaChannelGroupService::ctor: Group 0 (apa0) has 3072 channels from 3/3 readout planes.
GeoApaChannelGroupService::ctor: Group 1 (apa1) has 3072 channels from 3/3 readout planes.
GeoApaChannelGroupService::ctor: Group 2 (apa2) has 3072 channels from 3/3 readout planes.
GeoApaChannelGroupService::ctor: Group 3 (apa3) has 3072 channels from 3/3 readout planes.
%MSG-i SimpleChannelStatusService: Early 26-Nov-2025 06:50:04 CST JobSetup
Loaded from configuration:
- 0 bad channels
- 0 noisy channels
- largest channel ID: 12287, largest present: 12287
%MSG
%MSG-i setupProvider<DetectorPropertiesStandard>: Early 26-Nov-2025 06:50:04 CST JobSetup
Asked to ignore 1 keys: 'InheritNumberTimeSamples'
%MSG
PDSP Channel Map: Building RCE TPC wiremap from file protoDUNETPCChannelMap_RCE_v4.txt
PDSP Channel Map: Building FELIX TPC wiremap from file protoDUNETPCChannelMap_RCE_v4.txt
PDSP Channel Map: Building SSP channel map from file protoDUNESSPChannelMap_v1.txt
%MSG-i SignalShapingServiceDUNE: Early 26-Nov-2025 06:50:04 CST JobSetup
Getting Filter from .fcl file
%MSG
%MSG-i SignalShapingServiceDUNE: Early 26-Nov-2025 06:50:04 CST 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).
26-Nov-2025 06:50:05 CST Initiating request to open input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/e7/40/np02vd_raw_run040890_0042_df-s04-d0_dw_0_20251125T220526_reco_stage1_20251126T000427_keepup.root"
26-Nov-2025 06:50:10 CST Opened input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/e7/40/np02vd_raw_run040890_0042_df-s04-d0_dw_0_20251125T220526_reco_stage1_20251126T000427_keepup.root"
db: runtime1764108324
Begin processing the 1st record. run: 40890 subRun: 1 event: 1944 at 26-Nov-2025 06:50:14 CST
Begin processing the 2nd record. run: 40890 subRun: 1 event: 1946 at 26-Nov-2025 06:52:17 CST
Begin processing the 3rd record. run: 40890 subRun: 1 event: 1948 at 26-Nov-2025 06:53:26 CST
Begin processing the 4th record. run: 40890 subRun: 1 event: 1950 at 26-Nov-2025 06:55:14 CST
Begin processing the 5th record. run: 40890 subRun: 1 event: 1952 at 26-Nov-2025 06:56:31 CST
Begin processing the 6th record. run: 40890 subRun: 1 event: 1954 at 26-Nov-2025 06:58:43 CST
Begin processing the 7th record. run: 40890 subRun: 1 event: 1956 at 26-Nov-2025 07:00:03 CST
Begin processing the 8th record. run: 40890 subRun: 1 event: 1958 at 26-Nov-2025 07:01:55 CST
Begin processing the 9th record. run: 40890 subRun: 1 event: 1960 at 26-Nov-2025 07:03:01 CST
Begin processing the 10th record. run: 40890 subRun: 1 event: 1962 at 26-Nov-2025 09:23:40 CST
Begin processing the 11th record. run: 40890 subRun: 1 event: 1964 at 26-Nov-2025 09:25:45 CST
Begin processing the 12th record. run: 40890 subRun: 1 event: 1966 at 26-Nov-2025 09:28:41 CST
Begin processing the 13th record. run: 40890 subRun: 1 event: 1968 at 26-Nov-2025 09:30:32 CST
Begin processing the 14th record. run: 40890 subRun: 1 event: 1970 at 26-Nov-2025 09:33:49 CST
Begin processing the 15th record. run: 40890 subRun: 1 event: 1972 at 26-Nov-2025 09:36:46 CST
Begin processing the 16th record. run: 40890 subRun: 1 event: 1974 at 26-Nov-2025 09:38:32 CST
Begin processing the 17th record. run: 40890 subRun: 1 event: 1976 at 26-Nov-2025 09:40:32 CST
Begin processing the 18th record. run: 40890 subRun: 1 event: 1978 at 26-Nov-2025 09:41:50 CST
Begin processing the 19th record. run: 40890 subRun: 1 event: 1980 at 26-Nov-2025 09:43:41 CST
Begin processing the 20th record. run: 40890 subRun: 1 event: 1982 at 26-Nov-2025 09:45:40 CST
Begin processing the 21st record. run: 40890 subRun: 1 event: 1984 at 26-Nov-2025 09:47:06 CST
Begin processing the 22nd record. run: 40890 subRun: 1 event: 1986 at 26-Nov-2025 09:48:51 CST
26-Nov-2025 09:50:16 CST Closed input file "root://fndcadoor.fnal.gov:1094/pnfs/fnal.gov/usr/dune/persistent/staging/vd-protodune-det-reco/e7/40/np02vd_raw_run040890_0042_df-s04-d0_dw_0_20251125T220526_reco_stage1_20251126T000427_keepup.root"
====================================================================================================================
TimeTracker printout (sec) Min Avg Max Median RMS nEvts
====================================================================================================================
Full event 65.8903 491 8439.87 111.094 1734.92 22
--------------------------------------------------------------------------------------------------------------------
source:RootInput(read) 0.000430431 0.0050509 0.0224835 0.000745208 0.00820016 22
end_path:ana:SingleHit 65.8895 490.995 8439.87 111.093 1734.92 22
====================================================================================================================
%MSG-i NuRandomService: SingleHit:ana@EndJob 26-Nov-2025 09:50:16 CST ModuleEndJob
Summary of seeds computed by the NuRandomService
Random policy: 'random'
master seed: 386059090
seed within: [ 1 ; 900000000 ]
%MSG
====================================================================================================
MemoryTracker summary (base-10 MB units used)
Peak virtual memory usage (VmPeak) : 7643.64 MB
Peak resident set size usage (VmHWM): 4834.79 MB
Details saved in: 'mem.db'
====================================================================================================
TrigReport ---------- Event summary -------------
TrigReport Events total = 22 passed = 22 failed = 0
TrigReport ---------- Modules in End-path ----------
TrigReport Run Success Error Name
TrigReport 22 22 0 ana
TimeReport ---------- Time summary [sec] -------
TimeReport CPU = 10548.352218 Real = 10802.292167
MemReport ---------- Memory summary [base-10 MB] ------
MemReport VmPeak = 7643.64 VmHWM = 4834.79
Art has completed and will exit with status 0.
Output files:
\tHists: np02vd_raw_run040890_0042_df-s04-d0_dw_0_20251125T220526_reco_stage1_20251126T000427_keepup_20251126T124942Z_singlehit.root
formed
{
"name": "np02vd_raw_run040890_0042_df-s04-d0_dw_0_20251125T220526_reco_stage1_20251126T000427_keepup_20251126T124942Z_singlehit.root",
"namespace": "usertests",
"metadata": {
"core.file_format": "root",
"core.application.name": "reco",
"core.application.family": "dunesw",
"core.application.version": "v10_12_01d00",
"core.data_tier": "root-tuple-virtual",
"dune.config_file": "runSingleHit_PDVD.fcl",
"dune.campaign": "vd-protodune-det-reco",
"core.start_time": 1764172216.0,
"core.end_time": 1764172216.0,
"core.data_stream": "cosmics",
"core.file_content_status": "good",
"core.file_type": "detector",
"core.run_type": "vd-protodune",
"core.runs": [
40890
],
"core.runs_subruns": [
4089000001
],
"dune.daq_test": false,
"retention.status": "active",
"retention.class": "physics"
},
"parents": [
{
"did": "vd-protodune-det-reco:np02vd_raw_run040890_0042_df-s04-d0_dw_0_20251125T220526_reco_stage1_20251126T000427_keepup.root"
}
]
} 