File: Test3dBidomainProblemWithMetisForEfficiency Type: cpu Showing nodes accounting for 3730ms, 88.05% of 4236ms total Dropped 464 nodes (cum <= 21.18ms) flat flat% sum% cum cum% 0 0% 0% 4229ms 99.83% __libc_start_call_main 0 0% 0% 4229ms 99.83% __libc_start_main_impl 0 0% 0% 4229ms 99.83% _start 0 0% 0% 4226ms 99.76% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4226ms 99.76% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4226ms 99.76% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4226ms 99.76% main 0 0% 0% 4185ms 98.80% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4185ms 98.80% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4185ms 98.80% Test3dBidomainProblemWithMetisForEfficiency::TestBidomain3d 1ms 0.024% 0.024% 4177ms 98.61% AbstractCardiacProblem::Solve 5ms 0.12% 0.14% 4165ms 98.32% AbstractDynamicLinearPdeSolver::Solve 11ms 0.26% 0.4% 2284ms 53.92% AbstractCardiacTissue::SolveCellSystems 1ms 0.024% 0.42% 1522ms 35.93% LinearSystem::Solve 0 0% 0.42% 1473ms 34.77% KSPSolve 5ms 0.12% 0.54% 1473ms 34.77% KSPSolve_Private 2ms 0.047% 0.59% 1416ms 33.43% KSPSolve_SYMMLQ 28ms 0.66% 1.25% 1278ms 30.17% AbstractCardiacCell::ComputeExceptVoltage 36ms 0.85% 2.10% 1099ms 25.94% AbstractOneStepIvpOdeSolver::InternalSolve 68ms 1.61% 3.71% 918ms 21.67% EulerIvpOdeSolver::CalculateNextYValue 242ms 5.71% 9.42% 845ms 19.95% CellLuoRudy1991FromCellML::EvaluateYDerivatives 1ms 0.024% 9.44% 823ms 19.43% PCApply 23ms 0.54% 9.99% 815ms 19.24% AbstractCardiacTissue::UpdateCaches (inline) 1ms 0.024% 10.01% 815ms 19.24% PCApply_BJacobi_Singleblock 406ms 9.58% 19.59% 795ms 18.77% __GI___exp 278ms 6.56% 26.16% 759ms 17.92% CellLuoRudy1991FromCellML::GetIIonic 0 0% 26.16% 697ms 16.45% KSPSolve_PREONLY (partial-inline) 3ms 0.071% 26.23% 646ms 15.25% MatSolve 0 0% 26.23% 646ms 15.25% PCApply_ICC 632ms 14.92% 41.15% 637ms 15.04% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 0 0% 41.15% 605ms 14.28% KSP_PCApply.lto_priv.18 2ms 0.047% 41.19% 445ms 10.51% MatMult 10ms 0.24% 41.43% 432ms 10.20% MatMult_SeqAIJ (partial-inline) 421ms 9.94% 51.37% 432ms 10.20% MatMult_SeqAIJ_Inode (inline) 318ms 7.51% 58.88% 366ms 8.64% __ieee754_exp_fma 0 0% 58.88% 313ms 7.39% KSPConvergedDefault 67ms 1.58% 60.46% 309ms 7.29% BidomainSolver::SetupLinearSystem 0 0% 60.46% 302ms 7.13% KSP_PCApply (partial-inline) 2ms 0.047% 60.51% 229ms 5.41% std::pow (inline) 20ms 0.47% 60.98% 227ms 5.36% __pow 21ms 0.5% 61.47% 206ms 4.86% __ieee754_pow_fma 1ms 0.024% 61.50% 136ms 3.21% MatNullSpaceRemove 63ms 1.49% 62.98% 109ms 2.57% CellLuoRudy1991FromCellML::VerifyStateVariables 106ms 2.50% 65.49% 106ms 2.50% log_inline (inline) 84ms 1.98% 67.47% 84ms 1.98% ddot_ 0 0% 67.47% 83ms 1.96% VecMDot 9ms 0.21% 67.68% 83ms 1.96% VecMXDot_Private 20ms 0.47% 68.15% 79ms 1.86% MPI_Wtime 0 0% 68.15% 75ms 1.77% VecDuplicate 70ms 1.65% 69.81% 73ms 1.72% exp_inline (inline) 21ms 0.5% 70.30% 71ms 1.68% PetscLogEventBeginDefault 28ms 0.66% 70.96% 66ms 1.56% TimeStepper::TimeStepper 20ms 0.47% 71.44% 64ms 1.51% AbstractCardiacCell::SetVoltage (partial-inline) 1ms 0.024% 71.46% 63ms 1.49% VecDot 9ms 0.21% 71.67% 61ms 1.44% VecCopy 2ms 0.047% 71.72% 59ms 1.39% __GI___clock_gettime 57ms 1.35% 73.06% 57ms 1.35% [[vdso]] 0 0% 73.06% 56ms 1.32% VecDot_Seq 55ms 1.30% 74.36% 56ms 1.32% VecMDot_Seq 0 0% 74.36% 56ms 1.32% VecXDot_Seq_Private.constprop.0 5ms 0.12% 74.48% 52ms 1.23% VecMAXPY 51ms 1.20% 75.68% 51ms 1.20% [libchaste_heart.so] 5ms 0.12% 75.80% 49ms 1.16% VecAXPY 2ms 0.047% 75.85% 49ms 1.16% VecDestroy 1ms 0.024% 75.87% 49ms 1.16% VecDuplicate_MPI 2ms 0.047% 75.92% 48ms 1.13% VecNorm 12ms 0.28% 76.20% 47ms 1.11% PetscLogEventEndDefault 1ms 0.024% 76.23% 43ms 1.02% VecNorm_Seq 36ms 0.85% 77.08% 41ms 0.97% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 3ms 0.071% 77.15% 41ms 0.97% PetscTimeSubtract (inline) 40ms 0.94% 78.09% 40ms 0.94% TimeStepper::CalculateNextTime 40ms 0.94% 79.04% 40ms 0.94% __memcpy_avx_unaligned_erms 33ms 0.78% 79.82% 38ms 0.9% AbstractParameterisedSystem::SetAnyVariable 19ms 0.45% 80.26% 36ms 0.85% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 0 0% 80.26% 36ms 0.85% CxxTest::RealWorldDescription::setUp 0 0% 80.26% 36ms 0.85% PetscSetup::setUpWorld 0 0% 80.26% 36ms 0.85% PetscSetupUtils::CommonSetup 27ms 0.64% 80.90% 36ms 0.85% top12 (inline) 9ms 0.21% 81.11% 35ms 0.83% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 81.11% 35ms 0.83% AbstractBidomainSolver::FinaliseLinearSystem 23ms 0.54% 81.66% 35ms 0.83% AbstractOneStepIvpOdeSolver::Solve 0 0% 81.66% 35ms 0.83% PMPI_Init_thread 0 0% 81.66% 35ms 0.83% PetscInitialize 9ms 0.21% 81.87% 35ms 0.83% PetscMallocA 0 0% 81.87% 35ms 0.83% PetscSetupUtils::InitialisePetsc 0 0% 81.87% 35ms 0.83% ompi_mpi_init 34ms 0.8% 82.67% 34ms 0.8% DistributedVector::Stripe::operator[] (inline) 0 0% 82.67% 34ms 0.8% VecCreate 0 0% 82.67% 33ms 0.78% PetscMemcpy (inline) 0 0% 82.67% 33ms 0.78% VecNorm_MPI 0 0% 82.67% 33ms 0.78% VecNorm_MPI_Default.constprop.0 32ms 0.76% 83.43% 32ms 0.76% VecMAXPY_Seq 21ms 0.5% 83.92% 31ms 0.73% TimeStepper::AdvanceOneTimeStep 30ms 0.71% 84.63% 30ms 0.71% asuint64 (inline) 1ms 0.024% 84.66% 29ms 0.68% VecAXPY_Seq 28ms 0.66% 85.32% 28ms 0.66% __ieee754_log_fma 1ms 0.024% 85.34% 27ms 0.64% 27ms 0.64% 85.98% 27ms 0.64% daxpy_ 26ms 0.61% 86.59% 26ms 0.61% DistributedVector::Iterator::operator!= 1ms 0.024% 86.61% 26ms 0.61% PetscMallocAlign 0 0% 86.61% 26ms 0.61% VecCopy_Seq (partial-inline) 0 0% 86.61% 26ms 0.61% VecDuplicate_Seq 20ms 0.47% 87.09% 25ms 0.59% AbstractParameterisedSystem::GetAnyVariable 25ms 0.59% 87.68% 25ms 0.59% [libm.so.6] 6ms 0.14% 87.82% 23ms 0.54% PetscHeaderDestroy_Private 8ms 0.19% 88.01% 22ms 0.52% AbstractCardiacCell::GetVoltage (partial-inline) 2ms 0.047% 88.05% 22ms 0.52% VecCreate_MPI_Private 0 0% 88.05% 22ms 0.52% orte_init