justIN           Dashboard       Workflows       Jobs       AWT       Sites       Storages       Docs       Login

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

Jobsub ID108731.71@justin-prod-sched02.dune.hep.ac.uk
Workflow ID4213
Stage ID1
User nameismerio@fnal.gov
HTCondor Groupgroup_dune
RequestedProcessors1
RSS bytes4194304000 (4000 MiB)
Wall seconds limit80000 (22 hours)
Submitted time2024-11-23 04:10:33
SiteUK_Imperial
EntryDUNE_T2_UK_London_IC_ceprod01
Last heartbeat2024-11-23 04:21:13
From worker nodeHostnamewj47.grid.hep.ph.ic.ac.uk
cpuinfoIntel(R) Xeon(R) CPU E5-2620 v4 @ 2.10GHz
OS releaseScientific Linux release 7.9 (Nitrogen)
Processors1
RSS bytes4194304000 (4000 MiB)
Wall seconds limit171000 (47 hours)
Inner Apptainer?True
Job stateoutputting_failed
Allocator namejustin-allocator-pro.dune.hep.ac.uk
Started2024-11-23 04:11:44
Input filesfardet-hd:atmnu_max_weighted_randompolicy_dune10kt_1x2x6_50542963_488_20231202T051303Z_gen_g4_detsim_hitreco__20240507T205643Z_reco2.root
JobscriptExit code0
Real time9m (550s)
CPU time2m (125s = 22%)
Outputting started2024-11-23 04:20:54
Output files
Finished2024-11-23 04:21:13
List job events     Wrapper job log

Jobscript log (last 10,000 characters)

k<hep::concurrency::SerialTaskQueue::QueuedTask>::execute(tbb::detail::d1::execution_data&) + 0x1f (0x14f0fd603adf in /cvmfs/larsoft.opensciencegrid.org/products/hep_concurrency/v1_09_02/slf7.x86_64.e26.prof/lib/libhep_concurrency.so)
frame #25: <unknown function> + 0x424f1 (0x14f0fc2424f1 in /cvmfs/larsoft.opensciencegrid.org/products/tbb/v2021_9_0/Linux64bit+3.10-2.17-e26/lib/libtbb_debug.so.12)
frame #26: <unknown function> + 0x41437 (0x14f0fc241437 in /cvmfs/larsoft.opensciencegrid.org/products/tbb/v2021_9_0/Linux64bit+3.10-2.17-e26/lib/libtbb_debug.so.12)
frame #27: <unknown function> + 0x408ba (0x14f0fc2408ba 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 (0x14f0fc2406fe 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 (0x14f0f8a2b537 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 (0x14f0f8a48845 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 (0x14f0f8a48a15 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 (0x14f0f8a48bb5 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 (0x14f0f8a48d65 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 (0x14f0f8a325b9 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 (0x14f0fe28c252 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 (0x14f0fe2859f2 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 (0x14f0f9622555 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.194324 GeV, E=0.299983 GeV, p2=0.078826
CCQEL_MU (Nuance 1001, Nu 1, CR 0)
IsLost (NNuLosses: 1) 
Parent Neutrino: 14
Neutrino Energy: 0.457963
Neutrino Momentum:   x: 0.342604  y: 0.22124  z: 0.208345 length: 0.457965
PrimaryId 1, Nu 1, CR 0, MCPDG 13, Energy 0.299983, Dist. 71.5863, nMCHits 263 (114, 106, 43)
-No matched Pfo

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

---INTERPRETED-MATCHING-OUTPUT------------------(my version)-----------------------------------------
Found: 13 with KE=0.194324 GeV, E=0.299983 GeV, p2=0.078826
CCQEL_MU (Nuance 1001, Nu 1, CR 0)
IsLost (NNuLosses: 1) 
Parent Neutrino: 14
Neutrino Energy: 0.457963
Neutrino Momentum:   x: 0.342604  y: 0.22124  z: 0.208345 length: 0.457965
PrimaryId 1, Nu 1, CR 0, MCPDG 13, Energy 0.299983, Dist. 71.5863, nMCHits 263 (114, 106, 43)
-No matched Pfo

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

Found: 13 with KE=0.194324 GeV, E=0.299983 GeV, p2=0.078826
PandoraMonitoring, only able to use default TApplication (limited functionality).
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtrack@BeginModule  23-Nov-2024 04:18:13 GMT run: 50542963 subRun: 1 event: 48801
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtrack@BeginModule  23-Nov-2024 04:18:13 GMT run: 50542963 subRun: 1 event: 48801
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtrack@BeginModule  23-Nov-2024 04:18:13 GMT run: 50542963 subRun: 1 event: 48801
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtrack@BeginModule  23-Nov-2024 04:18:13 GMT run: 50542963 subRun: 1 event: 48801
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtracktc@BeginModule  23-Nov-2024 04:18:13 GMT run: 50542963 subRun: 1 event: 48801
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtracktc@BeginModule  23-Nov-2024 04:18:14 GMT run: 50542963 subRun: 1 event: 48801
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtracktc@BeginModule  23-Nov-2024 04:18:14 GMT run: 50542963 subRun: 1 event: 48801
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrackMaker:pmtracktc@BeginModule  23-Nov-2024 04:18:14 GMT run: 50542963 subRun: 1 event: 48801
Comparing two wires in the same plane: return failure
%MSG
%MSG-e WireIDIntersectionCheck:  PMAlgTrajFitter:pmtrajfittc@BeginModule  23-Nov-2024 04:18:14 GMT run: 50542963 subRun: 1 event: 48801
Comparing two wires in the same plane: return failure
%MSG
Boundary wire vector sizes: 122, 127, 53
minwire 0: 1961
minwire 1: 623
minwire 2: 2177
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 04:19:12 GMT  Opened output file with pattern "atmnu_max_weighted_randompolicy_dune10kt_1x2x6_50542963_488_20231202T051303Z_gen_g4_detsim_hitreco__20240507T205643Z_reco2_reco_data_2024-11-23T_041149Z.root"
23-Nov-2024 04:20:53 GMT  Closed input file "root://xrootd.echo.stfc.ac.uk:1094/dune:/protodune/RSE/fardet-hd/d5/e4/atmnu_max_weighted_randompolicy_dune10kt_1x2x6_50542963_488_20231202T051303Z_gen_g4_detsim_hitreco__20240507T205643Z_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.44 MB
  Peak resident set size usage (VmHWM): 1153.46 MB
====================================================================================================
%MSG-s ArtException:  PostEndJob 23-Nov-2024 04:20:53 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: 50542963 subRun: 1 event: 48801
      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-7e5b-6ee6-3ffe-a04a.root
Validation_ccnc_atmnu_max_weighted_randompolicy_dune10kt_1x2x6_50542963_488_20231202T051303Z_gen_g4_detsim_hitreco__20240507T205643Z_reco2.root
all-input-dids.txt
atmnu_max_weighted_randompolicy_dune10kt_1x2x6_50542963_488_20231202T051303Z_gen_g4_detsim_hitreco__20240507T205643Z_reco2_reco_2024-11-23T_041149Z.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
justIN time: 2024-11-23 12:19:08 UTC       justIN version: 01.01.09