File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3668ms, 86.84% of 4224ms total Dropped 484 nodes (cum <= 21.12ms) flat flat% sum% cum cum% 0 0% 0% 4215ms 99.79% __libc_start_call_main 0 0% 0% 4215ms 99.79% __libc_start_main_impl 0 0% 0% 4215ms 99.79% _start 0 0% 0% 4212ms 99.72% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4212ms 99.72% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4212ms 99.72% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4212ms 99.72% main 0 0% 0% 4170ms 98.72% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4170ms 98.72% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4170ms 98.72% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4162ms 98.53% AbstractCardiacProblem::Solve 1ms 0.024% 0.024% 4147ms 98.18% AbstractDynamicLinearPdeSolver::Solve 10ms 0.24% 0.26% 2256ms 53.41% AbstractCardiacTissue::SolveCellSystems 1ms 0.024% 0.28% 1607ms 38.04% LinearSystem::Solve 0 0% 0.28% 1560ms 36.93% KSPSolve 5ms 0.12% 0.4% 1560ms 36.93% KSPSolve_Private 2ms 0.047% 0.45% 1522ms 36.03% KSPSolve_SYMMLQ 30ms 0.71% 1.16% 1286ms 30.45% AbstractCardiacCell::ComputeExceptVoltage 33ms 0.78% 1.94% 1111ms 26.30% AbstractOneStepIvpOdeSolver::InternalSolve 2ms 0.047% 1.99% 953ms 22.56% PCApply 0 0% 1.99% 942ms 22.30% PCApply_BJacobi_Singleblock 56ms 1.33% 3.31% 937ms 22.18% EulerIvpOdeSolver::CalculateNextYValue 225ms 5.33% 8.64% 867ms 20.53% CellLuoRudy1991FromCellML::EvaluateYDerivatives 0 0% 8.64% 786ms 18.61% KSPSolve_PREONLY (partial-inline) 353ms 8.36% 17.00% 785ms 18.58% __GI___exp 33ms 0.78% 17.78% 780ms 18.47% AbstractCardiacTissue::UpdateCaches (inline) 1ms 0.024% 17.80% 735ms 17.40% PCApply_ICC 5ms 0.12% 17.92% 734ms 17.38% MatSolve 720ms 17.05% 34.97% 723ms 17.12% MatSolve_SeqSBAIJ_1_NaturalOrdering 262ms 6.20% 41.17% 720ms 17.05% CellLuoRudy1991FromCellML::GetIIonic 0 0% 41.17% 690ms 16.34% KSP_PCApply.lto_priv.18 3ms 0.071% 41.24% 435ms 10.30% MatMult 3ms 0.071% 41.31% 422ms 9.99% MatMult_SeqAIJ 414ms 9.80% 51.11% 422ms 9.99% MatMult_SeqAIJ_Inode (inline) 390ms 9.23% 60.35% 410ms 9.71% __ieee754_exp_fma 0 0% 60.35% 357ms 8.45% KSPConvergedDefault 1ms 0.024% 60.37% 338ms 8.00% KSP_PCApply (partial-inline) 2ms 0.047% 60.42% 237ms 5.61% std::pow (inline) 28ms 0.66% 61.08% 235ms 5.56% __pow 35ms 0.83% 61.91% 233ms 5.52% BidomainSolver::SetupLinearSystem 24ms 0.57% 62.48% 207ms 4.90% __ieee754_pow_fma 62ms 1.47% 63.94% 139ms 3.29% CellLuoRudy1991FromCellML::VerifyStateVariables 2ms 0.047% 63.99% 122ms 2.89% MatNullSpaceRemove 25ms 0.59% 64.58% 110ms 2.60% MPI_Wtime 0 0% 64.58% 105ms 2.49% VecDuplicate 102ms 2.41% 67.00% 102ms 2.41% log_inline (inline) 85ms 2.01% 69.01% 85ms 2.01% [[vdso]] 0 0% 69.01% 85ms 2.01% __GI___clock_gettime 0 0% 69.01% 75ms 1.78% VecMDot 4ms 0.095% 69.11% 75ms 1.78% VecMXDot_Private 11ms 0.26% 69.37% 74ms 1.75% PetscLogEventBeginDefault 73ms 1.73% 71.09% 73ms 1.73% exp_inline (inline) 5ms 0.12% 71.21% 62ms 1.47% PetscMallocA 11ms 0.26% 71.47% 59ms 1.40% PetscLogEventEndDefault 3ms 0.071% 71.54% 57ms 1.35% PetscMallocAlign (partial-inline) 2ms 0.047% 71.59% 56ms 1.33% VecDuplicate_MPI 30ms 0.71% 72.30% 54ms 1.28% TimeStepper::TimeStepper 4ms 0.095% 72.40% 52ms 1.23% VecCopy 0 0% 72.40% 52ms 1.23% VecDestroy 0 0% 72.40% 49ms 1.16% VecDuplicate_Seq 48ms 1.14% 73.53% 48ms 1.14% __ieee754_log_fma 41ms 0.97% 74.50% 47ms 1.11% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 44ms 1.04% 75.54% 46ms 1.09% VecMDot_Seq 46ms 1.09% 76.63% 46ms 1.09% ddot_ 3ms 0.071% 76.70% 45ms 1.07% VecMAXPY 15ms 0.36% 77.06% 44ms 1.04% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 77.06% 44ms 1.04% AbstractBidomainSolver::FinaliseLinearSystem 3ms 0.071% 77.13% 44ms 1.04% VecCreate 8ms 0.19% 77.32% 43ms 1.02% VecAXPY 0 0% 77.32% 43ms 1.02% VecNorm 11ms 0.26% 77.58% 39ms 0.92% std::__cxx11::basic_string::basic_string 29ms 0.69% 78.27% 38ms 0.9% AbstractOneStepIvpOdeSolver::Solve 15ms 0.36% 78.62% 37ms 0.88% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 0 0% 78.62% 37ms 0.88% CxxTest::RealWorldDescription::setUp 0 0% 78.62% 37ms 0.88% PetscInitialize 0 0% 78.62% 37ms 0.88% PetscMemzero (inline) 0 0% 78.62% 37ms 0.88% PetscSetup::setUpWorld 0 0% 78.62% 37ms 0.88% PetscSetupUtils::CommonSetup 0 0% 78.62% 37ms 0.88% PetscSetupUtils::InitialisePetsc 0 0% 78.62% 36ms 0.85% PMPI_Init_thread 0 0% 78.62% 36ms 0.85% ompi_mpi_init 35ms 0.83% 79.45% 35ms 0.83% [libchaste_heart.so] 35ms 0.83% 80.28% 35ms 0.83% __memset_avx2_unaligned_erms 4ms 0.095% 80.37% 34ms 0.8% VecDot 0 0% 80.37% 34ms 0.8% VecNorm_Seq 10ms 0.24% 80.61% 33ms 0.78% AbstractCardiacCell::SetVoltage (partial-inline) 32ms 0.76% 81.37% 32ms 0.76% TimeStepper::CalculateNextTime 32ms 0.76% 82.13% 32ms 0.76% __memcpy_avx_unaligned_erms 1ms 0.024% 82.15% 31ms 0.73% 31ms 0.73% 82.88% 31ms 0.73% DistributedVector::Iterator::operator!= 0 0% 82.88% 29ms 0.69% VecCreate_MPI_Private 2ms 0.047% 82.93% 29ms 0.69% __posix_memalign 29ms 0.69% 83.62% 29ms 0.69% std::isinf (inline) 15ms 0.36% 83.97% 27ms 0.64% top12 (inline) 0 0% 83.97% 26ms 0.62% VecDot_Seq 22ms 0.52% 84.49% 26ms 0.62% VecMAXPY_Seq 2ms 0.047% 84.54% 26ms 0.62% VecSet 2ms 0.047% 84.59% 26ms 0.62% VecSetType 0 0% 84.59% 26ms 0.62% VecXDot_Seq_Private.constprop.0 10ms 0.24% 84.82% 26ms 0.62% __GI___libc_malloc 0 0% 84.82% 26ms 0.62% orte_init 0 0% 84.82% 25ms 0.59% PetscMemcpy (inline) 3ms 0.071% 84.90% 25ms 0.59% VecAXPY_Seq 25ms 0.59% 85.49% 25ms 0.59% __strlen_avx2 0 0% 85.49% 24ms 0.57% VecNorm_MPI 0 0% 85.49% 24ms 0.57% VecNorm_MPI_Default.constprop.0 0 0% 85.49% 24ms 0.57% std::char_traits::length (inline) 23ms 0.54% 86.03% 23ms 0.54% TimeStepper::GetNextTimeStep 10ms 0.24% 86.27% 22ms 0.52% TimeStepper::AdvanceOneTimeStep 2ms 0.047% 86.32% 22ms 0.52% VecDestroy_MPI 22ms 0.52% 86.84% 22ms 0.52% [libm.so.6]