File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 3736ms, 88.34% of 4229ms total Dropped 428 nodes (cum <= 21.14ms) flat flat% sum% cum cum% 0 0% 0% 4222ms 99.83% __libc_start_call_main 0 0% 0% 4222ms 99.83% __libc_start_main_impl 0 0% 0% 4222ms 99.83% _start 0 0% 0% 4219ms 99.76% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4219ms 99.76% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4219ms 99.76% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4219ms 99.76% main 0 0% 0% 4184ms 98.94% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4184ms 98.94% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4184ms 98.94% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 0 0% 0% 4177ms 98.77% AbstractCardiacProblem::Solve 1ms 0.024% 0.024% 4161ms 98.39% AbstractDynamicLinearPdeSolver::Solve 11ms 0.26% 0.28% 2472ms 58.45% AbstractCardiacTissue::SolveCellSystems 47ms 1.11% 1.40% 1395ms 32.99% AbstractCardiacCell::ComputeExceptVoltage 0 0% 1.40% 1319ms 31.19% LinearSystem::Solve 0 0% 1.40% 1274ms 30.13% KSPSolve 3ms 0.071% 1.47% 1274ms 30.13% KSPSolve_Private 2ms 0.047% 1.51% 1221ms 28.87% KSPSolve_SYMMLQ 24ms 0.57% 2.08% 1173ms 27.74% AbstractOneStepIvpOdeSolver::InternalSolve 60ms 1.42% 3.50% 998ms 23.60% EulerIvpOdeSolver::CalculateNextYValue 294ms 6.95% 10.45% 922ms 21.80% CellLuoRudy1991FromCellML::EvaluateYDerivatives 356ms 8.42% 18.87% 791ms 18.70% __GI___exp 36ms 0.85% 19.72% 782ms 18.49% AbstractCardiacTissue::UpdateCaches (inline) 3ms 0.071% 19.79% 732ms 17.31% PCApply 231ms 5.46% 25.25% 722ms 17.07% CellLuoRudy1991FromCellML::GetIIonic 2ms 0.047% 25.30% 721ms 17.05% PCApply_BJacobi_Singleblock 0 0% 25.30% 585ms 13.83% KSPSolve_PREONLY (partial-inline) 3ms 0.071% 25.37% 557ms 13.17% MatSolve 0 0% 25.37% 557ms 13.17% PCApply_ICC 0 0% 25.37% 550ms 13.01% KSP_PCApply.lto_priv.18 544ms 12.86% 38.24% 548ms 12.96% MatSolve_SeqSBAIJ_1_NaturalOrdering 3ms 0.071% 38.31% 484ms 11.44% MatMult 197ms 4.66% 42.97% 479ms 11.33% MatMult_SeqAIJ 371ms 8.77% 51.74% 403ms 9.53% __ieee754_exp_fma 52ms 1.23% 52.97% 325ms 7.69% BidomainSolver::SetupLinearSystem 277ms 6.55% 59.52% 284ms 6.72% MatMult_SeqAIJ_Inode (inline) 19ms 0.45% 59.97% 257ms 6.08% __pow 0 0% 59.97% 257ms 6.08% std::pow (inline) 133ms 3.14% 63.11% 242ms 5.72% CellLuoRudy1991FromCellML::VerifyStateVariables 2ms 0.047% 63.16% 238ms 5.63% KSPConvergedDefault 28ms 0.66% 63.82% 238ms 5.63% __ieee754_pow_fma 0 0% 63.82% 231ms 5.46% KSP_PCApply (partial-inline) 106ms 2.51% 66.33% 106ms 2.51% log_inline (inline) 0 0% 66.33% 104ms 2.46% VecDuplicate 94ms 2.22% 68.55% 94ms 2.22% exp_inline (inline) 3ms 0.071% 68.62% 81ms 1.92% MatNullSpaceRemove 19ms 0.45% 69.07% 70ms 1.66% MPI_Wtime 37ms 0.87% 69.95% 66ms 1.56% TimeStepper::TimeStepper 28ms 0.66% 70.61% 56ms 1.32% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 56ms 1.32% 71.93% 56ms 1.32% std::isinf (inline) 47ms 1.11% 73.04% 55ms 1.30% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 1ms 0.024% 73.07% 54ms 1.28% VecDuplicate_Seq 15ms 0.35% 73.42% 53ms 1.25% PetscLogEventEndDefault 51ms 1.21% 74.63% 51ms 1.21% ddot_ 5ms 0.12% 74.75% 50ms 1.18% PetscMallocA 2ms 0.047% 74.79% 50ms 1.18% VecDuplicate_MPI 50ms 1.18% 75.98% 50ms 1.18% [[vdso]] 0 0% 75.98% 50ms 1.18% __GI___clock_gettime 1ms 0.024% 76.00% 49ms 1.16% VecCopy 0 0% 76.00% 46ms 1.09% VecMDot 4ms 0.095% 76.09% 46ms 1.09% VecMXDot_Private 2ms 0.047% 76.14% 45ms 1.06% PetscMallocAlign (partial-inline) 11ms 0.26% 76.40% 44ms 1.04% PetscLogEventBeginDefault 2ms 0.047% 76.45% 43ms 1.02% VecDot 21ms 0.5% 76.94% 42ms 0.99% top12 (inline) 40ms 0.95% 77.89% 40ms 0.95% __memcpy_avx_unaligned_erms 21ms 0.5% 78.39% 39ms 0.92% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 78.39% 39ms 0.92% AbstractBidomainSolver::FinaliseLinearSystem 6ms 0.14% 78.53% 39ms 0.92% VecCreate 2ms 0.047% 78.58% 39ms 0.92% VecNorm 38ms 0.9% 79.48% 39ms 0.92% __ieee754_log_fma 38ms 0.9% 80.37% 38ms 0.9% [libchaste_heart.so] 5ms 0.12% 80.49% 37ms 0.87% AbstractCardiacCell::SetVoltage (partial-inline) 37ms 0.87% 81.37% 37ms 0.87% TimeStepper::CalculateNextTime 3ms 0.071% 81.44% 36ms 0.85% VecDestroy 0 0% 81.44% 35ms 0.83% VecDot_Seq 0 0% 81.44% 35ms 0.83% VecXDot_Seq_Private.constprop.0 24ms 0.57% 82.01% 34ms 0.8% AbstractOneStepIvpOdeSolver::Solve 0 0% 82.01% 33ms 0.78% PetscMemcpy (inline) 0 0% 82.01% 32ms 0.76% CxxTest::RealWorldDescription::setUp 0 0% 82.01% 32ms 0.76% PMPI_Init_thread 0 0% 82.01% 32ms 0.76% PetscInitialize 0 0% 82.01% 32ms 0.76% PetscSetup::setUpWorld 0 0% 82.01% 32ms 0.76% PetscSetupUtils::CommonSetup 0 0% 82.01% 32ms 0.76% PetscSetupUtils::InitialisePetsc 1ms 0.024% 82.03% 32ms 0.76% VecMAXPY 32ms 0.76% 82.79% 32ms 0.76% [libm.so.6] 0 0% 82.79% 32ms 0.76% ompi_mpi_init 30ms 0.71% 83.49% 31ms 0.73% VecMDot_Seq 1ms 0.024% 83.52% 30ms 0.71% VecAXPY 2ms 0.047% 83.57% 30ms 0.71% VecCreate_MPI_Private 0 0% 83.57% 30ms 0.71% VecNorm_Seq 27ms 0.64% 84.20% 29ms 0.69% AbstractParameterisedSystem::SetAnyVariable 1ms 0.024% 84.23% 29ms 0.69% KSP_RemoveNullSpace (inline) 6ms 0.14% 84.37% 29ms 0.69% __GI___libc_malloc 1ms 0.024% 84.39% 29ms 0.69% __posix_memalign 2ms 0.047% 84.44% 28ms 0.66% VecSetType 10ms 0.24% 84.68% 28ms 0.66% std::__cxx11::basic_string::basic_string 22ms 0.52% 85.20% 26ms 0.61% AbstractParameterisedSystem::GetAnyVariable 0 0% 85.20% 26ms 0.61% VecCopy_Seq (partial-inline) 25ms 0.59% 85.79% 25ms 0.59% std::vector::operator[] (inline) 0 0% 85.79% 23ms 0.54% 13ms 0.31% 86.10% 23ms 0.54% AbstractCardiacCell::GetVoltage (partial-inline) 0 0% 86.10% 23ms 0.54% PetscMemzero (inline) 23ms 0.54% 86.64% 23ms 0.54% __memset_avx2_unaligned_erms 22ms 0.52% 87.16% 22ms 0.52% DistributedVector::Iterator::operator!= 7ms 0.17% 87.33% 22ms 0.52% PetscOptionsFindPair 21ms 0.5% 87.82% 22ms 0.52% VecMAXPY_Seq 22ms 0.52% 88.34% 22ms 0.52% asuint64 (inline) 0 0% 88.34% 22ms 0.52% orte_init