tim-asc-parm: $Id: @(#) XGraph v20040124 '/home/bertin/work/src/xgraph/tim-asc-parm.c'-[Jan 25 2004,00:53:41]-(
		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 -DFFTW_CALIBRATE=1.0 ] $
Timing a requested wait of 0.0166667s using Elapsed_Since: user-time=0, system-time=0 real-time=0.02/0.018278
Timing a requested wait of 0.0166667s using lowlevel fftw routines: lowlevel timer: 8.09503e+06s; AVERAGE gain with real timer (0.018278s): lowlevel/real=442883958.85764306784 (2.2579277935000387346e-09); AVERAGE gain with ACTUAL time (0.0166667s): lowlevel/real=485701980 (2.0588756916329639849e-09)
Timing a requested wait of 0.0333333s using Elapsed_Since: user-time=0, system-time=0 real-time=0.04/0.039879
Timing a requested wait of 0.0333333s using lowlevel fftw routines: lowlevel timer: 1.79386e+07s; AVERAGE gain with real timer (0.039879s): lowlevel/real=446354452.15882980824 (2.2403719626037518102e-09); AVERAGE gain with ACTUAL time (0.0333333s): lowlevel/real=511929525 (1.9533938777998785821e-09)
Timing a requested wait of 0.05s using Elapsed_Since: user-time=0, system-time=0 real-time=0.05/0.049782
Timing a requested wait of 0.05s using lowlevel fftw routines: lowlevel timer: 2.23795e+07s; AVERAGE gain with real timer (0.049782s): lowlevel/real=447419413.13956671953 (2.2350393626931493313e-09); AVERAGE gain with ACTUAL time (0.05s): lowlevel/real=490482783.33333331347 (2.0388075463199234563e-09)
Timing a requested wait of 0.05s using Elapsed_Since: user-time=0, system-time=0 real-time=0.05/0.049899
Timing a requested wait of 0.05s using lowlevel fftw routines: lowlevel timer: 2.23836e+07s; AVERAGE gain with real timer (0.049899s): lowlevel/real=447709066.75862103701 (2.2335933628503940942e-09); AVERAGE gain with ACTUAL time (0.05s): lowlevel/real=479780062.5 (2.0842883607736411203e-09)
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: 4.47786e+07s; AVERAGE gain with real timer (0.099901s): lowlevel/real=447813136.8314871788 (2.2330742842327593598e-09); AVERAGE gain with ACTUAL time (0.1s): lowlevel/real=473381184 (2.1124625012556476935e-09)
Timing a requested wait of 0.2s using Elapsed_Since: user-time=0, system-time=0 real-time=0.2/0.199951
Timing a requested wait of 0.2s using lowlevel fftw routines: lowlevel timer: 8.95686e+07s; AVERAGE gain with real timer (0.199951s): lowlevel/real=447836420.43380922079 (2.2329581837746073933e-09); AVERAGE gain with ACTUAL time (0.2s): lowlevel/real=469124835 (2.1316287806421506014e-09)
Timing a requested wait of 0.5s using Elapsed_Since: user-time=0, system-time=0 real-time=0.5/0.499896
Timing a requested wait of 0.5s using lowlevel fftw routines: lowlevel timer: 2.23998e+08s; AVERAGE gain with real timer (0.499896s): lowlevel/real=447872557.88491916656 (2.2327780132868731397e-09); AVERAGE gain with ACTUAL time (0.5s): lowlevel/real=466106455.71428573132 (2.1454326318384672634e-09)
Timing a requested wait of 1s using Elapsed_Since: user-time=0, system-time=0 real-time=1/0.999888
Timing a requested wait of 1s using lowlevel fftw routines: lowlevel timer: 4.48029e+08s; AVERAGE gain with real timer (0.999888s): lowlevel/real=447898344.87825793028 (2.2326494648507966079e-09); AVERAGE gain with ACTUAL time (1s): lowlevel/real=463846732.375 (2.1558845416022965785e-09)
Timing a requested wait of 1.45s using Elapsed_Since: user-time=0, system-time=0 real-time=1.45/1.44989
Timing a requested wait of 1.45s using lowlevel fftw routines: lowlevel timer: 6.49655e+08s; AVERAGE gain with real timer (1.44989s): lowlevel/real=447917719.21873313189 (2.2325528932952674888e-09); AVERAGE gain with ACTUAL time (1.45s): lowlevel/real=462090183.79693484306 (2.1640797295954876631e-09)
Timing a requested wait of 5s using Elapsed_Since: user-time=0, system-time=0 real-time=5/4.99988
Timing a requested wait of 5s using lowlevel fftw routines: lowlevel timer: 2.00499e+09s; AVERAGE gain with real timer (4.99988s): lowlevel/real=443226811.81795418262 (2.2561811996399000816e-09); AVERAGE gain with ACTUAL time (5s): lowlevel/real=455981051.4772413969 (2.1930735866332622278e-09)
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: 4.38833e+09s; AVERAGE gain with real timer (10.2399s): lowlevel/real=441892843.33826595545 (2.2629920694020085711e-09); AVERAGE gain with ACTUAL time (10.2345s): lowlevel/real=453508044.68343061209 (2.2050325495285225304e-09)
Timing a requested wait of 20s using Elapsed_Since: user-time=0, system-time=0 real-time=20/19.9999
Timing a requested wait of 20s using lowlevel fftw routines: lowlevel timer: 8.0015e+09s; AVERAGE gain with real timer (19.9999s): lowlevel/real=438408241.43383783102 (2.2809790179341659413e-09); AVERAGE gain with ACTUAL time (20s): lowlevel/real=449055300.95981138945 (2.2268972170300599737e-09)
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: 4.48111e+07s; AVERAGE gain with real timer (0.09984s): lowlevel/real=439209825.64183646441 (2.2768160947644020851e-09); AVERAGE gain with ACTUAL time (0.1s): lowlevel/real=448982640.11634480953 (2.2272576056412118004e-09)
Timing a requested wait of 0.129562s using Elapsed_Since: user-time=0, system-time=0 real-time=0.13/0.129829
Timing a requested wait of 0.129562s using lowlevel fftw routines: lowlevel timer: 5.82248e+07s; AVERAGE gain with real timer (0.129829s): lowlevel/real=439871500.69503611326 (2.2733912027033144548e-09); AVERAGE gain with ACTUAL time (0.129562s): lowlevel/real=449012311.50218850374 (2.2271104252229974562e-09)
Timing a requested wait of 1.34893s using Elapsed_Since: user-time=0, system-time=0 real-time=1.35/1.35032
Timing a requested wait of 1.34893s using lowlevel fftw routines: lowlevel timer: 5.82859e+08s; AVERAGE gain with real timer (1.35032s): lowlevel/real=439323147.32299983501 (2.2762287989910498982e-09); AVERAGE gain with ACTUAL time (1.34893s): lowlevel/real=447884158.27332615852 (2.2327201833955893338e-09)
Timing 15000000 times times() routine:
	result= 6.27s
	user time: 5.39s (6.27sys): 8.98333e-08s/call, 2.78293e+06 calls/s
	real time: 11.6673s: 1.94455e-07s/call, 1.28565e+06 calls/s

