File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3823ms, 88.31% of 4329ms total Dropped 409 nodes (cum <= 21.64ms) flat flat% sum% cum cum% 0 0% 0% 4321ms 99.82% __libc_start_call_main 0 0% 0% 4321ms 99.82% __libc_start_main_impl 0 0% 0% 4321ms 99.82% _start 0 0% 0% 4318ms 99.75% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4318ms 99.75% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4318ms 99.75% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4318ms 99.75% main 0 0% 0% 4283ms 98.94% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4283ms 98.94% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4283ms 98.94% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4277ms 98.80% AbstractCardiacProblem::Solve 0 0% 0% 4268ms 98.59% AbstractDynamicLinearPdeSolver::Solve 16ms 0.37% 0.37% 2006ms 46.34% AbstractCardiacTissue::SolveCellSystems 0 0% 0.37% 1807ms 41.74% LinearSystem::Solve 0 0% 0.37% 1772ms 40.93% KSPSolve 3ms 0.069% 0.44% 1772ms 40.93% KSPSolve_Private 1ms 0.023% 0.46% 1726ms 39.87% KSPSolve_SYMMLQ 14ms 0.32% 0.79% 1155ms 26.68% AbstractCardiacCell::ComputeExceptVoltage 24ms 0.55% 1.34% 1037ms 23.95% AbstractOneStepIvpOdeSolver::InternalSolve 3ms 0.069% 1.41% 981ms 22.66% PCApply 2ms 0.046% 1.46% 969ms 22.38% PCApply_BJacobi_Singleblock 89ms 2.06% 3.51% 879ms 20.30% EulerIvpOdeSolver::CalculateNextYValue 0 0% 3.51% 816ms 18.85% KSPSolve_PREONLY (partial-inline) 280ms 6.47% 9.98% 782ms 18.06% CellLuoRudy1991FromCellML::EvaluateYDerivatives 1ms 0.023% 10.00% 777ms 17.95% MatMult 5ms 0.12% 10.12% 772ms 17.83% MatMult_SeqAIJ (partial-inline) 767ms 17.72% 27.84% 772ms 17.83% MatMult_SeqAIJ_Inode (inline) 3ms 0.069% 27.90% 765ms 17.67% MatSolve 0 0% 27.90% 765ms 17.67% PCApply_ICC 749ms 17.30% 45.21% 752ms 17.37% MatSolve_SeqSBAIJ_1_NaturalOrdering 0 0% 45.21% 700ms 16.17% KSP_PCApply.lto_priv.18 17ms 0.39% 45.60% 659ms 15.22% AbstractCardiacTissue::UpdateCaches (inline) 271ms 6.26% 51.86% 613ms 14.16% CellLuoRudy1991FromCellML::GetIIonic 198ms 4.57% 56.43% 583ms 13.47% __GI___exp 56ms 1.29% 57.73% 407ms 9.40% BidomainSolver::SetupLinearSystem 1ms 0.023% 57.75% 374ms 8.64% KSPConvergedDefault 0 0% 57.75% 350ms 8.09% KSP_PCApply (partial-inline) 335ms 7.74% 65.49% 345ms 7.97% __ieee754_exp_fma 7ms 0.16% 65.65% 184ms 4.25% std::pow (inline) 14ms 0.32% 65.97% 176ms 4.07% __pow 6ms 0.14% 66.11% 158ms 3.65% __ieee754_pow_fma 95ms 2.19% 68.31% 143ms 3.30% CellLuoRudy1991FromCellML::VerifyStateVariables 3ms 0.069% 68.38% 138ms 3.19% MatNullSpaceRemove 117ms 2.70% 71.08% 117ms 2.70% [libchaste_heart.so] 0 0% 71.08% 108ms 2.49% VecDuplicate 24ms 0.55% 71.63% 92ms 2.13% MPI_Wtime 1ms 0.023% 71.66% 87ms 2.01% VecMDot 7ms 0.16% 71.82% 86ms 1.99% VecMXDot_Private 76ms 1.76% 73.57% 76ms 1.76% log_inline (inline) 64ms 1.48% 75.05% 75ms 1.73% exp_inline (inline) 29ms 0.67% 75.72% 67ms 1.55% TimeStepper::TimeStepper 1ms 0.023% 75.74% 66ms 1.52% __GI___clock_gettime 65ms 1.50% 77.25% 65ms 1.50% [[vdso]] 7ms 0.16% 77.41% 64ms 1.48% PetscLogEventEndDefault 61ms 1.41% 78.82% 63ms 1.46% VecMDot_Seq 2ms 0.046% 78.86% 58ms 1.34% VecDuplicate_MPI 2ms 0.046% 78.91% 53ms 1.22% VecDestroy 51ms 1.18% 80.09% 51ms 1.18% TimeStepper::CalculateNextTime 7ms 0.16% 80.25% 50ms 1.16% PetscLogEventBeginDefault 5ms 0.12% 80.36% 50ms 1.16% PetscMallocA 0 0% 80.36% 50ms 1.16% VecDuplicate_Seq 50ms 1.16% 81.52% 50ms 1.16% ddot_ 2ms 0.046% 81.57% 48ms 1.11% VecMAXPY 3ms 0.069% 81.64% 47ms 1.09% VecCopy 1ms 0.023% 81.66% 46ms 1.06% VecCreate 1ms 0.023% 81.68% 45ms 1.04% PetscMallocAlign 45ms 1.04% 82.72% 45ms 1.04% [libm.so.6] 12ms 0.28% 83.00% 42ms 0.97% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 83.00% 42ms 0.97% AbstractBidomainSolver::FinaliseLinearSystem 34ms 0.79% 83.78% 42ms 0.97% AbstractOneStepIvpOdeSolver::Solve 1ms 0.023% 83.81% 42ms 0.97% KSP_RemoveNullSpace (inline) 2ms 0.046% 83.85% 40ms 0.92% VecNorm 5ms 0.12% 83.97% 38ms 0.88% VecAXPY 0 0% 83.97% 37ms 0.85% VecDot 16ms 0.37% 84.34% 35ms 0.81% TimeStepper::AdvanceOneTimeStep 33ms 0.76% 85.10% 33ms 0.76% VecMAXPY_Seq 4ms 0.092% 85.19% 33ms 0.76% __posix_memalign 0 0% 85.19% 31ms 0.72% VecDot_Seq 0 0% 85.19% 31ms 0.72% VecXDot_Seq_Private.constprop.0 31ms 0.72% 85.91% 31ms 0.72% [libchaste_ode.so] 0 0% 85.91% 30ms 0.69% CxxTest::RealWorldDescription::setUp 0 0% 85.91% 30ms 0.69% PetscSetup::setUpWorld 0 0% 85.91% 30ms 0.69% PetscSetupUtils::CommonSetup 1ms 0.023% 85.93% 30ms 0.69% VecNorm_Seq 0 0% 85.93% 29ms 0.67% PMPI_Init_thread 0 0% 85.93% 29ms 0.67% PetscInitialize 0 0% 85.93% 29ms 0.67% PetscSetupUtils::InitialisePetsc 2ms 0.046% 85.98% 29ms 0.67% VecCreate_MPI_Private 11ms 0.25% 86.23% 29ms 0.67% __GI___libc_malloc 0 0% 86.23% 29ms 0.67% ompi_mpi_init 8ms 0.18% 86.42% 28ms 0.65% AbstractCardiacCell::SetVoltage (partial-inline) 17ms 0.39% 86.81% 27ms 0.62% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 2ms 0.046% 86.86% 27ms 0.62% VecSetType 27ms 0.62% 87.48% 27ms 0.62% __memcpy_evex_unaligned_erms 0 0% 87.48% 26ms 0.6% PetscHeaderCreate_Private 1ms 0.023% 87.50% 25ms 0.58% PMPI_Comm_get_attr 1ms 0.023% 87.53% 25ms 0.58% PetscHeaderDestroy_Private 0 0% 87.53% 25ms 0.58% VecAXPY_Seq 2ms 0.046% 87.57% 24ms 0.55% 1ms 0.023% 87.60% 24ms 0.55% ompi_attr_get_c 8ms 0.18% 87.78% 24ms 0.55% std::__cxx11::basic_string::basic_string 0 0% 87.78% 23ms 0.53% PetscMemcpy (inline) 23ms 0.53% 88.31% 23ms 0.53% __ieee754_log_fma