File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 3624ms, 84.50% of 4289ms total Dropped 471 nodes (cum <= 21.45ms) flat flat% sum% cum cum% 0 0% 0% 4280ms 99.79% __libc_start_call_main 0 0% 0% 4280ms 99.79% __libc_start_main_impl 0 0% 0% 4280ms 99.79% _start 0 0% 0% 4277ms 99.72% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4277ms 99.72% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4277ms 99.72% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4277ms 99.72% main 0 0% 0% 4237ms 98.79% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4237ms 98.79% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4237ms 98.79% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 1ms 0.023% 0.023% 4230ms 98.62% AbstractCardiacProblem::Solve 3ms 0.07% 0.093% 4219ms 98.37% AbstractDynamicLinearPdeSolver::Solve 25ms 0.58% 0.68% 2168ms 50.55% AbstractCardiacTissue::SolveCellSystems 1ms 0.023% 0.7% 1692ms 39.45% LinearSystem::Solve 1ms 0.023% 0.72% 1637ms 38.17% KSPSolve 8ms 0.19% 0.91% 1637ms 38.17% KSPSolve_Private 2ms 0.047% 0.96% 1582ms 36.89% KSPSolve_SYMMLQ 29ms 0.68% 1.63% 1228ms 28.63% AbstractCardiacCell::ComputeExceptVoltage 21ms 0.49% 2.12% 1074ms 25.04% AbstractOneStepIvpOdeSolver::InternalSolve 1ms 0.023% 2.15% 975ms 22.73% PCApply 0 0% 2.15% 964ms 22.48% PCApply_BJacobi_Singleblock 64ms 1.49% 3.64% 886ms 20.66% EulerIvpOdeSolver::CalculateNextYValue 242ms 5.64% 9.28% 815ms 19.00% CellLuoRudy1991FromCellML::EvaluateYDerivatives 0 0% 9.28% 809ms 18.86% KSPSolve_PREONLY (partial-inline) 26ms 0.61% 9.89% 764ms 17.81% AbstractCardiacTissue::UpdateCaches (inline) 1ms 0.023% 9.91% 752ms 17.53% PCApply_ICC 5ms 0.12% 10.03% 751ms 17.51% MatSolve 734ms 17.11% 27.14% 742ms 17.30% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 0 0% 27.14% 724ms 16.88% KSP_PCApply.lto_priv.18 327ms 7.62% 34.76% 709ms 16.53% __GI___exp 251ms 5.85% 40.62% 703ms 16.39% CellLuoRudy1991FromCellML::GetIIonic 6ms 0.14% 40.76% 495ms 11.54% MatMult 11ms 0.26% 41.01% 483ms 11.26% MatMult_SeqAIJ (partial-inline) 472ms 11.00% 52.02% 483ms 11.26% MatMult_SeqAIJ_Inode (inline) 316ms 7.37% 59.38% 366ms 8.53% __ieee754_exp_fma 0 0% 59.38% 333ms 7.76% KSPConvergedDefault 0 0% 59.38% 332ms 7.74% KSP_PCApply (partial-inline) 60ms 1.40% 60.78% 306ms 7.13% BidomainSolver::SetupLinearSystem 17ms 0.4% 61.18% 250ms 5.83% __pow 0 0% 61.18% 250ms 5.83% std::pow (inline) 22ms 0.51% 61.69% 233ms 5.43% __ieee754_pow_fma 1ms 0.023% 61.72% 142ms 3.31% MatNullSpaceRemove 123ms 2.87% 64.58% 123ms 2.87% log_inline (inline) 1ms 0.023% 64.61% 106ms 2.47% VecDuplicate 55ms 1.28% 65.89% 105ms 2.45% CellLuoRudy1991FromCellML::VerifyStateVariables 0 0% 65.89% 98ms 2.28% VecMDot 10ms 0.23% 66.12% 98ms 2.28% VecMXDot_Private 75ms 1.75% 67.87% 76ms 1.77% exp_inline (inline) 28ms 0.65% 68.52% 72ms 1.68% TimeStepper::TimeStepper 69ms 1.61% 70.13% 72ms 1.68% VecMDot_Seq 17ms 0.4% 70.53% 71ms 1.66% MPI_Wtime 64ms 1.49% 72.02% 64ms 1.49% ddot_ 16ms 0.37% 72.39% 63ms 1.47% PetscLogEventEndDefault 4ms 0.093% 72.49% 56ms 1.31% VecCopy 55ms 1.28% 73.77% 55ms 1.28% TimeStepper::CalculateNextTime 1ms 0.023% 73.79% 54ms 1.26% VecDuplicate_Seq 2ms 0.047% 73.84% 54ms 1.26% __GI___clock_gettime 0 0% 73.84% 52ms 1.21% VecDot 52ms 1.21% 75.05% 52ms 1.21% [[vdso]] 1ms 0.023% 75.08% 51ms 1.19% VecDestroy 2ms 0.047% 75.12% 51ms 1.19% VecDuplicate_MPI 42ms 0.98% 76.10% 51ms 1.19% top12 (inline) 11ms 0.26% 76.36% 50ms 1.17% PetscLogEventBeginDefault 11ms 0.26% 76.61% 49ms 1.14% PetscMallocA 4ms 0.093% 76.71% 47ms 1.10% VecAXPY 1ms 0.023% 76.73% 46ms 1.07% VecNorm 1ms 0.023% 76.75% 45ms 1.05% AbstractBidomainSolver::FinaliseLinearSystem 0 0% 76.75% 45ms 1.05% VecDot_Seq 0 0% 76.75% 45ms 1.05% VecXDot_Seq_Private.constprop.0 14ms 0.33% 77.08% 44ms 1.03% AbstractBidomainSolver::CheckCompatibilityCondition 1ms 0.023% 77.10% 43ms 1.00% VecMAXPY 42ms 0.98% 78.08% 42ms 0.98% __memcpy_avx_unaligned_erms 12ms 0.28% 78.36% 41ms 0.96% AbstractCardiacCell::SetVoltage (partial-inline) 0 0% 78.36% 41ms 0.96% VecCreate 40ms 0.93% 79.30% 40ms 0.93% __ieee754_log_fma 18ms 0.42% 79.72% 39ms 0.91% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 1ms 0.023% 79.74% 39ms 0.91% VecNorm_Seq 2ms 0.047% 79.79% 38ms 0.89% PetscMallocAlign 0 0% 79.79% 37ms 0.86% KSP_RemoveNullSpace (inline) 37ms 0.86% 80.65% 37ms 0.86% [libchaste_heart.so] 0 0% 80.65% 36ms 0.84% CxxTest::RealWorldDescription::setUp 0 0% 80.65% 36ms 0.84% PMPI_Init_thread 0 0% 80.65% 36ms 0.84% PetscInitialize 0 0% 80.65% 36ms 0.84% PetscSetup::setUpWorld 0 0% 80.65% 36ms 0.84% PetscSetupUtils::CommonSetup 0 0% 80.65% 36ms 0.84% PetscSetupUtils::InitialisePetsc 0 0% 80.65% 36ms 0.84% ompi_mpi_init 15ms 0.35% 81.00% 34ms 0.79% TimeStepper::AdvanceOneTimeStep 2ms 0.047% 81.04% 32ms 0.75% PetscHeaderDestroy_Private 0 0% 81.04% 31ms 0.72% PetscMemcpy (inline) 29ms 0.68% 81.72% 31ms 0.72% VecMAXPY_Seq 2ms 0.047% 81.77% 30ms 0.7% 24ms 0.56% 82.33% 30ms 0.7% AbstractParameterisedSystem::GetAnyVariable 0 0% 82.33% 30ms 0.7% VecAXPY_Seq 30ms 0.7% 83.03% 30ms 0.7% daxpy_ 26ms 0.61% 83.63% 29ms 0.68% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 0 0% 83.63% 29ms 0.68% VecSetType 18ms 0.42% 84.05% 27ms 0.63% AbstractParameterisedSystem::SetAnyVariable 2ms 0.047% 84.10% 27ms 0.63% VecCreate_MPI_Private 5ms 0.12% 84.22% 26ms 0.61% PMPI_Comm_get_attr 2ms 0.047% 84.26% 26ms 0.61% PetscTimeSubtract (inline) 0 0% 84.26% 26ms 0.61% VecNorm_MPI 0 0% 84.26% 26ms 0.61% VecNorm_MPI_Default.constprop.0 6ms 0.14% 84.40% 26ms 0.61% __GI___libc_malloc 0 0% 84.40% 25ms 0.58% std::vector::_M_assign_aux (inline) 0 0% 84.40% 25ms 0.58% std::vector::assign (inline) 1ms 0.023% 84.43% 24ms 0.56% __posix_memalign 0 0% 84.43% 23ms 0.54% VecCreate_Seq 3ms 0.07% 84.50% 23ms 0.54% VecScale 0 0% 84.50% 22ms 0.51% orte_init