File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 2943ms, 87.23% of 3374ms total Dropped 418 nodes (cum <= 16.87ms) flat flat% sum% cum cum% 0 0% 0% 3367ms 99.79% __libc_start_call_main 0 0% 0% 3367ms 99.79% __libc_start_main_impl 0 0% 0% 3367ms 99.79% _start 0 0% 0% 3365ms 99.73% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 3365ms 99.73% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 3365ms 99.73% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 3365ms 99.73% main 0 0% 0% 3325ms 98.55% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 3325ms 98.55% CxxTest::TestRunner::runTest (inline) 0 0% 0% 3325ms 98.55% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 0 0% 0% 3320ms 98.40% AbstractCardiacProblem::Solve 3ms 0.089% 0.089% 3312ms 98.16% AbstractDynamicLinearPdeSolver::Solve 39ms 1.16% 1.24% 2094ms 62.06% AbstractCardiacTissue::SolveCellSystems 30ms 0.89% 2.13% 1149ms 34.05% AbstractCardiacCell::ComputeExceptVoltage 30ms 0.89% 3.02% 980ms 29.05% AbstractOneStepIvpOdeSolver::InternalSolve 1ms 0.03% 3.05% 878ms 26.02% LinearSystem::Solve 0 0% 3.05% 837ms 24.81% KSPSolve 2ms 0.059% 3.11% 837ms 24.81% KSPSolve_Private 41ms 1.22% 4.33% 818ms 24.24% EulerIvpOdeSolver::CalculateNextYValue 1ms 0.03% 4.36% 801ms 23.74% KSPSolve_SYMMLQ 192ms 5.69% 10.05% 767ms 22.73% CellLuoRudy1991FromCellML::EvaluateYDerivatives 17ms 0.5% 10.55% 743ms 22.02% AbstractCardiacTissue::UpdateCaches (inline) 368ms 10.91% 21.46% 719ms 21.31% __GI___exp 233ms 6.91% 28.36% 698ms 20.69% CellLuoRudy1991FromCellML::GetIIonic 3ms 0.089% 28.45% 465ms 13.78% PCApply 1ms 0.03% 28.48% 459ms 13.60% PCApply_BJacobi_Singleblock 1ms 0.03% 28.51% 387ms 11.47% KSPSolve_PREONLY (partial-inline) 0 0% 28.51% 385ms 11.41% KSP_PCApply.lto_priv.18 1ms 0.03% 28.54% 356ms 10.55% MatSolve 0 0% 28.54% 356ms 10.55% PCApply_ICC 351ms 10.40% 38.94% 354ms 10.49% MatSolve_SeqSBAIJ_1_NaturalOrdering 3ms 0.089% 39.03% 351ms 10.40% MatMult 88ms 2.61% 41.64% 341ms 10.11% MatMult_SeqAIJ (partial-inline) 288ms 8.54% 50.18% 330ms 9.78% __ieee754_exp_fma 63ms 1.87% 52.05% 310ms 9.19% BidomainSolver::SetupLinearSystem 252ms 7.47% 59.51% 266ms 7.88% MatMult_SeqAIJ_Inode (inline) 2ms 0.059% 59.57% 244ms 7.23% std::pow (inline) 25ms 0.74% 60.31% 241ms 7.14% __pow 19ms 0.56% 60.88% 216ms 6.40% __ieee754_pow_fma 0 0% 60.88% 122ms 3.62% KSPConvergedDefault 0 0% 60.88% 119ms 3.53% KSP_PCApply (partial-inline) 107ms 3.17% 64.05% 107ms 3.17% log_inline (inline) 55ms 1.63% 65.68% 106ms 3.14% CellLuoRudy1991FromCellML::VerifyStateVariables 76ms 2.25% 67.93% 78ms 2.31% exp_inline (inline) 1ms 0.03% 67.96% 69ms 2.05% MatNullSpaceRemove 26ms 0.77% 68.73% 59ms 1.75% TimeStepper::TimeStepper 12ms 0.36% 69.09% 57ms 1.69% MPI_Wtime 0 0% 69.09% 56ms 1.66% VecDuplicate 45ms 1.33% 70.42% 45ms 1.33% [[vdso]] 0 0% 70.42% 45ms 1.33% __GI___clock_gettime 44ms 1.30% 71.72% 44ms 1.30% [libchaste_heart.so] 11ms 0.33% 72.05% 43ms 1.27% AbstractCardiacCell::SetVoltage (partial-inline) 9ms 0.27% 72.32% 43ms 1.27% VecCopy 43ms 1.27% 73.59% 43ms 1.27% __memcpy_avx512_unaligned_erms 35ms 1.04% 74.63% 39ms 1.16% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 11ms 0.33% 74.96% 39ms 1.16% PetscLogEventEndDefault 39ms 1.16% 76.11% 39ms 1.16% __ieee754_log_fma 39ms 1.16% 77.27% 39ms 1.16% ddot_ 29ms 0.86% 78.13% 37ms 1.10% top12 (inline) 0 0% 78.13% 36ms 1.07% CxxTest::RealWorldDescription::setUp 0 0% 78.13% 36ms 1.07% PMPI_Init_thread 0 0% 78.13% 36ms 1.07% PetscInitialize 2ms 0.059% 78.19% 36ms 1.07% PetscLogEventBeginDefault 0 0% 78.19% 36ms 1.07% PetscSetup::setUpWorld 0 0% 78.19% 36ms 1.07% PetscSetupUtils::CommonSetup 0 0% 78.19% 36ms 1.07% PetscSetupUtils::InitialisePetsc 0 0% 78.19% 36ms 1.07% ompi_mpi_init 1ms 0.03% 78.22% 35ms 1.04% VecDuplicate_MPI 1ms 0.03% 78.25% 34ms 1.01% 34ms 1.01% 79.25% 34ms 1.01% TimeStepper::CalculateNextTime 2ms 0.059% 79.31% 34ms 1.01% VecMAXPY 0 0% 79.31% 34ms 1.01% VecMDot 1ms 0.03% 79.34% 34ms 1.01% VecMXDot_Private 21ms 0.62% 79.96% 31ms 0.92% AbstractOneStepIvpOdeSolver::Solve 0 0% 79.96% 30ms 0.89% VecDot 15ms 0.44% 80.41% 29ms 0.86% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 1ms 0.03% 80.44% 28ms 0.83% VecNorm 23ms 0.68% 81.12% 27ms 0.8% AbstractParameterisedSystem::SetAnyVariable 5ms 0.15% 81.27% 27ms 0.8% VecAXPY 1ms 0.03% 81.30% 27ms 0.8% VecCreate 0 0% 81.30% 27ms 0.8% VecDot_Seq 0 0% 81.30% 27ms 0.8% VecXDot_Seq_Private.constprop.0 27ms 0.8% 82.10% 27ms 0.8% asuint64 (inline) 0 0% 82.10% 26ms 0.77% std::vector::_M_assign_aux (inline) 0 0% 82.10% 26ms 0.77% std::vector::assign (inline) 4ms 0.12% 82.22% 25ms 0.74% PetscMallocA 4ms 0.12% 82.34% 24ms 0.71% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 82.34% 24ms 0.71% AbstractBidomainSolver::FinaliseLinearSystem 20ms 0.59% 82.93% 24ms 0.71% AbstractParameterisedSystem::GetAnyVariable 24ms 0.71% 83.64% 24ms 0.71% DistributedVector::Iterator::operator!= 1ms 0.03% 83.67% 24ms 0.71% std::__copy_move::__copy_m (inline) 0 0% 83.67% 24ms 0.71% std::__copy_move_a (inline) 0 0% 83.67% 24ms 0.71% std::__copy_move_a1 (inline) 0 0% 83.67% 24ms 0.71% std::__copy_move_a2 (inline) 10ms 0.3% 83.97% 24ms 0.71% std::__cxx11::basic_string::basic_string 0 0% 83.97% 24ms 0.71% std::copy (inline) 0 0% 83.97% 23ms 0.68% orte_init 1ms 0.03% 84.00% 22ms 0.65% VecNorm_Seq 1ms 0.03% 84.02% 21ms 0.62% PetscMallocAlign 0 0% 84.02% 21ms 0.62% PetscMemcpy (inline) 21ms 0.62% 84.65% 21ms 0.62% RegularStimulus::GetStimulus 1ms 0.03% 84.68% 21ms 0.62% VecDuplicate_Seq 21ms 0.62% 85.30% 21ms 0.62% VecMAXPY_Seq 21ms 0.62% 85.92% 21ms 0.62% [libm.so.6] 3ms 0.089% 86.01% 19ms 0.56% PetscOptionsFindPair 19ms 0.56% 86.57% 19ms 0.56% VecMDot_Seq 5ms 0.15% 86.72% 18ms 0.53% AbstractCardiacCell::GetVoltage (inline) 14ms 0.41% 87.14% 18ms 0.53% TimeStepper::AdvanceOneTimeStep 1ms 0.03% 87.17% 18ms 0.53% VecCreate_MPI_Private 1ms 0.03% 87.20% 18ms 0.53% VecDestroy 1ms 0.03% 87.23% 17ms 0.5% VecAXPY_Seq 0 0% 87.23% 17ms 0.5% VecNorm_MPI 0 0% 87.23% 17ms 0.5% VecNorm_MPI_Default.constprop.0