File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 3780ms, 88.36% of 4278ms total Dropped 458 nodes (cum <= 21.39ms) flat flat% sum% cum cum% 0 0% 0% 4270ms 99.81% __libc_start_call_main 0 0% 0% 4270ms 99.81% __libc_start_main_impl 0 0% 0% 4270ms 99.81% _start 0 0% 0% 4267ms 99.74% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4267ms 99.74% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4267ms 99.74% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4267ms 99.74% main 0 0% 0% 4230ms 98.88% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4230ms 98.88% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4230ms 98.88% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 0 0% 0% 4224ms 98.74% AbstractCardiacProblem::Solve 1ms 0.023% 0.023% 4209ms 98.39% AbstractDynamicLinearPdeSolver::Solve 4ms 0.094% 0.12% 2359ms 55.14% AbstractCardiacTissue::SolveCellSystems 1ms 0.023% 0.14% 1533ms 35.83% LinearSystem::Solve 0 0% 0.14% 1493ms 34.90% KSPSolve 7ms 0.16% 0.3% 1493ms 34.90% KSPSolve_Private 1ms 0.023% 0.33% 1445ms 33.78% KSPSolve_SYMMLQ 36ms 0.84% 1.17% 1365ms 31.91% AbstractCardiacCell::ComputeExceptVoltage 21ms 0.49% 1.66% 1161ms 27.14% AbstractOneStepIvpOdeSolver::InternalSolve 59ms 1.38% 3.04% 964ms 22.53% EulerIvpOdeSolver::CalculateNextYValue 3ms 0.07% 3.11% 947ms 22.14% PCApply 2ms 0.047% 3.16% 941ms 22.00% PCApply_BJacobi_Singleblock 262ms 6.12% 9.28% 897ms 20.97% CellLuoRudy1991FromCellML::EvaluateYDerivatives 0 0% 9.28% 797ms 18.63% KSPSolve_PREONLY (partial-inline) 332ms 7.76% 17.04% 790ms 18.47% __GI___exp 26ms 0.61% 17.65% 781ms 18.26% AbstractCardiacTissue::UpdateCaches (inline) 2ms 0.047% 17.70% 752ms 17.58% MatSolve 0 0% 17.70% 752ms 17.58% PCApply_ICC 740ms 17.30% 34.99% 750ms 17.53% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 246ms 5.75% 40.74% 732ms 17.11% CellLuoRudy1991FromCellML::GetIIonic 2ms 0.047% 40.79% 679ms 15.87% KSP_PCApply.lto_priv.18 405ms 9.47% 50.26% 435ms 10.17% __ieee754_exp_fma 8ms 0.19% 50.44% 369ms 8.63% MatMult 2ms 0.047% 50.49% 357ms 8.35% MatMult_SeqAIJ 353ms 8.25% 58.74% 357ms 8.35% MatMult_SeqAIJ_Inode (inline) 0 0% 58.74% 350ms 8.18% KSPConvergedDefault 0 0% 58.74% 345ms 8.06% KSP_PCApply (partial-inline) 54ms 1.26% 60.00% 274ms 6.40% BidomainSolver::SetupLinearSystem 4ms 0.094% 60.10% 257ms 6.01% std::pow (inline) 19ms 0.44% 60.54% 253ms 5.91% __pow 30ms 0.7% 61.24% 234ms 5.47% __ieee754_pow_fma 81ms 1.89% 63.14% 173ms 4.04% CellLuoRudy1991FromCellML::VerifyStateVariables 0 0% 63.14% 112ms 2.62% MatNullSpaceRemove 112ms 2.62% 65.76% 112ms 2.62% log_inline (inline) 49ms 1.15% 66.90% 98ms 2.29% TimeStepper::TimeStepper 0 0% 66.90% 92ms 2.15% VecDuplicate 83ms 1.94% 68.84% 85ms 1.99% exp_inline (inline) 77ms 1.80% 70.64% 77ms 1.80% ddot_ 0 0% 70.64% 72ms 1.68% VecMDot 3ms 0.07% 70.71% 72ms 1.68% VecMXDot_Private 13ms 0.3% 71.01% 66ms 1.54% MPI_Wtime 0 0% 71.01% 58ms 1.36% VecDot 12ms 0.28% 71.29% 54ms 1.26% PetscLogEventBeginDefault 17ms 0.4% 71.69% 54ms 1.26% PetscLogEventEndDefault 53ms 1.24% 72.93% 53ms 1.24% TimeStepper::CalculateNextTime 6ms 0.14% 73.07% 53ms 1.24% VecCopy 1ms 0.023% 73.09% 53ms 1.24% __GI___clock_gettime 51ms 1.19% 74.29% 53ms 1.24% __ieee754_log_fma 33ms 0.77% 75.06% 52ms 1.22% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 0 0% 75.06% 52ms 1.22% VecDot_Seq 2ms 0.047% 75.11% 52ms 1.22% VecDuplicate_MPI 0 0% 75.11% 52ms 1.22% VecXDot_Seq_Private.constprop.0 52ms 1.22% 76.32% 52ms 1.22% [[vdso]] 50ms 1.17% 77.49% 51ms 1.19% VecMDot_Seq 3ms 0.07% 77.56% 48ms 1.12% KSP_RemoveNullSpace (inline) 3ms 0.07% 77.63% 48ms 1.12% PetscMallocA 4ms 0.094% 77.72% 48ms 1.12% VecAXPY 1ms 0.023% 77.75% 45ms 1.05% PetscMallocAlign 0 0% 77.75% 45ms 1.05% VecDestroy 44ms 1.03% 78.78% 44ms 1.03% [libchaste_heart.so] 1ms 0.023% 78.80% 43ms 1.01% VecNorm 6ms 0.14% 78.94% 41ms 0.96% AbstractCardiacCell::SetVoltage (partial-inline) 35ms 0.82% 79.76% 40ms 0.94% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 34ms 0.79% 80.55% 40ms 0.94% AbstractOneStepIvpOdeSolver::Solve 2ms 0.047% 80.60% 40ms 0.94% VecCreate 0 0% 80.60% 40ms 0.94% VecDuplicate_Seq 2ms 0.047% 80.65% 40ms 0.94% VecMAXPY 38ms 0.89% 81.53% 38ms 0.89% __memcpy_avx_unaligned_erms 16ms 0.37% 81.91% 38ms 0.89% std::__cxx11::basic_string::basic_string 0 0% 81.91% 37ms 0.86% VecNorm_Seq 12ms 0.28% 82.19% 36ms 0.84% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 82.19% 36ms 0.84% AbstractBidomainSolver::FinaliseLinearSystem 35ms 0.82% 83.01% 35ms 0.82% std::isinf (inline) 16ms 0.37% 83.38% 34ms 0.79% top12 (inline) 0 0% 83.38% 33ms 0.77% CxxTest::RealWorldDescription::setUp 0 0% 83.38% 33ms 0.77% PMPI_Init_thread 0 0% 83.38% 33ms 0.77% PetscInitialize 0 0% 83.38% 33ms 0.77% PetscSetup::setUpWorld 0 0% 83.38% 33ms 0.77% PetscSetupUtils::CommonSetup 0 0% 83.38% 33ms 0.77% PetscSetupUtils::InitialisePetsc 0 0% 83.38% 33ms 0.77% ompi_mpi_init 0 0% 83.38% 32ms 0.75% PetscMemcpy (inline) 25ms 0.58% 83.96% 31ms 0.72% AbstractParameterisedSystem::SetAnyVariable 31ms 0.72% 84.69% 31ms 0.72% TimeStepper::GetNextTimeStep 4ms 0.094% 84.78% 31ms 0.72% VecCreate_MPI_Private 0 0% 84.78% 29ms 0.68% PetscTimeSubtract (inline) 2ms 0.047% 84.83% 29ms 0.68% __posix_memalign 1ms 0.023% 84.85% 28ms 0.65% 2ms 0.047% 84.90% 27ms 0.63% VecAXPY_Seq 24ms 0.56% 85.46% 27ms 0.63% VecMAXPY_Seq 5ms 0.12% 85.58% 27ms 0.63% __GI___libc_malloc 21ms 0.49% 86.07% 25ms 0.58% AbstractParameterisedSystem::GetAnyVariable 25ms 0.58% 86.65% 25ms 0.58% DistributedVector::Iterator::operator!= 25ms 0.58% 87.24% 25ms 0.58% asuint64 (inline) 0 0% 87.24% 23ms 0.54% VecCopy_Seq (partial-inline) 23ms 0.54% 87.77% 23ms 0.54% [libm.so.6] 23ms 0.54% 88.31% 23ms 0.54% daxpy_ 2ms 0.047% 88.36% 22ms 0.51% PetscHeaderDestroy_Private 0 0% 88.36% 22ms 0.51% orte_init