File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 3788ms, 87.28% of 4340ms total Dropped 429 nodes (cum <= 21.70ms) flat flat% sum% cum cum% 0 0% 0% 4332ms 99.82% __libc_start_call_main 0 0% 0% 4332ms 99.82% __libc_start_main_impl 0 0% 0% 4332ms 99.82% _start 0 0% 0% 4329ms 99.75% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4329ms 99.75% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4329ms 99.75% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4329ms 99.75% main 0 0% 0% 4293ms 98.92% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4293ms 98.92% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4293ms 98.92% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 1ms 0.023% 0.023% 4287ms 98.78% AbstractCardiacProblem::Solve 1ms 0.023% 0.046% 4273ms 98.46% AbstractDynamicLinearPdeSolver::Solve 9ms 0.21% 0.25% 2150ms 49.54% AbstractCardiacTissue::SolveCellSystems 2ms 0.046% 0.3% 1784ms 41.11% LinearSystem::Solve 0 0% 0.3% 1736ms 40.00% KSPSolve 3ms 0.069% 0.37% 1736ms 40.00% KSPSolve_Private 1ms 0.023% 0.39% 1693ms 39.01% KSPSolve_SYMMLQ 24ms 0.55% 0.94% 1193ms 27.49% AbstractCardiacCell::ComputeExceptVoltage 3ms 0.069% 1.01% 1092ms 25.16% PCApply 1ms 0.023% 1.04% 1078ms 24.84% PCApply_BJacobi_Singleblock 29ms 0.67% 1.71% 1028ms 23.69% AbstractOneStepIvpOdeSolver::InternalSolve 0 0% 1.71% 892ms 20.55% KSPSolve_PREONLY (partial-inline) 52ms 1.20% 2.90% 847ms 19.52% EulerIvpOdeSolver::CalculateNextYValue 1ms 0.023% 2.93% 840ms 19.35% PCApply_ICC 1ms 0.023% 2.95% 839ms 19.33% MatSolve 821ms 18.92% 21.87% 829ms 19.10% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 0 0% 21.87% 799ms 18.41% KSP_PCApply.lto_priv.18 239ms 5.51% 27.37% 785ms 18.09% CellLuoRudy1991FromCellML::EvaluateYDerivatives 24ms 0.55% 27.93% 720ms 16.59% AbstractCardiacTissue::UpdateCaches (inline) 308ms 7.10% 35.02% 701ms 16.15% __GI___exp 227ms 5.23% 40.25% 668ms 15.39% CellLuoRudy1991FromCellML::GetIIonic 4ms 0.092% 40.35% 484ms 11.15% MatMult 4ms 0.092% 40.44% 477ms 10.99% MatMult_SeqAIJ 471ms 10.85% 51.29% 475ms 10.94% MatMult_SeqAIJ_Inode (inline) 346ms 7.97% 59.26% 375ms 8.64% __ieee754_exp_fma 0 0% 59.26% 374ms 8.62% KSP_PCApply (partial-inline) 0 0% 59.26% 371ms 8.55% KSPConvergedDefault 59ms 1.36% 60.62% 286ms 6.59% BidomainSolver::SetupLinearSystem 3ms 0.069% 60.69% 229ms 5.28% std::pow (inline) 24ms 0.55% 61.24% 226ms 5.21% __pow 22ms 0.51% 61.75% 202ms 4.65% __ieee754_pow_fma 95ms 2.19% 63.94% 186ms 4.29% CellLuoRudy1991FromCellML::VerifyStateVariables 2ms 0.046% 63.99% 136ms 3.13% MatNullSpaceRemove 0 0% 63.99% 114ms 2.63% VecDuplicate 105ms 2.42% 66.41% 105ms 2.42% log_inline (inline) 21ms 0.48% 66.89% 90ms 2.07% MPI_Wtime 13ms 0.3% 67.19% 81ms 1.87% PetscLogEventBeginDefault 0 0% 67.19% 74ms 1.71% VecMDot 4ms 0.092% 67.28% 74ms 1.71% VecMXDot_Private 68ms 1.57% 68.85% 71ms 1.64% exp_inline (inline) 2ms 0.046% 68.89% 69ms 1.59% __GI___clock_gettime 68ms 1.57% 70.46% 68ms 1.57% ddot_ 36ms 0.83% 71.29% 67ms 1.54% TimeStepper::TimeStepper 67ms 1.54% 72.83% 67ms 1.54% [[vdso]] 3ms 0.069% 72.90% 62ms 1.43% VecDot 4ms 0.092% 73.00% 60ms 1.38% VecMAXPY 1ms 0.023% 73.02% 58ms 1.34% VecDuplicate_Seq 0 0% 73.02% 56ms 1.29% VecCreate 1ms 0.023% 73.04% 56ms 1.29% VecDuplicate_MPI 53ms 1.22% 74.26% 54ms 1.24% VecMDot_Seq 12ms 0.28% 74.54% 52ms 1.20% PetscLogEventEndDefault 0 0% 74.54% 51ms 1.18% VecDot_Seq 0 0% 74.54% 51ms 1.18% VecXDot_Seq_Private.constprop.0 1ms 0.023% 74.56% 50ms 1.15% VecDestroy 5ms 0.12% 74.68% 48ms 1.11% VecCopy 4ms 0.092% 74.77% 47ms 1.08% VecAXPY 4ms 0.092% 74.86% 45ms 1.04% PetscMallocA 45ms 1.04% 75.90% 45ms 1.04% TimeStepper::CalculateNextTime 1ms 0.023% 75.92% 43ms 0.99% AbstractBidomainSolver::FinaliseLinearSystem 16ms 0.37% 76.29% 42ms 0.97% AbstractBidomainSolver::CheckCompatibilityCondition 38ms 0.88% 77.17% 42ms 0.97% AbstractOneStepIvpOdeSolver::Solve 4ms 0.092% 77.26% 41ms 0.94% PetscMallocAlign (partial-inline) 40ms 0.92% 78.18% 40ms 0.92% __ieee754_log_fma 9ms 0.21% 78.39% 39ms 0.9% AbstractCardiacCell::SetVoltage (partial-inline) 0 0% 78.39% 39ms 0.9% VecNorm 38ms 0.88% 79.26% 38ms 0.88% std::isinf (inline) 19ms 0.44% 79.70% 36ms 0.83% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 6ms 0.14% 79.84% 36ms 0.83% PetscHeaderCreate_Private 16ms 0.37% 80.21% 36ms 0.83% TimeStepper::AdvanceOneTimeStep 36ms 0.83% 81.04% 36ms 0.83% __memcpy_avx_unaligned_erms 30ms 0.69% 81.73% 35ms 0.81% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 14ms 0.32% 82.05% 35ms 0.81% std::__cxx11::basic_string::basic_string 0 0% 82.05% 33ms 0.76% PetscMemzero (inline) 30ms 0.69% 82.74% 33ms 0.76% VecMAXPY_Seq 33ms 0.76% 83.50% 33ms 0.76% __memset_avx2_unaligned_erms 14ms 0.32% 83.82% 33ms 0.76% top12 (inline) 0 0% 83.82% 32ms 0.74% CxxTest::RealWorldDescription::setUp 0 0% 83.82% 32ms 0.74% PetscInitialize 0 0% 83.82% 32ms 0.74% PetscSetup::setUpWorld 0 0% 83.82% 32ms 0.74% PetscSetupUtils::CommonSetup 0 0% 83.82% 32ms 0.74% PetscSetupUtils::InitialisePetsc 0 0% 83.82% 31ms 0.71% PMPI_Init_thread 0 0% 83.82% 31ms 0.71% PetscMemcpy (inline) 1ms 0.023% 83.85% 31ms 0.71% VecNorm_Seq 31ms 0.71% 84.56% 31ms 0.71% [libchaste_heart.so] 0 0% 84.56% 31ms 0.71% ompi_mpi_init 0 0% 84.56% 30ms 0.69% VecAXPY_Seq 0 0% 84.56% 27ms 0.62% VecSetType 27ms 0.62% 85.18% 27ms 0.62% daxpy_ 0 0% 85.18% 26ms 0.6% PetscHeaderDestroy_Private 24ms 0.55% 85.74% 25ms 0.58% AbstractParameterisedSystem::SetAnyVariable 0 0% 85.74% 24ms 0.55% 6ms 0.14% 85.88% 24ms 0.55% PMPI_Comm_get_attr 2ms 0.046% 85.92% 24ms 0.55% VecCreate_MPI_Private 1ms 0.023% 85.94% 24ms 0.55% VecCreate_Seq 12ms 0.28% 86.22% 23ms 0.53% AbstractCardiacCell::GetVoltage (partial-inline) 23ms 0.53% 86.75% 23ms 0.53% DistributedVector::Iterator::operator!= 0 0% 86.75% 23ms 0.53% VecCopy_Seq (partial-inline) 0 0% 86.75% 23ms 0.53% VecNorm_MPI 0 0% 86.75% 23ms 0.53% VecNorm_MPI_Default.constprop.0 1ms 0.023% 86.77% 23ms 0.53% VecSet 0 0% 86.77% 23ms 0.53% orte_init 22ms 0.51% 87.28% 22ms 0.51% asuint64 (inline)