File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 3672ms, 85.32% of 4304ms total Dropped 482 nodes (cum <= 21.52ms) flat flat% sum% cum cum% 0 0% 0% 4296ms 99.81% __libc_start_call_main 0 0% 0% 4296ms 99.81% __libc_start_main_impl 0 0% 0% 4296ms 99.81% _start 0 0% 0% 4293ms 99.74% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4293ms 99.74% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4293ms 99.74% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4293ms 99.74% main 0 0% 0% 4253ms 98.82% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4253ms 98.82% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4253ms 98.82% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 0 0% 0% 4248ms 98.70% AbstractCardiacProblem::Solve 1ms 0.023% 0.023% 4229ms 98.26% AbstractDynamicLinearPdeSolver::Solve 13ms 0.3% 0.33% 2214ms 51.44% AbstractCardiacTissue::SolveCellSystems 1ms 0.023% 0.35% 1686ms 39.17% LinearSystem::Solve 0 0% 0.35% 1639ms 38.08% KSPSolve 4ms 0.093% 0.44% 1639ms 38.08% KSPSolve_Private 4ms 0.093% 0.53% 1592ms 36.99% KSPSolve_SYMMLQ 37ms 0.86% 1.39% 1267ms 29.44% AbstractCardiacCell::ComputeExceptVoltage 29ms 0.67% 2.07% 1069ms 24.84% AbstractOneStepIvpOdeSolver::InternalSolve 1ms 0.023% 2.09% 1039ms 24.14% PCApply 0 0% 2.09% 1034ms 24.02% PCApply_BJacobi_Singleblock 53ms 1.23% 3.32% 896ms 20.82% EulerIvpOdeSolver::CalculateNextYValue 0 0% 3.32% 873ms 20.28% KSPSolve_PREONLY (partial-inline) 252ms 5.86% 9.18% 829ms 19.26% CellLuoRudy1991FromCellML::EvaluateYDerivatives 5ms 0.12% 9.29% 828ms 19.24% MatSolve 0 0% 9.29% 828ms 19.24% PCApply_ICC 811ms 18.84% 28.14% 816ms 18.96% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 0 0% 28.14% 763ms 17.73% KSP_PCApply.lto_priv.18 349ms 8.11% 36.25% 763ms 17.73% __GI___exp 23ms 0.53% 36.78% 759ms 17.63% AbstractCardiacTissue::UpdateCaches (inline) 242ms 5.62% 42.40% 711ms 16.52% CellLuoRudy1991FromCellML::GetIIonic 8ms 0.19% 42.59% 423ms 9.83% MatMult 3ms 0.07% 42.66% 410ms 9.53% MatMult_SeqAIJ 402ms 9.34% 52.00% 408ms 9.48% MatMult_SeqAIJ_Inode (inline) 376ms 8.74% 60.73% 402ms 9.34% __ieee754_exp_fma 1ms 0.023% 60.76% 373ms 8.67% KSPConvergedDefault 0 0% 60.76% 356ms 8.27% KSP_PCApply (partial-inline) 58ms 1.35% 62.11% 292ms 6.78% BidomainSolver::SetupLinearSystem 3ms 0.07% 62.17% 215ms 5.00% std::pow (inline) 26ms 0.6% 62.78% 212ms 4.93% __pow 21ms 0.49% 63.27% 186ms 4.32% __ieee754_pow_fma 59ms 1.37% 64.64% 141ms 3.28% CellLuoRudy1991FromCellML::VerifyStateVariables 0 0% 64.64% 136ms 3.16% MatNullSpaceRemove 0 0% 64.64% 101ms 2.35% VecDuplicate 90ms 2.09% 66.73% 90ms 2.09% log_inline (inline) 0 0% 66.73% 89ms 2.07% VecMDot 5ms 0.12% 66.84% 89ms 2.07% VecMXDot_Private 87ms 2.02% 68.87% 87ms 2.02% ddot_ 64ms 1.49% 70.35% 66ms 1.53% exp_inline (inline) 27ms 0.63% 70.98% 65ms 1.51% MPI_Wtime 33ms 0.77% 71.75% 64ms 1.49% TimeStepper::TimeStepper 1ms 0.023% 71.77% 64ms 1.49% VecNorm 1ms 0.023% 71.79% 63ms 1.46% VecDestroy 61ms 1.42% 73.21% 63ms 1.46% VecMDot_Seq 0 0% 73.21% 60ms 1.39% VecDot 0 0% 73.21% 55ms 1.28% VecDot_Seq 1ms 0.023% 73.23% 55ms 1.28% VecXDot_Seq_Private.constprop.0 10ms 0.23% 73.47% 54ms 1.25% PetscLogEventBeginDefault 17ms 0.39% 73.86% 54ms 1.25% PetscLogEventEndDefault 1ms 0.023% 73.88% 52ms 1.21% KSP_RemoveNullSpace (inline) 1ms 0.023% 73.91% 52ms 1.21% VecDuplicate_Seq 51ms 1.18% 75.09% 52ms 1.21% __ieee754_log_fma 7ms 0.16% 75.26% 51ms 1.18% PetscMallocA 0 0% 75.26% 51ms 1.18% VecNorm_Seq 2ms 0.046% 75.30% 49ms 1.14% VecDuplicate_MPI 5ms 0.12% 75.42% 48ms 1.12% AbstractCardiacCell::SetVoltage (partial-inline) 2ms 0.046% 75.46% 47ms 1.09% VecMAXPY 4ms 0.093% 75.56% 44ms 1.02% PetscMallocAlign 19ms 0.44% 76.00% 42ms 0.98% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 40ms 0.93% 76.93% 42ms 0.98% AbstractParameterisedSystem::SetAnyVariable 2ms 0.046% 76.97% 42ms 0.98% VecCreate 41ms 0.95% 77.93% 41ms 0.95% std::isinf (inline) 40ms 0.93% 78.86% 40ms 0.93% TimeStepper::CalculateNextTime 1ms 0.023% 78.88% 40ms 0.93% VecCopy 30ms 0.7% 79.58% 39ms 0.91% AbstractOneStepIvpOdeSolver::Solve 35ms 0.81% 80.39% 38ms 0.88% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 1ms 0.023% 80.41% 37ms 0.86% __GI___clock_gettime 36ms 0.84% 81.25% 36ms 0.84% [[vdso]] 0 0% 81.25% 35ms 0.81% CxxTest::RealWorldDescription::setUp 0 0% 81.25% 35ms 0.81% PetscInitialize 0 0% 81.25% 35ms 0.81% PetscSetup::setUpWorld 0 0% 81.25% 35ms 0.81% PetscSetupUtils::CommonSetup 0 0% 81.25% 35ms 0.81% PetscSetupUtils::InitialisePetsc 2ms 0.046% 81.30% 35ms 0.81% VecAXPY 0 0% 81.30% 35ms 0.81% VecNorm_MPI 1ms 0.023% 81.32% 35ms 0.81% VecNorm_MPI_Default.constprop.0 0 0% 81.32% 34ms 0.79% PMPI_Init_thread 34ms 0.79% 82.11% 34ms 0.79% __memcpy_avx_unaligned_erms 0 0% 82.11% 34ms 0.79% ompi_mpi_init 17ms 0.39% 82.50% 34ms 0.79% top12 (inline) 12ms 0.28% 82.78% 33ms 0.77% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 82.78% 33ms 0.77% AbstractBidomainSolver::FinaliseLinearSystem 32ms 0.74% 83.53% 33ms 0.77% VecMAXPY_Seq 32ms 0.74% 84.27% 32ms 0.74% [libchaste_heart.so] 2ms 0.046% 84.32% 30ms 0.7% PetscHeaderDestroy_Private 0 0% 84.32% 29ms 0.67% PetscMemcpy (inline) 7ms 0.16% 84.48% 29ms 0.67% __posix_memalign 2ms 0.046% 84.53% 27ms 0.63% 2ms 0.046% 84.57% 26ms 0.6% VecSetType 2ms 0.046% 84.62% 25ms 0.58% VecCreate_MPI_Private 24ms 0.56% 85.18% 24ms 0.56% DistributedVector::Stripe::operator[] (inline) 1ms 0.023% 85.20% 24ms 0.56% VecCopy_Seq (partial-inline) 5ms 0.12% 85.32% 23ms 0.53% __GI___libc_malloc 0 0% 85.32% 23ms 0.53% orte_init