File: Test1dBidomainProblemForEfficiency Type: cpu Showing nodes accounting for 356ms, 92.71% of 384ms total Dropped 139 nodes (cum <= 1.92ms) flat flat% sum% cum cum% 0 0% 0% 375ms 97.66% __libc_start_call_main 0 0% 0% 375ms 97.66% __libc_start_main_impl 0 0% 0% 375ms 97.66% _start 0 0% 0% 372ms 96.88% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 372ms 96.88% CxxTest::TestRunner::runAllTests 0 0% 0% 372ms 96.88% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 372ms 96.88% main 0 0% 0% 337ms 87.76% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 336ms 87.50% CxxTest::RealTestDescription::run 0 0% 0% 336ms 87.50% CxxTest::TestRunner::runTest (inline) 0 0% 0% 336ms 87.50% Test1dBidomainProblemForEfficiency::TestBidomainDg01WithNoOutput 0 0% 0% 336ms 87.50% TestDescription_Test1dBidomainProblemForEfficiency_TestBidomainDg01WithNoOutput::runTest (inline) 0 0% 0% 333ms 86.72% AbstractCardiacProblem::Solve 0 0% 0% 332ms 86.46% AbstractDynamicLinearPdeSolver::Solve 0 0% 0% 296ms 77.08% LinearSystem::Solve 0 0% 0% 295ms 76.82% KSPSolve 1ms 0.26% 0.26% 295ms 76.82% KSPSolve_CG 1ms 0.26% 0.52% 295ms 76.82% KSPSolve_Private 1ms 0.26% 0.78% 178ms 46.35% KSP_PCApply.lto_priv.7 0 0% 0.78% 172ms 44.79% PCApply 0 0% 0.78% 172ms 44.79% PCApply_BJacobi_Singleblock 0 0% 0.78% 139ms 36.20% KSPSolve_PREONLY (partial-inline) 0 0% 0.78% 132ms 34.38% MatSolve 131ms 34.11% 34.90% 132ms 34.38% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 0 0% 34.90% 132ms 34.38% PCApply_ICC 0 0% 34.90% 57ms 14.84% MatMult 0 0% 34.90% 57ms 14.84% MatMult_SeqAIJ 57ms 14.84% 49.74% 57ms 14.84% MatMult_SeqAIJ_Inode (inline) 42ms 10.94% 60.68% 42ms 10.94% ddot_ 0 0% 60.68% 32ms 8.33% CxxTest::RealWorldDescription::setUp 0 0% 60.68% 32ms 8.33% PMPI_Init_thread 0 0% 60.68% 32ms 8.33% PetscInitialize 0 0% 60.68% 32ms 8.33% PetscSetup::setUpWorld 0 0% 60.68% 32ms 8.33% PetscSetupUtils::CommonSetup 0 0% 60.68% 32ms 8.33% PetscSetupUtils::InitialisePetsc 0 0% 60.68% 32ms 8.33% ompi_mpi_init 0 0% 60.68% 28ms 7.29% MatNullSpaceRemove 3ms 0.78% 61.46% 27ms 7.03% 0 0% 61.46% 26ms 6.77% VecTDot 0 0% 61.46% 26ms 6.77% VecTDot_Seq 0 0% 61.46% 26ms 6.77% VecXDot_Seq_Private.constprop.0 1ms 0.26% 61.72% 20ms 5.21% AbstractCardiacTissue::SolveCellSystems 0 0% 61.72% 20ms 5.21% orte_init 0 0% 61.72% 16ms 4.17% VecNorm 0 0% 61.72% 16ms 4.17% VecNorm_Seq 4ms 1.04% 62.76% 15ms 3.91% BidomainSolver::SetupLinearSystem 0 0% 62.76% 15ms 3.91% VecDuplicate 1ms 0.26% 63.02% 14ms 3.65% VecDuplicate_Seq 0 0% 63.02% 14ms 3.65% VecMAXPY 12ms 3.12% 66.15% 14ms 3.65% VecMAXPY_Seq 0 0% 66.15% 14ms 3.65% VecMDot 1ms 0.26% 66.41% 14ms 3.65% VecMXDot_Private 0 0% 66.41% 13ms 3.39% AbstractCardiacCell::ComputeExceptVoltage 13ms 3.39% 69.79% 13ms 3.39% VecMDot_Seq 0 0% 69.79% 13ms 3.39% __GI__dl_catch_exception 0 0% 69.79% 13ms 3.39% _dl_catch_error 0 0% 69.79% 13ms 3.39% _dlerror_run 0 0% 69.79% 12ms 3.12% opal_stackframe_output 0 0% 69.79% 11ms 2.86% VecAYPX 11ms 2.86% 72.66% 11ms 2.86% VecAYPX_Seq 0 0% 72.66% 11ms 2.86% hwloc_topology_load 0 0% 72.66% 11ms 2.86% mca_base_framework_open 0 0% 72.66% 11ms 2.86% mca_base_framework_register 0 0% 72.66% 10ms 2.60% AbstractOneStepIvpOdeSolver::InternalSolve 0 0% 72.66% 10ms 2.60% ___dlopen 0 0% 72.66% 10ms 2.60% _dl_init 0 0% 72.66% 10ms 2.60% _dl_open 0 0% 72.66% 10ms 2.60% call_init (inline) 0 0% 72.66% 10ms 2.60% dl_open_worker 0 0% 72.66% 10ms 2.60% dlopen_doit 0 0% 72.66% 10ms 2.60% dlopen_implementation (inline) 0 0% 72.66% 10ms 2.60% hwloc_linux_get_tid_last_cpu_location 0 0% 72.66% 10ms 2.60% mca_base_framework_components_register 0 0% 72.66% 9ms 2.34% KSP_PCApply (partial-inline) 0 0% 72.66% 9ms 2.34% mca_base_component_find 0 0% 72.66% 9ms 2.34% mca_base_component_repository_open 0 0% 72.66% 8ms 2.08% KSP_RemoveNullSpace (inline) 0 0% 72.66% 8ms 2.08% PetscMallocA 1ms 0.26% 72.92% 8ms 2.08% PetscMallocAlign (partial-inline) 0 0% 72.92% 8ms 2.08% VecSetType 0 0% 72.92% 7ms 1.82% VecAXPY 0 0% 72.92% 7ms 1.82% VecAXPY_Seq 0 0% 72.92% 7ms 1.82% VecCreate_Seq 3ms 0.78% 73.70% 7ms 1.82% __GI___libc_malloc 0 0% 73.70% 7ms 1.82% _dl_start_user 0 0% 73.70% 7ms 1.82% dl_open_worker_begin 1ms 0.26% 73.96% 6ms 1.56% CellLuoRudy1991FromCellML::EvaluateYDerivatives 0 0% 73.96% 6ms 1.56% EulerIvpOdeSolver::CalculateNextYValue 0 0% 73.96% 6ms 1.56% _dl_lookup_symbol_x 1ms 0.26% 74.22% 6ms 1.56% boost::serialization::singleton::get_instance 6ms 1.56% 75.78% 6ms 1.56% daxpy_ 3ms 0.78% 76.56% 6ms 1.56% do_lookup_x 1ms 0.26% 76.82% 5ms 1.30% AbstractCardiacTissue::UpdateCaches (inline) 1ms 0.26% 77.08% 5ms 1.30% VecCreate_Seq_Private (partial-inline) 2ms 0.52% 77.60% 5ms 1.30% __GI___exp 5ms 1.30% 78.91% 5ms 1.30% __memset_evex_unaligned_erms 1ms 0.26% 79.17% 5ms 1.30% _dl_runtime_resolve_xsavec 0 0% 79.17% 5ms 1.30% mca_base_framework_components_open 0 0% 79.17% 5ms 1.30% mca_topo_base_dist_graph_neighbors_count 2ms 0.52% 79.69% 4ms 1.04% CellLuoRudy1991FromCellML::GetIIonic 0 0% 79.69% 4ms 1.04% PetscMemzero (inline) 0 0% 79.69% 4ms 1.04% VecCreate 4ms 1.04% 80.73% 4ms 1.04% __GI___libc_read 0 0% 80.73% 4ms 1.04% __posix_memalign 1ms 0.26% 80.99% 4ms 1.04% _dl_fixup 0 0% 80.99% 4ms 1.04% _dl_map_object 1ms 0.26% 81.25% 4ms 1.04% _dl_map_object_from_fd 0 0% 81.25% 4ms 1.04% boost::serialization::detail::singleton_wrapper::singleton_wrapper (inline) 0 0% 81.25% 4ms 1.04% opal_hwloc_base_get_topology 0 0% 81.25% 4ms 1.04% orte_ess_base_proc_binding 0 0% 81.25% 3ms 0.78% AbstractCardiacProblem::Initialise 0 0% 81.25% 3ms 0.78% CxxTest::RealWorldDescription::tearDown 0 0% 81.25% 3ms 0.78% DistributedTetrahedralMesh::ConstructFromMeshReader 0 0% 81.25% 3ms 0.78% KSPConvergedDefault 0 0% 81.25% 3ms 0.78% PetscFinalize 0 0% 81.25% 3ms 0.78% PetscSetup::tearDownWorld 0 0% 81.25% 3ms 0.78% PetscSetupUtils::CommonFinalize 3ms 0.78% 82.03% 3ms 0.78% VecRestoreArray 3ms 0.78% 82.81% 3ms 0.78% [libchaste_heart.so] 0 0% 82.81% 3ms 0.78% _GLOBAL__sub_I_ChasteParameters_3_4.cpp 0 0% 82.81% 3ms 0.78% __GI_exit 1ms 0.26% 83.07% 3ms 0.78% __GI_getenv 3ms 0.78% 83.85% 3ms 0.78% __GI_munmap 0 0% 83.85% 3ms 0.78% __cxa_finalize 0 0% 83.85% 3ms 0.78% __dlclose 3ms 0.78% 84.64% 3ms 0.78% __ieee754_exp_fma 3ms 0.78% 85.42% 3ms 0.78% __memcpy_evex_unaligned_erms 0 0% 85.42% 3ms 0.78% __run_exit_handlers 3ms 0.78% 86.20% 3ms 0.78% __strcmp_evex 0 0% 86.20% 3ms 0.78% _dl_call_fini 0 0% 86.20% 3ms 0.78% _dl_close 0 0% 86.20% 3ms 0.78% _dl_close_worker (inline) 0 0% 86.20% 3ms 0.78% _dl_fini 0 0% 86.20% 3ms 0.78% _dl_open_relocate_one_object (inline) 0 0% 86.20% 3ms 0.78% _dl_relocate_object 0 0% 86.20% 3ms 0.78% _dl_unmap 0 0% 86.20% 3ms 0.78% _dl_unmap_segments (inline) 1ms 0.26% 86.46% 3ms 0.78% _int_malloc 0 0% 86.46% 3ms 0.78% boost::serialization::detail::singleton_wrapper::~singleton_wrapper 1ms 0.26% 86.72% 3ms 0.78% boost::serialization::typeid_system::extended_type_info_typeid_0::is_equal 0 0% 86.72% 3ms 0.78% boost::serialization::void_cast_detail::void_caster::recursive_register 0 0% 86.72% 3ms 0.78% boost::serialization::void_cast_detail::void_caster_primitive::void_caster_primitive (inline) 2ms 0.52% 87.24% 3ms 0.78% check_match 0 0% 87.24% 3ms 0.78% elf_dynamic_do_Rela (inline) 0 0% 87.24% 3ms 0.78% elf_machine_rela (inline) 0 0% 87.24% 3ms 0.78% mca_base_component_repository_release 0 0% 87.24% 3ms 0.78% ompi_mpi_finalize 0 0% 87.24% 3ms 0.78% resolve_map (inline) 0 0% 87.24% 2ms 0.52% AbstractFeAssemblerInterface::AssembleMatrix (inline) 0 0% 87.24% 2ms 0.52% AbstractFeVolumeIntegralAssembler::AssembleOnElement 0 0% 87.24% 2ms 0.52% AbstractFeVolumeIntegralAssembler::DoAssemble 1ms 0.26% 87.50% 2ms 0.52% AbstractOneStepIvpOdeSolver::Solve 0 0% 87.50% 2ms 0.52% BidomainAssembler::ComputeMatrixTerm 1ms 0.26% 87.76% 2ms 0.52% MPI_Comm_set_attr 0 0% 87.76% 2ms 0.52% PetscHeaderCreate_Private 0 0% 87.76% 2ms 0.52% PetscMemcpy (inline) 2ms 0.52% 88.28% 2ms 0.52% TimeStepper::CalculateNextTime 0 0% 88.28% 2ms 0.52% TimeStepper::TimeStepper 0 0% 88.28% 2ms 0.52% VecDestroy 0 0% 88.28% 2ms 0.52% [hwloc_pci.so] 2ms 0.52% 88.80% 2ms 0.52% [libchaste_ode.so] 2ms 0.52% 89.32% 2ms 0.52% [libopen-rte.so.40.30.3] 0 0% 89.32% 2ms 0.52% [libpciaccess.so.0.11.1] 2ms 0.52% 89.84% 2ms 0.52% __GI___clock_nanosleep 0 0% 89.84% 2ms 0.52% __GI___nanosleep 2ms 0.52% 90.36% 2ms 0.52% __libc_openat64 2ms 0.52% 90.89% 2ms 0.52% __strncmp_evex 1ms 0.26% 91.15% 2ms 0.52% boost::numeric::ublas::indexing_matrix_assign 0 0% 91.15% 2ms 0.52% boost::numeric::ublas::matrix_assign (inline) 0 0% 91.15% 2ms 0.52% boost::numeric::ublas::matrix_slice::operator= (inline) 0 0% 91.15% 2ms 0.52% clone3 2ms 0.52% 91.67% 2ms 0.52% elf_get_dynamic_info (inline) 0 0% 91.67% 2ms 0.52% event_base_loop 0 0% 91.67% 2ms 0.52% event_del_nolock_ 0 0% 91.67% 2ms 0.52% gzgets 2ms 0.52% 92.19% 2ms 0.52% malloc_consolidate 0 0% 92.19% 2ms 0.52% mca_base_components_close 0 0% 92.19% 2ms 0.52% mca_base_framework_close 0 0% 92.19% 2ms 0.52% mca_base_var_group_register 0 0% 92.19% 2ms 0.52% mca_base_var_register 0 0% 92.19% 2ms 0.52% opal_timer_base_get_freq 0 0% 92.19% 2ms 0.52% operator new 0 0% 92.19% 2ms 0.52% orte_finalize 0 0% 92.19% 2ms 0.52% pci_get_strings 0 0% 92.19% 2ms 0.52% start_thread 0 0% 92.19% 2ms 0.52% std::_Rb_tree::_M_create_node (inline) 0 0% 92.19% 2ms 0.52% std::_Rb_tree::_M_get_node (inline) 2ms 0.52% 92.71% 2ms 0.52% std::_Rb_tree_increment 0 0% 92.71% 2ms 0.52% std::__new_allocator::allocate (inline) 0 0% 92.71% 2ms 0.52% std::allocator_traits::allocate (inline) 0 0% 92.71% 2ms 0.52% usleep