Timing 15000000 times gettimeofday() routine:
	result= 1.07499e+09s
	user time: 5.45s (10.12sys): 9.08333e-08s/call, 2.75229e+06 calls/s
	real time: 15.5648s: 2.59414e-07s/call, 963710 calls/s

Timing 15000000 times lowlevel fftw_time routines:
	result= 1.26117e+12s
	user time: 1.23s (0sys): 2.05e-08s/call, 1.21951e+07 calls/s
	real time: 1.23004s: 2.05006e-08s/call, 1.21948e+07 calls/s

Timing 30000000 times original ascanf callback mechanism:
		lowlevel timer: 9.0689e+08s; AVERAGE gain with real timer (2.02405s): lowlevel/real=439869073.49493068457 (2.2734037472891909764e-09)
	user time: 2.02s: 6.73333e-08s/call, 1.48515e+07 calls/s
	real time: 2.02405s: 6.74682e-08s/call, 1.48218e+07 calls/s
	returns 0, result=3e+07, level=0

Timing 30000000 times new ascanf callback mechanism, no use of new functionality:
		lowlevel timer: 9.34546e+08s; AVERAGE gain with real timer (2.08577s): lowlevel/real=440350752.07713490725 (2.2709169798915959548e-09)
	user time: 2.09s: 6.96667e-08s/call, 1.43541e+07 calls/s
	real time: 2.08577s: 6.95257e-08s/call, 1.43832e+07 calls/s
	returns 0, result=3e+07, level=0

Timing 30000000 times new ascanf callback mechanism, full use of new functionality:
		lowlevel timer: 9.52268e+08s; AVERAGE gain with real timer (2.12532s): lowlevel/real=440778933.5437001586 (2.2687109657450471338e-09)
	user time: 2.13s: 7.1e-08s/call, 1.40845e+07 calls/s
	real time: 2.12532s: 7.08441e-08s/call, 1.41155e+07 calls/s
	returns 1, result=3e+07, level=0

Timing 30000000 times alternate ascanf callback mechanism, no use of new functionality:
		lowlevel timer: 1.02289e+09s; AVERAGE gain with real timer (2.28295s): lowlevel/real=441162045.16632664204 (2.2667407837022347381e-09)
	user time: 2.28s: 7.6e-08s/call, 1.31579e+07 calls/s
	real time: 2.28295s: 7.60982e-08s/call, 1.31409e+07 calls/s
	returns 0, result=3e+07, level=0

