File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 3716ms, 86.88% of 4277ms total Dropped 469 nodes (cum <= 21.39ms) flat flat% sum% cum cum% 0 0% 0% 4270ms 99.84% __libc_start_call_main 0 0% 0% 4270ms 99.84% __libc_start_main_impl 0 0% 0% 4270ms 99.84% _start 0 0% 0% 4267ms 99.77% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4267ms 99.77% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4267ms 99.77% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4267ms 99.77% main 0 0% 0% 4230ms 98.90% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4230ms 98.90% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4230ms 98.90% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 0 0% 0% 4224ms 98.76% AbstractCardiacProblem::Solve 2ms 0.047% 0.047% 4212ms 98.48% AbstractDynamicLinearPdeSolver::Solve 12ms 0.28% 0.33% 2410ms 56.35% AbstractCardiacTissue::SolveCellSystems 2ms 0.047% 0.37% 1473ms 34.44% LinearSystem::Solve 0 0% 0.37% 1435ms 33.55% KSPSolve 5ms 0.12% 0.49% 1435ms 33.55% KSPSolve_Private 44ms 1.03% 1.52% 1420ms 33.20% AbstractCardiacCell::ComputeExceptVoltage 3ms 0.07% 1.59% 1388ms 32.45% KSPSolve_SYMMLQ 26ms 0.61% 2.20% 1184ms 27.68% AbstractOneStepIvpOdeSolver::InternalSolve 56ms 1.31% 3.51% 1005ms 23.50% EulerIvpOdeSolver::CalculateNextYValue 266ms 6.22% 9.73% 939ms 21.95% CellLuoRudy1991FromCellML::EvaluateYDerivatives 5ms 0.12% 9.84% 890ms 20.81% PCApply 0 0% 9.84% 878ms 20.53% PCApply_BJacobi_Singleblock 372ms 8.70% 18.54% 843ms 19.71% __GI___exp 20ms 0.47% 19.01% 773ms 18.07% AbstractCardiacTissue::UpdateCaches (inline) 1ms 0.023% 19.03% 740ms 17.30% KSPSolve_PREONLY (partial-inline) 239ms 5.59% 24.62% 713ms 16.67% CellLuoRudy1991FromCellML::GetIIonic 2ms 0.047% 24.67% 705ms 16.48% MatSolve 0 0% 24.67% 705ms 16.48% PCApply_ICC 696ms 16.27% 40.94% 700ms 16.37% MatSolve_SeqSBAIJ_1_NaturalOrdering 0 0% 40.94% 630ms 14.73% KSP_PCApply.lto_priv.18 422ms 9.87% 50.81% 447ms 10.45% __ieee754_exp_fma 9ms 0.21% 51.02% 406ms 9.49% MatMult 6ms 0.14% 51.16% 389ms 9.10% MatMult_SeqAIJ (partial-inline) 377ms 8.81% 59.97% 389ms 9.10% MatMult_SeqAIJ_Inode (inline) 0 0% 59.97% 323ms 7.55% KSP_PCApply (partial-inline) 2ms 0.047% 60.02% 321ms 7.51% KSPConvergedDefault 57ms 1.33% 61.35% 277ms 6.48% BidomainSolver::SetupLinearSystem 4ms 0.094% 61.44% 239ms 5.59% std::pow (inline) 22ms 0.51% 61.96% 235ms 5.49% __pow 27ms 0.63% 62.59% 213ms 4.98% __ieee754_pow_fma 69ms 1.61% 64.20% 175ms 4.09% CellLuoRudy1991FromCellML::VerifyStateVariables 111ms 2.60% 66.80% 111ms 2.60% log_inline (inline) 2ms 0.047% 66.85% 107ms 2.50% MatNullSpaceRemove 0 0% 66.85% 87ms 2.03% VecDuplicate 72ms 1.68% 68.53% 72ms 1.68% exp_inline (inline) 16ms 0.37% 68.90% 69ms 1.61% MPI_Wtime 63ms 1.47% 70.38% 68ms 1.59% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 2ms 0.047% 70.42% 66ms 1.54% VecMDot 27ms 0.63% 71.05% 64ms 1.50% TimeStepper::TimeStepper 1ms 0.023% 71.08% 64ms 1.50% VecMXDot_Private 61ms 1.43% 72.50% 61ms 1.43% ddot_ 11ms 0.26% 72.76% 60ms 1.40% PetscLogEventBeginDefault 5ms 0.12% 72.88% 56ms 1.31% VecCopy 29ms 0.68% 73.56% 55ms 1.29% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 53ms 1.24% 74.80% 53ms 1.24% [[vdso]] 0 0% 74.80% 53ms 1.24% __GI___clock_gettime 1ms 0.023% 74.82% 52ms 1.22% VecDot 50ms 1.17% 75.99% 50ms 1.17% std::isinf (inline) 9ms 0.21% 76.20% 49ms 1.15% PetscLogEventEndDefault 2ms 0.047% 76.25% 49ms 1.15% VecDuplicate_Seq 47ms 1.10% 77.34% 47ms 1.10% TimeStepper::CalculateNextTime 44ms 1.03% 78.37% 47ms 1.10% VecMDot_Seq 46ms 1.08% 79.45% 46ms 1.08% [libchaste_heart.so] 0 0% 79.45% 44ms 1.03% VecDot_Seq 0 0% 79.45% 44ms 1.03% VecXDot_Seq_Private.constprop.0 1ms 0.023% 79.47% 43ms 1.01% AbstractBidomainSolver::FinaliseLinearSystem 43ms 1.01% 80.48% 43ms 1.01% __memcpy_avx_unaligned_erms 20ms 0.47% 80.94% 42ms 0.98% AbstractBidomainSolver::CheckCompatibilityCondition 8ms 0.19% 81.13% 40ms 0.94% AbstractCardiacCell::SetVoltage (partial-inline) 0 0% 81.13% 39ms 0.91% VecDestroy 4ms 0.094% 81.23% 39ms 0.91% VecMAXPY 38ms 0.89% 82.11% 39ms 0.91% __ieee754_log_fma 2ms 0.047% 82.16% 38ms 0.89% VecAXPY 2ms 0.047% 82.21% 38ms 0.89% VecDuplicate_MPI 9ms 0.21% 82.42% 36ms 0.84% PetscMallocA 1ms 0.023% 82.44% 34ms 0.79% PetscTimeSubtract (inline) 0 0% 82.44% 33ms 0.77% CxxTest::RealWorldDescription::setUp 0 0% 82.44% 33ms 0.77% PetscMemcpy (inline) 0 0% 82.44% 33ms 0.77% PetscSetup::setUpWorld 0 0% 82.44% 33ms 0.77% PetscSetupUtils::CommonSetup 0 0% 82.44% 33ms 0.77% VecNorm 0 0% 82.44% 32ms 0.75% PMPI_Init_thread 0 0% 82.44% 32ms 0.75% PetscInitialize 0 0% 82.44% 32ms 0.75% PetscSetupUtils::InitialisePetsc 0 0% 82.44% 32ms 0.75% ompi_mpi_init 0 0% 82.44% 31ms 0.72% VecNorm_Seq 2ms 0.047% 82.49% 31ms 0.72% VecSetType 25ms 0.58% 83.07% 30ms 0.7% AbstractOneStepIvpOdeSolver::Solve 12ms 0.28% 83.35% 30ms 0.7% TimeStepper::AdvanceOneTimeStep 11ms 0.26% 83.61% 30ms 0.7% std::__cxx11::basic_string::basic_string 1ms 0.023% 83.63% 29ms 0.68% VecCopy_Seq (partial-inline) 1ms 0.023% 83.66% 27ms 0.63% 2ms 0.047% 83.70% 27ms 0.63% PetscMallocAlign 27ms 0.63% 84.33% 27ms 0.63% RegularStimulus::GetStimulus 0 0% 84.33% 26ms 0.61% VecCreate 9ms 0.21% 84.55% 26ms 0.61% top12 (inline) 23ms 0.54% 85.08% 24ms 0.56% VecMAXPY_Seq 24ms 0.56% 85.64% 24ms 0.56% [libm.so.6] 6ms 0.14% 85.78% 24ms 0.56% std::vector::_M_assign_aux (inline) 0 0% 85.78% 24ms 0.56% std::vector::assign (inline) 22ms 0.51% 86.30% 23ms 0.54% AbstractParameterisedSystem::SetAnyVariable 1ms 0.023% 86.32% 23ms 0.54% VecCreate_Seq 23ms 0.54% 86.86% 23ms 0.54% std::vector::operator[] (inline) 1ms 0.023% 86.88% 22ms 0.51% VecAXPY_Seq 0 0% 86.88% 22ms 0.51% orte_init