File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 4321ms, 90.70% of 4764ms total Dropped 438 nodes (cum <= 23.82ms) flat flat% sum% cum cum% 0 0% 0% 4754ms 99.79% __libc_start_call_main 0 0% 0% 4754ms 99.79% __libc_start_main_impl 0 0% 0% 4754ms 99.79% _start 0 0% 0% 4752ms 99.75% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4752ms 99.75% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4752ms 99.75% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4752ms 99.75% main 0 0% 0% 4723ms 99.14% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4723ms 99.14% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4722ms 99.12% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 5ms 0.1% 0.1% 4716ms 98.99% AbstractCardiacProblem::Solve 2ms 0.042% 0.15% 4693ms 98.51% AbstractDynamicLinearPdeSolver::Solve 17ms 0.36% 0.5% 2239ms 47.00% AbstractCardiacTissue::SolveCellSystems 0 0% 0.5% 1876ms 39.38% LinearSystem::Solve 1ms 0.021% 0.52% 1824ms 38.29% KSPSolve 4ms 0.084% 0.61% 1824ms 38.29% KSPSolve_Private 3ms 0.063% 0.67% 1779ms 37.34% KSPSolve_SYMMLQ 16ms 0.34% 1.01% 1237ms 25.97% AbstractCardiacCell::ComputeExceptVoltage 25ms 0.52% 1.53% 1113ms 23.36% AbstractOneStepIvpOdeSolver::InternalSolve 1ms 0.021% 1.55% 1063ms 22.31% MatMult 74ms 1.55% 3.11% 1057ms 22.19% MatMult_SeqAIJ (partial-inline) 978ms 20.53% 23.64% 991ms 20.80% MatMult_SeqAIJ_Inode (inline) 99ms 2.08% 25.71% 959ms 20.13% EulerIvpOdeSolver::CalculateNextYValue 4ms 0.084% 25.80% 896ms 18.81% PCApply 1ms 0.021% 25.82% 892ms 18.72% PCApply_BJacobi_Singleblock 298ms 6.26% 32.07% 850ms 17.84% CellLuoRudy1991FromCellML::EvaluateYDerivatives 25ms 0.52% 32.60% 792ms 16.62% AbstractCardiacTissue::UpdateCaches (inline) 1ms 0.021% 32.62% 769ms 16.14% KSPSolve_PREONLY (partial-inline) 339ms 7.12% 39.74% 744ms 15.62% CellLuoRudy1991FromCellML::GetIIonic 2ms 0.042% 39.78% 731ms 15.34% MatSolve 0 0% 39.78% 731ms 15.34% PCApply_ICC 720ms 15.11% 54.89% 723ms 15.18% MatSolve_SeqSBAIJ_1_NaturalOrdering 222ms 4.66% 59.55% 646ms 13.56% __GI___exp 0 0% 59.55% 591ms 12.41% KSP_PCApply.lto_priv.18 30ms 0.63% 60.18% 526ms 11.04% BidomainSolver::SetupLinearSystem 0 0% 60.18% 390ms 8.19% KSPConvergedDefault 0 0% 60.18% 369ms 7.75% KSP_PCApply (partial-inline) 355ms 7.45% 67.63% 355ms 7.45% __ieee754_exp_fma 4ms 0.084% 67.72% 205ms 4.30% std::pow (inline) 17ms 0.36% 68.07% 199ms 4.18% __pow 17ms 0.36% 68.43% 181ms 3.80% __ieee754_pow_fma 80ms 1.68% 70.11% 151ms 3.17% CellLuoRudy1991FromCellML::VerifyStateVariables 136ms 2.85% 72.96% 136ms 2.85% [libchaste_heart.so] 2ms 0.042% 73.01% 103ms 2.16% MatNullSpaceRemove 95ms 1.99% 75.00% 95ms 1.99% log_inline (inline) 0 0% 75.00% 86ms 1.81% VecDuplicate 76ms 1.60% 76.60% 76ms 1.60% [libm.so.6] 19ms 0.4% 76.99% 70ms 1.47% TimeStepper::TimeStepper 69ms 1.45% 78.44% 69ms 1.45% ddot_ 65ms 1.36% 79.81% 66ms 1.39% exp_inline (inline) 23ms 0.48% 80.29% 63ms 1.32% MPI_Wtime 63ms 1.32% 81.61% 63ms 1.32% TimeStepper::CalculateNextTime 6ms 0.13% 81.74% 59ms 1.24% std::__cxx11::basic_string::basic_string 0 0% 81.74% 57ms 1.20% VecMDot 3ms 0.063% 81.80% 57ms 1.20% VecMXDot_Private 0 0% 81.80% 53ms 1.11% VecNorm 0 0% 81.80% 51ms 1.07% VecNorm_Seq 9ms 0.19% 81.99% 50ms 1.05% PetscLogEventEndDefault 2ms 0.042% 82.03% 48ms 1.01% std::char_traits::length (inline) 3ms 0.063% 82.09% 47ms 0.99% VecAXPY 5ms 0.1% 82.20% 46ms 0.97% VecCopy 46ms 0.97% 83.17% 46ms 0.97% __strlen_evex 1ms 0.021% 83.19% 45ms 0.94% AbstractBidomainSolver::FinaliseLinearSystem 0 0% 83.19% 45ms 0.94% VecDot 1ms 0.021% 83.21% 45ms 0.94% VecDuplicate_Seq 45ms 0.94% 84.15% 45ms 0.94% VecMDot_Seq 10ms 0.21% 84.36% 44ms 0.92% AbstractBidomainSolver::CheckCompatibilityCondition 1ms 0.021% 84.38% 44ms 0.92% VecMAXPY 7ms 0.15% 84.53% 43ms 0.9% AbstractCardiacCell::SetVoltage (partial-inline) 11ms 0.23% 84.76% 43ms 0.9% PetscLogEventBeginDefault 0 0% 84.76% 41ms 0.86% VecDestroy 0 0% 84.76% 41ms 0.86% VecDuplicate_MPI 0 0% 84.76% 40ms 0.84% VecDot_Seq 2ms 0.042% 84.80% 40ms 0.84% VecXDot_Seq_Private.constprop.0 1ms 0.021% 84.82% 40ms 0.84% __GI___clock_gettime 39ms 0.82% 85.64% 39ms 0.82% [[vdso]] 0 0% 85.64% 38ms 0.8% VecNorm_MPI 0 0% 85.64% 38ms 0.8% VecNorm_MPI_Default.constprop.0 9ms 0.19% 85.83% 37ms 0.78% PetscMallocA 34ms 0.71% 86.54% 35ms 0.73% VecMAXPY_Seq 4ms 0.084% 86.63% 33ms 0.69% VecCreate 0 0% 86.63% 31ms 0.65% VecAXPY_Seq 12ms 0.25% 86.88% 30ms 0.63% TimeStepper::AdvanceOneTimeStep 29ms 0.61% 87.49% 29ms 0.61% __ieee754_log_fma 29ms 0.61% 88.10% 29ms 0.61% daxpy_ 18ms 0.38% 88.48% 28ms 0.59% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 0 0% 88.48% 28ms 0.59% PetscMallocAlign 27ms 0.57% 89.04% 27ms 0.57% [libchaste_ode.so] 21ms 0.44% 89.48% 26ms 0.55% AbstractOneStepIvpOdeSolver::Solve 0 0% 89.48% 26ms 0.55% CxxTest::RealWorldDescription::setUp 0 0% 89.48% 26ms 0.55% PetscSetup::setUpWorld 0 0% 89.48% 26ms 0.55% PetscSetupUtils::CommonSetup 1ms 0.021% 89.50% 26ms 0.55% VecSetType 25ms 0.52% 90.03% 25ms 0.52% AbstractParameterisedSystem::SetAnyVariable 0 0% 90.03% 25ms 0.52% PMPI_Init_thread 0 0% 90.03% 25ms 0.52% PetscInitialize 0 0% 90.03% 25ms 0.52% PetscSetupUtils::InitialisePetsc 25ms 0.52% 90.55% 25ms 0.52% __memcpy_evex_unaligned_erms 5ms 0.1% 90.66% 25ms 0.52% __posix_memalign 0 0% 90.66% 25ms 0.52% ompi_mpi_init 0 0% 90.66% 24ms 0.5% PetscTimeSubtract (inline) 2ms 0.042% 90.70% 24ms 0.5% VecCreate_MPI_Private