tim-asc-parm: $Id: @(#) XGraph v20040124 '/home/bertin/work/src/xgraph/tim-asc-parm.c'-[Jan 25 2004,01:09:07]-(
		ascanfc callback timing
	)[/usr/local/bin/gcc32.2 -fwritable-strings -mcpu=pentium3 -march=pentium3 -mmmx -msse -mfpmath=sse -fno-fast-math -malign-double -mpreferred-stack-boundary=4 -fno-float-store -fomit-frame-pointer -mieee-fp -fno-strict-aliasing  -O3 -fstrength-reduce  -fexpensive-optimizations -frerun-cse-after-loop -fno-schedule-insns -fno-schedule-insns2 -funroll-loops -fno-unroll-all-loops -finline-functions     -o /home/bertin/work/src/xgraph/tim-asc-parm tim-asc-parm.c ] $
Timing a requested wait of 0.0166667s using Elapsed_Since: user-time=0, system-time=0 real-time=0.02/0.018473
Timing a requested wait of 0.0166667s using lowlevel fftw routines: lowlevel timer: 0.0134743s; AVERAGE gain with real timer (0.018473s): lowlevel/real=0.7294049042959618312 (1.3709806365577192899); AVERAGE gain with ACTUAL time (0.0166667s): lowlevel/real=0.80845780782355813887 (1.2369229294625663229)
Timing a requested wait of 0.0333333s using Elapsed_Since: user-time=0, system-time=0 real-time=0.04/0.039851
Timing a requested wait of 0.0333333s using lowlevel fftw routines: lowlevel timer: 0.0399413s; AVERAGE gain with real timer (0.039851s): lowlevel/real=0.8658360370034686726 (1.1549530826423592966); AVERAGE gain with ACTUAL time (0.0333333s): lowlevel/real=1.0033491386140602764 (0.99666204067440922199)
Timing a requested wait of 0.05s using Elapsed_Since: user-time=0, system-time=0 real-time=0.05/0.049921
Timing a requested wait of 0.05s using lowlevel fftw routines: lowlevel timer: 0.0499512s; AVERAGE gain with real timer (0.049921s): lowlevel/real=0.9107589232363970666 (1.097985399304662657); AVERAGE gain with ACTUAL time (0.05s): lowlevel/real=1.0019073391703883225 (0.99809629184674142355)
Timing a requested wait of 0.05s using Elapsed_Since: user-time=0, system-time=0 real-time=0.05/0.050059
Timing a requested wait of 0.05s using lowlevel fftw routines: lowlevel timer: 0.0498146s; AVERAGE gain with real timer (0.050059s): lowlevel/real=0.9318487081226504154 (1.0731355758539931067); AVERAGE gain with ACTUAL time (0.05s): lowlevel/real=1.000503579901664386 (0.9994966735634130961)
Timing a requested wait of 0.1s using Elapsed_Since: user-time=0, system-time=0 real-time=0.1/0.099901
Timing a requested wait of 0.1s using lowlevel fftw routines: lowlevel timer: 0.0999478s; AVERAGE gain with real timer (0.099901s): lowlevel/real=0.94557274671960078738 (1.0575600909281905881); AVERAGE gain with ACTUAL time (0.1s): lowlevel/real=1.0002985513003925799 (0.99970153780588355552)
Timing a requested wait of 0.2s using Elapsed_Since: user-time=0, system-time=0 real-time=0.2/0.199936
Timing a requested wait of 0.2s using lowlevel fftw routines: lowlevel timer: 0.199928s; AVERAGE gain with real timer (0.199936s): lowlevel/real=0.95463754373840548073 (1.0475179889573094094); AVERAGE gain with ACTUAL time (0.2s): lowlevel/real=1.000189049607527636 (0.99981098612547114612)
Timing a requested wait of 0.5s using Elapsed_Since: user-time=0, system-time=0 real-time=0.5/0.499904
Timing a requested wait of 0.5s using lowlevel fftw routines: lowlevel timer: 0.499947s; AVERAGE gain with real timer (0.499904s): lowlevel/real=0.96113016932618366983 (1.0404417964541334829); AVERAGE gain with ACTUAL time (0.5s): lowlevel/real=1.0001468862858329256 (0.99985313528657937532)
Timing a requested wait of 1s using Elapsed_Since: user-time=0, system-time=0 real-time=1/0.999901
Timing a requested wait of 1s using lowlevel fftw routines: lowlevel timer: 0.928418s; AVERAGE gain with real timer (0.999901s): lowlevel/real=0.95705266056103777128 (1.0448745834046224701); AVERAGE gain with ACTUAL time (1s): lowlevel/real=0.99118079758825328174 (1.0088976727890670571)
Timing a requested wait of 1.45s using Elapsed_Since: user-time=0, system-time=0 real-time=1.45/1.44996
Timing a requested wait of 1.45s using lowlevel fftw routines: lowlevel timer: 1.41743s; AVERAGE gain with real timer (1.44996s): lowlevel/real=0.95933200820085295923 (1.0423919888542201306); AVERAGE gain with ACTUAL time (1.45s): lowlevel/real=0.9896649838108566577 (1.0104429441863718164)
Timing a requested wait of 5s using Elapsed_Since: user-time=0, system-time=0 real-time=5/4.99991
Timing a requested wait of 5s using lowlevel fftw routines: lowlevel timer: 4.43073s; AVERAGE gain with real timer (4.99991s): lowlevel/real=0.95201507980726174374 (1.0504035295349030132); AVERAGE gain with ACTUAL time (5s): lowlevel/real=0.97931312731685227213 (1.0211238592704523498)
Timing a requested wait of 10.2345s using Elapsed_Since: user-time=0, system-time=0 real-time=10.24/10.2399
Timing a requested wait of 10.2345s using lowlevel fftw routines: lowlevel timer: 8.97908s; AVERAGE gain with real timer (10.2399s): lowlevel/real=0.94518390612507796611 (1.0579951621263303796); AVERAGE gain with ACTUAL time (10.2345s): lowlevel/real=0.97004230299336891008 (1.0308828768747375637)
Timing a requested wait of 20s using Elapsed_Since: user-time=0, system-time=0 real-time=20/19.9998
Timing a requested wait of 20s using lowlevel fftw routines: lowlevel timer: 19.8655s; AVERAGE gain with real timer (19.9998s): lowlevel/real=0.94919239144791145435 (1.053527197446858743); AVERAGE gain with ACTUAL time (20s): lowlevel/real=0.97197827437418660157 (1.0288295802124336475)
Timing a requested wait of 0.1s using Elapsed_Since: user-time=0, system-time=0 real-time=0.1/0.09984
Timing a requested wait of 0.1s using lowlevel fftw routines: lowlevel timer: 0.0999486s; AVERAGE gain with real timer (0.09984s): lowlevel/real=0.95318431407626569296 (1.0491150402208448966); AVERAGE gain with ACTUAL time (0.1s): lowlevel/real=0.97409422602138862057 (1.0265947310707523865)
Timing a requested wait of 0.129562s using Elapsed_Since: user-time=0, system-time=0 real-time=0.13/0.129923
Timing a requested wait of 0.129562s using lowlevel fftw routines: lowlevel timer: 0.129925s; AVERAGE gain with real timer (0.129923s): lowlevel/real=0.95652942466166768209 (1.0454461454269514675); AVERAGE gain with ACTUAL time (0.129562s): lowlevel/real=0.97614488514903186012 (1.024438088252981105)
Timing a requested wait of 1.34893s using Elapsed_Since: user-time=0, system-time=0 real-time=1.35/1.34991
Timing a requested wait of 1.34893s using lowlevel fftw routines: lowlevel timer: 1.204s; AVERAGE gain with real timer (1.34991s): lowlevel/real=0.95222134925815338224 (1.0501759919361917106); AVERAGE gain with ACTUAL time (1.34893s): lowlevel/real=0.97057230846495001764 (1.0303199372971938441)
Timing 15000000 times times() routine:
	result= 6.07s
	user time: 5.56s (6.07sys): 9.26667e-08s/call, 2.69784e+06 calls/s
	real time: 11.6368s: 1.93947e-07s/call, 1.28901e+06 calls/s

