File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 3759ms, 86.35% of 4353ms total Dropped 430 nodes (cum <= 21.77ms) flat flat% sum% cum cum% 0 0% 0% 4346ms 99.84% __libc_start_call_main 0 0% 0% 4346ms 99.84% __libc_start_main_impl 0 0% 0% 4346ms 99.84% _start 0 0% 0% 4343ms 99.77% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4343ms 99.77% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4343ms 99.77% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4343ms 99.77% main 0 0% 0% 4307ms 98.94% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4307ms 98.94% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4307ms 98.94% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 0 0% 0% 4301ms 98.81% AbstractCardiacProblem::Solve 1ms 0.023% 0.023% 4290ms 98.55% AbstractDynamicLinearPdeSolver::Solve 11ms 0.25% 0.28% 2214ms 50.86% AbstractCardiacTissue::SolveCellSystems 0 0% 0.28% 1747ms 40.13% LinearSystem::Solve 0 0% 0.28% 1704ms 39.15% KSPSolve 10ms 0.23% 0.51% 1704ms 39.15% KSPSolve_Private 0 0% 0.51% 1649ms 37.88% KSPSolve_SYMMLQ 35ms 0.8% 1.31% 1294ms 29.73% AbstractCardiacCell::ComputeExceptVoltage 39ms 0.9% 2.21% 1105ms 25.38% AbstractOneStepIvpOdeSolver::InternalSolve 1ms 0.023% 2.23% 1032ms 23.71% PCApply 3ms 0.069% 2.30% 1024ms 23.52% PCApply_BJacobi_Singleblock 50ms 1.15% 3.45% 883ms 20.28% EulerIvpOdeSolver::CalculateNextYValue 0 0% 3.45% 855ms 19.64% KSPSolve_PREONLY (partial-inline) 0 0% 3.45% 829ms 19.04% KSP_PCApply.lto_priv.18 217ms 4.99% 8.43% 824ms 18.93% CellLuoRudy1991FromCellML::EvaluateYDerivatives 2ms 0.046% 8.48% 801ms 18.40% MatSolve 0 0% 8.48% 801ms 18.40% PCApply_ICC 789ms 18.13% 26.60% 791ms 18.17% MatSolve_SeqSBAIJ_1_NaturalOrdering 316ms 7.26% 33.86% 749ms 17.21% __GI___exp 23ms 0.53% 34.39% 690ms 15.85% AbstractCardiacTissue::UpdateCaches (inline) 238ms 5.47% 39.86% 646ms 14.84% CellLuoRudy1991FromCellML::GetIIonic 5ms 0.11% 39.97% 481ms 11.05% MatMult 6ms 0.14% 40.11% 468ms 10.75% MatMult_SeqAIJ (partial-inline) 458ms 10.52% 50.63% 468ms 10.75% MatMult_SeqAIJ_Inode (inline) 376ms 8.64% 59.27% 412ms 9.46% __ieee754_exp_fma 0 0% 59.27% 298ms 6.85% KSPConvergedDefault 0 0% 59.27% 295ms 6.78% KSP_PCApply (partial-inline) 60ms 1.38% 60.65% 289ms 6.64% BidomainSolver::SetupLinearSystem 2ms 0.046% 60.69% 203ms 4.66% std::pow (inline) 26ms 0.6% 61.29% 201ms 4.62% __pow 76ms 1.75% 63.04% 180ms 4.14% CellLuoRudy1991FromCellML::VerifyStateVariables 26ms 0.6% 63.63% 175ms 4.02% __ieee754_pow_fma 2ms 0.046% 63.68% 144ms 3.31% MatNullSpaceRemove 0 0% 63.68% 119ms 2.73% VecDuplicate 31ms 0.71% 64.39% 88ms 2.02% TimeStepper::TimeStepper 85ms 1.95% 66.35% 85ms 1.95% TimeStepper::CalculateNextTime 19ms 0.44% 66.78% 82ms 1.88% MPI_Wtime 0 0% 66.78% 82ms 1.88% VecMDot 6ms 0.14% 66.92% 82ms 1.88% VecMXDot_Private 76ms 1.75% 68.67% 76ms 1.75% log_inline (inline) 74ms 1.70% 70.37% 74ms 1.70% ddot_ 71ms 1.63% 72.00% 71ms 1.63% exp_inline (inline) 1ms 0.023% 72.02% 67ms 1.54% VecDuplicate_Seq 63ms 1.45% 73.47% 63ms 1.45% [[vdso]] 0 0% 73.47% 63ms 1.45% __GI___clock_gettime 14ms 0.32% 73.79% 62ms 1.42% PetscLogEventEndDefault 3ms 0.069% 73.86% 62ms 1.42% VecDot 10ms 0.23% 74.09% 60ms 1.38% PetscLogEventBeginDefault 6ms 0.14% 74.22% 60ms 1.38% VecMAXPY 52ms 1.19% 75.42% 55ms 1.26% VecMDot_Seq 8ms 0.18% 75.60% 53ms 1.22% VecCopy 0 0% 75.60% 52ms 1.19% VecDestroy 0 0% 75.60% 52ms 1.19% VecDot_Seq 4ms 0.092% 75.69% 52ms 1.19% VecDuplicate_MPI 1ms 0.023% 75.72% 52ms 1.19% VecXDot_Seq_Private.constprop.0 1ms 0.023% 75.74% 51ms 1.17% VecCreate 4ms 0.092% 75.83% 49ms 1.13% PetscMallocA 47ms 1.08% 76.91% 47ms 1.08% std::isinf (inline) 1ms 0.023% 76.94% 45ms 1.03% PetscMallocAlign 25ms 0.57% 77.51% 43ms 0.99% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 39ms 0.9% 78.41% 43ms 0.99% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 10ms 0.23% 78.64% 43ms 0.99% TimeStepper::AdvanceOneTimeStep 0 0% 78.64% 43ms 0.99% VecNorm 41ms 0.94% 79.58% 42ms 0.96% __ieee754_log_fma 34ms 0.78% 80.36% 41ms 0.94% AbstractOneStepIvpOdeSolver::Solve 6ms 0.14% 80.50% 41ms 0.94% VecAXPY 37ms 0.85% 81.35% 40ms 0.92% VecMAXPY_Seq 39ms 0.9% 82.24% 39ms 0.9% [libchaste_heart.so] 3ms 0.069% 82.31% 38ms 0.87% AbstractCardiacCell::SetVoltage (partial-inline) 3ms 0.069% 82.38% 38ms 0.87% VecSetType 37ms 0.85% 83.23% 37ms 0.85% __memcpy_avx_unaligned_erms 1ms 0.023% 83.25% 36ms 0.83% VecNorm_Seq 10ms 0.23% 83.48% 35ms 0.8% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 83.48% 35ms 0.8% AbstractBidomainSolver::FinaliseLinearSystem 35ms 0.8% 84.29% 35ms 0.8% asuint64 (inline) 0 0% 84.29% 33ms 0.76% PetscMemcpy (inline) 12ms 0.28% 84.56% 33ms 0.76% std::__cxx11::basic_string::basic_string 0 0% 84.56% 32ms 0.74% CxxTest::RealWorldDescription::setUp 0 0% 84.56% 32ms 0.74% PetscSetup::setUpWorld 0 0% 84.56% 32ms 0.74% PetscSetupUtils::CommonSetup 7ms 0.16% 84.72% 32ms 0.74% __GI___libc_malloc 2ms 0.046% 84.77% 32ms 0.74% __posix_memalign 4ms 0.092% 84.86% 32ms 0.74% top12 (inline) 29ms 0.67% 85.53% 31ms 0.71% AbstractParameterisedSystem::SetAnyVariable 0 0% 85.53% 31ms 0.71% PMPI_Init_thread 0 0% 85.53% 31ms 0.71% PetscInitialize 0 0% 85.53% 31ms 0.71% PetscSetupUtils::InitialisePetsc 0 0% 85.53% 31ms 0.71% ompi_mpi_init 30ms 0.69% 86.22% 30ms 0.69% DistributedVector::Iterator::operator!= 0 0% 86.22% 27ms 0.62% VecCopy_Seq (partial-inline) 0 0% 86.22% 27ms 0.62% VecNorm_MPI 1ms 0.023% 86.24% 27ms 0.62% VecNorm_MPI_Default.constprop.0 2ms 0.046% 86.29% 25ms 0.57% 0 0% 86.29% 25ms 0.57% KSP_RemoveNullSpace (inline) 0 0% 86.29% 25ms 0.57% VecCreate_Seq 1ms 0.023% 86.31% 24ms 0.55% PetscHeaderCreate_Private 1ms 0.023% 86.33% 24ms 0.55% VecAXPY_Seq 1ms 0.023% 86.35% 23ms 0.53% VecCreate_MPI_Private 0 0% 86.35% 23ms 0.53% orte_init