Detailed Description

Method execution time micro test.

Date: Nov 30, 2012

Author: Andrey Belomutskiy, (c) 2012-2020

Functions
static void	testSystemCalls (const int count)

static void	testRusefiMethods (const int count)

static void	testMath (const int count)

static void	runTests (const int count)

static void	timeInfo ()

static void	runChibioTest ()

void	initTimePerfActions ()

Variables
static Engine	testEngine

Overflow64Counter	halTime

static int	rtcStartTime

Function Documentation

◆ initTimePerfActions()

void initTimePerfActions ( )

Definition at line 293 of file rfi_perftest.cpp.

                           {
#if EFI_RTC
    rtcStartTime = rtcGetTimeUnixSec(&RTCD1);
#endif
 
    addConsoleActionI("perftest", runTests);
 
    addConsoleAction("timeinfo", timeInfo);
    addConsoleAction("chtest", runChibioTest);
}

Referenced by initEfiWithConfig().

Here is the call graph for this function:

Here is the caller graph for this function:

◆ runChibioTest()

static void runChibioTest ( )

static

Definition at line 275 of file rfi_perftest.cpp.

                            {
    print("EFI_SHAFT_POSITION_INPUT=%d\r\n", EFI_SHAFT_POSITION_INPUT);
    print("EFI_EMULATE_POSITION_SENSORS=%d\r\n", EFI_EMULATE_POSITION_SENSORS);
    print("EFI_ANALOG_SENSORS=%d\r\n", EFI_ANALOG_SENSORS);
    print("EFI_INTERNAL_ADC=%d\r\n", EFI_INTERNAL_ADC);
    print("EFI_MAP_AVERAGING=%d\r\n", EFI_MAP_AVERAGING);
    print("EFI_LOGIC_ANALYZER=%d\r\n", EFI_LOGIC_ANALYZER);
    print("EFI_ENGINE_SNIFFER=%d\r\n", EFI_ENGINE_SNIFFER);
    print("EFI_SHAFT_POSITION_INPUT=%d\r\n", EFI_SHAFT_POSITION_INPUT);
    print("EFI_ENGINE_CONTROL=%d\r\n", EFI_ENGINE_CONTROL);
    print("CH_DBG_SYSTEM_STATE_CHECK=%d\r\n", CH_DBG_SYSTEM_STATE_CHECK);
    print("CH_DBG_ENABLE_CHECKS=%d\r\n", CH_DBG_ENABLE_CHECKS);
    print("CH_DBG_ENABLE_ASSERTS=%d\r\n", CH_DBG_ENABLE_ASSERTS);
    print("CH_DBG_ENABLE_STACK_CHECK=%d\r\n", CH_DBG_ENABLE_STACK_CHECK);
    print("CH_DBG_THREADS_PROFILING=%d\r\n", CH_DBG_THREADS_PROFILING);
    TestThread(getConsoleChannel());
}

Referenced by initTimePerfActions().

Here is the call graph for this function:

Here is the caller graph for this function:

◆ runTests()

static void runTests ( const int count )

static

Definition at line 250 of file rfi_perftest.cpp.

                                      {
    efiPrintf("Running tests: %d", count);
    testRusefiMethods(count / 10);
    testSystemCalls(count);
    testMath(count);
}

Referenced by initTimePerfActions().

Here is the call graph for this function:

Here is the caller graph for this function:

◆ testMath()

static void testMath ( const int count )

static

