public static void PerformanceTestRL()
{
// create a lox for release logging
Lox lox= new Lox( "ReleaseLox" );
TextLogger relLogger= Lox.CreateConsoleLogger();
MemoryLogger ml = new MemoryLogger();
lox.SetVerbosity( relLogger, Verbosity.Verbose, "/CON" );
lox.SetVerbosity( ml , Verbosity.Verbose, "/MEM" );
lox.Info( "/CON", "Logging simple info lines into a memory logger" );
AString msgBuf= new AString( );
long fastest= long.MaxValue;
Ticks timer= new Ticks();
int qtyLines= 100;
int qtyLoops= 1000;
if( System.Diagnostics.Debugger.IsAttached )
qtyLines= qtyLoops= 10;
for ( int i= 0 ; i < qtyLoops ; i++ )
{
#if ALOX_DBG_LOG || ALOX_REL_LOG
ml.MemoryLog.Clear();
#endif
timer.Set();
for ( int ii= 0 ; ii < qtyLines ; ii++ )
{
lox.Info( "/MEM", "Test Line" );
if( i== 0 && ii == 0) Console.WriteLine( ml.MemoryLog.ToString() );
}
long t= timer.Age().Raw();
if ( fastest > t )
{
fastest= t;
lox.Info( "/CON", msgBuf.Clear()._( "Pass " )._( i, 3)._( " is new fastest: ")
._( (int) (new Ticks( fastest)).InMicros(), 0)
._( " micros per ")._(qtyLines)._(" logs.") );
}
}
double microsPerLog= ( (double) (new Ticks(fastest)).InMicros() ) / qtyLines;
int logsPerSecond= (int)( 1000000.0 / microsPerLog);
lox.Info( "/CON", msgBuf._()._( " " )._( ESC.MAGENTA )._( "Fastest Release Logging: " )
._( microsPerLog )._( " micros per log (resp " )
._( logsPerSecond )._( " logs per second) " ) );
lox.RemoveLogger( ml );
lox.RemoveLogger( relLogger );
}
public void SpeedTest()
{
UT_INIT();
Log.SetVerbosity( new ConsoleLogger(), Verbosity.Verbose, "/" );
Log.MapThreadName( "UnitTest" );
Log.SetDomain( "TickWatch", Scope.Method );
Log.Info( "\n### TicksSpeedTest ###" );
for (int runs= 0; runs < 5; runs ++ )
{
int aLotOf= 100;
Ticks t= new Ticks();
Ticks tkMeasure= new Ticks();
for (int i= 0; i < aLotOf; i++ )
t.Set();
long nanos= tkMeasure.Age().InNanos();
long averageNanos= nanos/ aLotOf ;
Log.Info( "Doing " + aLotOf + " Ticks.Set() calls took " + nanos + " ns. This is an average of " + averageNanos + " nanoseconds per call" );
UT_TRUE ( averageNanos < 20000 ); // this is much slower on Windows than on mono/linux.
}
}
public void ThreadLock_SpeedTest()
{
UT_INIT();
Log.SetVerbosity( new ConsoleLogger(), Verbosity.Verbose, "/" );
Log.MapThreadName( "UnitTest" );
Log.SetDomain( "TestTLock", Scope.Method );
ThreadLock aLock= new ThreadLock();
int repeats= 100000;
int rrepeats= 5;
Ticks stopwatch= new Ticks();
for ( int r= 0; r < rrepeats; r ++)
{
Log.Info("Run " + rrepeats );
aLock.SetSafeness( Safeness.Unsafe );
stopwatch.Set();
for ( int i= 0; i < repeats; i++ )
{
aLock.Acquire();
aLock.Release();
}
long time= stopwatch.Age().InMillis();
Log.Info("Safe mode, " + repeats + " lock/unlock ops: " + time + " ms" );
aLock.SetSafeness( Safeness.Safe );
stopwatch.Set();
for ( int i= 0; i < repeats; i++ )
{
//aLock.acquire();
//aLock.release();
// in java, adding the following two loops, results in similar execution speed
for ( int tt= 0; tt < 70; tt++ )
i+= tt;
for ( int tt= 0; tt < 70; tt++ )
i-= tt;
}
time= stopwatch.Age().InMillis();
Log.Info("Unsafe mode, " + repeats + " lock/unlock ops: " + time + " ms" );
}
}