Timing 15000000 times gettimeofday() routine:
	result= 1.07499e+09s
	user time: 5.24s (10.32sys): 8.73333e-08s/call, 2.8626e+06 calls/s
	real time: 15.5552s: 2.59254e-07s/call, 964306 calls/s

Timing 15000000 times lowlevel fftw_time routines:
	result= 3999.45s
	user time: 1.55s (0sys): 2.58333e-08s/call, 9.67742e+06 calls/s
	real time: 1.55117s: 2.58529e-08s/call, 9.67011e+06 calls/s

Timing 30000000 times original ascanf callback mechanism:
		lowlevel timer: 2.02556s; AVERAGE gain with real timer (2.02556s): lowlevel/real=0.95520764834344595862 (1.0468927899962217065)
	user time: 2.03s: 6.76667e-08s/call, 1.47783e+07 calls/s
	real time: 2.02556s: 6.75185e-08s/call, 1.48107e+07 calls/s
	returns 0, result=3e+07, level=0

Timing 30000000 times new ascanf callback mechanism, no use of new functionality:
		lowlevel timer: 2.07785s; AVERAGE gain with real timer (2.07785s): lowlevel/real=0.95784258161882740445 (1.0440128881198027511)
	user time: 2.07s: 6.9e-08s/call, 1.44928e+07 calls/s
	real time: 2.07785s: 6.92617e-08s/call, 1.4438e+07 calls/s
	returns 0, result=3e+07, level=0

