File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3641ms, 87.55% of 4159ms total Dropped 452 nodes (cum <= 20.80ms) flat flat% sum% cum cum% 0 0% 0% 4152ms 99.83% __libc_start_call_main 0 0% 0% 4152ms 99.83% __libc_start_main_impl 0 0% 0% 4152ms 99.83% _start 0 0% 0% 4150ms 99.78% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4150ms 99.78% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4150ms 99.78% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4150ms 99.78% main 0 0% 0% 4115ms 98.94% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4115ms 98.94% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4115ms 98.94% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 1ms 0.024% 0.024% 4107ms 98.75% AbstractCardiacProblem::Solve 0 0% 0.024% 4096ms 98.49% AbstractDynamicLinearPdeSolver::Solve 15ms 0.36% 0.38% 2337ms 56.19% AbstractCardiacTissue::SolveCellSystems 0 0% 0.38% 1433ms 34.46% LinearSystem::Solve 0 0% 0.38% 1394ms 33.52% KSPSolve 1ms 0.024% 0.41% 1394ms 33.52% KSPSolve_Private 2ms 0.048% 0.46% 1352ms 32.51% KSPSolve_SYMMLQ 21ms 0.5% 0.96% 1280ms 30.78% AbstractCardiacCell::ComputeExceptVoltage 35ms 0.84% 1.80% 1109ms 26.67% AbstractOneStepIvpOdeSolver::InternalSolve 55ms 1.32% 3.13% 938ms 22.55% EulerIvpOdeSolver::CalculateNextYValue 240ms 5.77% 8.90% 875ms 21.04% CellLuoRudy1991FromCellML::EvaluateYDerivatives 0 0% 8.90% 830ms 19.96% PCApply 1ms 0.024% 8.92% 824ms 19.81% PCApply_BJacobi_Singleblock 28ms 0.67% 9.59% 792ms 19.04% AbstractCardiacTissue::UpdateCaches (inline) 357ms 8.58% 18.18% 784ms 18.85% __GI___exp 248ms 5.96% 24.14% 740ms 17.79% CellLuoRudy1991FromCellML::GetIIonic 1ms 0.024% 24.16% 680ms 16.35% KSPSolve_PREONLY (partial-inline) 3ms 0.072% 24.24% 640ms 15.39% MatSolve 0 0% 24.24% 640ms 15.39% PCApply_ICC 625ms 15.03% 39.26% 631ms 15.17% MatSolve_SeqSBAIJ_1_NaturalOrdering 0 0% 39.26% 619ms 14.88% KSP_PCApply.lto_priv.18 2ms 0.048% 39.31% 418ms 10.05% MatMult 3ms 0.072% 39.38% 412ms 9.91% MatMult_SeqAIJ 405ms 9.74% 49.12% 412ms 9.91% MatMult_SeqAIJ_Inode (inline) 381ms 9.16% 58.28% 402ms 9.67% __ieee754_exp_fma 4ms 0.096% 58.38% 303ms 7.29% KSPConvergedDefault 61ms 1.47% 59.85% 295ms 7.09% BidomainSolver::SetupLinearSystem 0 0% 59.85% 276ms 6.64% KSP_PCApply (partial-inline) 2ms 0.048% 59.89% 249ms 5.99% std::pow (inline) 22ms 0.53% 60.42% 247ms 5.94% __pow 35ms 0.84% 61.26% 223ms 5.36% __ieee754_pow_fma 88ms 2.12% 63.38% 201ms 4.83% CellLuoRudy1991FromCellML::VerifyStateVariables 1ms 0.024% 63.40% 114ms 2.74% MatNullSpaceRemove 0 0% 63.40% 97ms 2.33% VecDuplicate 93ms 2.24% 65.64% 93ms 2.24% log_inline (inline) 83ms 2.00% 67.64% 86ms 2.07% exp_inline (inline) 20ms 0.48% 68.12% 76ms 1.83% MPI_Wtime 0 0% 68.12% 72ms 1.73% VecMDot 6ms 0.14% 68.26% 72ms 1.73% VecMXDot_Private 58ms 1.39% 69.66% 60ms 1.44% __ieee754_log_fma 2ms 0.048% 69.70% 59ms 1.42% VecDestroy 11ms 0.26% 69.97% 56ms 1.35% PetscLogEventEndDefault 23ms 0.55% 70.52% 56ms 1.35% TimeStepper::TimeStepper 6ms 0.14% 70.67% 56ms 1.35% VecCopy 56ms 1.35% 72.01% 56ms 1.35% [[vdso]] 0 0% 72.01% 56ms 1.35% __GI___clock_gettime 56ms 1.35% 73.36% 56ms 1.35% ddot_ 43ms 1.03% 74.39% 54ms 1.30% AbstractOneStepIvpOdeSolver::Solve 7ms 0.17% 74.56% 54ms 1.30% PetscLogEventBeginDefault 3ms 0.072% 74.63% 54ms 1.30% VecDuplicate_MPI 21ms 0.5% 75.14% 52ms 1.25% std::__cxx11::basic_string::basic_string 38ms 0.91% 76.05% 45ms 1.08% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 43ms 1.03% 77.09% 43ms 1.03% TimeStepper::CalculateNextTime 0 0% 77.09% 43ms 1.03% VecDuplicate_Seq 41ms 0.99% 78.07% 43ms 1.03% VecMDot_Seq 42ms 1.01% 79.08% 42ms 1.01% __memcpy_avx_unaligned_erms 0 0% 79.08% 41ms 0.99% KSP_RemoveNullSpace (inline) 5ms 0.12% 79.20% 41ms 0.99% PetscMallocA 2ms 0.048% 79.25% 41ms 0.99% VecDot 4ms 0.096% 79.35% 41ms 0.99% VecMAXPY 2ms 0.048% 79.39% 41ms 0.99% VecNorm 16ms 0.38% 79.78% 40ms 0.96% AbstractCardiacCell::SetVoltage (partial-inline) 3ms 0.072% 79.85% 40ms 0.96% VecAXPY 0 0% 79.85% 40ms 0.96% VecCreate 39ms 0.94% 80.79% 39ms 0.94% [libchaste_heart.so] 0 0% 80.79% 37ms 0.89% PetscMemcpy (inline) 3ms 0.072% 80.86% 36ms 0.87% PetscMallocAlign (partial-inline) 0 0% 80.86% 36ms 0.87% VecDot_Seq 0 0% 80.86% 36ms 0.87% VecXDot_Seq_Private.constprop.0 0 0% 80.86% 32ms 0.77% CxxTest::RealWorldDescription::setUp 0 0% 80.86% 32ms 0.77% PetscInitialize 0 0% 80.86% 32ms 0.77% PetscSetup::setUpWorld 0 0% 80.86% 32ms 0.77% PetscSetupUtils::CommonSetup 0 0% 80.86% 32ms 0.77% PetscSetupUtils::InitialisePetsc 32ms 0.77% 81.63% 32ms 0.77% std::isinf (inline) 0 0% 81.63% 31ms 0.75% PMPI_Init_thread 5ms 0.12% 81.75% 31ms 0.75% PetscHeaderDestroy_Private 9ms 0.22% 81.97% 31ms 0.75% __GI___libc_free 0 0% 81.97% 31ms 0.75% ompi_mpi_init 3ms 0.072% 82.04% 30ms 0.72% PetscFreeAlign 0 0% 82.04% 30ms 0.72% VecCopy_Seq (partial-inline) 3ms 0.072% 82.11% 30ms 0.72% VecCreate_MPI_Private 0 0% 82.11% 30ms 0.72% VecNorm_Seq 12ms 0.29% 82.40% 30ms 0.72% top12 (inline) 2ms 0.048% 82.45% 29ms 0.7% VecAXPY_Seq 27ms 0.65% 83.10% 27ms 0.65% [libm.so.6] 0 0% 83.10% 25ms 0.6% 5ms 0.12% 83.22% 25ms 0.6% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 83.22% 25ms 0.6% AbstractBidomainSolver::FinaliseLinearSystem 0 0% 83.22% 25ms 0.6% std::char_traits::length (inline) 25ms 0.6% 83.82% 25ms 0.6% std::vector::operator[] (inline) 0 0% 83.82% 24ms 0.58% PetscMemzero (inline) 8ms 0.19% 84.01% 24ms 0.58% TimeStepper::AdvanceOneTimeStep 24ms 0.58% 84.59% 24ms 0.58% __strlen_avx2 24ms 0.58% 85.16% 24ms 0.58% daxpy_ 0 0% 85.16% 24ms 0.58% orte_init 23ms 0.55% 85.72% 23ms 0.55% __memset_avx2_unaligned_erms 23ms 0.55% 86.27% 23ms 0.55% asuint64 (inline) 15ms 0.36% 86.63% 22ms 0.53% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 4ms 0.096% 86.73% 22ms 0.53% PetscHeaderCreate_Private 0 0% 86.73% 22ms 0.53% VecNorm_MPI 1ms 0.024% 86.75% 22ms 0.53% VecNorm_MPI_Default.constprop.0 7ms 0.17% 86.92% 22ms 0.53% _int_free 21ms 0.5% 87.42% 21ms 0.5% DistributedVector::Stripe::operator[] (inline) 5ms 0.12% 87.55% 21ms 0.5% VecSetType