File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3644ms, 87.62% of 4159ms total Dropped 442 nodes (cum <= 20.80ms) flat flat% sum% cum cum% 0 0% 0% 4153ms 99.86% __libc_start_call_main 0 0% 0% 4153ms 99.86% __libc_start_main_impl 0 0% 0% 4153ms 99.86% _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% 4114ms 98.92% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4114ms 98.92% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4114ms 98.92% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4107ms 98.75% AbstractCardiacProblem::Solve 1ms 0.024% 0.024% 4091ms 98.36% AbstractDynamicLinearPdeSolver::Solve 11ms 0.26% 0.29% 2433ms 58.50% AbstractCardiacTissue::SolveCellSystems 38ms 0.91% 1.20% 1410ms 33.90% AbstractCardiacCell::ComputeExceptVoltage 0 0% 1.20% 1326ms 31.88% LinearSystem::Solve 0 0% 1.20% 1294ms 31.11% KSPSolve 6ms 0.14% 1.35% 1294ms 31.11% KSPSolve_Private 4ms 0.096% 1.44% 1240ms 29.81% KSPSolve_SYMMLQ 33ms 0.79% 2.24% 1191ms 28.64% AbstractOneStepIvpOdeSolver::InternalSolve 74ms 1.78% 4.02% 990ms 23.80% EulerIvpOdeSolver::CalculateNextYValue 252ms 6.06% 10.07% 906ms 21.78% CellLuoRudy1991FromCellML::EvaluateYDerivatives 360ms 8.66% 18.73% 852ms 20.49% __GI___exp 36ms 0.87% 19.60% 827ms 19.88% AbstractCardiacTissue::UpdateCaches (inline) 254ms 6.11% 25.70% 767ms 18.44% CellLuoRudy1991FromCellML::GetIIonic 1ms 0.024% 25.73% 753ms 18.11% PCApply 1ms 0.024% 25.75% 748ms 17.99% PCApply_BJacobi_Singleblock 0 0% 25.75% 607ms 14.59% KSPSolve_PREONLY (partial-inline) 3ms 0.072% 25.82% 573ms 13.78% MatSolve 0 0% 25.82% 573ms 13.78% PCApply_ICC 564ms 13.56% 39.38% 567ms 13.63% MatSolve_SeqSBAIJ_1_NaturalOrdering 0 0% 39.38% 545ms 13.10% KSP_PCApply.lto_priv.18 432ms 10.39% 49.77% 468ms 11.25% __ieee754_exp_fma 4ms 0.096% 49.87% 378ms 9.09% MatMult 5ms 0.12% 49.99% 368ms 8.85% MatMult_SeqAIJ (partial-inline) 359ms 8.63% 58.62% 368ms 8.85% MatMult_SeqAIJ_Inode (inline) 60ms 1.44% 60.06% 286ms 6.88% BidomainSolver::SetupLinearSystem 1ms 0.024% 60.09% 268ms 6.44% KSPConvergedDefault 1ms 0.024% 60.11% 256ms 6.16% KSP_PCApply (partial-inline) 3ms 0.072% 60.18% 235ms 5.65% std::pow (inline) 31ms 0.75% 60.93% 232ms 5.58% __pow 28ms 0.67% 61.60% 200ms 4.81% __ieee754_pow_fma 56ms 1.35% 62.95% 141ms 3.39% CellLuoRudy1991FromCellML::VerifyStateVariables 101ms 2.43% 65.38% 101ms 2.43% log_inline (inline) 1ms 0.024% 65.40% 94ms 2.26% MatNullSpaceRemove 0 0% 65.40% 90ms 2.16% VecDuplicate 41ms 0.99% 66.39% 70ms 1.68% TimeStepper::TimeStepper 21ms 0.5% 66.89% 67ms 1.61% MPI_Wtime 67ms 1.61% 68.50% 67ms 1.61% ddot_ 64ms 1.54% 70.04% 64ms 1.54% exp_inline (inline) 5ms 0.12% 70.16% 62ms 1.49% VecCopy 61ms 1.47% 71.63% 61ms 1.47% __ieee754_log_fma 0 0% 71.63% 60ms 1.44% VecMDot 1ms 0.024% 71.65% 60ms 1.44% VecMXDot_Private 15ms 0.36% 72.01% 56ms 1.35% PetscLogEventEndDefault 2ms 0.048% 72.06% 56ms 1.35% VecDot 9ms 0.22% 72.28% 53ms 1.27% AbstractCardiacCell::SetVoltage (partial-inline) 53ms 1.27% 73.55% 53ms 1.27% __memcpy_avx_unaligned_erms 30ms 0.72% 74.27% 51ms 1.23% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 5ms 0.12% 74.39% 50ms 1.20% VecDuplicate_MPI 8ms 0.19% 74.59% 49ms 1.18% PetscMallocA 6ms 0.14% 74.73% 48ms 1.15% PetscLogEventBeginDefault 0 0% 74.73% 48ms 1.15% VecDot_Seq 0 0% 74.73% 48ms 1.15% VecXDot_Seq_Private.constprop.0 46ms 1.11% 75.84% 46ms 1.11% [[vdso]] 0 0% 75.84% 46ms 1.11% __GI___clock_gettime 5ms 0.12% 75.96% 44ms 1.06% VecAXPY 2ms 0.048% 76.00% 44ms 1.06% VecDestroy 37ms 0.89% 76.89% 43ms 1.03% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 34ms 0.82% 77.71% 43ms 1.03% AbstractOneStepIvpOdeSolver::Solve 42ms 1.01% 78.72% 42ms 1.01% TimeStepper::CalculateNextTime 39ms 0.94% 79.66% 42ms 1.01% VecMDot_Seq 42ms 1.01% 80.67% 42ms 1.01% [libchaste_heart.so] 2ms 0.048% 80.72% 41ms 0.99% PetscMallocAlign (partial-inline) 1ms 0.024% 80.74% 40ms 0.96% VecDuplicate_Seq 0 0% 80.74% 39ms 0.94% PetscMemcpy (inline) 1ms 0.024% 80.76% 38ms 0.91% VecCreate 15ms 0.36% 81.13% 36ms 0.87% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 81.13% 36ms 0.87% AbstractBidomainSolver::FinaliseLinearSystem 10ms 0.24% 81.37% 36ms 0.87% std::__cxx11::basic_string::basic_string 13ms 0.31% 81.68% 36ms 0.87% top12 (inline) 33ms 0.79% 82.47% 35ms 0.84% AbstractParameterisedSystem::SetAnyVariable 16ms 0.38% 82.86% 34ms 0.82% TimeStepper::AdvanceOneTimeStep 0 0% 82.86% 34ms 0.82% VecCopy_Seq (partial-inline) 0 0% 82.86% 33ms 0.79% VecMAXPY 0 0% 82.86% 32ms 0.77% CxxTest::RealWorldDescription::setUp 0 0% 82.86% 32ms 0.77% PMPI_Init_thread 0 0% 82.86% 32ms 0.77% PetscInitialize 0 0% 82.86% 32ms 0.77% PetscSetup::setUpWorld 0 0% 82.86% 32ms 0.77% PetscSetupUtils::CommonSetup 0 0% 82.86% 32ms 0.77% PetscSetupUtils::InitialisePetsc 0 0% 82.86% 32ms 0.77% VecNorm 0 0% 82.86% 32ms 0.77% ompi_mpi_init 30ms 0.72% 83.58% 30ms 0.72% asuint64 (inline) 7ms 0.17% 83.75% 29ms 0.7% __GI___libc_malloc 28ms 0.67% 84.42% 28ms 0.67% std::isinf (inline) 0 0% 84.42% 27ms 0.65% VecNorm_Seq 1ms 0.024% 84.44% 26ms 0.63% __posix_memalign 1ms 0.024% 84.47% 25ms 0.6% 4ms 0.096% 84.56% 25ms 0.6% VecCreate_MPI_Private 25ms 0.6% 85.16% 25ms 0.6% [libm.so.6] 0 0% 85.16% 24ms 0.58% VecAXPY_Seq 24ms 0.58% 85.74% 24ms 0.58% daxpy_ 2ms 0.048% 85.79% 23ms 0.55% std::vector::_M_assign_aux (inline) 0 0% 85.79% 23ms 0.55% std::vector::assign (inline) 22ms 0.53% 86.32% 22ms 0.53% DistributedVector::Iterator::operator!= 0 0% 86.32% 22ms 0.53% orte_init 12ms 0.29% 86.61% 21ms 0.5% AbstractCardiacCell::GetVoltage (partial-inline) 0 0% 86.61% 21ms 0.5% PetscMemzero (inline) 21ms 0.5% 87.11% 21ms 0.5% __memset_avx2_unaligned_erms 21ms 0.5% 87.62% 21ms 0.5% std::vector::operator[] (inline)