File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3592ms, 86.68% of 4144ms total Dropped 453 nodes (cum <= 20.72ms) flat flat% sum% cum cum% 0 0% 0% 4138ms 99.86% __libc_start_call_main 0 0% 0% 4138ms 99.86% __libc_start_main_impl 0 0% 0% 4138ms 99.86% _start 0 0% 0% 4135ms 99.78% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4135ms 99.78% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4135ms 99.78% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4135ms 99.78% main 0 0% 0% 4098ms 98.89% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4098ms 98.89% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4098ms 98.89% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4090ms 98.70% AbstractCardiacProblem::Solve 2ms 0.048% 0.048% 4081ms 98.48% AbstractDynamicLinearPdeSolver::Solve 13ms 0.31% 0.36% 2495ms 60.21% AbstractCardiacTissue::SolveCellSystems 28ms 0.68% 1.04% 1451ms 35.01% AbstractCardiacCell::ComputeExceptVoltage 20ms 0.48% 1.52% 1223ms 29.51% AbstractOneStepIvpOdeSolver::InternalSolve 1ms 0.024% 1.54% 1210ms 29.20% LinearSystem::Solve 1ms 0.024% 1.57% 1158ms 27.94% KSPSolve 11ms 0.27% 1.83% 1157ms 27.92% KSPSolve_Private 5ms 0.12% 1.95% 1122ms 27.08% KSPSolve_SYMMLQ 49ms 1.18% 3.14% 1019ms 24.59% EulerIvpOdeSolver::CalculateNextYValue 266ms 6.42% 9.56% 959ms 23.14% CellLuoRudy1991FromCellML::EvaluateYDerivatives 405ms 9.77% 19.33% 885ms 21.36% __GI___exp 39ms 0.94% 20.27% 798ms 19.26% AbstractCardiacTissue::UpdateCaches (inline) 253ms 6.11% 26.38% 727ms 17.54% CellLuoRudy1991FromCellML::GetIIonic 1ms 0.024% 26.40% 700ms 16.89% PCApply 1ms 0.024% 26.42% 695ms 16.77% PCApply_BJacobi_Singleblock 0 0% 26.42% 557ms 13.44% KSPSolve_PREONLY (partial-inline) 1ms 0.024% 26.45% 527ms 12.72% PCApply_ICC 2ms 0.048% 26.50% 526ms 12.69% MatSolve 518ms 12.50% 39.00% 520ms 12.55% MatSolve_SeqSBAIJ_1_NaturalOrdering 0 0% 39.00% 507ms 12.23% KSP_PCApply.lto_priv.18 442ms 10.67% 49.66% 459ms 11.08% __ieee754_exp_fma 3ms 0.072% 49.73% 420ms 10.14% MatMult 3ms 0.072% 49.81% 409ms 9.87% MatMult_SeqAIJ 398ms 9.60% 59.41% 409ms 9.87% MatMult_SeqAIJ_Inode (inline) 57ms 1.38% 60.79% 327ms 7.89% BidomainSolver::SetupLinearSystem 0 0% 60.79% 255ms 6.15% KSPConvergedDefault 0 0% 60.79% 233ms 5.62% KSP_PCApply (partial-inline) 2ms 0.048% 60.83% 214ms 5.16% std::pow (inline) 19ms 0.46% 61.29% 212ms 5.12% __pow 95ms 2.29% 63.59% 200ms 4.83% CellLuoRudy1991FromCellML::VerifyStateVariables 15ms 0.36% 63.95% 193ms 4.66% __ieee754_pow_fma 4ms 0.097% 64.04% 111ms 2.68% VecDuplicate 101ms 2.44% 66.48% 101ms 2.44% log_inline (inline) 3ms 0.072% 66.55% 92ms 2.22% MatNullSpaceRemove 38ms 0.92% 67.47% 72ms 1.74% TimeStepper::TimeStepper 72ms 1.74% 69.21% 72ms 1.74% exp_inline (inline) 22ms 0.53% 69.74% 66ms 1.59% MPI_Wtime 54ms 1.30% 71.04% 63ms 1.52% AbstractOneStepIvpOdeSolver::Solve 2ms 0.048% 71.09% 63ms 1.52% VecDuplicate_MPI 46ms 1.11% 72.20% 56ms 1.35% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 2ms 0.048% 72.25% 50ms 1.21% VecCopy 11ms 0.27% 72.51% 48ms 1.16% PetscLogEventBeginDefault 48ms 1.16% 73.67% 48ms 1.16% TimeStepper::CalculateNextTime 48ms 1.16% 74.83% 48ms 1.16% __memcpy_avx_unaligned_erms 0 0% 74.83% 47ms 1.13% VecMDot 5ms 0.12% 74.95% 47ms 1.13% VecMXDot_Private 8ms 0.19% 75.14% 46ms 1.11% PetscLogEventEndDefault 1ms 0.024% 75.17% 46ms 1.11% VecCreate 46ms 1.11% 76.28% 46ms 1.11% ddot_ 22ms 0.53% 76.81% 44ms 1.06% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 2ms 0.048% 76.86% 44ms 1.06% VecDuplicate_Seq 6ms 0.14% 77.00% 44ms 1.06% __GI___clock_gettime 6ms 0.14% 77.15% 43ms 1.04% PetscMallocA 43ms 1.04% 78.19% 43ms 1.04% std::isinf (inline) 1ms 0.024% 78.21% 42ms 1.01% VecMAXPY 14ms 0.34% 78.55% 41ms 0.99% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 78.55% 41ms 0.99% AbstractBidomainSolver::FinaliseLinearSystem 10ms 0.24% 78.79% 41ms 0.99% std::__cxx11::basic_string::basic_string 37ms 0.89% 79.68% 40ms 0.97% __ieee754_log_fma 38ms 0.92% 80.60% 38ms 0.92% [[vdso]] 1ms 0.024% 80.62% 37ms 0.89% PetscMallocAlign 37ms 0.89% 81.52% 37ms 0.89% [libchaste_heart.so] 0 0% 81.52% 36ms 0.87% PetscMemcpy (inline) 11ms 0.27% 81.78% 33ms 0.8% AbstractCardiacCell::SetVoltage (partial-inline) 0 0% 81.78% 33ms 0.8% CxxTest::RealWorldDescription::setUp 0 0% 81.78% 33ms 0.8% PMPI_Init_thread 0 0% 81.78% 33ms 0.8% PetscInitialize 0 0% 81.78% 33ms 0.8% PetscSetup::setUpWorld 0 0% 81.78% 33ms 0.8% PetscSetupUtils::CommonSetup 0 0% 81.78% 33ms 0.8% PetscSetupUtils::InitialisePetsc 15ms 0.36% 82.14% 33ms 0.8% TimeStepper::AdvanceOneTimeStep 0 0% 82.14% 33ms 0.8% ompi_mpi_init 3ms 0.072% 82.22% 32ms 0.77% VecCreate_MPI_Private 1ms 0.024% 82.24% 30ms 0.72% VecCopy_Seq (partial-inline) 0 0% 82.24% 30ms 0.72% VecDot 2ms 0.048% 82.29% 29ms 0.7% VecAXPY 17ms 0.41% 82.70% 28ms 0.68% AbstractCardiacCell::GetVoltage (partial-inline) 28ms 0.68% 83.37% 28ms 0.68% DistributedVector::Iterator::operator!= 1ms 0.024% 83.40% 28ms 0.68% VecDestroy 28ms 0.68% 84.07% 28ms 0.68% VecMDot_Seq 2ms 0.048% 84.12% 28ms 0.68% VecNorm 0 0% 84.12% 27ms 0.65% VecDot_Seq 0 0% 84.12% 27ms 0.65% VecXDot_Seq_Private.constprop.0 1ms 0.024% 84.15% 26ms 0.63% 25ms 0.6% 84.75% 26ms 0.63% VecMAXPY_Seq 5ms 0.12% 84.87% 25ms 0.6% std::vector::_M_assign_aux (inline) 0 0% 84.87% 25ms 0.6% std::vector::assign (inline) 23ms 0.56% 85.42% 23ms 0.56% TimeStepper::GetNextTimeStep 0 0% 85.42% 23ms 0.56% VecNorm_Seq 1ms 0.024% 85.45% 23ms 0.56% __posix_memalign 0 0% 85.45% 23ms 0.56% orte_init 4ms 0.097% 85.55% 22ms 0.53% __GI___libc_malloc 0 0% 85.55% 21ms 0.51% AbstractFeVolumeIntegralAssembler::DoAssemble 17ms 0.41% 85.96% 21ms 0.51% AbstractParameterisedSystem::GetAnyVariable 0 0% 85.96% 21ms 0.51% VecSetType 21ms 0.51% 86.46% 21ms 0.51% [libm.so.6] 1ms 0.024% 86.49% 21ms 0.51% std::char_traits::length (inline) 8ms 0.19% 86.68% 21ms 0.51% top12 (inline)