File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3723ms, 87.01% of 4279ms total Dropped 468 nodes (cum <= 21.39ms) flat flat% sum% cum cum% 0 0% 0% 4273ms 99.86% __libc_start_call_main 0 0% 0% 4273ms 99.86% __libc_start_main_impl 0 0% 0% 4273ms 99.86% _start 0 0% 0% 4270ms 99.79% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4270ms 99.79% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4270ms 99.79% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4270ms 99.79% main 0 0% 0% 4228ms 98.81% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4228ms 98.81% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4228ms 98.81% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 0 0% 0% 4221ms 98.64% AbstractCardiacProblem::Solve 1ms 0.023% 0.023% 4199ms 98.13% AbstractDynamicLinearPdeSolver::Solve 9ms 0.21% 0.23% 2179ms 50.92% AbstractCardiacTissue::SolveCellSystems 0 0% 0.23% 1727ms 40.36% LinearSystem::Solve 0 0% 0.23% 1697ms 39.66% KSPSolve 2ms 0.047% 0.28% 1697ms 39.66% KSPSolve_Private 1ms 0.023% 0.3% 1643ms 38.40% KSPSolve_SYMMLQ 31ms 0.72% 1.03% 1244ms 29.07% AbstractCardiacCell::ComputeExceptVoltage 2ms 0.047% 1.08% 1093ms 25.54% PCApply 0 0% 1.08% 1083ms 25.31% PCApply_BJacobi_Singleblock 28ms 0.65% 1.73% 1050ms 24.54% AbstractOneStepIvpOdeSolver::InternalSolve 0 0% 1.73% 949ms 22.18% KSPSolve_PREONLY (partial-inline) 1ms 0.023% 1.75% 908ms 21.22% PCApply_ICC 7ms 0.16% 1.92% 907ms 21.20% MatSolve 804ms 18.79% 20.71% 895ms 20.92% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 54ms 1.26% 21.97% 886ms 20.71% EulerIvpOdeSolver::CalculateNextYValue 0 0% 21.97% 820ms 19.16% KSP_PCApply.lto_priv.18 226ms 5.28% 27.25% 819ms 19.14% CellLuoRudy1991FromCellML::EvaluateYDerivatives 331ms 7.74% 34.98% 762ms 17.81% __GI___exp 27ms 0.63% 35.62% 721ms 16.85% AbstractCardiacTissue::UpdateCaches (inline) 235ms 5.49% 41.11% 669ms 15.63% CellLuoRudy1991FromCellML::GetIIonic 3ms 0.07% 41.18% 429ms 10.03% MatMult 4ms 0.093% 41.27% 421ms 9.84% MatMult_SeqAIJ 413ms 9.65% 50.92% 420ms 9.82% MatMult_SeqAIJ_Inode (inline) 385ms 9.00% 59.92% 418ms 9.77% __ieee754_exp_fma 2ms 0.047% 59.97% 354ms 8.27% KSPConvergedDefault 0 0% 59.97% 341ms 7.97% KSP_PCApply (partial-inline) 42ms 0.98% 60.95% 251ms 5.87% BidomainSolver::SetupLinearSystem 3ms 0.07% 61.02% 206ms 4.81% std::pow (inline) 17ms 0.4% 61.42% 202ms 4.72% __pow 21ms 0.49% 61.91% 185ms 4.32% __ieee754_pow_fma 77ms 1.80% 63.71% 155ms 3.62% CellLuoRudy1991FromCellML::VerifyStateVariables 126ms 2.94% 66.65% 126ms 2.94% __memcpy_avx_unaligned_erms 1ms 0.023% 66.67% 114ms 2.66% MatNullSpaceRemove 0 0% 66.67% 112ms 2.62% PetscMemcpy (inline) 1ms 0.023% 66.70% 90ms 2.10% VecDuplicate 84ms 1.96% 68.66% 84ms 1.96% log_inline (inline) 22ms 0.51% 69.18% 82ms 1.92% MPI_Wtime 79ms 1.85% 71.02% 79ms 1.85% ddot_ 0 0% 71.02% 70ms 1.64% VecMDot 2ms 0.047% 71.07% 70ms 1.64% VecMXDot_Private 67ms 1.57% 72.63% 70ms 1.64% exp_inline (inline) 35ms 0.82% 73.45% 68ms 1.59% TimeStepper::TimeStepper 16ms 0.37% 73.83% 63ms 1.47% PetscLogEventEndDefault 60ms 1.40% 75.23% 60ms 1.40% [[vdso]] 0 0% 75.23% 60ms 1.40% __GI___clock_gettime 0 0% 75.23% 57ms 1.33% VecDestroy 0 0% 75.23% 56ms 1.31% VecDot 44ms 1.03% 76.26% 53ms 1.24% AbstractOneStepIvpOdeSolver::Solve 0 0% 76.26% 51ms 1.19% VecDuplicate_Seq 4ms 0.093% 76.35% 50ms 1.17% VecNorm 43ms 1.00% 77.35% 49ms 1.15% VecMDot_Seq 2ms 0.047% 77.40% 47ms 1.10% VecCopy 0 0% 77.40% 46ms 1.08% VecDot_Seq 0 0% 77.40% 46ms 1.08% VecXDot_Seq_Private.constprop.0 1ms 0.023% 77.42% 45ms 1.05% VecCreate 5ms 0.12% 77.54% 43ms 1.00% PetscLogEventBeginDefault 2ms 0.047% 77.59% 43ms 1.00% VecMAXPY 18ms 0.42% 78.01% 43ms 1.00% top12 (inline) 8ms 0.19% 78.20% 42ms 0.98% AbstractCardiacCell::SetVoltage (partial-inline) 0 0% 78.20% 39ms 0.91% VecNorm_Seq 22ms 0.51% 78.71% 38ms 0.89% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 38ms 0.89% 79.60% 38ms 0.89% TimeStepper::CalculateNextTime 1ms 0.023% 79.62% 38ms 0.89% VecDuplicate_MPI 37ms 0.86% 80.49% 37ms 0.86% [libchaste_heart.so] 0 0% 80.49% 36ms 0.84% CxxTest::RealWorldDescription::setUp 0 0% 80.49% 36ms 0.84% PetscInitialize 0 0% 80.49% 36ms 0.84% PetscSetup::setUpWorld 0 0% 80.49% 36ms 0.84% PetscSetupUtils::CommonSetup 0 0% 80.49% 36ms 0.84% PetscSetupUtils::InitialisePetsc 3ms 0.07% 80.56% 36ms 0.84% VecAXPY 36ms 0.84% 81.40% 36ms 0.84% __ieee754_log_fma 10ms 0.23% 81.63% 35ms 0.82% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 81.63% 35ms 0.82% AbstractBidomainSolver::FinaliseLinearSystem 0 0% 81.63% 35ms 0.82% PMPI_Init_thread 0 0% 81.63% 35ms 0.82% ompi_mpi_init 35ms 0.82% 82.45% 35ms 0.82% std::isinf (inline) 4ms 0.093% 82.54% 34ms 0.79% PetscMallocA 0 0% 82.54% 32ms 0.75% PetscHeaderDestroy_Private 23ms 0.54% 83.08% 31ms 0.72% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 2ms 0.047% 83.13% 30ms 0.7% 0 0% 83.13% 30ms 0.7% PetscMallocAlign 25ms 0.58% 83.71% 28ms 0.65% AbstractParameterisedSystem::SetAnyVariable 28ms 0.65% 84.37% 28ms 0.65% asuint64 (inline) 13ms 0.3% 84.67% 28ms 0.65% std::__cxx11::basic_string::basic_string 23ms 0.54% 85.21% 27ms 0.63% AbstractParameterisedSystem::GetAnyVariable 27ms 0.63% 85.84% 27ms 0.63% DistributedVector::Iterator::operator!= 27ms 0.63% 86.47% 27ms 0.63% VecMAXPY_Seq 3ms 0.07% 86.54% 26ms 0.61% PetscHeaderCreate_Private 5ms 0.12% 86.66% 25ms 0.58% PMPI_Comm_get_attr 1ms 0.023% 86.68% 24ms 0.56% VecAXPY_Seq 12ms 0.28% 86.96% 23ms 0.54% TimeStepper::AdvanceOneTimeStep 0 0% 86.96% 23ms 0.54% orte_init 2ms 0.047% 87.01% 22ms 0.51% VecCreate_MPI_Private