Замер времени работы программы

[JuniorProger]

Всем привет!
Я написал программу с использованием MPI (Message Passing Interface).
Мне нужно замерить время работы всей программы и отдельных ее частей. Для замера времени использовал функцию MPI_Wtime(). Программа выдавала не такие результаты, какие мне были нужны. Тогда я стал разбираться в чем же дело, писать различные тесты и т.д. И вот один из тестов показал мне странный результат: я замерил время работы ДВУХ операций сложения чисел типа double и время работы ЧЕТЫРЕХ операций сложения чисел типа double. В результате получилось, что время работы четырех сложений почти такое же как и для двух сложений. Если подумать, то время работы двух сложений должно быть почти в два раза меньше, чем четырех. Может я что-то не учитываю или неправильно понимаю. Очень нужна помощь, т.к. сроки поджимают! У кого есть какие либо предположения или может кто-то знает в чем дело - поделитесь знаниями пожалуйста. Код описанного теста ниже (он простой и его не так много, как кажется). Результат двух операций сложения выводится как "Oper Time 1", четырех операций - "Oper Time 2".

#include <mpi.h>
#include <iostream>
#include <stdio.h>
#include <windows.h>

using namespace std;


//-----------------------------------------------------------------------------------------

int main(int argc, char* argv[])
{
	double N = 100000000;
	double StartOper1, EndOper1, StartOper2, EndOper2;

	int namelen, numprocs, rank;
	char processor_name[MPI_MAX_PROCESSOR_NAME];

	MPI_Init(&argc,&argv);
	
    MPI_Comm_size(MPI_COMM_WORLD,&numprocs);
    MPI_Comm_rank(MPI_COMM_WORLD,&rank);
    MPI_Get_processor_name(processor_name,&namelen);	

	cout<< "Process "<<rank<<" of "<<numprocs<<" is on "<<processor_name<<endl;	
	
	double Time1, Time2;
	double Time3, Time4;
	double TimeAVG = 0;
	double TimeAVG1 = 0;

//----------------------------------------------------------------------------------------
	double i;

	double x = 0;	
	double y = 0;
	double z = 0;

	StartOper1 = MPI_Wtime();
	for (i = 0; i<N; i++){
		Time3 = MPI_Wtime();
		x = 1;
		y = 4;	
		Time1 = MPI_Wtime();
		z = x + y;
		x = z + y;
		Time2 = MPI_Wtime();
		
		Time4 = MPI_Wtime();

		TimeAVG = TimeAVG + Time2 - Time1;
		TimeAVG1 = TimeAVG1 + Time4 - Time3;

	};
	EndOper1 = MPI_Wtime();

	cout<<endl;
	cout<< "All Time 1 = "<<(EndOper1 - StartOper1) <<endl;
	cout<< "Oper Time 1 = "<<TimeAVG <<endl;
	cout<< "Circle Time 1 = "<<TimeAVG1 <<endl;

//----------------------------------------------------------------------------------------

	StartOper2 = 0; 
	EndOper1 = 0;
	TimeAVG = 0;
	TimeAVG1 = 0;

	StartOper2 = MPI_Wtime();
	for (i = 0; i<N; i++){
		Time3 = MPI_Wtime();
		x = 5;
		y = 7;
		Time1 = MPI_Wtime();
		z = x + y;
		x = z + y;
		y = z + x;
		x = y + y;
		Time2 = MPI_Wtime();
		
		Time4 = MPI_Wtime();

		TimeAVG = TimeAVG + Time2 - Time1;
		TimeAVG1 = TimeAVG1 + Time4 - Time3;

	};
	EndOper2 = MPI_Wtime();

	cout<<endl;
	cout<< "All Time 2 = "<<(EndOper2 - StartOper2) <<endl;
	cout<< "Oper Time 2 = "<<TimeAVG <<endl;
	cout<< "Circle Time 2 = "<<TimeAVG1 <<endl;

	MPI_Finalize();
	
	return 0;
};

[onewho]

эти сложения выполняются за такое ничтожное время что там и мерять то нечего


можешь также попробывать убрать все оптимизации компилятора.

[JuniorProger]

эти сложения выполняются за такое ничтожное время что там и мерять то нечего

Но я ведь в цикле гоняю, поэтому за 100000000 шагов цикла должна накопиться приличная разница

можешь также попробывать убрать все оптимизации компилятора.

Пользуюсь Visual Studio. Там в свойствах проекта указано, что оптимизация выключена.

[onewho]

Но я ведь в цикле гоняю, поэтому за 100000000 шагов цикла должна накопиться приличная разница

ну ок, а точно ВСЕ оптимизации выключены?

[JuniorProger]

Ну я смотрел так: Проект -> Свойства -> С/С++ -> Оптимизация
Там в верхней строке написано, что отключено. Да и в других строках тоже самое. Я сам сначала думал на оптимизацию, стал в интернете искать как отключить. Нашел где посмотреть (описано в первой строке). Там посмотрел - оказалось что выключено. Пробовал писать #pragma optimize("", off) - результат не изменился

[JuniorProger]

Что же, никто не знает совсем? Буду рад любым догадкам.

[Ivan_32]

Нормальный способ измерять время это присваивание процессу максимального приоритета и измерять нужно не время как таковое, а количество тактов затраченных на вычисления. Для этого есть команда rdtsc, к слову вам вообще в раздел ассемблера, помнится Goodwin98 создавал тему(в разделе ассемблера), где выкладывал полигон для тестов, правда, он на ассемблере, но суть всех действий, думаю, будет вполне понятна.

Мне почему-то кажется, что даже если этот самый MPI делает тоже самое, он непременно накладывает определенные задержки, которые совсем не линейны. Хотя по правде говоря, смысл таких точных измерений есть только в случае с очень короткими отрезками кода.

[JuniorProger]

Сообщение от Ivan_32

Нормальный способ измерять время это присваивание процессу максимального приоритета и измерять нужно не время как таковое, а количество тактов затраченных на вычисления. Для этого есть команда rdtsc

Спасибо за ответ!
Я запускал этот тест на кластере. На узле, где он запускался, не было других процессов, поэтому думаю, что дело не в приоритете. Мне нужно измерить именно время, а не количество тиков, т.к. нужно учесть время пересылки между процессами (в моей программе несколько взаимодействующих между собой процессов). Вообще программа была написана для тестирования на кластере, но писал я ее дома и никаких проблем не было, пока не стал тестить на кластере.

[JuniorProger]

Всем спасибо! Закрывайте.

[ElectroMent]

Если аппаратно поддерживается счетчик производительности, попробуй связку функций: QueryPerformanceCounter и QueryPerformanceFrequency

псевдо код:
QueryPerformanceCounter(Ctr1)

//код время выполнения, которого хотим измерить

QueryPerformanceCounter(Ctr2)

QueryPerformanceFrequency(Freq)

time = ( Ctr2 - Ctr1 ) / Freq