Jobsub ID 108851.88@justin-prod-sched02.dune.hep.ac.uk

Jobsub ID	108851.88@justin-prod-sched02.dune.hep.ac.uk
Workflow ID	4213
Stage ID	1
User name	ismerio@fnal.gov
HTCondor Group	group_dune
Requested	Processors	1
	RSS bytes	4194304000 (4000 MiB)
	Wall seconds limit	80000 (22 hours)
Submitted time	2024-11-23 06:03:18
Site	UK_Imperial
Entry	DUNE_T2_UK_London_IC_ceprod03
Last heartbeat	2024-11-23 06:15:21
From worker node	Hostname	wj23.grid.hep.ph.ic.ac.uk
	cpuinfo	Intel(R) Xeon(R) CPU E5-2620 v4 @ 2.10GHz
	OS release	Scientific Linux release 7.9 (Nitrogen)
	Processors	1
	RSS bytes	4194304000 (4000 MiB)
	Wall seconds limit	171000 (47 hours)
	Inner Apptainer?	True
Job state	outputting_failed
Allocator name	justin-allocator-pro.dune.hep.ac.uk
Started	2024-11-23 06:04:22
Input files	fardet-hd:atmnu_max_weighted_randompolicy_dune10kt_1x2x6_6419624_633_20231204T015442Z_gen_g4_detsim_hitreco__20240509T213500Z_reco2.root
Jobscript	Exit code	0
	Real time	10m (645s)
	CPU time	1m (78s = 12%)
Outputting started	2024-11-23 06:15:07
Output files
Finished	2024-11-23 06:15:21
List job events Wrapper job log
Jobscript log (last 10,000 characters)

larsoft.opensciencegrid.org/products/tbb/v2021_9_0/Linux64bit+3.10-2.17-e26/lib/libtbb_debug.so.12)
frame #27: <unknown function> + 0x408ba (0x14c9074408ba in /cvmfs/larsoft.opensciencegrid.org/products/tbb/v2021_9_0/Linux64bit+3.10-2.17-e26/lib/libtbb_debug.so.12)
frame #28: tbb::detail::r1::execute_and_wait(tbb::detail::d1::task&, tbb::detail::d1::task_group_context&, tbb::detail::d1::wait_context&, tbb::detail::d1::task_group_context&) + 0x43 (0x14c9074406fe in /cvmfs/larsoft.opensciencegrid.org/products/tbb/v2021_9_0/Linux64bit+3.10-2.17-e26/lib/libtbb_debug.so.12)
frame #29: void art::EventProcessor::process<(art::Level)4>() + 0x2b7 (0x14c903c2b537 in /cvmfs/larsoft.opensciencegrid.org/products/art/v3_14_04/slf7.x86_64.e26.prof/lib/libart_Framework_EventProcessor.so)
frame #30: void art::EventProcessor::process<(art::Level)3>() + 0x75 (0x14c903c48845 in /cvmfs/larsoft.opensciencegrid.org/products/art/v3_14_04/slf7.x86_64.e26.prof/lib/libart_Framework_EventProcessor.so)
frame #31: void art::EventProcessor::process<(art::Level)2>() + 0x75 (0x14c903c48a15 in /cvmfs/larsoft.opensciencegrid.org/products/art/v3_14_04/slf7.x86_64.e26.prof/lib/libart_Framework_EventProcessor.so)
frame #32: void art::EventProcessor::process<(art::Level)1>() + 0x75 (0x14c903c48bb5 in /cvmfs/larsoft.opensciencegrid.org/products/art/v3_14_04/slf7.x86_64.e26.prof/lib/libart_Framework_EventProcessor.so)
frame #33: void art::EventProcessor::process<(art::Level)0>() + 0x85 (0x14c903c48d65 in /cvmfs/larsoft.opensciencegrid.org/products/art/v3_14_04/slf7.x86_64.e26.prof/lib/libart_Framework_EventProcessor.so)
frame #34: art::EventProcessor::runToCompletion() + 0x19 (0x14c903c325b9 in /cvmfs/larsoft.opensciencegrid.org/products/art/v3_14_04/slf7.x86_64.e26.prof/lib/libart_Framework_EventProcessor.so)
frame #35: art::run_art(int, char**, boost::program_options::options_description&, std::vector<std::unique_ptr<art::OptionsHandler, std::default_delete<art::OptionsHandler> >, std::allocator<std::unique_ptr<art::OptionsHandler, std::default_delete<art::OptionsHandler> > > >&&) + 0x2762 (0x14c90948c252 in /cvmfs/larsoft.opensciencegrid.org/products/art/v3_14_04/slf7.x86_64.e26.prof/lib/libart_Framework_Art.so)
frame #36: artapp(int, char**, bool) + 0x732 (0x14c9094859f2 in /cvmfs/larsoft.opensciencegrid.org/products/art/v3_14_04/slf7.x86_64.e26.prof/lib/libart_Framework_Art.so)
frame #37: main + 0xe (0x40124e in lar)
frame #38: __libc_start_main + 0xf5 (0x14c904822555 in /lib64/libc.so.6)
frame #39: lar() [0x4012d0]

