File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 3683ms, 86.52% of 4257ms total Dropped 485 nodes (cum <= 21.29ms) flat flat% sum% cum cum% 0 0% 0% 4248ms 99.79% __libc_start_call_main 0 0% 0% 4248ms 99.79% __libc_start_main_impl 0 0% 0% 4248ms 99.79% _start 0 0% 0% 4245ms 99.72% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4245ms 99.72% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4245ms 99.72% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4245ms 99.72% main 0 0% 0% 4209ms 98.87% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4209ms 98.87% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4209ms 98.87% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 0 0% 0% 4204ms 98.75% AbstractCardiacProblem::Solve 1ms 0.023% 0.023% 4198ms 98.61% AbstractDynamicLinearPdeSolver::Solve 9ms 0.21% 0.23% 2113ms 49.64% AbstractCardiacTissue::SolveCellSystems 0 0% 0.23% 1648ms 38.71% LinearSystem::Solve 0 0% 0.23% 1601ms 37.61% KSPSolve 9ms 0.21% 0.45% 1601ms 37.61% KSPSolve_Private 0 0% 0.45% 1549ms 36.39% KSPSolve_SYMMLQ 30ms 0.7% 1.15% 1180ms 27.72% AbstractCardiacCell::ComputeExceptVoltage 30ms 0.7% 1.86% 1009ms 23.70% AbstractOneStepIvpOdeSolver::InternalSolve 2ms 0.047% 1.90% 946ms 22.22% PCApply 0 0% 1.90% 940ms 22.08% PCApply_BJacobi_Singleblock 59ms 1.39% 3.29% 831ms 19.52% EulerIvpOdeSolver::CalculateNextYValue 0 0% 3.29% 793ms 18.63% KSPSolve_PREONLY (partial-inline) 218ms 5.12% 8.41% 760ms 17.85% CellLuoRudy1991FromCellML::EvaluateYDerivatives 1ms 0.023% 8.43% 737ms 17.31% MatSolve 0 0% 8.43% 737ms 17.31% PCApply_ICC 724ms 17.01% 25.44% 729ms 17.12% MatSolve_SeqSBAIJ_1_NaturalOrdering 23ms 0.54% 25.98% 714ms 16.77% AbstractCardiacTissue::UpdateCaches (inline) 0 0% 25.98% 705ms 16.56% KSP_PCApply.lto_priv.18 296ms 6.95% 32.93% 689ms 16.19% __GI___exp 223ms 5.24% 38.17% 661ms 15.53% CellLuoRudy1991FromCellML::GetIIonic 7ms 0.16% 38.34% 525ms 12.33% MatMult 3ms 0.07% 38.41% 511ms 12.00% MatMult_SeqAIJ 502ms 11.79% 50.20% 510ms 11.98% MatMult_SeqAIJ_Inode (inline) 94ms 2.21% 52.41% 404ms 9.49% BidomainSolver::SetupLinearSystem 346ms 8.13% 60.54% 376ms 8.83% __ieee754_exp_fma 0 0% 60.54% 322ms 7.56% KSPConvergedDefault 0 0% 60.54% 310ms 7.28% KSP_PCApply (partial-inline) 2ms 0.047% 60.58% 248ms 5.83% std::pow (inline) 27ms 0.63% 61.22% 246ms 5.78% __pow 36ms 0.85% 62.06% 218ms 5.12% __ieee754_pow_fma 81ms 1.90% 63.97% 156ms 3.66% CellLuoRudy1991FromCellML::VerifyStateVariables 3ms 0.07% 64.04% 133ms 3.12% MatNullSpaceRemove 102ms 2.40% 66.43% 102ms 2.40% log_inline (inline) 1ms 0.023% 66.46% 100ms 2.35% VecDuplicate 1ms 0.023% 66.48% 90ms 2.11% VecMDot 6ms 0.14% 66.62% 89ms 2.09% VecMXDot_Private 24ms 0.56% 67.18% 81ms 1.90% MPI_Wtime 76ms 1.79% 68.97% 76ms 1.79% exp_inline (inline) 24ms 0.56% 69.53% 75ms 1.76% PetscLogEventEndDefault 42ms 0.99% 70.52% 75ms 1.76% TimeStepper::TimeStepper 5ms 0.12% 70.64% 69ms 1.62% VecCopy 62ms 1.46% 72.09% 62ms 1.46% ddot_ 10ms 0.23% 72.33% 59ms 1.39% PetscLogEventBeginDefault 59ms 1.39% 73.71% 59ms 1.39% VecMDot_Seq 1ms 0.023% 73.74% 57ms 1.34% VecDuplicate_MPI 2ms 0.047% 73.78% 57ms 1.34% __GI___clock_gettime 55ms 1.29% 75.08% 55ms 1.29% [[vdso]] 1ms 0.023% 75.10% 51ms 1.20% VecDot 38ms 0.89% 75.99% 50ms 1.17% AbstractOneStepIvpOdeSolver::Solve 3ms 0.07% 76.06% 47ms 1.10% VecNorm 44ms 1.03% 77.10% 44ms 1.03% TimeStepper::CalculateNextTime 0 0% 77.10% 43ms 1.01% VecDot_Seq 1ms 0.023% 77.12% 43ms 1.01% VecXDot_Seq_Private.constprop.0 2ms 0.047% 77.17% 42ms 0.99% VecDuplicate_Seq 42ms 0.99% 78.15% 42ms 0.99% __memcpy_avx_unaligned_erms 0 0% 78.15% 40ms 0.94% VecCreate 2ms 0.047% 78.20% 40ms 0.94% VecMAXPY 39ms 0.92% 79.12% 39ms 0.92% [libchaste_heart.so] 4ms 0.094% 79.21% 38ms 0.89% VecAXPY 0 0% 79.21% 38ms 0.89% VecDestroy 10ms 0.23% 79.45% 37ms 0.87% AbstractCardiacCell::SetVoltage (partial-inline) 10ms 0.23% 79.68% 36ms 0.85% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 4ms 0.094% 79.77% 36ms 0.85% PetscMallocA 0 0% 79.77% 36ms 0.85% PetscMemcpy (inline) 0 0% 79.77% 35ms 0.82% VecCopy_Seq (partial-inline) 30ms 0.7% 80.48% 33ms 0.78% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 2ms 0.047% 80.53% 32ms 0.75% PetscMallocAlign 12ms 0.28% 80.81% 32ms 0.75% top12 (inline) 0 0% 80.81% 31ms 0.73% CxxTest::RealWorldDescription::setUp 0 0% 80.81% 31ms 0.73% PetscInitialize 0 0% 80.81% 31ms 0.73% PetscSetup::setUpWorld 0 0% 80.81% 31ms 0.73% PetscSetupUtils::CommonSetup 0 0% 80.81% 31ms 0.73% PetscSetupUtils::InitialisePetsc 15ms 0.35% 81.16% 31ms 0.73% TimeStepper::AdvanceOneTimeStep 31ms 0.73% 81.89% 31ms 0.73% std::isinf (inline) 30ms 0.7% 82.59% 30ms 0.7% DistributedVector::Stripe::operator[] (inline) 0 0% 82.59% 30ms 0.7% PMPI_Init_thread 4ms 0.094% 82.69% 30ms 0.7% VecCreate_MPI_Private 0 0% 82.69% 30ms 0.7% VecNorm_Seq 0 0% 82.69% 30ms 0.7% ompi_mpi_init 6ms 0.14% 82.83% 30ms 0.7% std::__cxx11::basic_string::basic_string 5ms 0.12% 82.95% 28ms 0.66% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 82.95% 28ms 0.66% AbstractBidomainSolver::FinaliseLinearSystem 27ms 0.63% 83.58% 28ms 0.66% __ieee754_log_fma 0 0% 83.58% 27ms 0.63% 25ms 0.59% 84.17% 27ms 0.63% VecMAXPY_Seq 26ms 0.61% 84.78% 26ms 0.61% AbstractParameterisedSystem::SetAnyVariable 3ms 0.07% 84.85% 26ms 0.61% VecScale 25ms 0.59% 85.44% 25ms 0.59% DistributedVector::Iterator::operator!= 18ms 0.42% 85.86% 23ms 0.54% AbstractParameterisedSystem::GetAnyVariable 4ms 0.094% 85.95% 23ms 0.54% PetscHeaderDestroy_Private 23ms 0.54% 86.49% 23ms 0.54% asuint64 (inline) 1ms 0.023% 86.52% 22ms 0.52% VecSetType 0 0% 86.52% 22ms 0.52% orte_init