Definition at line 96 of file rfi_perftest.cpp.

                                      {
    time_t start, time;
 
    int64_t temp64 = 0;
    start = getTimeNowMs();
    for (int64_t i = 0; i < count; i++) {
        temp64 += i;
    }
    time = getTimeNowMs() - start;
    if (temp64 != 0) {
        efiPrintf("Finished %d iterations of int64_t summation in %dms", count, time);
    }
 
    temp64 = 1;
    start = getTimeNowMs();
    for (int64_t i = 0; i < count; i++) {
        temp64 *= i;
    }
    time = getTimeNowMs() - start;
    if (temp64 == 0) {
        efiPrintf("Finished %d iterations of int64_t multiplication in %dms", count, time);
    }
 
    start = getTimeNowMs();
    for (int i = 0; i < count; i++)
        ;
    time = getTimeNowMs() - start;
    efiPrintf("Finished %d iterations of empty loop in %dms", count, time);
 
    uint32_t tempi = 1;
    start = getTimeNowMs();
    for (int i = 0; i < count; i++) {
        tempi += tempi;
    }
    time = getTimeNowMs() - start;
    if (tempi == 0) {
        // 11ms is 1848000 ticks
        // 18.48 ticks per iteration
        // Finished 100000 iterations of uint32_t summation in 11ms
        efiPrintf("Finished %d iterations of uint32_t summation in %dms", count, time);
    }
 
    start = getTimeNowMs();
    tempi = 1;
    for (int i = 0; i < count; i++) {
        tempi += (tempi + 100) / 130;
    }
    time = getTimeNowMs() - start;
    if (tempi != 0) {
        // Finished 100000 iterations of uint32_t division in 16ms
        efiPrintf("Finished %d iterations of uint32_t division in %dms", count, time);
    }
 
    start = getTimeNowMs();
    temp64 = 1;
    for (int i = 0; i < count; i++) {
        temp64 += temp64;
    }
    time = getTimeNowMs() - start;
    if (temp64 == 0) {
        //  Finished 100000 iterations of int64_t summation in 21ms
        efiPrintf("Finished %d iterations of int64_t summation in %dms", count, time);
    }
 
    start = getTimeNowMs();
    temp64 = 1;
    for (int i = 0; i < count; i++) {
        temp64 += (temp64 + 100) / 130;
    }
    time = getTimeNowMs() - start;
    if (temp64 != 0) {
        // Finished 100000 iterations of int64_t division in 181ms
        efiPrintf("Finished %d iterations of int64_t division in %dms", count, time);
    }
 
    start = getTimeNowMs();
    float tempf = 1;
    for (int i = 0; i < count; i++) {
        tempf += tempf;
    }
    time = getTimeNowMs() - start;
    if (tempf != 0) {
        efiPrintf("Finished %d iterations of float summation in %dms", count, time);
    }
 
    start = getTimeNowMs();
    tempf = 1;
    for (int i = 0; i < count; i++) {
        tempf += tempf * 130.0f;
    }
    time = getTimeNowMs() - start;
    if (tempf != 0) {
        //  ms =  ticks
        //  ticks per iteration
        // Finished 100000 iterations of float division in ms
        efiPrintf("Finished %d iterations of float multiplication in %dms", count, time);
    }
 
    start = getTimeNowMs();
    tempf = 1;
    for (int i = 0; i < count; i++) {
        tempf += (tempf + 100) / 130.0;
    }
    time = getTimeNowMs() - start;
    if (tempf != 0) {
        // 65 ms = 10920000 ticks
        // 109.2 ticks per iteration
        // Finished 100000 iterations of float division in 65ms
        efiPrintf("Finished %d iterations of float division in %dms", count, time);
    }
 
    start = getTimeNowMs();
    tempf = 1;
    for (int i = 0; i < count; i++) {
        tempf += logf(tempf);
    }
    time = getTimeNowMs() - start;
    if (tempf != 0) {
        // Finished 100000 iterations of float log in 191ms
        efiPrintf("Finished %d iterations of float log in %dms", count, time);
    }
 
    start = getTimeNowMs();
    double tempd = 1;
    for (int i = 0; i < count; i++)
        tempd += tempd / 2;
    time = getTimeNowMs() - start;
    if (tempd != 0) {
        // Finished 100000 iterations of double summation in 80ms
        efiPrintf("Finished %d iterations of double summation in %dms", count, time);
    }
 
    start = getTimeNowMs();
    tempd = 1;
    for (int i = 0; i < count; i++)
        tempd += (tempd + 100) / 130.0;
    time = getTimeNowMs() - start;
    if (tempd != 0) {
        // Finished 100000 iterations of double division in 497ms
        efiPrintf("Finished %d iterations of double division in %dms", count, time);
    }
 
    start = getTimeNowMs();
    tempd = 1;
    for (int i = 0; i < count; i++) {
        tempd += log(tempd);
    }
    time = getTimeNowMs() - start;
    if (tempd != 0) {
        // Finished 100000 iterations of double log in 242ms
        efiPrintf("Finished %d iterations of double log in %dms", count, time);
    }
}