AdaBoostDecisionTree::Initialize - Invalid xml file.
AdaBoostDecisionTree::Initialize - Invalid xml file.
AdaBoostDecisionTree::Initialize - Invalid xml file.
AdaBoostDecisionTree::Initialize - Invalid xml file.
Failure in algorithm Alg0002, LArDLVertexing, unknown exception
PandoraContentApi::GetList(*this, m_inputVertexListName, pVertexList) throw STATUS_CODE_NOT_INITIALIZED
    in function: GetHitRegion
    in file:     /exp/dune/app/users/ismerio/env-testreco/srcs/larpandoracontent/larpandoradlcontent/LArVertex/DlVertexingAlgorithm.cc line#: 624
Failure in algorithm Alg0003, LArDLVertexing, STATUS_CODE_NOT_INITIALIZED
> Running Algorithm: Alg0003, LArNeutrinoEventValidation
---RAW-MATCHING-OUTPUT--------------------------------------------------------------------------
Found: 13 with KE=0.0605398 GeV, E=0.166198 GeV, p2=0.0164581
Found: 2212 with KE=0.102721 GeV, E=1.04099 GeV, p2=0.203311
CCQEL_MU_P (Nuance 1001, Nu 1, CR 0)
IsLost (NNuLosses: 2) 
Parent Neutrino: 14
Neutrino Energy: 0.310318
Neutrino Momentum:   x: -0.090528  y: 0.225057  z: -0.193525 length: 0.310319
PrimaryId 1, Nu 1, CR 0, MCPDG 13, Energy 0.166198, Dist. 14.9763, nMCHits 75 (27, 21, 27)
-No matched Pfo
Parent Neutrino: 14
Neutrino Energy: 0.310318
Neutrino Momentum:   x: -0.090528  y: 0.225057  z: -0.193525 length: 0.310319
PrimaryId 2, Nu 1, CR 0, MCPDG 2212, Energy 1.04099, Dist. 8.23921, nMCHits 28 (12, 7, 9)
-No matched Pfo

------------------------------------------------------------------------------------------------

---INTERPRETED-MATCHING-OUTPUT------------------(my version)-----------------------------------------
Found: 13 with KE=0.0605398 GeV, E=0.166198 GeV, p2=0.0164581
Found: 2212 with KE=0.102721 GeV, E=1.04099 GeV, p2=0.203311
CCQEL_MU_P (Nuance 1001, Nu 1, CR 0)
IsLost (NNuLosses: 2) 
Parent Neutrino: 14
Neutrino Energy: 0.310318
Neutrino Momentum:   x: -0.090528  y: 0.225057  z: -0.193525 length: 0.310319
PrimaryId 1, Nu 1, CR 0, MCPDG 13, Energy 0.166198, Dist. 14.9763, nMCHits 75 (27, 21, 27)
-No matched Pfo
Parent Neutrino: 14
Neutrino Energy: 0.310318
Neutrino Momentum:   x: -0.090528  y: 0.225057  z: -0.193525 length: 0.310319
PrimaryId 2, Nu 1, CR 0, MCPDG 2212, Energy 1.04099, Dist. 8.23921, nMCHits 28 (12, 7, 9)
-No matched Pfo

---SUMMARY--------------------------------------------------------------------------------------
#CorrectNu: 0/1, Fraction: 0
#Lost: 1 
------------------------------------------------------------------------------------------------

