File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3658ms, 87.39% of 4186ms total Dropped 437 nodes (cum <= 20.93ms) flat flat% sum% cum cum% 0 0% 0% 4179ms 99.83% __libc_start_call_main 0 0% 0% 4179ms 99.83% __libc_start_main_impl 0 0% 0% 4179ms 99.83% _start 0 0% 0% 4176ms 99.76% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4176ms 99.76% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4176ms 99.76% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4176ms 99.76% main 0 0% 0% 4138ms 98.85% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4138ms 98.85% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4138ms 98.85% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4131ms 98.69% AbstractCardiacProblem::Solve 0 0% 0% 4119ms 98.40% AbstractDynamicLinearPdeSolver::Solve 13ms 0.31% 0.31% 2482ms 59.29% AbstractCardiacTissue::SolveCellSystems 46ms 1.10% 1.41% 1442ms 34.45% AbstractCardiacCell::ComputeExceptVoltage 2ms 0.048% 1.46% 1297ms 30.98% LinearSystem::Solve 0 0% 1.46% 1258ms 30.05% KSPSolve 3ms 0.072% 1.53% 1258ms 30.05% KSPSolve_Private 31ms 0.74% 2.27% 1237ms 29.55% AbstractOneStepIvpOdeSolver::InternalSolve 1ms 0.024% 2.29% 1213ms 28.98% KSPSolve_SYMMLQ 75ms 1.79% 4.09% 1038ms 24.80% EulerIvpOdeSolver::CalculateNextYValue 278ms 6.64% 10.73% 946ms 22.60% CellLuoRudy1991FromCellML::EvaluateYDerivatives 377ms 9.01% 19.73% 837ms 20.00% __GI___exp 31ms 0.74% 20.47% 806ms 19.25% AbstractCardiacTissue::UpdateCaches (inline) 269ms 6.43% 26.90% 743ms 17.75% CellLuoRudy1991FromCellML::GetIIonic 2ms 0.048% 26.95% 713ms 17.03% PCApply 1ms 0.024% 26.97% 708ms 16.91% PCApply_BJacobi_Singleblock 0 0% 26.97% 593ms 14.17% KSPSolve_PREONLY (partial-inline) 1ms 0.024% 26.99% 544ms 13.00% PCApply_ICC 3ms 0.072% 27.07% 543ms 12.97% MatSolve 0 0% 27.07% 542ms 12.95% KSP_PCApply.lto_priv.18 536ms 12.80% 39.87% 538ms 12.85% MatSolve_SeqSBAIJ_1_NaturalOrdering 6ms 0.14% 40.01% 445ms 10.63% MatMult 413ms 9.87% 49.88% 441ms 10.54% __ieee754_exp_fma 3ms 0.072% 49.95% 434ms 10.37% MatMult_SeqAIJ 429ms 10.25% 60.20% 434ms 10.37% MatMult_SeqAIJ_Inode (inline) 45ms 1.08% 61.28% 295ms 7.05% BidomainSolver::SetupLinearSystem 2ms 0.048% 61.32% 240ms 5.73% std::pow (inline) 1ms 0.024% 61.35% 238ms 5.69% KSPConvergedDefault 14ms 0.33% 61.68% 238ms 5.69% __pow 0 0% 61.68% 237ms 5.66% KSP_PCApply (partial-inline) 28ms 0.67% 62.35% 224ms 5.35% __ieee754_pow_fma 76ms 1.82% 64.17% 180ms 4.30% CellLuoRudy1991FromCellML::VerifyStateVariables 0 0% 64.17% 117ms 2.80% MatNullSpaceRemove 114ms 2.72% 66.89% 114ms 2.72% log_inline (inline) 0 0% 66.89% 84ms 2.01% VecDuplicate 0 0% 66.89% 77ms 1.84% VecMDot 6ms 0.14% 67.03% 77ms 1.84% VecMXDot_Private 74ms 1.77% 68.80% 75ms 1.79% exp_inline (inline) 39ms 0.93% 69.73% 74ms 1.77% TimeStepper::TimeStepper 17ms 0.41% 70.14% 66ms 1.58% MPI_Wtime 7ms 0.17% 70.31% 61ms 1.46% VecCopy 8ms 0.19% 70.50% 55ms 1.31% PetscLogEventBeginDefault 52ms 1.24% 71.74% 54ms 1.29% VecMDot_Seq 11ms 0.26% 72.00% 51ms 1.22% PetscLogEventEndDefault 2ms 0.048% 72.05% 49ms 1.17% __GI___clock_gettime 7ms 0.17% 72.22% 48ms 1.15% AbstractCardiacCell::SetVoltage (partial-inline) 0 0% 72.22% 47ms 1.12% VecDuplicate_MPI 47ms 1.12% 73.34% 47ms 1.12% [[vdso]] 47ms 1.12% 74.46% 47ms 1.12% ddot_ 45ms 1.08% 75.54% 45ms 1.08% [libchaste_heart.so] 44ms 1.05% 76.59% 44ms 1.05% std::isinf (inline) 33ms 0.79% 77.38% 43ms 1.03% AbstractOneStepIvpOdeSolver::Solve 4ms 0.096% 77.47% 42ms 1.00% PetscMallocA 42ms 1.00% 78.48% 42ms 1.00% TimeStepper::CalculateNextTime 41ms 0.98% 79.46% 41ms 0.98% __ieee754_log_fma 35ms 0.84% 80.29% 40ms 0.96% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 3ms 0.072% 80.36% 40ms 0.96% VecMAXPY 0 0% 80.36% 40ms 0.96% VecNorm 13ms 0.31% 80.67% 39ms 0.93% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 80.67% 39ms 0.93% AbstractBidomainSolver::FinaliseLinearSystem 19ms 0.45% 81.13% 39ms 0.93% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 1ms 0.024% 81.15% 38ms 0.91% PetscMallocAlign 2ms 0.048% 81.20% 37ms 0.88% VecDuplicate_Seq 37ms 0.88% 82.08% 37ms 0.88% __memcpy_avx_unaligned_erms 0 0% 82.08% 35ms 0.84% VecDot 0 0% 82.08% 35ms 0.84% VecNorm_Seq 2ms 0.048% 82.13% 34ms 0.81% VecDestroy 16ms 0.38% 82.51% 34ms 0.81% std::__cxx11::basic_string::basic_string 32ms 0.76% 83.28% 33ms 0.79% AbstractParameterisedSystem::SetAnyVariable 0 0% 83.28% 33ms 0.79% CxxTest::RealWorldDescription::setUp 0 0% 83.28% 33ms 0.79% PMPI_Init_thread 0 0% 83.28% 33ms 0.79% PetscInitialize 0 0% 83.28% 33ms 0.79% PetscSetup::setUpWorld 0 0% 83.28% 33ms 0.79% PetscSetupUtils::CommonSetup 0 0% 83.28% 33ms 0.79% PetscSetupUtils::InitialisePetsc 1ms 0.024% 83.30% 33ms 0.79% VecAXPY 0 0% 83.30% 33ms 0.79% ompi_mpi_init 12ms 0.29% 83.59% 33ms 0.79% top12 (inline) 0 0% 83.59% 29ms 0.69% VecCreate 0 0% 83.59% 28ms 0.67% VecDot_Seq 0 0% 83.59% 28ms 0.67% VecXDot_Seq_Private.constprop.0 1ms 0.024% 83.61% 27ms 0.65% 1ms 0.024% 83.64% 27ms 0.65% VecCopy_Seq (partial-inline) 0 0% 83.64% 27ms 0.65% VecCreate_MPI_Private 27ms 0.65% 84.28% 27ms 0.65% std::vector::operator[] (inline) 24ms 0.57% 84.85% 26ms 0.62% VecMAXPY_Seq 0 0% 84.85% 25ms 0.6% PetscMemcpy (inline) 14ms 0.33% 85.19% 25ms 0.6% TimeStepper::AdvanceOneTimeStep 0 0% 85.19% 25ms 0.6% VecNorm_MPI 1ms 0.024% 85.21% 25ms 0.6% VecNorm_MPI_Default.constprop.0 5ms 0.12% 85.33% 25ms 0.6% __posix_memalign 24ms 0.57% 85.91% 24ms 0.57% asuint64 (inline) 0 0% 85.91% 23ms 0.55% orte_init 18ms 0.43% 86.34% 21ms 0.5% AbstractParameterisedSystem::GetAnyVariable 21ms 0.5% 86.84% 21ms 0.5% RegularStimulus::GetStimulus 21ms 0.5% 87.34% 21ms 0.5% TimeStepper::GetNextTimeStep 1ms 0.024% 87.36% 21ms 0.5% VecAXPY_Seq 1ms 0.024% 87.39% 21ms 0.5% VecSetType