Referenced by runTests().

Here is the call graph for this function:

Here is the caller graph for this function:

◆ testRusefiMethods()

static void testRusefiMethods ( const int count )

static

Definition at line 69 of file rfi_perftest.cpp.

                                               {
    time_t start, time;
    int tempi = 1;
 
    start = getTimeNowMs();
 
    time = getTimeNowMs() - start;
    if (tempi != 0)
        efiPrintf("Finished %d iterations of getBaseFuel in %dms", count, time);
 
//  start = getTimeNowMs();
//  for (int i = 0; i < count; i++)
//      tempi += getInjectionDuration(1200, NULL); // todo
//  time = getTimeNowMs() - start;
//  if (tempi != 0)
//      efiPrintf("Finished %d iterations of getFuelMs in %dms", count, time);
 
    start = getTimeNowMs();
    for (int i = 0; i < count; i++) {
        testEngine.updateSlowSensors();
        tempi += testEngine.engineState.clt;
    }
    time = getTimeNowMs() - start;
    if (tempi != 0)
        efiPrintf("Finished %d iterations of updateSlowSensors in %dms", count, time);
}

Referenced by runTests().

Here is the call graph for this function:

Here is the caller graph for this function:

◆ testSystemCalls()

static void testSystemCalls ( const int count )

static

Definition at line 22 of file rfi_perftest.cpp.

                                             {
    time_t start, time;
    long result = 0;
 
    start = getTimeNowMs();
    for (int i = 0; i < count / 2; i++) {
//      setPinValue(&testOutput, 0);
//      setPinValue(&testOutput, 1);
    }
 
    time = getTimeNowMs() - start;
    // Finished 100000 iterations of 'setPinValue()' in 120ms
//  prin("Finished %d iterations of 'setPinValue()' in %dms\r\n", count, time);
 
    start = getTimeNowMs();
    for (int i = 0; i < count; i++)
        result += chTimeNow();
    time = getTimeNowMs() - start;
    if (result != 0) {
        // Finished 100000 iterations of 'chTimeNow()' in 33ms
        efiPrintf("Finished %d iterations of 'chTimeNow()' in %dms", count, time);
    }
 
    start = getTimeNowMs();
    for (int i = 0; i < count; i++) {
        chSysLock()
        ;
        result += chTimeNow();
        chSysUnlock()
        ;
    }
    time = getTimeNowMs() - start;
    if (result != 0) {
        // Finished 100000 iterations of 'chTimeNow()' with chSysLock in 144ms
        efiPrintf("Finished %d iterations of 'chTimeNow()' with chSysLock in %dms", count, time);
    }
 
    start = getTimeNowMs();
    for (int i = 0; i < count; i++)
        result += getTimeNowMs();
    time = getTimeNowMs() - start;
    if (result != 0)
        efiPrintf("Finished %d iterations of 'getTimeNowMs' in %dms", count, time);
}

Referenced by runTests().

Here is the call graph for this function:

Here is the caller graph for this function:

◆ timeInfo()

static void timeInfo ( )

static

Definition at line 265 of file rfi_perftest.cpp.

                       {
    efiPrintf("chTimeNow as seconds = %d", getTimeNowS());
    efiPrintf("hal seconds = %d", halTime.get() / (long)STM32_SYSCLK);
 
#if EFI_RTC
    int unix = rtcGetTimeUnixSec(&RTCD1) - rtcStartTime;
    efiPrintf("unix seconds = %d", unix);
#endif
}