Found: 13 with KE=0.0605398 GeV, E=0.166198 GeV, p2=0.0164581
PandoraMonitoring, only able to use default TApplication (limited functionality).
Found: 2212 with KE=0.102721 GeV, E=1.04099 GeV, p2=0.203311
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtrack@BeginModule  23-Nov-2024 06:10:01 GMT run: 6419624 subRun: 1 event: 63301
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtrack@BeginModule  23-Nov-2024 06:10:01 GMT run: 6419624 subRun: 1 event: 63301
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtrack@BeginModule  23-Nov-2024 06:10:01 GMT run: 6419624 subRun: 1 event: 63301
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtrack@BeginModule  23-Nov-2024 06:10:01 GMT run: 6419624 subRun: 1 event: 63301
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtracktc@BeginModule  23-Nov-2024 06:10:01 GMT run: 6419624 subRun: 1 event: 63301
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtracktc@BeginModule  23-Nov-2024 06:10:01 GMT run: 6419624 subRun: 1 event: 63301
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrajFitter:pmtrajfittc@BeginModule  23-Nov-2024 06:10:01 GMT run: 6419624 subRun: 1 event: 63301
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrajFitter:pmtrajfittc@BeginModule  23-Nov-2024 06:10:02 GMT run: 6419624 subRun: 1 event: 63301
Comparing two wires in the same plane: return failure
%MSG
Boundary wire vector sizes: 33, 43, 39
minwire 0: 1266
minwire 1: 1234
minwire 2: 1441
Used alternate method to get min and max tdcs due to vertex determination failure: 0, 199
Used alternate method to get min and max tdcs due to vertex determination failure: 0, 199
Used alternate method to get min and max tdcs due to vertex determination failure: 0, 199
Could not find serving_default in model signatures.
[libprotobuf FATAL /cvmfs/larsoft.opensciencegrid.org/products/protobuf/v3_21_12a/Linux64bit+3.10-2.17-e26/include/google/protobuf/map.h:1300] CHECK failed: it != end(): key not found: serving_default
23-Nov-2024 06:10:27 GMT  Opened output file with pattern "atmnu_max_weighted_randompolicy_dune10kt_1x2x6_6419624_633_20231204T015442Z_gen_g4_detsim_hitreco__20240509T213500Z_reco2_reco_data_2024-11-23T_060425Z.root"
23-Nov-2024 06:15:06 GMT  Closed input file "root://golias100.farm.particle.cz:1094/dpm/farm.particle.cz/home/dune/RSE/fardet-hd/2b/3d/atmnu_max_weighted_randompolicy_dune10kt_1x2x6_6419624_633_20231204T015442Z_gen_g4_detsim_hitreco__20240509T213500Z_reco2.root"
Malformed TimeTracker database.  The TimeEvent table is empty, but
the TimeModule table is not.  This can happen if an exception has
been thrown from a module while processing the first event.  Any
saved database file is suspect and should not be used.

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

  Peak virtual memory usage (VmPeak)  : 7071.41 MB
  Peak resident set size usage (VmHWM): 1153.56 MB
====================================================================================================
%MSG-s ArtException:  PostEndJob 23-Nov-2024 06:15:07 GMT ModuleEndJob
---- EventProcessorFailure BEGIN
  EventProcessor: an exception occurred during current event processing
  ---- ScheduleExecutionFailure BEGIN
    Path: ProcessingStopped.
    ---- StdException BEGIN
      An exception was thrown while processing module CVNEvaluator/cvneva run: 6419624 subRun: 1 event: 63301
      CHECK failed: it != end(): key not found: serving_default
    ---- StdException END
    Exception going through path reco
  ---- ScheduleExecutionFailure END
---- EventProcessorFailure END
---- FatalRootError BEGIN
  Fatal Root Error: TTree::SetEntries
  Tree branches have different numbers of entries, eg EventAuxiliary has 0 entries while sim::OpDetDivRecs_opdigi__detsim. has 100 entries.
  ROOT severity: 2000
---- FatalRootError END
%MSG
Art has completed and will exit with status 1.
=== End last 100 lines of lar log file ===
RootOutput-7cd9-d278-a309-5fe3.root
Validation_ccnc_atmnu_max_weighted_randompolicy_dune10kt_1x2x6_6419624_633_20231204T015442Z_gen_g4_detsim_hitreco__20240509T213500Z_reco2.root
all-input-dids.txt
atmnu_max_weighted_randompolicy_dune10kt_1x2x6_6419624_633_20231204T015442Z_gen_g4_detsim_hitreco__20240509T213500Z_reco2_reco_2024-11-23T_060425Z.log
debugprod.log
jobscript.log
justin-processed-pfns.txt
reco2_hist.root
MyPandoraSettings_Master_Atmos_DUNEFD.xml
MyPandoraSettings_Master_DUNEFD.xml
build_slf7.x86_64
localProducts_larsoft_v09_91_02_e26_prof
setup_env-testreco.sh
srcs
temp.txt
temp2.txt
test
work