File: Test3dBidomainProblemWithPermForEfficiency Type: cpu Showing nodes accounting for 3716ms, 86.58% of 4292ms total Dropped 457 nodes (cum <= 21.46ms) flat flat% sum% cum cum% 0 0% 0% 4284ms 99.81% __libc_start_call_main 0 0% 0% 4284ms 99.81% __libc_start_main_impl 0 0% 0% 4284ms 99.81% _start 0 0% 0% 4281ms 99.74% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4281ms 99.74% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4281ms 99.74% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4281ms 99.74% main 0 0% 0% 4244ms 98.88% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4244ms 98.88% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4244ms 98.88% Test3dBidomainProblemWithPermForEfficiency::TestBidomain3d 0 0% 0% 4238ms 98.74% AbstractCardiacProblem::Solve 2ms 0.047% 0.047% 4218ms 98.28% AbstractDynamicLinearPdeSolver::Solve 12ms 0.28% 0.33% 2361ms 55.01% AbstractCardiacTissue::SolveCellSystems 0 0% 0.33% 1569ms 36.56% LinearSystem::Solve 0 0% 0.33% 1538ms 35.83% KSPSolve 5ms 0.12% 0.44% 1538ms 35.83% KSPSolve_Private 0 0% 0.44% 1495ms 34.83% KSPSolve_SYMMLQ 23ms 0.54% 0.98% 1378ms 32.11% AbstractCardiacCell::ComputeExceptVoltage 30ms 0.7% 1.68% 1198ms 27.91% AbstractOneStepIvpOdeSolver::InternalSolve 63ms 1.47% 3.15% 1029ms 23.97% EulerIvpOdeSolver::CalculateNextYValue 246ms 5.73% 8.88% 952ms 22.18% CellLuoRudy1991FromCellML::EvaluateYDerivatives 1ms 0.023% 8.90% 949ms 22.11% PCApply 0 0% 8.90% 939ms 21.88% PCApply_BJacobi_Singleblock 422ms 9.83% 18.73% 903ms 21.04% __GI___exp 0 0% 18.73% 791ms 18.43% KSPSolve_PREONLY (partial-inline) 29ms 0.68% 19.41% 755ms 17.59% AbstractCardiacTissue::UpdateCaches (inline) 1ms 0.023% 19.43% 751ms 17.50% PCApply_ICC 4ms 0.093% 19.52% 750ms 17.47% MatSolve 732ms 17.05% 36.58% 740ms 17.24% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 229ms 5.34% 41.92% 709ms 16.52% CellLuoRudy1991FromCellML::GetIIonic 0 0% 41.92% 687ms 16.01% KSP_PCApply.lto_priv.18 435ms 10.14% 52.05% 467ms 10.88% __ieee754_exp_fma 8ms 0.19% 52.24% 413ms 9.62% MatMult 3ms 0.07% 52.31% 399ms 9.30% MatMult_SeqAIJ 392ms 9.13% 61.44% 399ms 9.30% MatMult_SeqAIJ_Inode (inline) 2ms 0.047% 61.49% 330ms 7.69% KSPConvergedDefault 0 0% 61.49% 319ms 7.43% KSP_PCApply (partial-inline) 39ms 0.91% 62.40% 249ms 5.80% BidomainSolver::SetupLinearSystem 1ms 0.023% 62.42% 234ms 5.45% std::pow (inline) 22ms 0.51% 62.93% 233ms 5.43% __pow 33ms 0.77% 63.70% 211ms 4.92% __ieee754_pow_fma 64ms 1.49% 65.19% 168ms 3.91% CellLuoRudy1991FromCellML::VerifyStateVariables 105ms 2.45% 67.64% 105ms 2.45% log_inline (inline) 1ms 0.023% 67.66% 102ms 2.38% MatNullSpaceRemove 1ms 0.023% 67.68% 102ms 2.38% VecDuplicate 23ms 0.54% 68.22% 81ms 1.89% MPI_Wtime 71ms 1.65% 69.87% 71ms 1.65% exp_inline (inline) 39ms 0.91% 70.78% 68ms 1.58% TimeStepper::TimeStepper 0 0% 70.78% 66ms 1.54% VecMDot 2ms 0.047% 70.83% 66ms 1.54% VecMXDot_Private 65ms 1.51% 72.34% 65ms 1.51% ddot_ 8ms 0.19% 72.53% 61ms 1.42% PetscLogEventBeginDefault 1ms 0.023% 72.55% 58ms 1.35% __GI___clock_gettime 57ms 1.33% 73.88% 57ms 1.33% [[vdso]] 5ms 0.12% 74.00% 56ms 1.30% VecAXPY 1ms 0.023% 74.02% 56ms 1.30% VecDuplicate_MPI 11ms 0.26% 74.28% 55ms 1.28% PetscLogEventEndDefault 47ms 1.10% 75.37% 51ms 1.19% AbstractIvpOdeSolver::SolveAndUpdateStateVariable 2ms 0.047% 75.42% 51ms 1.19% VecDestroy 9ms 0.21% 75.63% 50ms 1.16% VecCopy 12ms 0.28% 75.91% 48ms 1.12% PetscMallocA 33ms 0.77% 76.68% 47ms 1.10% AbstractOneStepIvpOdeSolver::Solve 1ms 0.023% 76.70% 47ms 1.10% VecDot 0 0% 76.70% 45ms 1.05% VecDuplicate_Seq 45ms 1.05% 77.75% 45ms 1.05% std::isinf (inline) 41ms 0.96% 78.70% 43ms 1.00% VecMDot_Seq 43ms 1.00% 79.71% 43ms 1.00% __memcpy_avx_unaligned_erms 36ms 0.84% 80.55% 39ms 0.91% __ieee754_log_fma 16ms 0.37% 80.92% 38ms 0.89% AbstractCardiacCellInterface::SetVoltageDerivativeToZero 0 0% 80.92% 38ms 0.89% VecDot_Seq 1ms 0.023% 80.94% 38ms 0.89% VecNorm 0 0% 80.94% 38ms 0.89% VecXDot_Seq_Private.constprop.0 2ms 0.047% 80.99% 37ms 0.86% VecAXPY_Seq 1ms 0.023% 81.01% 36ms 0.84% PetscMallocAlign 3ms 0.07% 81.08% 36ms 0.84% VecCreate 0 0% 81.08% 35ms 0.82% VecMAXPY 0 0% 81.08% 34ms 0.79% PetscMemcpy (inline) 10ms 0.23% 81.31% 34ms 0.79% std::__cxx11::basic_string::basic_string 0 0% 81.31% 33ms 0.77% CxxTest::RealWorldDescription::setUp 0 0% 81.31% 33ms 0.77% PetscSetup::setUpWorld 0 0% 81.31% 33ms 0.77% PetscSetupUtils::CommonSetup 2ms 0.047% 81.36% 33ms 0.77% VecCreate_MPI_Private 0 0% 81.36% 33ms 0.77% VecNorm_Seq 33ms 0.77% 82.13% 33ms 0.77% daxpy_ 7ms 0.16% 82.29% 32ms 0.75% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 82.29% 32ms 0.75% AbstractBidomainSolver::FinaliseLinearSystem 1ms 0.023% 82.32% 32ms 0.75% PetscInitialize 0 0% 82.32% 32ms 0.75% PetscSetupUtils::InitialisePetsc 32ms 0.75% 83.06% 32ms 0.75% TimeStepper::CalculateNextTime 0 0% 83.06% 31ms 0.72% PMPI_Init_thread 0 0% 83.06% 31ms 0.72% ompi_mpi_init 5ms 0.12% 83.18% 29ms 0.68% AbstractCardiacCell::SetVoltage (partial-inline) 0 0% 83.18% 29ms 0.68% KSP_RemoveNullSpace (inline) 0 0% 83.18% 29ms 0.68% VecSetType 2ms 0.047% 83.22% 26ms 0.61% 26ms 0.61% 83.83% 26ms 0.61% DistributedVector::Iterator::operator!= 26ms 0.61% 84.44% 26ms 0.61% asuint64 (inline) 4ms 0.093% 84.53% 25ms 0.58% __GI___libc_malloc 11ms 0.26% 84.79% 25ms 0.58% top12 (inline) 18ms 0.42% 85.21% 24ms 0.56% AbstractParameterisedSystem::GetAnyVariable 1ms 0.023% 85.23% 24ms 0.56% VecScale 2ms 0.047% 85.27% 24ms 0.56% __posix_memalign 11ms 0.26% 85.53% 23ms 0.54% TimeStepper::AdvanceOneTimeStep 0 0% 85.53% 23ms 0.54% VecCopy_Seq (partial-inline) 22ms 0.51% 86.04% 23ms 0.54% VecMAXPY_Seq 23ms 0.54% 86.58% 23ms 0.54% std::vector::operator[] (inline)