tim-asc-parm: $Id: @(#) XGraph v20040124 '/home/bertin/work/src/xgraph/tim-asc-parm.c'-[Jan 25 2004,01:19:53]-(
		ascanfc callback timing
	)[gcc295.3 -fwritable-strings -mcpu=pentiumpro -march=pentiumpro -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.017689
Timing a requested wait of 0.0166667s using lowlevel fftw routines: lowlevel timer: 0.0132364s; AVERAGE gain with real timer (0.017689s): lowlevel/real=0.74828567337036211438 (1.3363880073981484475); AVERAGE gain with ACTUAL time (0.0166667s): lowlevel/real=0.79418551657490010776 (1.2591516454653066681)
Timing a requested wait of 0.0333333s using Elapsed_Since: user-time=0, system-time=0 real-time=0.04/0.039845
Timing a requested wait of 0.0333333s using lowlevel fftw routines: lowlevel timer: 0.0399484s; AVERAGE gain with real timer (0.039845s): lowlevel/real=0.87544030134419892342 (1.1422823446265213398); AVERAGE gain with ACTUAL time (0.0333333s): lowlevel/real=0.99631868266760714725 (1.0036949195036033444)
Timing a requested wait of 0.05s using Elapsed_Since: user-time=0, system-time=0 real-time=0.05/0.049915
Timing a requested wait of 0.05s using lowlevel fftw routines: lowlevel timer: 0.0499373s; AVERAGE gain with real timer (0.049915s): lowlevel/real=0.91710942140632067954 (1.0903824305572749065); AVERAGE gain with ACTUAL time (0.05s): lowlevel/real=0.99712808861372548286 (1.0028801830166746978)
Timing a requested wait of 0.05s using Elapsed_Since: user-time=0, system-time=0 real-time=0.05/0.049912
Timing a requested wait of 0.05s using lowlevel fftw routines: lowlevel timer: 0.0499522s; AVERAGE gain with real timer (0.049912s): lowlevel/real=0.93803326532578323871 (1.0660602741553044659); AVERAGE gain with ACTUAL time (0.05s): lowlevel/real=0.99760691162061976289 (1.0023988289891583303)
Timing a requested wait of 0.1s using Elapsed_Since: user-time=0, system-time=0 real-time=0.1/0.099923
Timing a requested wait of 0.1s using lowlevel fftw routines: lowlevel timer: 0.0999389s; AVERAGE gain with real timer (0.099923s): lowlevel/real=0.95045840229110079012 (1.0521238989412666776); AVERAGE gain with ACTUAL time (0.1s): lowlevel/real=0.99796329484864654269 (1.0020408617850642408)
Timing a requested wait of 0.2s using Elapsed_Since: user-time=0, system-time=0 real-time=0.2/0.199923
Timing a requested wait of 0.2s using lowlevel fftw routines: lowlevel timer: 0.199932s; AVERAGE gain with real timer (0.199923s): lowlevel/real=0.95872325281400117003 (1.0430538709318306623); AVERAGE gain with ACTUAL time (0.2s): lowlevel/real=0.99824649356369088427 (1.0017565866222570836)
Timing a requested wait of 0.5s using Elapsed_Since: user-time=0, system-time=0 real-time=0.5/0.499906
Timing a requested wait of 0.5s using lowlevel fftw routines: lowlevel timer: 0.49995s; AVERAGE gain with real timer (0.499906s): lowlevel/real=0.96463243962522959851 (1.0366642867499988867); AVERAGE gain with ACTUAL time (0.5s): lowlevel/real=0.99848264363048200298 (1.001519662238695485)
Timing a requested wait of 1s using Elapsed_Since: user-time=0, system-time=0 real-time=1/0.99991
Timing a requested wait of 1s using lowlevel fftw routines: lowlevel timer: 0.877718s; AVERAGE gain with real timer (0.99991s): lowlevel/real=0.95377806766353867562 (1.0484619366952845354); AVERAGE gain with ACTUAL time (1s): lowlevel/real=0.98338712094666524965 (1.0168935292108993718)
Timing a requested wait of 1.45s using Elapsed_Since: user-time=0, system-time=0 real-time=1.45/1.4499
Timing a requested wait of 1.45s using lowlevel fftw routines: lowlevel timer: 1.32725s; AVERAGE gain with real timer (1.4499s): lowlevel/real=0.94951451864763924959 (1.0531697834639384137); AVERAGE gain with ACTUAL time (1.45s): lowlevel/real=0.97582680280732270184 (1.0247720160208084739)
Timing a requested wait of 5s using Elapsed_Since: user-time=0, system-time=0 real-time=5/4.9999
Timing a requested wait of 5s using lowlevel fftw routines: lowlevel timer: 4.40453s; AVERAGE gain with real timer (4.9999s): lowlevel/real=0.94265547730264209747 (1.0608329597377890163); AVERAGE gain with ACTUAL time (5s): lowlevel/real=0.96633480643511093788 (1.0348380223300479575)
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: 9.92113s; AVERAGE gain with real timer (10.2399s): lowlevel/real=0.94503870377081100607 (1.0581577199006635404); AVERAGE gain with ACTUAL time (10.2345s): lowlevel/real=0.96661169328031959491 (1.0345415919875466937)
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: 19.2994s; AVERAGE gain with real timer (19.9999s): lowlevel/real=0.94669998173662495144 (1.0563008548554122523); AVERAGE gain with ACTUAL time (20s): lowlevel/real=0.96647498891828154122 (1.0346879241223210233)
Timing a requested wait of 0.1s using Elapsed_Since: user-time=0, system-time=0 real-time=0.1/0.099855
Timing a requested wait of 0.1s using lowlevel fftw routines: lowlevel timer: 0.0999469s; AVERAGE gain with real timer (0.099855s): lowlevel/real=0.95087080531284928764 (1.0516675813503251202); AVERAGE gain with ACTUAL time (0.1s): lowlevel/real=0.96901301694132868647 (1.0319778811191631096)
Timing a requested wait of 0.129562s using Elapsed_Since: user-time=0, system-time=0 real-time=0.13/0.129919
Timing a requested wait of 0.129562s using lowlevel fftw routines: lowlevel timer: 0.129939s; AVERAGE gain with real timer (0.129919s): lowlevel/real=0.95439087656977250607 (1.0477887253010564894); AVERAGE gain with ACTUAL time (0.129562s): lowlevel/real=0.9714341511178509192 (1.0294058520068269935)
Timing a requested wait of 1.34893s using Elapsed_Since: user-time=0, system-time=0 real-time=1.35/1.3499
Timing a requested wait of 1.34893s using lowlevel fftw routines: lowlevel timer: 1.34995s; AVERAGE gain with real timer (1.3499s): lowlevel/real=0.95743396735181141022 (1.0444584525927389507); AVERAGE gain with ACTUAL time (1.34893s): lowlevel/real=0.9733888139754771629 (1.0273387012902259752)
Timing 15000000 times times() routine:
	result= 6.14s
	user time: 5.46s (6.14sys): 9.1e-08s/call, 2.74725e+06 calls/s
	real time: 11.6112s: 1.9352e-07s/call, 1.29185e+06 calls/s

Timing 15000000 times gettimeofday() routine:
	result= 1.07499e+09s
	user time: 5.14s (10.66sys): 8.56667e-08s/call, 2.91829e+06 calls/s
	real time: 15.8147s: 2.63578e-07s/call, 948487 calls/s

Timing 15000000 times lowlevel fftw_time routines:
	result= 4294.3s
	user time: 2.23s (0sys): 3.71667e-08s/call, 6.72646e+06 calls/s
	real time: 2.22904s: 3.71507e-08s/call, 6.72935e+06 calls/s

Timing 30000000 times original ascanf callback mechanism:
		lowlevel timer: 3.10212s; AVERAGE gain with real timer (3.10211s): lowlevel/real=0.96009453781314813003 (1.0415640966750487895)
	user time: 3.1s: 1.03333e-07s/call, 9.67742e+06 calls/s
	real time: 3.10211s: 1.03404e-07s/call, 9.67082e+06 calls/s
	returns 0, result=3e+07, level=0

Timing 30000000 times new ascanf callback mechanism, no use of new functionality:
		lowlevel timer: 3.58096s; AVERAGE gain with real timer (3.58094s): lowlevel/real=0.96244211112274036246 (1.0390235302915480364)
	user time: 3.58s: 1.19333e-07s/call, 8.37989e+06 calls/s
	real time: 3.58094s: 1.19365e-07s/call, 8.37768e+06 calls/s
	returns 0, result=3e+07, level=0

Timing 30000000 times new ascanf callback mechanism, full use of new functionality:
		lowlevel timer: 3.538s; AVERAGE gain with real timer (3.53799s): lowlevel/real=0.96452883403924782879 (1.036775640819576294)
	user time: 3.54s: 1.18e-07s/call, 8.47458e+06 calls/s
	real time: 3.53799s: 1.17933e-07s/call, 8.47939e+06 calls/s
	returns 1, result=3e+07, level=0

Timing 30000000 times alternate ascanf callback mechanism, no use of new functionality:
		lowlevel timer: 4.92181s; AVERAGE gain with real timer (4.92179s): lowlevel/real=0.96639593998994621771 (1.0347725591752832131)
	user time: 4.92s: 1.64e-07s/call, 6.09756e+06 calls/s
	real time: 4.92179s: 1.6406e-07s/call, 6.09534e+06 calls/s
	returns 0, result=3e+07, level=0

Timing 30000000 times alternate ascanf callback mechanism, full use of new functionality:
		lowlevel timer: 4.79946s; AVERAGE gain with real timer (4.79944s): lowlevel/real=0.96807633474295329279 (1.0329763925749957387)
	user time: 4.8s: 1.6e-07s/call, 6.25e+06 calls/s
	real time: 4.79944s: 1.59981e-07s/call, 6.25073e+06 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: 4.78694s; AVERAGE gain with real timer (4.78692s): lowlevel/real=0.96959668778996421779 (1.0313566584879072519)
	user time: 4.79s: 1.59667e-07s/call, 6.26305e+06 calls/s
	real time: 4.78692s: 1.59564e-07s/call, 6.26707e+06 calls/s
	returns 1, result=3e+07, level=0

Timing 30000000 times alternate2 ascanf callback mechanism, semi-full use of new functionality:
		lowlevel timer: 4.52674s; AVERAGE gain with real timer (4.52672s): lowlevel/real=0.9709788102426480938 (1.0298885922650562463)
	user time: 4.52s: 1.50667e-07s/call, 6.63717e+06 calls/s
	real time: 4.52672s: 1.50891e-07s/call, 6.62732e+06 calls/s
	returns 1, result=3e+07, level=0

Timing fmod(dist,div==1) for determining decimal part:
		lowlevel timer: 2.07325s; AVERAGE gain with real timer (2.07325s): lowlevel/real=0.97224067846043693564 (1.0285519029953782422)
	user time: 2.07s: 6.9e-08s/call, 1.44928e+07 calls/s
	real time: 2.07325s: 6.91083e-08s/call, 1.447e+07 calls/s
	returns result=0.983334

Timing ( dist - div*floor(dist/div) ) for determining decimal part:
		lowlevel timer: 5.8023s; AVERAGE gain with real timer (5.80228s): lowlevel/real=0.97339747251160135821 (1.0273295629376946181)
	user time: 5.8s: 1.93333e-07s/call, 5.17241e+06 calls/s
	real time: 5.80228s: 1.93409e-07s/call, 5.17038e+06 calls/s
	returns result=0.983334

Timing testing for NaN(speed), False:
		lowlevel timer: 0.829485s; AVERAGE gain with real timer (0.829489s): lowlevel/real=0.97446140476004183828 (1.0262079084047941446)
	user time: 0.83s: 2.76667e-08s/call, 3.61446e+07 calls/s
	real time: 0.829489s: 2.76496e-08s/call, 3.61668e+07 calls/s
	returns result=0

Timing testing for NaN(speed), True:
		lowlevel timer: 0.918999s; AVERAGE gain with real timer (0.919002s): lowlevel/real=0.97544354090713780714 (1.0251746595912925031)
	user time: 0.91s: 3.03333e-08s/call, 3.2967e+07 calls/s
	real time: 0.919002s: 3.06334e-08s/call, 3.26441e+07 calls/s
	returns result=1

Timing testing for isNaN(speed), False:
		lowlevel timer: 0.819407s; AVERAGE gain with real timer (0.819411s): lowlevel/real=0.97635287360462941209 (1.0242198564009619144)
	user time: 0.81s: 2.7e-08s/call, 3.7037e+07 calls/s
	real time: 0.819411s: 2.73137e-08s/call, 3.66117e+07 calls/s
	returns result=0

Timing testing for isNaN(speed), True:
		lowlevel timer: 0.90098s; AVERAGE gain with real timer (0.900985s): lowlevel/real=0.97719721768442413534 (1.0233348825630199652)
	user time: 0.9s: 3e-08s/call, 3.33333e+07 calls/s
	real time: 0.900985s: 3.00328e-08s/call, 3.32969e+07 calls/s
	returns result=1

Timing testing for speed==-2, True:
		lowlevel timer: 0.490579s; AVERAGE gain with real timer (0.490582s): lowlevel/real=0.97798333538165205248 (1.0225123106108511273)
	user time: 0.49s: 1.63333e-08s/call, 6.12245e+07 calls/s
	real time: 0.490582s: 1.63527e-08s/call, 6.11519e+07 calls/s
	returns result=1

Timing testing for integer==-2, True:
		lowlevel timer: 0.309671s; AVERAGE gain with real timer (0.309678s): lowlevel/real=0.97871649397484927757 (1.0217463444788923432)
	user time: 0.31s: 1.03333e-08s/call, 9.67742e+07 calls/s
	real time: 0.309678s: 1.03226e-08s/call, 9.68748e+07 calls/s
	returns result=1

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