File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3636ms, 85.13% of 4271ms total Dropped 486 nodes (cum <= 21.36ms) flat flat% sum% cum cum% 0 0% 0% 4261ms 99.77% __libc_start_call_main 0 0% 0% 4261ms 99.77% __libc_start_main_impl 0 0% 0% 4261ms 99.77% _start 0 0% 0% 4257ms 99.67% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4257ms 99.67% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4257ms 99.67% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4257ms 99.67% main 0 0% 0% 4213ms 98.64% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4213ms 98.64% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4213ms 98.64% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4204ms 98.43% AbstractCardiacProblem::Solve 1ms 0.023% 0.023% 4188ms 98.06% AbstractDynamicLinearPdeSolver::Solve 16ms 0.37% 0.4% 2242ms 52.49% AbstractCardiacTissue::SolveCellSystems 0 0% 0.4% 1575ms 36.88% LinearSystem::Solve 0 0% 0.4% 1523ms 35.66% KSPSolve 6ms 0.14% 0.54% 1523ms 35.66% KSPSolve_Private 2ms 0.047% 0.59% 1460ms 34.18% KSPSolve_SYMMLQ 20ms 0.47% 1.05% 1255ms 29.38% AbstractCardiacCell::ComputeExceptVoltage 17ms 0.4% 1.45% 1076ms 25.19% AbstractOneStepIvpOdeSolver::InternalSolve 3ms 0.07% 1.52% 896ms 20.98% PCApply 0 0% 1.52% 890ms 20.84% PCApply_BJacobi_Singleblock 49ms 1.15% 2.67% 885ms 20.72% EulerIvpOdeSolver::CalculateNextYValue 196ms 4.59% 7.26% 826ms 19.34% CellLuoRudy1991FromCellML::EvaluateYDerivatives 37ms 0.87% 8.12% 805ms 18.85% AbstractCardiacTissue::UpdateCaches (inline) 0 0% 8.12% 777ms 18.19% KSPSolve_PREONLY (partial-inline) 369ms 8.64% 16.76% 763ms 17.86% __GI___exp 264ms 6.18% 22.95% 731ms 17.12% CellLuoRudy1991FromCellML::GetIIonic 1ms 0.023% 22.97% 728ms 17.05% MatSolve 0 0% 22.97% 728ms 17.05% PCApply_ICC 719ms 16.83% 39.80% 723ms 16.93% MatSolve_SeqSBAIJ_1_NaturalOrdering 0 0% 39.80% 641ms 15.01% KSP_PCApply.lto_priv.18 2ms 0.047% 39.85% 472ms 11.05% MatMult 7ms 0.16% 40.01% 461ms 10.79% MatMult_SeqAIJ (partial-inline) 448ms 10.49% 50.50% 460ms 10.77% MatMult_SeqAIJ_Inode (inline) 317ms 7.42% 57.93% 375ms 8.78% __ieee754_exp_fma 4ms 0.094% 58.02% 341ms 7.98% KSPConvergedDefault 0 0% 58.02% 325ms 7.61% KSP_PCApply (partial-inline) 62ms 1.45% 59.47% 319ms 7.47% BidomainSolver::SetupLinearSystem 1ms 0.023% 59.49% 253ms 5.92% std::pow (inline) 24ms 0.56% 60.06% 251ms 5.88% __pow 20ms 0.47% 60.52% 227ms 5.31% __ieee754_pow_fma 60ms 1.40% 61.93% 120ms 2.81% CellLuoRudy1991FromCellML::VerifyStateVariables 2ms 0.047% 61.98% 120ms 2.81% MatNullSpaceRemove 118ms 2.76% 64.74% 118ms 2.76% log_inline (inline) 1ms 0.023% 64.76% 99ms 2.32% VecDuplicate 20ms 0.47% 65.23% 92ms 2.15% MPI_Wtime 34ms 0.8% 66.03% 84ms 1.97% TimeStepper::TimeStepper 78ms 1.83% 67.85% 83ms 1.94% exp_inline (inline) 11ms 0.26% 68.11% 73ms 1.71% PetscLogEventBeginDefault 2ms 0.047% 68.16% 72ms 1.69% __GI___clock_gettime 70ms 1.64% 69.80% 70ms 1.64% [[vdso]] 0 0% 69.80% 69ms 1.62% VecMDot 4ms 0.094% 69.89% 69ms 1.62% VecMXDot_Private 3ms 0.07% 69.96% 65ms 1.52% VecCopy 15ms 0.35% 70.31% 63ms 1.48% PetscLogEventEndDefault 4ms 0.094% 70.41% 61ms 1.43% VecDuplicate_MPI 58ms 1.36% 71.76% 58ms 1.36% [libchaste_heart.so] 53ms 1.24% 73.00% 53ms 1.24% TimeStepper::CalculateNextTime 51ms 1.19% 74.20% 51ms 1.19% ddot_ 40ms 0.94% 75.13% 51ms 1.19% top12 (inline) 1ms 0.023% 75.16% 49ms 1.15% VecMAXPY 1ms 0.023% 75.18% 46ms 1.08% VecDot 45ms 1.05% 76.24% 46ms 1.08% __ieee754_log_fma 46ms 1.08% 77.31% 46ms 1.08% __memcpy_avx_unaligned_erms 1ms 0.023% 77.34% 45ms 1.05% AbstractBidomainSolver::FinaliseLinearSystem 5ms 0.12% 77.45% 45ms 1.05% VecAXPY 44ms 1.03% 78.48% 45ms 1.05% VecMDot_Seq 13ms 0.3% 78.79% 43ms 1.01% AbstractBidomainSolver::CheckCompatibilityCondition 3ms 0.07% 78.86% 43ms 1.01% PetscTimeSubtract (inline) 0 0% 78.86% 43ms 1.01% VecDestroy 10ms 0.23% 79.09% 42ms 0.98% AbstractCardiacCell::SetVoltage (partial-inline) 27ms 0.63% 79.72% 42ms 0.98% AbstractOneStepIvpOdeSolver::Solve 5ms 0.12% 79.84% 42ms 0.98% PetscMallocA 1ms 0.023% 79.86% 41ms 0.96% VecCreate 31ms 0.73% 80.59% 40ms 0.94% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 0 0% 80.59% 40ms 0.94% CxxTest::RealWorldDescription::setUp 0 0% 80.59% 40ms 0.94% PMPI_Init_thread 0 0% 80.59% 40ms 0.94% PetscInitialize 0 0% 80.59% 40ms 0.94% PetscSetup::setUpWorld 0 0% 80.59% 40ms 0.94% PetscSetupUtils::CommonSetup 0 0% 80.59% 40ms 0.94% PetscSetupUtils::InitialisePetsc 0 0% 80.59% 40ms 0.94% ompi_mpi_init 0 0% 80.59% 38ms 0.89% 18ms 0.42% 81.01% 37ms 0.87% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 4ms 0.094% 81.11% 37ms 0.87% PetscMallocAlign 0 0% 81.11% 37ms 0.87% VecDuplicate_Seq 0 0% 81.11% 36ms 0.84% VecDot_Seq 0 0% 81.11% 36ms 0.84% VecNorm 0 0% 81.11% 36ms 0.84% VecXDot_Seq_Private.constprop.0 0 0% 81.11% 33ms 0.77% PetscMemcpy (inline) 19ms 0.44% 81.55% 33ms 0.77% TimeStepper::AdvanceOneTimeStep 2ms 0.047% 81.60% 32ms 0.75% VecCopy_Seq (partial-inline) 1ms 0.023% 81.62% 31ms 0.73% VecCreate_MPI_Private 22ms 0.52% 82.14% 30ms 0.7% AbstractParameterisedSystem::SetAnyVariable 0 0% 82.14% 30ms 0.7% VecNorm_Seq 30ms 0.7% 82.84% 30ms 0.7% asuint64 (inline) 28ms 0.66% 83.49% 28ms 0.66% VecMAXPY_Seq 0 0% 83.49% 27ms 0.63% VecNorm_MPI 0 0% 83.49% 27ms 0.63% VecNorm_MPI_Default.constprop.0 26ms 0.61% 84.10% 26ms 0.61% DistributedVector::Iterator::operator!= 1ms 0.023% 84.13% 24ms 0.56% VecAXPY_Seq 7ms 0.16% 84.29% 24ms 0.56% __GI___libc_malloc 1ms 0.023% 84.31% 23ms 0.54% __posix_memalign 0 0% 84.31% 23ms 0.54% orte_init 1ms 0.023% 84.34% 22ms 0.52% VecStashCreate_Private 22ms 0.52% 84.85% 22ms 0.52% daxpy_ 12ms 0.28% 85.13% 22ms 0.52% std::__cxx11::basic_string::basic_string 0 0% 85.13% 22ms 0.52% std::vector::_M_assign_aux (inline) 0 0% 85.13% 22ms 0.52% std::vector::assign (inline)