File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 3357ms, 88.90% of 3776ms total Dropped 420 nodes (cum <= 18.88ms) flat flat% sum% cum cum% 0 0% 0% 3769ms 99.81% __libc_start_call_main 0 0% 0% 3769ms 99.81% __libc_start_main_impl 0 0% 0% 3769ms 99.81% _start 0 0% 0% 3766ms 99.74% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 3766ms 99.74% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 3766ms 99.74% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 3766ms 99.74% main 0 0% 0% 3735ms 98.91% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 3735ms 98.91% CxxTest::TestRunner::runTest (inline) 0 0% 0% 3735ms 98.91% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 0 0% 0% 3731ms 98.81% AbstractCardiacProblem::Solve 0 0% 0% 3719ms 98.49% AbstractDynamicLinearPdeSolver::Solve 22ms 0.58% 0.58% 1873ms 49.60% AbstractCardiacTissue::SolveCellSystems 0 0% 0.58% 1477ms 39.12% LinearSystem::Solve 0 0% 0.58% 1442ms 38.19% KSPSolve 1ms 0.026% 0.61% 1442ms 38.19% KSPSolve_Private 1ms 0.026% 0.64% 1408ms 37.29% KSPSolve_SYMMLQ 19ms 0.5% 1.14% 1092ms 28.92% AbstractCardiacCell::ComputeExceptVoltage 23ms 0.61% 1.75% 982ms 26.01% AbstractOneStepIvpOdeSolver::InternalSolve 1ms 0.026% 1.77% 848ms 22.46% PCApply 0 0% 1.77% 842ms 22.30% PCApply_BJacobi_Singleblock 101ms 2.67% 4.45% 815ms 21.58% EulerIvpOdeSolver::CalculateNextYValue 0 0% 4.45% 747ms 19.78% KSPSolve_PREONLY (partial-inline) 252ms 6.67% 11.12% 707ms 18.72% CellLuoRudy1991FromCellML::EvaluateYDerivatives 1ms 0.026% 11.15% 702ms 18.59% MatSolve 0 0% 11.15% 702ms 18.59% PCApply_ICC 694ms 18.38% 29.53% 698ms 18.49% MatSolve_SeqSBAIJ_1_NaturalOrdering 0 0% 29.53% 651ms 17.24% KSP_PCApply.lto_priv.18 2ms 0.053% 29.58% 632ms 16.74% MatMult 10ms 0.26% 29.85% 627ms 16.60% AbstractCardiacTissue::UpdateCaches (inline) 1ms 0.026% 29.87% 625ms 16.55% MatMult_SeqAIJ 623ms 16.50% 46.37% 625ms 16.55% MatMult_SeqAIJ_Inode (inline) 272ms 7.20% 53.58% 600ms 15.89% CellLuoRudy1991FromCellML::GetIIonic 190ms 5.03% 58.61% 516ms 13.67% __GI___exp 23ms 0.61% 59.22% 333ms 8.82% BidomainSolver::SetupLinearSystem 279ms 7.39% 66.60% 279ms 7.39% __ieee754_exp_fma 0 0% 66.60% 264ms 6.99% KSP_PCApply (partial-inline) 1ms 0.026% 66.63% 259ms 6.86% KSPConvergedDefault 6ms 0.16% 66.79% 180ms 4.77% std::pow (inline) 9ms 0.24% 67.03% 173ms 4.58% __pow 14ms 0.37% 67.40% 161ms 4.26% __ieee754_pow_fma 76ms 2.01% 69.41% 113ms 2.99% CellLuoRudy1991FromCellML::VerifyStateVariables 1ms 0.026% 69.44% 101ms 2.67% MatNullSpaceRemove 96ms 2.54% 71.98% 96ms 2.54% [libchaste_heart.so] 65ms 1.72% 73.70% 76ms 2.01% exp_inline (inline) 70ms 1.85% 75.56% 70ms 1.85% log_inline (inline) 0 0% 75.56% 66ms 1.75% VecDuplicate 0 0% 75.56% 59ms 1.56% VecMDot 6ms 0.16% 75.72% 59ms 1.56% VecMXDot_Private 16ms 0.42% 76.14% 58ms 1.54% MPI_Wtime 24ms 0.64% 76.77% 58ms 1.54% TimeStepper::TimeStepper 55ms 1.46% 78.23% 55ms 1.46% TimeStepper::CalculateNextTime 53ms 1.40% 79.63% 53ms 1.40% [libm.so.6] 43ms 1.14% 80.77% 43ms 1.14% ddot_ 39ms 1.03% 81.81% 42ms 1.11% VecMDot_Seq 1ms 0.026% 81.83% 42ms 1.11% __GI___clock_gettime 4ms 0.11% 81.94% 41ms 1.09% VecMAXPY 41ms 1.09% 83.02% 41ms 1.09% [[vdso]] 5ms 0.13% 83.16% 38ms 1.01% PetscLogEventBeginDefault 1ms 0.026% 83.18% 38ms 1.01% VecDuplicate_MPI 2ms 0.053% 83.24% 37ms 0.98% VecDestroy 10ms 0.26% 83.50% 36ms 0.95% PetscLogEventEndDefault 3ms 0.079% 83.58% 36ms 0.95% VecCopy 9ms 0.24% 83.82% 34ms 0.9% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 83.82% 34ms 0.9% AbstractBidomainSolver::FinaliseLinearSystem 6ms 0.16% 83.98% 34ms 0.9% TimeStepper::AdvanceOneTimeStep 0 0% 83.98% 34ms 0.9% VecNorm 34ms 0.9% 84.88% 34ms 0.9% [libchaste_ode.so] 0 0% 84.88% 32ms 0.85% KSP_RemoveNullSpace (inline) 32ms 0.85% 85.73% 32ms 0.85% __memcpy_avx512_unaligned_erms 3ms 0.079% 85.81% 31ms 0.82% VecAXPY 1ms 0.026% 85.83% 31ms 0.82% VecNorm_Seq 28ms 0.74% 86.57% 30ms 0.79% VecMAXPY_Seq 0 0% 86.57% 29ms 0.77% VecCreate 0 0% 86.57% 29ms 0.77% VecDot 2ms 0.053% 86.63% 28ms 0.74% 7ms 0.19% 86.81% 28ms 0.74% AbstractCardiacCell::SetVoltage (partial-inline) 0 0% 86.81% 28ms 0.74% CxxTest::RealWorldDescription::setUp 0 0% 86.81% 28ms 0.74% PMPI_Init_thread 0 0% 86.81% 28ms 0.74% PetscInitialize 0 0% 86.81% 28ms 0.74% PetscSetup::setUpWorld 0 0% 86.81% 28ms 0.74% PetscSetupUtils::CommonSetup 0 0% 86.81% 28ms 0.74% PetscSetupUtils::InitialisePetsc 0 0% 86.81% 28ms 0.74% VecDuplicate_Seq 28ms 0.74% 87.55% 28ms 0.74% __ieee754_log_fma 0 0% 87.55% 28ms 0.74% ompi_mpi_init 15ms 0.4% 87.95% 25ms 0.66% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 19ms 0.5% 88.45% 25ms 0.66% AbstractOneStepIvpOdeSolver::Solve 0 0% 88.45% 25ms 0.66% VecDot_Seq 0 0% 88.45% 25ms 0.66% VecXDot_Seq_Private.constprop.0 3ms 0.079% 88.53% 24ms 0.64% PetscMallocA 0 0% 88.53% 24ms 0.64% std::vector::_M_assign_aux (inline) 0 0% 88.53% 24ms 0.64% std::vector::assign (inline) 1ms 0.026% 88.56% 23ms 0.61% VecCreate_MPI_Private 2ms 0.053% 88.61% 22ms 0.58% VecAXPY_Seq 0 0% 88.61% 21ms 0.56% PetscMallocAlign 0 0% 88.61% 21ms 0.56% VecNorm_MPI 0 0% 88.61% 21ms 0.56% VecNorm_MPI_Default.constprop.0 2ms 0.053% 88.67% 20ms 0.53% PMPI_Comm_get_attr 0 0% 88.67% 19ms 0.5% PetscMemcpy (inline) 9ms 0.24% 88.90% 19ms 0.5% std::__cxx11::basic_string::basic_string