Timing 30000000 times new ascanf callback mechanism, full use of new functionality:
		lowlevel timer: 2.11159s; AVERAGE gain with real timer (2.11159s): lowlevel/real=0.96018477501545951291 (1.0414662115256922981)
	user time: 2.11s: 7.03333e-08s/call, 1.4218e+07 calls/s
	real time: 2.11159s: 7.03862e-08s/call, 1.42073e+07 calls/s
	returns 1, result=3e+07, level=0

Timing 30000000 times alternate ascanf callback mechanism, no use of new functionality:
		lowlevel timer: 2.26092s; AVERAGE gain with real timer (2.26091s): lowlevel/real=0.96228043526520357087 (1.0391981000054322237)
	user time: 2.26s: 7.53333e-08s/call, 1.32743e+07 calls/s
	real time: 2.26091s: 7.53638e-08s/call, 1.3269e+07 calls/s
	returns 0, result=3e+07, level=0

Timing 30000000 times alternate ascanf callback mechanism, full use of new functionality:
		lowlevel timer: 2.26565s; AVERAGE gain with real timer (2.26564s): lowlevel/real=0.96416652640741951075 (1.0371652329873966014)
	user time: 2.27s: 7.56667e-08s/call, 1.32159e+07 calls/s
	real time: 2.26564s: 7.55215e-08s/call, 1.32413e+07 calls/s
	returns 1, result=3e+07, level=0

Timing 30000000 times alternate ascanf callback mechanism, full use of new functionality, no convenience vars in callbacks:
		lowlevel timer: 2.31554s; AVERAGE gain with real timer (2.31554s): lowlevel/real=0.96587301739182240823 (1.035332783910178911)
	user time: 2.31s: 7.7e-08s/call, 1.2987e+07 calls/s
	real time: 2.31554s: 7.71845e-08s/call, 1.2956e+07 calls/s
	returns 1, result=3e+07, level=0