Timing 30000000 times alternate ascanf callback mechanism, full use of new functionality:
		lowlevel timer: 1.00822e+09s; AVERAGE gain with real timer (2.25021s): lowlevel/real=441506828.65833073854 (2.2649706303271491059e-09)
	user time: 2.25s: 7.5e-08s/call, 1.33333e+07 calls/s
	real time: 2.25021s: 7.50071e-08s/call, 1.33321e+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: 1.03623e+09s; AVERAGE gain with real timer (2.31271s): lowlevel/real=441818800.70579749346 (2.2633713151240241288e-09)
	user time: 2.32s: 7.73333e-08s/call, 1.2931e+07 calls/s
	real time: 2.31271s: 7.70903e-08s/call, 1.29718e+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: 1.04743e+09s; AVERAGE gain with real timer (2.33771s): lowlevel/real=442102387.41274130344 (2.2619194749256404126e-09)
	user time: 2.34s: 7.8e-08s/call, 1.28205e+07 calls/s
	real time: 2.33771s: 7.79236e-08s/call, 1.28331e+07 calls/s
	returns 1, result=3e+07, level=0

Timing fmod(dist,div==1) for determining decimal part:
		lowlevel timer: 9.2679e+08s; AVERAGE gain with real timer (2.06846s): lowlevel/real=442361328.61180764437 (2.2605954348182769629e-09)
	user time: 2.07s: 6.9e-08s/call, 1.44928e+07 calls/s
	real time: 2.06846s: 6.89486e-08s/call, 1.45036e+07 calls/s
	returns result=0.983334

Timing ( dist - div*floor(dist/div) ) for determining decimal part:
		lowlevel timer: 2.14999e+09s; AVERAGE gain with real timer (4.79845s): lowlevel/real=442598715.93463492393 (2.2593829670027436246e-09)
	user time: 4.8s: 1.6e-07s/call, 6.25e+06 calls/s
	real time: 4.79845s: 1.59948e-07s/call, 6.25202e+06 calls/s
	returns result=0.983334

Timing testing for NaN(speed), False:
		lowlevel timer: 1.33337e+08s; AVERAGE gain with real timer (0.297593s): lowlevel/real=442816817.88311684132 (2.2582701460628667274e-09)
	user time: 0.3s: 1e-08s/call, 1e+08 calls/s
	real time: 0.297593s: 9.91977e-09s/call, 1.00809e+08 calls/s
	returns result=0

Timing testing for NaN(speed), True:
		lowlevel timer: 2.89526e+08s; AVERAGE gain with real timer (0.646187s): lowlevel/real=443018236.35215502977 (2.2572434223793447977e-09)
	user time: 0.64s: 2.13333e-08s/call, 4.6875e+07 calls/s
	real time: 0.646187s: 2.15396e-08s/call, 4.64262e+07 calls/s
	returns result=1

Timing testing for isNaN(speed), False:
		lowlevel timer: 1.44571e+08s; AVERAGE gain with real timer (0.322665s): lowlevel/real=443204675.76413911581 (2.2562938856091210268e-09)
	user time: 0.33s: 1.1e-08s/call, 9.09091e+07 calls/s
	real time: 0.322665s: 1.07555e-08s/call, 9.29757e+07 calls/s
	returns result=0

Timing testing for isNaN(speed), True:
		lowlevel timer: 2.95683e+08s; AVERAGE gain with real timer (0.659925s): lowlevel/real=443377902.61060708761 (2.2554123561684166205e-09)
	user time: 0.66s: 2.2e-08s/call, 4.54545e+07 calls/s
	real time: 0.659925s: 2.19975e-08s/call, 4.54597e+07 calls/s
	returns result=1

Timing testing for speed==-2, True:
		lowlevel timer: 3.01035e+07s; AVERAGE gain with real timer (0.067189s): lowlevel/real=443538754.11570048332 (2.2545944198128454335e-09)
	user time: 0.07s: 2.33333e-09s/call, 4.28571e+08 calls/s
	real time: 0.067189s: 2.23963e-09s/call, 4.46502e+08 calls/s
	returns result=1

Timing testing for integer==-2, True:
		lowlevel timer: 2.12838e+07s; AVERAGE gain with real timer (0.047507s): lowlevel/real=443687902.94866222143 (2.2538365219204701414e-09)
	user time: 0.05s: 1.66667e-09s/call, 6e+08 calls/s
	real time: 0.047507s: 1.58357e-09s/call, 6.31486e+08 calls/s
	returns result=1

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