File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 3760ms, 86.90% of 4327ms total Dropped 461 nodes (cum <= 21.64ms) flat flat% sum% cum cum% 0 0% 0% 4317ms 99.77% __libc_start_call_main 0 0% 0% 4317ms 99.77% __libc_start_main_impl 0 0% 0% 4317ms 99.77% _start 0 0% 0% 4313ms 99.68% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4313ms 99.68% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4313ms 99.68% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4313ms 99.68% main 0 0% 0% 4270ms 98.68% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4270ms 98.68% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4270ms 98.68% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 2ms 0.046% 0.046% 4263ms 98.52% AbstractCardiacProblem::Solve 1ms 0.023% 0.069% 4244ms 98.08% AbstractDynamicLinearPdeSolver::Solve 25ms 0.58% 0.65% 2225ms 51.42% AbstractCardiacTissue::SolveCellSystems 1ms 0.023% 0.67% 1592ms 36.79% LinearSystem::Solve 0 0% 0.67% 1519ms 35.11% KSPSolve 6ms 0.14% 0.81% 1519ms 35.11% KSPSolve_Private 0 0% 0.81% 1456ms 33.65% KSPSolve_SYMMLQ 53ms 1.22% 2.03% 1265ms 29.24% AbstractCardiacCell::ComputeExceptVoltage 21ms 0.49% 2.52% 1068ms 24.68% AbstractOneStepIvpOdeSolver::InternalSolve 2ms 0.046% 2.57% 902ms 20.85% PCApply 69ms 1.59% 4.16% 898ms 20.75% EulerIvpOdeSolver::CalculateNextYValue 0 0% 4.16% 892ms 20.61% PCApply_BJacobi_Singleblock 214ms 4.95% 9.11% 817ms 18.88% CellLuoRudy1991FromCellML::EvaluateYDerivatives 17ms 0.39% 9.50% 783ms 18.10% AbstractCardiacTissue::UpdateCaches (inline) 0 0% 9.50% 733ms 16.94% KSPSolve_PREONLY (partial-inline) 0 0% 9.50% 730ms 16.87% KSP_PCApply.lto_priv.18 374ms 8.64% 18.14% 721ms 16.66% __GI___exp 244ms 5.64% 23.78% 712ms 16.45% CellLuoRudy1991FromCellML::GetIIonic 1ms 0.023% 23.80% 683ms 15.78% PCApply_ICC 2ms 0.046% 23.85% 682ms 15.76% MatSolve 671ms 15.51% 39.36% 672ms 15.53% MatSolve_SeqSBAIJ_1_NaturalOrdering 3ms 0.069% 39.43% 489ms 11.30% MatMult 8ms 0.18% 39.61% 479ms 11.07% MatMult_SeqAIJ (partial-inline) 465ms 10.75% 50.36% 479ms 11.07% MatMult_SeqAIJ_Inode (inline) 71ms 1.64% 52.00% 341ms 7.88% BidomainSolver::SetupLinearSystem 279ms 6.45% 58.45% 329ms 7.60% __ieee754_exp_fma 31ms 0.72% 59.16% 264ms 6.10% __pow 0 0% 59.16% 264ms 6.10% std::pow (inline) 0 0% 59.16% 239ms 5.52% KSP_PCApply (partial-inline) 1ms 0.023% 59.19% 233ms 5.38% KSPConvergedDefault 26ms 0.6% 59.79% 232ms 5.36% __ieee754_pow_fma 2ms 0.046% 59.83% 124ms 2.87% MatNullSpaceRemove 104ms 2.40% 62.24% 104ms 2.40% log_inline (inline) 0 0% 62.24% 97ms 2.24% VecDuplicate 44ms 1.02% 63.25% 90ms 2.08% CellLuoRudy1991FromCellML::VerifyStateVariables 87ms 2.01% 65.26% 90ms 2.08% exp_inline (inline) 19ms 0.44% 65.70% 88ms 2.03% MPI_Wtime 82ms 1.90% 67.60% 82ms 1.90% [libchaste_heart.so] 1ms 0.023% 67.62% 79ms 1.83% AbstractBidomainSolver::FinaliseLinearSystem 32ms 0.74% 68.36% 78ms 1.80% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 68.36% 77ms 1.78% VecMDot 4ms 0.092% 68.45% 77ms 1.78% VecMXDot_Private 29ms 0.67% 69.12% 71ms 1.64% AbstractCardiacCell::SetVoltage (partial-inline) 15ms 0.35% 69.47% 71ms 1.64% PetscLogEventBeginDefault 12ms 0.28% 69.75% 71ms 1.64% VecCopy 4ms 0.092% 69.84% 69ms 1.59% __GI___clock_gettime 66ms 1.53% 71.37% 66ms 1.53% [[vdso]] 24ms 0.55% 71.92% 62ms 1.43% TimeStepper::TimeStepper 2ms 0.046% 71.97% 61ms 1.41% VecDestroy 1ms 0.023% 71.99% 61ms 1.41% VecDuplicate_MPI 12ms 0.28% 72.27% 59ms 1.36% PetscLogEventEndDefault 55ms 1.27% 73.54% 58ms 1.34% VecMDot_Seq 56ms 1.29% 74.83% 56ms 1.29% ddot_ 1ms 0.023% 74.86% 50ms 1.16% VecDot 1ms 0.023% 74.88% 49ms 1.13% VecNorm 38ms 0.88% 75.76% 47ms 1.09% top12 (inline) 12ms 0.28% 76.03% 46ms 1.06% PetscMallocA 4ms 0.092% 76.13% 45ms 1.04% VecMAXPY 44ms 1.02% 77.14% 44ms 1.02% __ieee754_log_fma 43ms 0.99% 78.14% 43ms 0.99% __memcpy_avx_unaligned_erms 22ms 0.51% 78.65% 42ms 0.97% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 0 0% 78.65% 41ms 0.95% VecDot_Seq 0 0% 78.65% 41ms 0.95% VecXDot_Seq_Private.constprop.0 1ms 0.023% 78.67% 40ms 0.92% VecCreate 0 0% 78.67% 39ms 0.9% CxxTest::RealWorldDescription::setUp 0 0% 78.67% 39ms 0.9% PetscInitialize 0 0% 78.67% 39ms 0.9% PetscSetup::setUpWorld 0 0% 78.67% 39ms 0.9% PetscSetupUtils::CommonSetup 0 0% 78.67% 39ms 0.9% PetscSetupUtils::InitialisePetsc 0 0% 78.67% 39ms 0.9% VecNorm_Seq 0 0% 78.67% 38ms 0.88% PMPI_Init_thread 0 0% 78.67% 38ms 0.88% ompi_mpi_init 36ms 0.83% 79.50% 36ms 0.83% TimeStepper::CalculateNextTime 3ms 0.069% 79.57% 36ms 0.83% VecCreate_MPI_Private 0 0% 79.57% 36ms 0.83% VecDuplicate_Seq 33ms 0.76% 80.33% 35ms 0.81% AbstractParameterisedSystem::SetAnyVariable 3ms 0.069% 80.40% 34ms 0.79% 1ms 0.023% 80.43% 34ms 0.79% PetscMallocAlign (partial-inline) 0 0% 80.43% 34ms 0.79% VecAXPY 25ms 0.58% 81.00% 31ms 0.72% AbstractParameterisedSystem::GetAnyVariable 31ms 0.72% 81.72% 31ms 0.72% DistributedVector::Stripe::operator[] (inline) 0 0% 81.72% 30ms 0.69% KSP_RemoveNullSpace (inline) 0 0% 81.72% 29ms 0.67% PetscMemcpy (inline) 1ms 0.023% 81.74% 29ms 0.67% VecCopy_Seq (partial-inline) 0 0% 81.74% 29ms 0.67% VecNorm_MPI 0 0% 81.74% 29ms 0.67% VecNorm_MPI_Default.constprop.0 28ms 0.65% 82.39% 28ms 0.65% RegularStimulus::GetStimulus 3ms 0.069% 82.46% 27ms 0.62% PetscHeaderDestroy_Private 27ms 0.62% 83.08% 27ms 0.62% VecMAXPY_Seq 27ms 0.62% 83.71% 27ms 0.62% asuint64 (inline) 21ms 0.49% 84.19% 26ms 0.6% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 0 0% 84.19% 26ms 0.6% VecAXPY_Seq 26ms 0.6% 84.79% 26ms 0.6% daxpy_ 16ms 0.37% 85.16% 25ms 0.58% TimeStepper::AdvanceOneTimeStep 5ms 0.12% 85.28% 25ms 0.58% VecSet 0 0% 85.28% 25ms 0.58% std::vector::_M_assign_aux (inline) 0 0% 85.28% 25ms 0.58% std::vector::assign (inline) 11ms 0.25% 85.53% 24ms 0.55% AbstractOneStepIvpOdeSolver::Solve 1ms 0.023% 85.56% 24ms 0.55% PMPI_Comm_get_attr 23ms 0.53% 86.09% 23ms 0.53% dasum_ 7ms 0.16% 86.25% 23ms 0.53% ompi_attr_get_c 0 0% 86.25% 23ms 0.53% orte_init 0 0% 86.25% 22ms 0.51% PetscMemzero (inline) 0 0% 86.25% 22ms 0.51% VecStashCreate_Private 0 0% 86.25% 22ms 0.51% __GI__dl_catch_exception 22ms 0.51% 86.76% 22ms 0.51% __memset_avx2_unaligned_erms 2ms 0.046% 86.80% 22ms 0.51% __posix_memalign 0 0% 86.80% 22ms 0.51% _dl_catch_error 0 0% 86.80% 22ms 0.51% _dlerror_run 4ms 0.092% 86.90% 22ms 0.51% std::__copy_move::__copy_m (inline) 0 0% 86.90% 22ms 0.51% std::__copy_move_a (inline) 0 0% 86.90% 22ms 0.51% std::__copy_move_a1 (inline) 0 0% 86.90% 22ms 0.51% std::__copy_move_a2 (inline) 0 0% 86.90% 22ms 0.51% std::copy (inline)