Timing 30000000 times alternate2 ascanf callback mechanism, semi-full use of new functionality:
		lowlevel timer: 2.33466s; AVERAGE gain with real timer (2.33466s): lowlevel/real=0.96742434261840604126 (1.0336725632656973417)
	user time: 2.33s: 7.76667e-08s/call, 1.28755e+07 calls/s
	real time: 2.33466s: 7.7822e-08s/call, 1.28498e+07 calls/s
	returns 1, result=3e+07, level=0

Timing fmod(dist,div==1) for determining decimal part:
		lowlevel timer: 2.06909s; AVERAGE gain with real timer (2.06909s): lowlevel/real=0.96884076770305127546 (1.0321613554421555925)
	user time: 2.06s: 6.86667e-08s/call, 1.45631e+07 calls/s
	real time: 2.06909s: 6.89696e-08s/call, 1.44991e+07 calls/s
	returns result=0.983334

Timing ( dist - div*floor(dist/div) ) for determining decimal part:
		lowlevel timer: 4.797s; AVERAGE gain with real timer (4.79698s): lowlevel/real=0.97013922287916021236 (1.0307798885114853871)
	user time: 4.8s: 1.6e-07s/call, 6.25e+06 calls/s
	real time: 4.79698s: 1.59899e-07s/call, 6.25393e+06 calls/s
	returns result=0.983334

Timing testing for NaN(speed), False:
		lowlevel timer: 0.322891s; AVERAGE gain with real timer (0.322897s): lowlevel/real=0.97133292429929851686 (1.0295131308571481288)
	user time: 0.32s: 1.06667e-08s/call, 9.375e+07 calls/s
	real time: 0.322897s: 1.07632e-08s/call, 9.29089e+07 calls/s
	returns result=0

Timing testing for NaN(speed), True:
		lowlevel timer: 0.686487s; AVERAGE gain with real timer (0.686501s): lowlevel/real=0.97243474093946913328 (1.0283466415791562287)
	user time: 0.69s: 2.3e-08s/call, 4.34783e+07 calls/s
	real time: 0.686501s: 2.28834e-08s/call, 4.36999e+07 calls/s
	returns result=1

Timing testing for isNaN(speed), False:
		lowlevel timer: 0.323478s; AVERAGE gain with real timer (0.323484s): lowlevel/real=0.97345496311216506147 (1.0272688905945577265)
	user time: 0.33s: 1.1e-08s/call, 9.09091e+07 calls/s
	real time: 0.323484s: 1.07828e-08s/call, 9.27403e+07 calls/s
	returns result=0

Timing testing for isNaN(speed), True:
		lowlevel timer: 0.632166s; AVERAGE gain with real timer (0.632172s): lowlevel/real=0.97440267334872421312 (1.0262697623389165358)
	user time: 0.63s: 2.1e-08s/call, 4.7619e+07 calls/s
	real time: 0.632172s: 2.10724e-08s/call, 4.74554e+07 calls/s
	returns result=1

Timing testing for speed==-2, True:
		lowlevel timer: 0.0676158s; AVERAGE gain with real timer (0.067621s): lowlevel/real=0.9752826761379151943 (1.0253437536283989129)
	user time: 0.07s: 2.33333e-09s/call, 4.28571e+08 calls/s
	real time: 0.067621s: 2.25403e-09s/call, 4.43649e+08 calls/s
	returns result=1

Timing testing for integer==-2, True:
		lowlevel timer: 0.0481032s; AVERAGE gain with real timer (0.048106s): lowlevel/real=0.97610462592303903762 (1.0244803409822635931)
	user time: 0.05s: 1.66667e-09s/call, 6e+08 calls/s
	real time: 0.048106s: 1.60353e-09s/call, 6.23623e+08 calls/s
	returns result=1

Operations were performed on the arguments array; the result should equal the number of loops looped.
