File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3750ms, 86.91% of 4315ms total Dropped 511 nodes (cum <= 21.57ms) flat flat% sum% cum cum% 0 0% 0% 4305ms 99.77% __libc_start_call_main 0 0% 0% 4305ms 99.77% __libc_start_main_impl 0 0% 0% 4305ms 99.77% _start 0 0% 0% 4301ms 99.68% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4301ms 99.68% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4301ms 99.68% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4301ms 99.68% main 0 0% 0% 4264ms 98.82% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4264ms 98.82% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4264ms 98.82% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 1ms 0.023% 0.023% 4255ms 98.61% AbstractCardiacProblem::Solve 1ms 0.023% 0.046% 4234ms 98.12% AbstractDynamicLinearPdeSolver::Solve 23ms 0.53% 0.58% 2176ms 50.43% AbstractCardiacTissue::SolveCellSystems 1ms 0.023% 0.6% 1631ms 37.80% LinearSystem::Solve 0 0% 0.6% 1595ms 36.96% KSPSolve 3ms 0.07% 0.67% 1595ms 36.96% KSPSolve_Private 4ms 0.093% 0.76% 1559ms 36.13% KSPSolve_SYMMLQ 27ms 0.63% 1.39% 1185ms 27.46% AbstractCardiacCell::ComputeExceptVoltage 3ms 0.07% 1.46% 1025ms 23.75% PCApply 25ms 0.58% 2.04% 1020ms 23.64% AbstractOneStepIvpOdeSolver::InternalSolve 1ms 0.023% 2.06% 1016ms 23.55% PCApply_BJacobi_Singleblock 0 0% 2.06% 845ms 19.58% KSPSolve_PREONLY (partial-inline) 61ms 1.41% 3.48% 836ms 19.37% EulerIvpOdeSolver::CalculateNextYValue 0 0% 3.48% 792ms 18.35% KSP_PCApply.lto_priv.18 1ms 0.023% 3.50% 791ms 18.33% MatSolve 0 0% 3.50% 791ms 18.33% PCApply_ICC 23ms 0.53% 4.03% 787ms 18.24% AbstractCardiacTissue::UpdateCaches (inline) 777ms 18.01% 22.04% 786ms 18.22% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 217ms 5.03% 27.07% 765ms 17.73% CellLuoRudy1991FromCellML::EvaluateYDerivatives 253ms 5.86% 32.93% 720ms 16.69% CellLuoRudy1991FromCellML::GetIIonic 351ms 8.13% 41.07% 692ms 16.04% __GI___exp 5ms 0.12% 41.18% 469ms 10.87% MatMult 16ms 0.37% 41.55% 456ms 10.57% MatMult_SeqAIJ (partial-inline) 438ms 10.15% 51.70% 455ms 10.54% MatMult_SeqAIJ_Inode (inline) 78ms 1.81% 53.51% 379ms 8.78% BidomainSolver::SetupLinearSystem 286ms 6.63% 60.14% 331ms 7.67% __ieee754_exp_fma 0 0% 60.14% 314ms 7.28% KSP_PCApply (partial-inline) 1ms 0.023% 60.16% 310ms 7.18% KSPConvergedDefault 25ms 0.58% 60.74% 232ms 5.38% __pow 0 0% 60.74% 232ms 5.38% std::pow (inline) 17ms 0.39% 61.14% 207ms 4.80% __ieee754_pow_fma 1ms 0.023% 61.16% 133ms 3.08% MatNullSpaceRemove 54ms 1.25% 62.41% 129ms 2.99% CellLuoRudy1991FromCellML::VerifyStateVariables 108ms 2.50% 64.91% 108ms 2.50% log_inline (inline) 1ms 0.023% 64.94% 104ms 2.41% VecDuplicate 24ms 0.56% 65.49% 92ms 2.13% MPI_Wtime 0 0% 65.49% 77ms 1.78% VecMDot 6ms 0.14% 65.63% 77ms 1.78% VecMXDot_Private 68ms 1.58% 67.21% 71ms 1.65% exp_inline (inline) 1ms 0.023% 67.23% 68ms 1.58% __GI___clock_gettime 67ms 1.55% 68.78% 67ms 1.55% [[vdso]] 65ms 1.51% 70.29% 65ms 1.51% [libchaste_heart.so] 65ms 1.51% 71.80% 65ms 1.51% ddot_ 9ms 0.21% 72.00% 63ms 1.46% PetscLogEventBeginDefault 19ms 0.44% 72.44% 62ms 1.44% TimeStepper::TimeStepper 14ms 0.32% 72.77% 58ms 1.34% AbstractCardiacCell::SetVoltage (partial-inline) 8ms 0.19% 72.95% 55ms 1.27% PetscLogEventEndDefault 55ms 1.27% 74.23% 55ms 1.27% TimeStepper::CalculateNextTime 2ms 0.046% 74.28% 55ms 1.27% VecDot 1ms 0.023% 74.30% 55ms 1.27% VecMAXPY 0 0% 74.30% 54ms 1.25% VecDuplicate_Seq 4ms 0.093% 74.39% 52ms 1.21% VecCopy 50ms 1.16% 75.55% 50ms 1.16% __memcpy_avx_unaligned_erms 2ms 0.046% 75.60% 49ms 1.14% VecDuplicate_MPI 49ms 1.14% 76.73% 49ms 1.14% VecMDot_Seq 3ms 0.07% 76.80% 47ms 1.09% VecDestroy 0 0% 76.80% 47ms 1.09% VecDot_Seq 0 0% 76.80% 47ms 1.09% VecXDot_Seq_Private.constprop.0 35ms 0.81% 77.61% 45ms 1.04% top12 (inline) 1ms 0.023% 77.64% 44ms 1.02% PetscTimeSubtract (inline) 43ms 1% 78.63% 44ms 1.02% __ieee754_log_fma 4ms 0.093% 78.73% 42ms 0.97% PetscMallocA 18ms 0.42% 79.14% 41ms 0.95% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 79.14% 41ms 0.95% AbstractBidomainSolver::FinaliseLinearSystem 33ms 0.76% 79.91% 41ms 0.95% AbstractParameterisedSystem::SetAnyVariable 0 0% 79.91% 40ms 0.93% VecCreate 12ms 0.28% 80.19% 40ms 0.93% std::__cxx11::basic_string::basic_string 3ms 0.07% 80.25% 38ms 0.88% PetscMallocAlign (partial-inline) 36ms 0.83% 81.09% 36ms 0.83% DistributedVector::Stripe::operator[] (inline) 0 0% 81.09% 36ms 0.83% PetscMemcpy (inline) 36ms 0.83% 81.92% 36ms 0.83% VecMAXPY_Seq 1ms 0.023% 81.95% 36ms 0.83% VecNorm 30ms 0.7% 82.64% 34ms 0.79% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 0 0% 82.64% 33ms 0.76% VecAXPY 0 0% 82.64% 32ms 0.74% CxxTest::RealWorldDescription::setUp 0 0% 82.64% 32ms 0.74% PMPI_Init_thread 0 0% 82.64% 32ms 0.74% PetscInitialize 0 0% 82.64% 32ms 0.74% PetscSetup::setUpWorld 0 0% 82.64% 32ms 0.74% PetscSetupUtils::CommonSetup 0 0% 82.64% 32ms 0.74% PetscSetupUtils::InitialisePetsc 15ms 0.35% 82.99% 32ms 0.74% TimeStepper::AdvanceOneTimeStep 0 0% 82.99% 32ms 0.74% ompi_mpi_init 0 0% 82.99% 31ms 0.72% VecNorm_Seq 2ms 0.046% 83.04% 31ms 0.72% VecSetType 20ms 0.46% 83.50% 30ms 0.7% AbstractOneStepIvpOdeSolver::Solve 1ms 0.023% 83.52% 29ms 0.67% 29ms 0.67% 84.19% 29ms 0.67% DistributedVector::Iterator::operator!= 2ms 0.046% 84.24% 27ms 0.63% VecCreate_MPI_Private 15ms 0.35% 84.59% 26ms 0.6% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 8ms 0.19% 84.77% 25ms 0.58% PetscHeaderDestroy_Private 0 0% 84.77% 25ms 0.58% PetscMemzero (inline) 25ms 0.58% 85.35% 25ms 0.58% TimeStepper::GetNextTimeStep 1ms 0.023% 85.38% 25ms 0.58% VecCopy_Seq (partial-inline) 25ms 0.58% 85.96% 25ms 0.58% asuint64 (inline) 24ms 0.56% 86.51% 24ms 0.56% __memset_avx2_unaligned_erms 0 0% 86.51% 24ms 0.56% std::vector::_M_assign_aux (inline) 0 0% 86.51% 24ms 0.56% std::vector::assign (inline) 0 0% 86.51% 23ms 0.53% AbstractFeVolumeIntegralAssembler::DoAssemble 10ms 0.23% 86.74% 23ms 0.53% __GI___libc_malloc 0 0% 86.74% 23ms 0.53% orte_init 3ms 0.07% 86.81% 22ms 0.51% VecCreate_Seq 4ms 0.093% 86.91% 22ms 0.51% std::__copy_move::__copy_m (inline) 0 0% 86.91% 22ms 0.51% std::__copy_move_a (inline) 0 0% 86.91% 22ms 0.51% std::__copy_move_a1 (inline) 0 0% 86.91% 22ms 0.51% std::__copy_move_a2 (inline) 0 0% 86.91% 22ms 0.51% std::copy (inline)