File: Test3dBidomainProblemForEfficiencyWithFasterOdes Type: cpu Showing nodes accounting for 3741ms, 86.16% of 4342ms total Dropped 517 nodes (cum <= 21.71ms) flat flat% sum% cum cum% 0 0% 0% 4332ms 99.77% __libc_start_call_main 0 0% 0% 4332ms 99.77% __libc_start_main_impl 0 0% 0% 4332ms 99.77% _start 0 0% 0% 4329ms 99.70% CxxTest::ErrorFormatter::run (inline) 0 0% 0% 4329ms 99.70% CxxTest::TestRunner::runAllTests (inline) 0 0% 0% 4329ms 99.70% CxxTest::TestRunner::runWorld (inline) 0 0% 0% 4329ms 99.70% main 0 0% 0% 4289ms 98.78% CxxTest::TestRunner::runSuite (inline) 0 0% 0% 4289ms 98.78% CxxTest::TestRunner::runTest (inline) 0 0% 0% 4289ms 98.78% Test3dBidomainProblemForEfficiencyWithFasterOdes::TestBidomain3d 1ms 0.023% 0.023% 4281ms 98.60% AbstractCardiacProblem::Solve 3ms 0.069% 0.092% 4263ms 98.18% AbstractDynamicLinearPdeSolver::Solve 1ms 0.023% 0.12% 2000ms 46.06% LinearSystem::Solve 0 0% 0.12% 1944ms 44.77% KSPSolve 4ms 0.092% 0.21% 1944ms 44.77% KSPSolve_Private 20ms 0.46% 0.67% 1924ms 44.31% AbstractCardiacTissue::SolveCellSystems 1ms 0.023% 0.69% 1893ms 43.60% KSPSolve_CG 53ms 1.22% 1.91% 1263ms 29.09% AbstractBackwardEulerCardiacCell::ComputeExceptVoltage 3ms 0.069% 1.98% 1174ms 27.04% PCApply 0 0% 1.98% 1163ms 26.78% PCApply_BJacobi_Singleblock 258ms 5.94% 7.92% 1126ms 25.93% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeOneStepExceptVoltage 0 0% 7.92% 984ms 22.66% KSPSolve_PREONLY (partial-inline) 1ms 0.023% 7.95% 947ms 21.81% KSP_PCApply.lto_priv.7 2ms 0.046% 7.99% 914ms 21.05% MatSolve 0 0% 7.99% 914ms 21.05% PCApply_ICC 894ms 20.59% 28.58% 902ms 20.77% MatSolve_SeqSBAIJ_1_NaturalOrdering (partial-inline) 16ms 0.37% 28.95% 580ms 13.36% AbstractCardiacTissue::UpdateCaches (inline) 8ms 0.18% 29.13% 567ms 13.06% MatMult 22ms 0.51% 29.64% 552ms 12.71% MatMult_SeqAIJ (partial-inline) 530ms 12.21% 41.85% 552ms 12.71% MatMult_SeqAIJ_Inode (inline) 161ms 3.71% 45.56% 520ms 11.98% CellLuoRudy1991FromCellMLBackwardEulerOpt::GetIIonic 50ms 1.15% 46.71% 478ms 11.01% CardiacNewtonSolver::Solve 2ms 0.046% 46.75% 325ms 7.49% KSPConvergedDefault 1ms 0.023% 46.78% 323ms 7.44% KSP_PCApply (partial-inline) 157ms 3.62% 50.39% 289ms 6.66% CellLuoRudy1991FromCellMLBackwardEulerOpt::ComputeResidual 48ms 1.11% 51.50% 278ms 6.40% BidomainSolver::SetupLinearSystem 37ms 0.85% 52.35% 276ms 6.36% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::IndexTable0 (inline) 27ms 0.62% 52.97% 238ms 5.48% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::Instance 227ms 5.23% 58.20% 227ms 5.23% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::_lookup_0_row (inline) 0 0% 58.20% 211ms 4.86% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables (inline) 31ms 0.71% 58.91% 211ms 4.86% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables 2ms 0.046% 58.96% 171ms 3.94% std::pow (inline) 22ms 0.51% 59.47% 168ms 3.87% __pow 1ms 0.023% 59.49% 163ms 3.75% MatNullSpaceRemove 148ms 3.41% 62.90% 152ms 3.50% __ieee754_log_fma 147ms 3.39% 66.28% 147ms 3.39% ddot_ 71ms 1.64% 67.92% 141ms 3.25% __GI___exp 19ms 0.44% 68.36% 141ms 3.25% __ieee754_pow_fma 0 0% 68.36% 122ms 2.81% boost::numeric::ublas::norm_inf (inline) 81ms 1.87% 70.22% 122ms 2.81% boost::numeric::ublas::vector_norm_inf::apply (inline) 0 0% 70.22% 122ms 2.81% boost::numeric::ublas::vector_scalar_unary::evaluate (inline) 0 0% 70.22% 122ms 2.81% boost::numeric::ublas::vector_scalar_unary::operator double (inline) 0 0% 70.22% 104ms 2.40% VecDuplicate 3ms 0.069% 70.29% 102ms 2.35% VecTDot 1ms 0.023% 70.31% 97ms 2.23% VecMDot 3ms 0.069% 70.38% 96ms 2.21% VecMXDot_Private 1ms 0.023% 70.41% 91ms 2.10% VecNorm 19ms 0.44% 70.84% 85ms 1.96% MPI_Wtime 0 0% 70.84% 83ms 1.91% VecTDot_Seq 0 0% 70.84% 83ms 1.91% VecXDot_Seq_Private.constprop.0 0 0% 70.84% 82ms 1.89% VecNorm_Seq 79ms 1.82% 72.66% 81ms 1.87% exp_inline (inline) 55ms 1.27% 73.93% 78ms 1.80% CellLuoRudy1991FromCellMLBackwardEulerOpt::VerifyStateVariables 67ms 1.54% 75.47% 70ms 1.61% VecMDot_Seq 1ms 0.023% 75.50% 65ms 1.50% VecMAXPY 2ms 0.046% 75.54% 65ms 1.50% __GI___clock_gettime 63ms 1.45% 76.99% 63ms 1.45% [[vdso]] 11ms 0.25% 77.25% 60ms 1.38% PetscLogEventBeginDefault 2ms 0.046% 77.29% 59ms 1.36% VecDestroy 10ms 0.23% 77.52% 57ms 1.31% PetscLogEventEndDefault 56ms 1.29% 78.81% 56ms 1.29% [libchaste_heart.so] 2ms 0.046% 78.86% 54ms 1.24% VecDuplicate_MPI 49ms 1.13% 79.99% 54ms 1.24% __ieee754_exp_fma 20ms 0.46% 80.45% 51ms 1.17% AbstractBidomainSolver::CheckCompatibilityCondition 0 0% 80.45% 51ms 1.17% AbstractBidomainSolver::FinaliseLinearSystem 0 0% 80.45% 50ms 1.15% VecDuplicate_Seq 8ms 0.18% 80.63% 43ms 0.99% PetscMallocA 39ms 0.9% 81.53% 43ms 0.99% VecMAXPY_Seq 0 0% 81.53% 41ms 0.94% boost::numeric::ublas::scalar_traits::norm_inf (inline) 0 0% 81.53% 41ms 0.94% boost::numeric::ublas::scalar_traits::type_abs (inline) 0 0% 81.53% 41ms 0.94% boost_numeric_ublas_abs (inline) 41ms 0.94% 82.47% 41ms 0.94% std::abs (inline) 1ms 0.023% 82.50% 40ms 0.92% VecCreate 38ms 0.88% 83.37% 38ms 0.88% log_inline (inline) 0 0% 83.37% 36ms 0.83% CxxTest::RealWorldDescription::setUp 0 0% 83.37% 36ms 0.83% PetscInitialize 0 0% 83.37% 36ms 0.83% PetscSetup::setUpWorld 0 0% 83.37% 36ms 0.83% PetscSetupUtils::CommonSetup 0 0% 83.37% 36ms 0.83% PetscSetupUtils::InitialisePetsc 0 0% 83.37% 35ms 0.81% PMPI_Init_thread 2ms 0.046% 83.42% 35ms 0.81% PetscMallocAlign 0 0% 83.42% 35ms 0.81% ompi_mpi_init 1ms 0.023% 83.44% 34ms 0.78% VecCreate_MPI_Private 0 0% 83.44% 31ms 0.71% VecNorm_MPI 0 0% 83.44% 31ms 0.71% VecNorm_MPI_Default.constprop.0 0 0% 83.44% 29ms 0.67% 7ms 0.16% 83.60% 29ms 0.67% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#23}::operator() (inline) 6ms 0.14% 83.74% 28ms 0.64% VecCopy 9ms 0.21% 83.95% 27ms 0.62% AbstractCardiacCell::SetVoltage 27ms 0.62% 84.57% 27ms 0.62% DistributedVector::Iterator::operator!= 2ms 0.046% 84.62% 27ms 0.62% VecSetType 25ms 0.58% 85.19% 25ms 0.58% [libm.so.6] 0 0% 85.19% 24ms 0.55% PetscMemcpy (inline) 0 0% 85.19% 23ms 0.53% AbstractFeVolumeIntegralAssembler::DoAssemble 23ms 0.53% 85.72% 23ms 0.53% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::CheckIndex0 (inline) 8ms 0.18% 85.91% 23ms 0.53% CellLuoRudy1991FromCellMLBackwardEulerOpt_LookupTables::RegenerateTables()::{lambda(double)#3}::operator() (inline) 0 0% 85.91% 23ms 0.53% orte_init 3ms 0.069% 85.97% 22ms 0.51% PetscHeaderCreate_Private 7ms 0.16% 86.14% 22ms 0.51% PetscHeaderDestroy_Private 1ms 0.023% 86.16% 22ms 0.51% VecDestroy_MPI