From mboxrd@z Thu Jan 1 00:00:00 1970 Message-ID: <4445B232.9080200@domain.hid> Date: Tue, 18 Apr 2006 23:44:50 -0400 From: Jim Cromie MIME-Version: 1.0 References: <44454FB1.4090304@domain.hid> In-Reply-To: <44454FB1.4090304@domain.hid> Content-Type: multipart/mixed; boundary="------------070402020802030703090004" Subject: [Xenomai-core] ACPI_PROCESSOR vs idle=poll List-Id: "Xenomai life and development \(bug reports, patches, discussions\)" List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , Cc: xenomai-core This is a multi-part message in MIME format. --------------070402020802030703090004 Content-Type: text/plain; charset=ISO-8859-1; format=flowed Content-Transfer-Encoding: 7bit Jim Cromie wrote: > > Ive seen idle=poll boot-arg fix the Geode SC-1100's buggy TSC > by preventing it from entering C1 state (conjecture-1 ? ;). > Might this also 'fix' the latency problems caused by ACPI_PROCESSOR ? Well, Im feeling a little bit clairvoyant - attached are 2 xeno-test runs, the latter on kernel booted with idle=poll. the former shows bad latencies on all 3 latency test modes: running: ./run -- -l 30 -h -s -T 120 -t0 ... RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD|-1266627.628| -53.008| 1264755.249| 25560|-1266627.628| 1264755.249 RTD|-1265641.003| 113.577| 1266663.795| 49121|-1266627.628| 1266663.795 RTD|-1266453.979| -5.363| 1266902.797| 87842|-1266627.628| 1266902.797 RTD|-1266580.880| -85.109| 1266354.890| 110771|-1266627.628| 1266902.797 RTD|-1266094.150| 98.490| 1265902.295| 137065|-1266627.628| 1266902.797 RTD|-1266865.292| -50.600| 1265055.520| 162815|-1266865.292| 1266902.797 RTD|-1266120.870| 94.792| 1265683.848| 187479|-1266865.292| 1266902.797 RTD|-1265734.119| 34.185| 1265525.803| 212809|-1266865.292| 1266902.797 (the above test also appeared to lose data) running: ./run -- -l 30 -h -s -T 120 -t1 ... RTT| 00:00:01 (in-kernel periodic task, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.081| 181.175| 3953.049| 7591| -2.081| 3953.049 RTD| -2.079| 110.105| 3967.258| 14765| -2.081| 3967.258 RTD| -2.075| 94.426| 3917.985| 21963| -2.081| 3967.258 RTD| -2.078| 16.898| 3962.484| 28902| -2.081| 3967.258 RTD| -2.081| 100.439| 3970.640| 36007| -2.081| 3970.640 RTD| -2.072| 31.378| 3968.902| 43061| -2.081| 3970.640 RTD| -2.074| 3.306| 3968.906| 50045| -2.081| 3970.640 running: ./run -- -l 30 -h -s -T 120 -t2 ... RTT| 00:00:01 (in-kernel timer handler, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.490| 175.013| 3959.518| 7483| -2.490| 3959.518 RTD| -2.445| 154.893| 3947.893| 14800| -2.490| 3959.518 RTD| -2.441| 31.249| 3964.010| 21824| -2.490| 3964.010 RTD| -2.430| 11.978| 3977.738| 28916| -2.490| 3977.738 RTD| -2.429| 20.572| 3912.900| 35940| -2.490| 3977.738 RTD| -2.432| 5.764| 3972.310| 42848| -2.490| 3977.738 RTD| -2.435| 14.193| 3969.281| 49810| -2.490| 3977.738 RTD| -2.429| 13.096| 3971.266| 56818| -2.490| 3977.738 RTD| -2.431| 23.942| 3942.924| 63907| -2.490| 3977.738 with idle=poll, all tests looked good, so Im showing the statistics (the above failed stats, since the histogram buckets stop at 100uS) HSH|--param|--samples-|--average--|---stddev-- HSS| min| 119| 0.017| 0.129 HSS| avg| 1199823| 0.004| 0.085 HSS| max| 119| 3.202| 0.546 ---|------------|------------|------------|--------|------------------------- RTS| -1.004| -0.752| 6.490| 0| 00:02:00/00:02:00 HSH|--param|--samples-|--average--|---stddev-- HSS| min| 119| 2.000| 0.000 HSS| avg| 1199815| 1.392| 0.502 HSS| max| 119| 2.059| 0.586 ---|------------|------------|------------|--------|------------------------- RTS| -2.092| -1.925| 4.916| 0| 00:02:00/00:02:00 HSH|--param|--samples-|--average--|---stddev-- HSS| min| 119| 2.000| 0.000 HSS| avg| 1199835| 1.919| 0.291 HSS| max| 119| 1.151| 0.481 ---|------------|------------|------------|--------|------------------------- RTS| -2.429| -2.261| 3.137| 0| 00:02:00/00:02:00 This is on a pentium-M. IIUC the ACPI page on wikimedia, idle=poll avoids calling the halt instruction, so the CPU never enters C1 state. This seems like it will be universally true, independent of the latency costs on a given CPU - since the C1 sleep is prevented by linux code. Can anyone corroborate this ? --------------070402020802030703090004 Content-Type: text/plain; name="test-2.6.16-ipipe-124-acpi-sony-060418.224659" Content-Transfer-Encoding: 7bit Content-Disposition: inline; filename="test-2.6.16-ipipe-124-acpi-sony-060418.224659" Script started on Tue 18 Apr 2006 10:47:00 PM EDT warning: CONFIG_CPU_FREQ=y may be problematic creating workload using dd if=/dev/hda1 running ./xeno-test -l 30 -h -s -T 120 Tue Apr 18 22:47:00 EDT 2006 running: cat /proc/cpuinfo processor : 0 vendor_id : GenuineIntel cpu family : 6 model : 13 model name : Intel(R) Pentium(R) M processor 1.70GHz stepping : 6 cpu MHz : 1700.000 cache size : 2048 KB fpu : yes fpu_exception : yes cpuid level : 2 flags : fpu vme de pse tsc msr mce cx8 apic sep mtrr pge mca cmov pat clflush dts acpi mmx fxsr sse sse2 ss tm pbe est tm2 bogomips : 3393.88 Tue Apr 18 22:47:00 EDT 2006 running: cat /proc/meminfo MemTotal: 514872 kB MemFree: 181052 kB Buffers: 22748 kB Cached: 190388 kB SwapCached: 0 kB Active: 154992 kB Inactive: 151128 kB HighTotal: 0 kB HighFree: 0 kB LowTotal: 514872 kB LowFree: 181052 kB SwapTotal: 1048280 kB SwapFree: 1048280 kB Dirty: 72 kB Writeback: 0 kB Mapped: 132716 kB Slab: 18888 kB CommitLimit: 1305716 kB Committed_AS: 184248 kB PageTables: 2824 kB VmallocTotal: 515796 kB VmallocUsed: 4460 kB VmallocChunk: 511092 kB HugePages_Total: 0 HugePages_Free: 0 Hugepagesize: 4096 kB Tue Apr 18 22:47:00 EDT 2006 running: zgrep XENO /proc/config.gz CONFIG_XENOMAI=y CONFIG_XENO_OPT_NUCLEUS=y CONFIG_XENO_OPT_PERVASIVE=y CONFIG_XENO_OPT_SECURITY_ACCESS=y CONFIG_XENO_OPT_PIPE=y CONFIG_XENO_OPT_PIPE_NRDEV=32 CONFIG_XENO_OPT_REGISTRY=y CONFIG_XENO_OPT_REGISTRY_NRSLOTS=512 CONFIG_XENO_OPT_SYS_HEAPSZ=128 # CONFIG_XENO_OPT_ISHIELD is not set CONFIG_XENO_OPT_STATS=y # CONFIG_XENO_OPT_DEBUG is not set # CONFIG_XENO_OPT_WATCHDOG is not set CONFIG_XENO_OPT_TIMING_PERIODIC=y CONFIG_XENO_OPT_TIMING_PERIOD=0 CONFIG_XENO_OPT_TIMING_TIMERLAT=0 CONFIG_XENO_OPT_TIMING_SCHEDLAT=0 # CONFIG_XENO_OPT_SCALABLE_SCHED is not set CONFIG_XENO_OPT_TIMER_LIST=y # CONFIG_XENO_OPT_TIMER_HEAP is not set # CONFIG_XENO_OPT_SHIRQ_LEVEL is not set # CONFIG_XENO_OPT_SHIRQ_EDGE is not set CONFIG_XENO_HW_FPU=y # CONFIG_XENO_HW_NMI_DEBUG_LATENCY is not set # CONFIG_XENO_HW_SMI_DETECT_DISABLE is not set CONFIG_XENO_HW_SMI_DETECT=y CONFIG_XENO_HW_SMI_WORKAROUND=y CONFIG_XENO_HW_SMI_ALL=y CONFIG_XENO_SKIN_NATIVE=y CONFIG_XENO_OPT_NATIVE_PIPE=y CONFIG_XENO_OPT_NATIVE_PIPE_BUFSZ=4096 CONFIG_XENO_OPT_NATIVE_REGISTRY=y CONFIG_XENO_OPT_NATIVE_SEM=y CONFIG_XENO_OPT_NATIVE_EVENT=y CONFIG_XENO_OPT_NATIVE_MUTEX=y CONFIG_XENO_OPT_NATIVE_COND=y CONFIG_XENO_OPT_NATIVE_QUEUE=y CONFIG_XENO_OPT_NATIVE_HEAP=y CONFIG_XENO_OPT_NATIVE_ALARM=y CONFIG_XENO_OPT_NATIVE_MPS=y CONFIG_XENO_OPT_NATIVE_INTR=y CONFIG_XENO_SKIN_POSIX=y # CONFIG_XENO_SKIN_PSOS is not set # CONFIG_XENO_SKIN_UITRON is not set # CONFIG_XENO_SKIN_VRTX is not set # CONFIG_XENO_SKIN_VXWORKS is not set CONFIG_XENO_SKIN_RTDM=y CONFIG_XENO_OPT_RTDM_FILDES=128 # CONFIG_XENO_SKIN_UVM is not set CONFIG_XENO_DRIVERS_16550A=m CONFIG_XENO_DRIVERS_TIMERBENCH=m Tue Apr 18 22:47:00 EDT 2006 running: cat /proc/ipipe/Linux Priority=100, Id=0x00000000 irq0-223: accepted irq224-225: grabbed, virtual irq226: passed, virtual Tue Apr 18 22:47:00 EDT 2006 running: cat /proc/ipipe/version 1.2-04 Tue Apr 18 22:47:00 EDT 2006 running: cat /proc/ipipe/Xenomai Priority=200, Id=0x58454e4f irq0-215: passed irq216: grabbed irq217-223: passed irq224-225: passed, virtual irq226: grabbed, virtual Tue Apr 18 22:47:00 EDT 2006 running: generate_loads 1 dd workload started, pids 4266 Tue Apr 18 22:47:00 EDT 2006 running: cat /proc/interrupts CPU0 0: 45696 XT-PIC timer, rthal_broadcast_timer 1: 187 XT-PIC i8042 2: 0 XT-PIC cascade 3: 3 XT-PIC ehci_hcd:usb1, ohci1394 4: 0 XT-PIC uhci_hcd:usb6 7: 2022 XT-PIC ipw2200, ehci_hcd:usb2, uhci_hcd:usb7 8: 1 XT-PIC rtc 9: 325 XT-PIC acpi, Intel 82801DB-ICH4, Intel 82801DB-ICH4 Modem, ohci_hcd:usb3, ohci_hcd:usb4, uhci_hcd:usb5, yenta, eth0 11: 5 XT-PIC sonypi 12: 1072 XT-PIC i8042 14: 17397 XT-PIC ide0 15: 1190 XT-PIC ide1 NMI: 0 LOC: 45665 ERR: 0 MIS: 0 Tue Apr 18 22:47:00 EDT 2006 running: cat /proc/loadavg 1.50 0.91 0.36 5/147 4270 Tue Apr 18 22:47:00 EDT 2006 running: top -bn1c top - 22:47:01 up 3 min, 14 users, load average: 1.54, 0.93, 0.37 Tasks: 138 total, 2 running, 136 sleeping, 0 stopped, 0 zombie Cpu(s): 7.9% us, 2.6% sy, 0.2% ni, 38.7% id, 50.5% wa, 0.0% hi, 0.0% si Mem: 514872k total, 357188k used, 157684k free, 43288k buffers Swap: 1048280k total, 0k used, 1048280k free, 190484k cached PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND 4266 root 18 0 3612 420 352 R 17.9 0.1 0:00.10 dd if /dev/hda1 of /dev/null 1 root 16 0 1740 576 496 S 0.0 0.1 0:00.53 init [5] 2 root 34 19 0 0 0 S 0.0 0.0 0:00.00 [ksoftirqd/0] Tue Apr 18 22:47:01 EDT 2006 running: ./run -- -l 30 -h -s -T 120 -t0 * * * Type ^C to stop this application. * * == Sampling period: 100 us == Test mode: periodic user-mode task == All results in microseconds warming up... RTT| 00:00:06 (periodic user-mode task, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD|-1266627.628| -53.008| 1264755.249| 25560|-1266627.628| 1264755.249 RTD|-1265641.003| 113.577| 1266663.795| 49121|-1266627.628| 1266663.795 RTD|-1266453.979| -5.363| 1266902.797| 87842|-1266627.628| 1266902.797 RTD|-1266580.880| -85.109| 1266354.890| 110771|-1266627.628| 1266902.797 RTD|-1266094.150| 98.490| 1265902.295| 137065|-1266627.628| 1266902.797 RTD|-1266865.292| -50.600| 1265055.520| 162815|-1266865.292| 1266902.797 RTD|-1266120.870| 94.792| 1265683.848| 187479|-1266865.292| 1266902.797 RTD|-1265734.119| 34.185| 1265525.803| 212809|-1266865.292| 1266902.797 RTD|-1266035.973| -94.636| 1265091.460| 240514|-1266865.292| 1266902.797 RTD|-1266282.950| 19.881| 1266799.570| 267204|-1266865.292| 1266902.797 RTD|-1266773.109| -44.992| 1266209.110| 293414|-1266865.292| 1266902.797 RTD|-1263587.473| -11.913| 1266820.270| 319374|-1266865.292| 1266902.797 RTD|-1266800.756| -8.324| 1265663.593| 345232|-1266865.292| 1266902.797 RTD|-1266314.111| 24.849| 1265305.666| 370969|-1266865.292| 1266902.797 RTD|-1266627.366| 31.207| 1264749.397| 395779|-1266865.292| 1266902.797 RTD|-1266768.315| 68.495| 1265985.599| 421391|-1266865.292| 1266902.797 RTD|-1265681.797| -31.810| 1266547.664| 446367|-1266865.292| 1266902.797 RTD|-1266667.368| 93.597| 1264557.887| 473772|-1266865.292| 1266902.797 RTD|-1266579.965| -8.436| 1266591.376| 499753|-1266865.292| 1266902.797 RTD|-1265993.987| -12.260| 1266767.842| 524394|-1266865.292| 1266902.797 RTD|-1266806.833| 123.811| 1266994.852| 548825|-1266865.292| 1266994.852 RTD|-1265720.574| 18.936| 1266238.877| 573303|-1266865.292| 1266994.852 RTD|-1266733.775| -84.343| 1265015.069| 597615|-1266865.292| 1266994.852 RTD|-1265847.200| 107.028| 1265517.368| 623081|-1266865.292| 1266994.852 RTD|-1266018.223| 37.730| 1266259.040| 648925|-1266865.292| 1266994.852 RTD|-1266273.852| -62.592| 1266104.087| 674669|-1266865.292| 1266994.852 RTD|-1266587.132| -23.332| 1265175.547| 699453|-1266865.292| 1266994.852 RTD|-1266435.188| -97.963| 1267005.325| 723585|-1266865.292| 1267005.325 RTD|-1265099.395| -114.105| 1266336.449| 747582|-1266865.292| 1267005.325 RTD|-1266727.173| 85.155| 1265949.957| 772550|-1266865.292| 1267005.325 RTT| 00:01:54 (periodic user-mode task, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD|-1266040.495| -126.468| 1266190.457| 797399|-1266865.292| 1267005.325 RTD|-1264184.638| -65.008| 1266990.126| 822256|-1266865.292| 1267005.325 ---|--param|----range-|--samples HSD| min| 99 -100 | 32 ---|--param|----range-|--samples HSD| avg| 6 - 7 | 1 HSD| avg| 20 - 21 | 1 HSD| avg| 26 - 27 | 1 HSD| avg| 92 - 93 | 1 HSD| avg| 93 - 94 | 8 HSD| avg| 98 - 99 | 1 HSD| avg| 99 -100 | 327734 ---|--param|----range-|--samples HSD| max| 99 -100 | 32 HSH|--param|--samples-|--average--|---stddev-- HSS| min| 32| 99.000| 0.000 HSS| avg| 327747| 98.999| 0.250 HSS| max| 32| 99.000| 0.000 ---|------------|------------|------------|--------|------------------------- RTS|-1266865.292| -0.891| 1267005.325| 822256| 00:02:00/00:02:00 Tue Apr 18 22:49:19 EDT 2006 running: ./run -- -l 30 -h -s -T 120 -t1 * * * Type ^C to stop this application. * * == Sampling period: 100 us == Test mode: in-kernel periodic task == All results in microseconds warming up... RTT| 00:00:01 (in-kernel periodic task, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.081| 181.175| 3953.049| 7591| -2.081| 3953.049 RTD| -2.079| 110.105| 3967.258| 14765| -2.081| 3967.258 RTD| -2.075| 94.426| 3917.985| 21963| -2.081| 3967.258 RTD| -2.078| 16.898| 3962.484| 28902| -2.081| 3967.258 RTD| -2.081| 100.439| 3970.640| 36007| -2.081| 3970.640 RTD| -2.072| 31.378| 3968.902| 43061| -2.081| 3970.640 RTD| -2.074| 3.306| 3968.906| 50045| -2.081| 3970.640 RTD| -2.081| 41.557| 3957.299| 57139| -2.081| 3970.640 RTD| -2.087| 39.201| 3972.112| 64237| -2.087| 3972.112 RTD| -2.076| 10.549| 3938.170| 71281| -2.087| 3972.112 RTD| -2.085| 12.446| 3973.538| 78160| -2.087| 3973.538 RTD| -2.079| 17.276| 3957.115| 85204| -2.087| 3973.538 RTD| -2.072| 16.576| 3966.305| 92125| -2.087| 3973.538 RTD| -2.075| 138.083| 3943.570| 99412| -2.087| 3973.538 RTD| -2.078| 3.811| 3968.985| 106344| -2.087| 3973.538 RTD| -2.080| 49.531| 3953.292| 113361| -2.087| 3973.538 RTD| -2.076| 8.923| 3966.655| 120397| -2.087| 3973.538 RTD| -2.080| 13.582| 3956.791| 127413| -2.087| 3973.538 RTD| -2.077| 20.249| 3980.701| 134362| -2.087| 3980.701 RTD| -2.081| 9.533| 3979.628| 141301| -2.087| 3980.701 RTD| -2.078| -5.565| 3980.023| 148298| -2.087| 3980.701 RTD| -2.086| 156.978| 3966.375| 155631| -2.087| 3980.701 RTD| -2.079| 108.168| 3967.968| 162832| -2.087| 3980.701 RTD| -2.082| 123.516| 3981.376| 170139| -2.087| 3981.376 RTD| -2.082| 109.852| 3925.280| 177354| -2.087| 3981.376 RTD| -2.075| 21.610| 3975.324| 184298| -2.087| 3981.376 RTD| -2.083| 144.094| 3940.287| 191532| -2.087| 3981.376 RTD| -2.075| 83.113| 3974.253| 198721| -2.087| 3981.376 RTD| -2.080| 46.780| 3964.695| 205842| -2.087| 3981.376 RTD| -2.077| 181.090| 3976.525| 213172| -2.087| 3981.376 RTT| 00:00:31 (in-kernel periodic task, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.080| 20.064| 3960.078| 220088| -2.087| 3981.376 RTD| -2.087| 26.053| 3965.862| 227070| -2.087| 3981.376 RTD| -2.078| 89.038| 3965.321| 234126| -2.087| 3981.376 RTD| -2.079| -140.487| 3961.375| 241811| -2.087| 3981.376 RTD| -2.083| -168.027| 3975.363| 249364| -2.087| 3981.376 RTD| -2.074| -146.468| 3958.990| 257021| -2.087| 3981.376 RTD| -2.073| -12.979| 3979.235| 265072| -2.087| 3981.376 RTD| -2.091| -34.821| 3975.307| 273038| -2.091| 3981.376 RTD| -2.075| -45.487| 3975.051| 280883| -2.091| 3981.376 RTD| -2.078| -160.382| 3971.453| 288402| -2.091| 3981.376 RTD| -2.083| 142.150| 3975.208| 295604| -2.091| 3981.376 RTD| -2.074| 192.386| 3972.852| 302961| -2.091| 3981.376 RTD| -2.074| 203.286| 3945.796| 310318| -2.091| 3981.376 RTD| -2.072| -195.477| 3966.790| 317733| -2.091| 3981.376 RTD| -2.075| 194.429| 3973.646| 325068| -2.091| 3981.376 RTD| -2.079| 134.776| 3975.544| 331923| -2.091| 3981.376 RTD| -2.087| 78.871| 3954.079| 339006| -2.091| 3981.376 RTD| -2.081| 108.087| 3959.878| 346091| -2.091| 3981.376 RTD| -2.084| -193.802| 3974.562| 353464| -2.091| 3981.376 RTD| -2.077| -166.267| 3980.878| 360983| -2.091| 3981.376 RTD| -2.071| -187.846| 3972.894| 368408| -2.091| 3981.376 RTD| -2.075| 81.524| 3942.147| 375430| -2.091| 3981.376 RTD| -2.081| 48.909| 3970.037| 382224| -2.091| 3981.376 RTD| -2.081| 93.576| 3968.196| 389115| -2.091| 3981.376 RTD| -2.082| 131.545| 3970.268| 396281| -2.091| 3981.376 RTD| -2.085| -201.617| 3963.419| 403651| -2.091| 3981.376 RTD| -2.080| 50.461| 3957.503| 410611| -2.091| 3981.376 RTD| -2.080| -7.748| 3971.128| 417378| -2.091| 3981.376 RTD| -2.082| -8.883| 3954.320| 424163| -2.091| 3981.376 RTD| -2.074| -7.743| 3954.412| 430946| -2.091| 3981.376 RTT| 00:01:01 (in-kernel periodic task, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.077| 88.086| 3979.202| 438099| -2.091| 3981.376 RTD| -2.088| 16.850| 3913.242| 445009| -2.091| 3981.376 RTD| -2.075| -1.314| 3952.823| 451799| -2.091| 3981.376 RTD| -2.072| 74.398| 3965.193| 458892| -2.091| 3981.376 RTD| -2.085| 28.454| 3969.210| 465883| -2.091| 3981.376 RTD| -2.082| 56.695| 3971.660| 472835| -2.091| 3981.376 RTD| -2.079| 76.970| 3969.224| 479934| -2.091| 3981.376 RTD| -2.074| 118.680| 3977.972| 487016| -2.091| 3981.376 RTD| -2.078| -12.635| 3914.740| 493870| -2.091| 3981.376 RTD| -2.078| 157.386| 3971.909| 501064| -2.091| 3981.376 RTD| -2.081| 30.741| 3934.813| 507957| -2.091| 3981.376 RTD| -2.073| -12.744| 3969.568| 514791| -2.091| 3981.376 RTD| -2.080| -22.583| 3967.533| 521561| -2.091| 3981.376 RTD| -2.079| 36.904| 3962.228| 528494| -2.091| 3981.376 RTD| -2.088| -156.705| 3980.946| 535963| -2.091| 3981.376 RTD| -2.083| -4.449| 3898.057| 542854| -2.091| 3981.376 RTD| -2.084| 18.041| 3968.158| 549745| -2.091| 3981.376 RTD| -2.082| 6.690| 3945.346| 556681| -2.091| 3981.376 RTD| -2.078| -4.135| 3962.813| 563576| -2.091| 3981.376 RTD| -2.069| 19.762| 3947.414| 570503| -2.091| 3981.376 RTD| -2.084| -20.252| 3959.211| 577290| -2.091| 3981.376 RTD| -2.074| 11.141| 3976.116| 584166| -2.091| 3981.376 RTD| -2.075| 5.073| 3967.219| 591013| -2.091| 3981.376 RTD| -2.081| -13.892| 3933.221| 597831| -2.091| 3981.376 RTD| -2.080| -8.181| 3959.425| 604744| -2.091| 3981.376 RTD| -2.081| 6.128| 3973.736| 611576| -2.091| 3981.376 RTD| -2.073| -9.203| 3937.998| 618390| -2.091| 3981.376 RTD| -2.077| -2.601| 3978.152| 625241| -2.091| 3981.376 RTD| -2.076| 65.837| 3981.402| 632297| -2.091| 3981.402 RTD| -2.072| 37.389| 3975.579| 639335| -2.091| 3981.402 RTT| 00:01:31 (in-kernel periodic task, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.084| 8.079| 3938.894| 646294| -2.091| 3981.402 RTD| -2.093| 1.864| 3979.675| 653252| -2.093| 3981.402 RTD| -2.081| 58.570| 3955.724| 660234| -2.093| 3981.402 RTD| -2.085| -6.993| 3966.372| 667030| -2.093| 3981.402 RTD| -2.077| 16.040| 3966.380| 673966| -2.093| 3981.402 RTD| -2.078| 3.644| 3938.039| 680862| -2.093| 3981.402 RTD| -2.081| 29.152| 3978.232| 687838| -2.093| 3981.402 RTD| -2.082| -10.027| 3923.623| 694746| -2.093| 3981.402 RTD| -2.090| -14.338| 3975.944| 701587| -2.093| 3981.402 RTD| -2.083| 3.775| 3973.859| 708485| -2.093| 3981.402 RTD| -2.071| -5.822| 3963.121| 715352| -2.093| 3981.402 RTD| -2.076| -3.836| 3960.766| 722284| -2.093| 3981.402 RTD| -2.081| 0.300| 3955.902| 729184| -2.093| 3981.402 RTD| -2.075| 1.107| 3907.199| 736088| -2.093| 3981.402 RTD| -2.077| -11.287| 3943.076| 742897| -2.093| 3981.402 RTD| -2.083| 1.488| 3972.175| 749801| -2.093| 3981.402 RTD| -2.082| -13.073| 3963.243| 756597| -2.093| 3981.402 RTD| -2.084| -7.955| 3958.783| 763420| -2.093| 3981.402 RTD| -2.078| 53.279| 3977.361| 770495| -2.093| 3981.402 RTD| -2.080| 47.976| 3918.319| 777461| -2.093| 3981.402 RTD| -2.081| 43.124| 3961.484| 784423| -2.093| 3981.402 RTD| -2.073| -4.882| 3966.872| 791228| -2.093| 3981.402 RTD| -2.085| -3.688| 3973.984| 798054| -2.093| 3981.402 RTD| -2.080| 12.323| 3967.836| 804976| -2.093| 3981.402 RTD| -2.086| -15.357| 3899.386| 811880| -2.093| 3981.402 RTD| -2.080| 22.025| 3969.214| 818862| -2.093| 3981.402 RTD| -2.085| 129.906| 3952.083| 826103| -2.093| 3981.402 RTD| -2.078| -32.921| 3971.189| 832772| -2.093| 3981.402 RTD| -2.077| 147.791| 3944.746| 840018| -2.093| 3981.402 ---|--param|----range-|--samples HSD| min| 2 - 3 | 119 ---|--param|----range-|--samples HSD| avg| 0 - 1 | 8294 HSD| avg| 1 - 2 | 259120 HSD| avg| 2 - 3 | 38117 HSD| avg| 3 - 4 | 464 HSD| avg| 4 - 5 | 490 HSD| avg| 5 - 6 | 486 HSD| avg| 6 - 7 | 499 HSD| avg| 7 - 8 | 520 HSD| avg| 8 - 9 | 478 HSD| avg| 9 - 10 | 453 HSD| avg| 10 - 11 | 474 HSD| avg| 11 - 12 | 529 HSD| avg| 12 - 13 | 461 HSD| avg| 13 - 14 | 464 HSD| avg| 14 - 15 | 478 HSD| avg| 15 - 16 | 470 HSD| avg| 16 - 17 | 523 HSD| avg| 17 - 18 | 475 HSD| avg| 18 - 19 | 484 HSD| avg| 19 - 20 | 461 HSD| avg| 20 - 21 | 479 HSD| avg| 21 - 22 | 517 HSD| avg| 22 - 23 | 530 HSD| avg| 23 - 24 | 473 HSD| avg| 24 - 25 | 522 HSD| avg| 25 - 26 | 514 HSD| avg| 26 - 27 | 496 HSD| avg| 27 - 28 | 509 HSD| avg| 28 - 29 | 520 HSD| avg| 29 - 30 | 531 HSD| avg| 30 - 31 | 508 HSD| avg| 31 - 32 | 510 HSD| avg| 32 - 33 | 499 HSD| avg| 33 - 34 | 524 HSD| avg| 34 - 35 | 509 HSD| avg| 35 - 36 | 525 HSD| avg| 36 - 37 | 481 HSD| avg| 37 - 38 | 540 HSD| avg| 38 - 39 | 478 HSD| avg| 39 - 40 | 509 HSD| avg| 40 - 41 | 461 HSD| avg| 41 - 42 | 526 HSD| avg| 42 - 43 | 486 HSD| avg| 43 - 44 | 443 HSD| avg| 44 - 45 | 471 HSD| avg| 45 - 46 | 443 HSD| avg| 46 - 47 | 458 HSD| avg| 47 - 48 | 448 HSD| avg| 48 - 49 | 482 HSD| avg| 49 - 50 | 440 HSD| avg| 50 - 51 | 423 HSD| avg| 51 - 52 | 480 HSD| avg| 52 - 53 | 446 HSD| avg| 53 - 54 | 475 HSD| avg| 54 - 55 | 480 HSD| avg| 55 - 56 | 443 HSD| avg| 56 - 57 | 447 HSD| avg| 57 - 58 | 486 HSD| avg| 58 - 59 | 451 HSD| avg| 59 - 60 | 464 HSD| avg| 60 - 61 | 519 HSD| avg| 61 - 62 | 488 HSD| avg| 62 - 63 | 412 HSD| avg| 63 - 64 | 480 HSD| avg| 64 - 65 | 471 HSD| avg| 65 - 66 | 484 HSD| avg| 66 - 67 | 500 HSD| avg| 67 - 68 | 486 HSD| avg| 68 - 69 | 454 HSD| avg| 69 - 70 | 429 HSD| avg| 70 - 71 | 472 HSD| avg| 71 - 72 | 478 HSD| avg| 72 - 73 | 498 HSD| avg| 73 - 74 | 510 HSD| avg| 74 - 75 | 453 HSD| avg| 75 - 76 | 487 HSD| avg| 76 - 77 | 488 HSD| avg| 77 - 78 | 501 HSD| avg| 78 - 79 | 492 HSD| avg| 79 - 80 | 495 HSD| avg| 80 - 81 | 519 HSD| avg| 81 - 82 | 495 HSD| avg| 82 - 83 | 470 HSD| avg| 83 - 84 | 517 HSD| avg| 84 - 85 | 517 HSD| avg| 85 - 86 | 528 HSD| avg| 86 - 87 | 521 HSD| avg| 87 - 88 | 514 HSD| avg| 88 - 89 | 526 HSD| avg| 89 - 90 | 524 HSD| avg| 90 - 91 | 495 HSD| avg| 91 - 92 | 522 HSD| avg| 92 - 93 | 538 HSD| avg| 93 - 94 | 516 HSD| avg| 94 - 95 | 506 HSD| avg| 95 - 96 | 536 HSD| avg| 96 - 97 | 560 HSD| avg| 97 - 98 | 491 HSD| avg| 98 - 99 | 515 HSD| avg| 99 -100 | 847169 ---|--param|----range-|--samples HSD| max| 99 -100 | 119 HSH|--param|--samples-|--average--|---stddev-- HSS| min| 119| 2.000| 0.000 HSS| avg| 1199743| 72.177| 42.934 HSS| max| 119| 99.000| 0.000 ---|------------|------------|------------|--------|------------------------- RTS| -2.093| 23.887| 3981.402| 840018| 00:02:00/00:02:00 Tue Apr 18 22:51:38 EDT 2006 running: ./run -- -l 30 -h -s -T 120 -t2 * * * Type ^C to stop this application. * * == Sampling period: 100 us == Test mode: in-kernel timer handler == All results in microseconds warming up... RTT| 00:00:01 (in-kernel timer handler, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.490| 175.013| 3959.518| 7483| -2.490| 3959.518 RTD| -2.445| 154.893| 3947.893| 14800| -2.490| 3959.518 RTD| -2.441| 31.249| 3964.010| 21824| -2.490| 3964.010 RTD| -2.430| 11.978| 3977.738| 28916| -2.490| 3977.738 RTD| -2.429| 20.572| 3912.900| 35940| -2.490| 3977.738 RTD| -2.432| 5.764| 3972.310| 42848| -2.490| 3977.738 RTD| -2.435| 14.193| 3969.281| 49810| -2.490| 3977.738 RTD| -2.429| 13.096| 3971.266| 56818| -2.490| 3977.738 RTD| -2.431| 23.942| 3942.924| 63907| -2.490| 3977.738 RTD| -2.480| 9.320| 3902.216| 70844| -2.490| 3977.738 RTD| -2.428| 42.893| 3944.476| 77881| -2.490| 3977.738 RTD| -2.432| 19.053| 3916.166| 84935| -2.490| 3977.738 RTD| -2.431| 39.410| 3962.342| 91947| -2.490| 3977.738 RTD| -2.427| 15.575| 3965.480| 98981| -2.490| 3977.738 RTD| -2.493| 4.375| 3968.021| 105898| -2.493| 3977.738 RTD| -2.434| 1.978| 3969.928| 112856| -2.493| 3977.738 RTD| -2.433| 2.302| 3971.902| 119882| -2.493| 3977.738 RTD| -2.454| 134.779| 3969.486| 127141| -2.493| 3977.738 RTD| -2.476| 48.486| 3964.006| 134210| -2.493| 3977.738 RTD| -2.432| 17.787| 3950.317| 141197| -2.493| 3977.738 RTD| -2.431| 182.937| 3974.711| 148578| -2.493| 3977.738 RTD| -2.432| 134.346| 3978.418| 155884| -2.493| 3978.418 RTD| -2.431| 71.909| 3968.022| 162956| -2.493| 3978.418 RTD| -2.427| 115.390| 3973.630| 170228| -2.493| 3978.418 RTD| -2.432| 86.958| 3969.999| 177299| -2.493| 3978.418 RTD| -2.425| 108.943| 3932.802| 184517| -2.493| 3978.418 RTD| -2.427| 55.101| 3943.043| 191622| -2.493| 3978.418 RTD| -2.475| 61.147| 3941.239| 198756| -2.493| 3978.418 RTD| -2.430| 19.086| 3946.979| 205698| -2.493| 3978.418 RTD| -2.427| -1.804| 3932.770| 212582| -2.493| 3978.418 RTT| 00:00:31 (in-kernel timer handler, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.435| 9.959| 3939.640| 219488| -2.493| 3978.418 RTD| -2.428| 161.297| 3956.370| 226818| -2.493| 3978.418 RTD| -2.436| 86.825| 3942.274| 233915| -2.493| 3978.418 RTD| -2.429| -0.410| 3925.804| 240831| -2.493| 3978.418 RTD| -2.432| 3.554| 3978.129| 247775| -2.493| 3978.418 RTD| -2.428| 28.330| 3960.995| 254750| -2.493| 3978.418 RTD| -2.466| 70.397| 3948.748| 261843| -2.493| 3978.418 RTD| -2.426| 58.101| 3963.041| 268921| -2.493| 3978.418 RTD| -2.430| 3.572| 3976.131| 275774| -2.493| 3978.418 RTD| -2.427| 75.259| 3946.499| 282817| -2.493| 3978.418 RTD| -2.432| 71.451| 3970.033| 289665| -2.493| 3978.418 RTD| -2.433| 17.926| 3955.901| 295070| -2.493| 3978.418 RTD| -2.473| 147.739| 3970.230| 301603| -2.493| 3978.418 RTD| -2.459| 92.541| 3964.697| 307849| -2.493| 3978.418 RTD| -2.456| -122.606| 3950.008| 314422| -2.493| 3978.418 RTD| -2.434| 169.872| 3932.125| 320410| -2.493| 3978.418 RTD| -2.445| -138.118| 3906.916| 326830| -2.493| 3978.418 RTD| -2.458| 51.171| 3926.100| 333946| -2.493| 3978.418 RTD| -2.431| 135.171| 3933.633| 341484| -2.493| 3978.418 RTD| -2.437| 94.581| 3945.411| 348825| -2.493| 3978.418 RTD| -2.437| 129.010| 3974.080| 356265| -2.493| 3978.418 ^[[CRTD| -2.429| 24.578| 3968.366| 363008| -2.493| 3978.418 RTD| -2.426| 38.896| 3956.139| 370008| -2.493| 3978.418 RTD| -2.435| 36.878| 3972.271| 376962| -2.493| 3978.418 RTD| -2.443| 44.812| 3948.871| 383972| -2.493| 3978.418 RTD| -2.431| 33.315| 3970.539| 390918| -2.493| 3978.418 RTD| -2.429| 36.688| 3972.447| 397916| -2.493| 3978.418 RTD| -2.431| 45.956| 3965.208| 404882| -2.493| 3978.418 RTD| -2.436| 37.396| 3964.565| 411873| -2.493| 3978.418 RTD| -2.432| 48.952| 3972.128| 418852| -2.493| 3978.418 RTT| 00:01:01 (in-kernel timer handler, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.447| 62.092| 3919.644| 425876| -2.493| 3978.418 RTD| -2.426| 66.874| 3928.059| 432938| -2.493| 3978.418 RTD| -2.425| 53.100| 3972.278| 439928| -2.493| 3978.418 RTD| -2.429| 58.970| 3956.298| 446957| -2.493| 3978.418 RTD| -2.430| 83.305| 3975.062| 454057| -2.493| 3978.418 RTD| -2.481| 37.272| 3972.527| 461017| -2.493| 3978.418 RTD| -2.428| 165.083| 3951.583| 468329| -2.493| 3978.418 RTD| -2.457| 75.878| 3972.054| 475410| -2.493| 3978.418 RTD| -2.429| 48.632| 3972.391| 482391| -2.493| 3978.418 RTD| -2.490| 99.128| 3950.010| 489502| -2.493| 3978.418 RTD| -2.432| 73.466| 3958.779| 496553| -2.493| 3978.418 RTD| -2.437| 35.915| 3958.803| 503516| -2.493| 3978.418 RTD| -2.432| 52.120| 3945.517| 510501| -2.493| 3978.418 RTD| -2.450| 35.660| 3973.200| 517477| -2.493| 3978.418 RTD| -2.446| 95.640| 3958.319| 524595| -2.493| 3978.418 RTD| -2.428| 76.499| 3973.295| 531631| -2.493| 3978.418 RTD| -2.469| 53.205| 3966.609| 538630| -2.493| 3978.418 RTD| -2.491| 68.542| 3971.587| 545678| -2.493| 3978.418 RTD| -2.428| 39.211| 3961.822| 552682| -2.493| 3978.418 RTD| -2.428| 43.691| 3969.325| 559638| -2.493| 3978.418 RTD| -2.428| 49.954| 3933.609| 566645| -2.493| 3978.418 RTD| -2.431| 36.237| 3963.899| 573589| -2.493| 3978.418 RTD| -2.428| 40.788| 3937.451| 580559| -2.493| 3978.418 RTD| -2.425| 39.111| 3973.442| 587511| -2.493| 3978.418 RTD| -2.429| 109.271| 3932.634| 594663| -2.493| 3978.418 RTD| -2.432| 53.112| 3962.345| 601632| -2.493| 3978.418 RTD| -2.451| 51.368| 3971.656| 608615| -2.493| 3978.418 RTD| -2.477| 111.396| 3966.242| 615761| -2.493| 3978.418 RTD| -2.431| 109.815| 3963.162| 622947| -2.493| 3978.418 RTD| -2.430| 143.083| 3947.750| 630183| -2.493| 3978.418 RTT| 00:01:31 (in-kernel timer handler, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.465| 43.779| 3972.606| 637174| -2.493| 3978.418 RTD| -2.432| 36.464| 3946.153| 644151| -2.493| 3978.418 RTD| -2.427| 40.543| 3981.530| 651114| -2.493| 3981.530 RTD| -2.471| 28.543| 3940.760| 658074| -2.493| 3981.530 RTD| -2.432| 58.674| 3976.614| 665071| -2.493| 3981.530 RTD| -2.433| 31.974| 3961.962| 672008| -2.493| 3981.530 RTD| -2.453| 42.274| 3950.175| 678993| -2.493| 3981.530 RTD| -2.428| 35.295| 3976.260| 685949| -2.493| 3981.530 RTD| -2.431| 52.895| 3963.940| 692952| -2.493| 3981.530 RTD| -2.431| 34.647| 3978.143| 699905| -2.493| 3981.530 RTD| -2.440| 94.508| 3973.398| 707038| -2.493| 3981.530 RTD| -2.437| 77.727| 3979.598| 714082| -2.493| 3981.530 RTD| -2.434| 80.580| 3943.883| 721131| -2.493| 3981.530 RTD| -2.484| 153.813| 3979.125| 728382| -2.493| 3981.530 RTD| -2.429| 32.855| 3974.885| 735362| -2.493| 3981.530 RTD| -2.429| 47.040| 3950.898| 742349| -2.493| 3981.530 RTD| -2.427| 70.481| 3977.506| 749422| -2.493| 3981.530 RTD| -2.430| 51.719| 3961.052| 756416| -2.493| 3981.530 RTD| -2.449| 44.742| 3953.579| 763375| -2.493| 3981.530 RTD| -2.431| 41.029| 3927.870| 770370| -2.493| 3981.530 RTD| -2.433| 40.413| 3948.796| 777340| -2.493| 3981.530 RTD| -2.432| 83.039| 3970.482| 784434| -2.493| 3981.530 RTD| -2.480| 32.826| 3955.706| 791362| -2.493| 3981.530 RTD| -2.484| 44.158| 3950.869| 798363| -2.493| 3981.530 RTD| -2.429| 35.544| 3949.955| 805291| -2.493| 3981.530 RTD| -2.438| 41.659| 3946.116| 812294| -2.493| 3981.530 RTD| -2.428| 118.115| 3975.287| 819470| -2.493| 3981.530 RTD| -2.430| 59.892| 3942.539| 826524| -2.493| 3981.530 RTD| -2.429| 37.478| 3977.931| 833481| -2.493| 3981.530 ---|--param|----range-|--samples HSD| min| 2 - 3 | 119 ---|--param|----range-|--samples HSD| avg| 0 - 1 | 3891 HSD| avg| 1 - 2 | 41877 HSD| avg| 2 - 3 | 264826 HSD| avg| 3 - 4 | 504 HSD| avg| 4 - 5 | 522 HSD| avg| 5 - 6 | 514 HSD| avg| 6 - 7 | 501 HSD| avg| 7 - 8 | 486 HSD| avg| 8 - 9 | 530 HSD| avg| 9 - 10 | 511 HSD| avg| 10 - 11 | 506 HSD| avg| 11 - 12 | 521 HSD| avg| 12 - 13 | 560 HSD| avg| 13 - 14 | 502 HSD| avg| 14 - 15 | 540 HSD| avg| 15 - 16 | 518 HSD| avg| 16 - 17 | 535 HSD| avg| 17 - 18 | 505 HSD| avg| 18 - 19 | 515 HSD| avg| 19 - 20 | 499 HSD| avg| 20 - 21 | 494 HSD| avg| 21 - 22 | 507 HSD| avg| 22 - 23 | 538 HSD| avg| 23 - 24 | 498 HSD| avg| 24 - 25 | 511 HSD| avg| 25 - 26 | 509 HSD| avg| 26 - 27 | 525 HSD| avg| 27 - 28 | 462 HSD| avg| 28 - 29 | 513 HSD| avg| 29 - 30 | 505 HSD| avg| 30 - 31 | 508 HSD| avg| 31 - 32 | 505 HSD| avg| 32 - 33 | 498 HSD| avg| 33 - 34 | 457 HSD| avg| 34 - 35 | 488 HSD| avg| 35 - 36 | 485 HSD| avg| 36 - 37 | 516 HSD| avg| 37 - 38 | 428 HSD| avg| 38 - 39 | 498 HSD| avg| 39 - 40 | 503 HSD| avg| 40 - 41 | 507 HSD| avg| 41 - 42 | 553 HSD| avg| 42 - 43 | 473 HSD| avg| 43 - 44 | 498 HSD| avg| 44 - 45 | 456 HSD| avg| 45 - 46 | 456 HSD| avg| 46 - 47 | 477 HSD| avg| 47 - 48 | 493 HSD| avg| 48 - 49 | 507 HSD| avg| 49 - 50 | 468 HSD| avg| 50 - 51 | 479 HSD| avg| 51 - 52 | 494 HSD| avg| 52 - 53 | 492 HSD| avg| 53 - 54 | 512 HSD| avg| 54 - 55 | 516 HSD| avg| 55 - 56 | 446 HSD| avg| 56 - 57 | 520 HSD| avg| 57 - 58 | 472 HSD| avg| 58 - 59 | 492 HSD| avg| 59 - 60 | 501 HSD| avg| 60 - 61 | 448 HSD| avg| 61 - 62 | 504 HSD| avg| 62 - 63 | 530 HSD| avg| 63 - 64 | 505 HSD| avg| 64 - 65 | 484 HSD| avg| 65 - 66 | 475 HSD| avg| 66 - 67 | 495 HSD| avg| 67 - 68 | 533 HSD| avg| 68 - 69 | 530 HSD| avg| 69 - 70 | 471 HSD| avg| 70 - 71 | 495 HSD| avg| 71 - 72 | 533 HSD| avg| 72 - 73 | 544 HSD| avg| 73 - 74 | 524 HSD| avg| 74 - 75 | 504 HSD| avg| 75 - 76 | 537 HSD| avg| 76 - 77 | 517 HSD| avg| 77 - 78 | 524 HSD| avg| 78 - 79 | 545 HSD| avg| 79 - 80 | 496 HSD| avg| 80 - 81 | 508 HSD| avg| 81 - 82 | 525 HSD| avg| 82 - 83 | 471 HSD| avg| 83 - 84 | 510 HSD| avg| 84 - 85 | 520 HSD| avg| 85 - 86 | 496 HSD| avg| 86 - 87 | 500 HSD| avg| 87 - 88 | 538 HSD| avg| 88 - 89 | 548 HSD| avg| 89 - 90 | 519 HSD| avg| 90 - 91 | 520 HSD| avg| 91 - 92 | 497 HSD| avg| 92 - 93 | 534 HSD| avg| 93 - 94 | 500 HSD| avg| 94 - 95 | 512 HSD| avg| 95 - 96 | 490 HSD| avg| 96 - 97 | 512 HSD| avg| 97 - 98 | 451 HSD| avg| 98 - 99 | 514 HSD| avg| 99 -100 | 840610 ---|--param|----range-|--samples HSD| max| 99 -100 | 119 HSH|--param|--samples-|--average--|---stddev-- HSS| min| 119| 2.000| 0.000 HSS| avg| 1199592| 71.888| 42.841 HSS| max| 119| 99.000| 0.000 ---|------------|------------|------------|--------|------------------------- RTS| -2.493| 56.931| 3981.530| 833481| 00:02:00/00:02:00 Tue Apr 18 22:53:57 EDT 2006 running: cat /proc/interrupts CPU0 0: 149961 XT-PIC timer, rthal_broadcast_timer 1: 219 XT-PIC i8042 2: 0 XT-PIC cascade 3: 3 XT-PIC ehci_hcd:usb1, ohci1394 4: 0 XT-PIC uhci_hcd:usb6 7: 6672 XT-PIC ipw2200, ehci_hcd:usb2, uhci_hcd:usb7 8: 1 XT-PIC rtc 9: 949 XT-PIC acpi, Intel 82801DB-ICH4, Intel 82801DB-ICH4 Modem, ohci_hcd:usb3, ohci_hcd:usb4, uhci_hcd:usb5, yenta, eth0 11: 5 XT-PIC sonypi 12: 4132 XT-PIC i8042 14: 106997 XT-PIC ide0 15: 4916 XT-PIC ide1 NMI: 0 LOC: 149930 ERR: 0 MIS: 0 Tue Apr 18 22:53:57 EDT 2006 running: cat /proc/loadavg 1.50 1.34 0.76 3/147 4572 Tue Apr 18 22:53:57 EDT 2006 running: top -bn1c top - 22:54:02 up 10 min, 14 users, load average: 1.62, 1.37, 0.77 Tasks: 138 total, 2 running, 136 sleeping, 0 stopped, 0 zombie Cpu(s): 6.2% us, 11.1% sy, 0.1% ni, 11.7% id, 70.9% wa, 0.0% hi, 0.0% si Mem: 514872k total, 509192k used, 5680k free, 313968k buffers Swap: 1048280k total, 164k used, 1048116k free, 43636k cached PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND 4266 root 18 0 3612 420 352 R 15.8 0.1 1:07.36 dd if /dev/hda1 of /dev/null 1 root 16 0 1740 576 496 S 0.0 0.1 0:00.53 init [5] 2 root 34 19 0 0 0 S 0.0 0.0 0:00.00 [ksoftirqd/0] Script done on Tue 18 Apr 2006 10:54:02 PM EDT --------------070402020802030703090004 Content-Type: text/plain; name="test-2.6.16-ipipe-124-acpi-sony-060418.230256" Content-Transfer-Encoding: 7bit Content-Disposition: inline; filename="test-2.6.16-ipipe-124-acpi-sony-060418.230256" Script started on Tue 18 Apr 2006 11:02:56 PM EDT warning: CONFIG_CPU_FREQ=y may be problematic creating workload using dd if=/dev/hda1 running ./xeno-test -l 30 -h -s -T 120 Tue Apr 18 23:02:56 EDT 2006 running: cat /proc/cpuinfo processor : 0 vendor_id : GenuineIntel cpu family : 6 model : 13 model name : Intel(R) Pentium(R) M processor 1.70GHz stepping : 6 cpu MHz : 1700.000 cache size : 2048 KB fpu : yes fpu_exception : yes cpuid level : 2 flags : fpu vme de pse tsc msr mce cx8 apic sep mtrr pge mca cmov pat clflush dts acpi mmx fxsr sse sse2 ss tm pbe est tm2 bogomips : 3393.77 Tue Apr 18 23:02:56 EDT 2006 running: cat /proc/meminfo MemTotal: 514872 kB MemFree: 184256 kB Buffers: 22360 kB Cached: 187788 kB SwapCached: 0 kB Active: 153400 kB Inactive: 149624 kB HighTotal: 0 kB HighFree: 0 kB LowTotal: 514872 kB LowFree: 184256 kB SwapTotal: 1048280 kB SwapFree: 1048280 kB Dirty: 40 kB Writeback: 0 kB Mapped: 132892 kB Slab: 18660 kB CommitLimit: 1305716 kB Committed_AS: 184608 kB PageTables: 2824 kB VmallocTotal: 515796 kB VmallocUsed: 4460 kB VmallocChunk: 511092 kB HugePages_Total: 0 HugePages_Free: 0 Hugepagesize: 4096 kB Tue Apr 18 23:02:56 EDT 2006 running: zgrep XENO /proc/config.gz CONFIG_XENOMAI=y CONFIG_XENO_OPT_NUCLEUS=y CONFIG_XENO_OPT_PERVASIVE=y CONFIG_XENO_OPT_SECURITY_ACCESS=y CONFIG_XENO_OPT_PIPE=y CONFIG_XENO_OPT_PIPE_NRDEV=32 CONFIG_XENO_OPT_REGISTRY=y CONFIG_XENO_OPT_REGISTRY_NRSLOTS=512 CONFIG_XENO_OPT_SYS_HEAPSZ=128 # CONFIG_XENO_OPT_ISHIELD is not set CONFIG_XENO_OPT_STATS=y # CONFIG_XENO_OPT_DEBUG is not set # CONFIG_XENO_OPT_WATCHDOG is not set CONFIG_XENO_OPT_TIMING_PERIODIC=y CONFIG_XENO_OPT_TIMING_PERIOD=0 CONFIG_XENO_OPT_TIMING_TIMERLAT=0 CONFIG_XENO_OPT_TIMING_SCHEDLAT=0 # CONFIG_XENO_OPT_SCALABLE_SCHED is not set CONFIG_XENO_OPT_TIMER_LIST=y # CONFIG_XENO_OPT_TIMER_HEAP is not set # CONFIG_XENO_OPT_SHIRQ_LEVEL is not set # CONFIG_XENO_OPT_SHIRQ_EDGE is not set CONFIG_XENO_HW_FPU=y # CONFIG_XENO_HW_NMI_DEBUG_LATENCY is not set # CONFIG_XENO_HW_SMI_DETECT_DISABLE is not set CONFIG_XENO_HW_SMI_DETECT=y CONFIG_XENO_HW_SMI_WORKAROUND=y CONFIG_XENO_HW_SMI_ALL=y CONFIG_XENO_SKIN_NATIVE=y CONFIG_XENO_OPT_NATIVE_PIPE=y CONFIG_XENO_OPT_NATIVE_PIPE_BUFSZ=4096 CONFIG_XENO_OPT_NATIVE_REGISTRY=y CONFIG_XENO_OPT_NATIVE_SEM=y CONFIG_XENO_OPT_NATIVE_EVENT=y CONFIG_XENO_OPT_NATIVE_MUTEX=y CONFIG_XENO_OPT_NATIVE_COND=y CONFIG_XENO_OPT_NATIVE_QUEUE=y CONFIG_XENO_OPT_NATIVE_HEAP=y CONFIG_XENO_OPT_NATIVE_ALARM=y CONFIG_XENO_OPT_NATIVE_MPS=y CONFIG_XENO_OPT_NATIVE_INTR=y CONFIG_XENO_SKIN_POSIX=y # CONFIG_XENO_SKIN_PSOS is not set # CONFIG_XENO_SKIN_UITRON is not set # CONFIG_XENO_SKIN_VRTX is not set # CONFIG_XENO_SKIN_VXWORKS is not set CONFIG_XENO_SKIN_RTDM=y CONFIG_XENO_OPT_RTDM_FILDES=128 # CONFIG_XENO_SKIN_UVM is not set CONFIG_XENO_DRIVERS_16550A=m CONFIG_XENO_DRIVERS_TIMERBENCH=m Tue Apr 18 23:02:56 EDT 2006 running: cat /proc/ipipe/Linux Priority=100, Id=0x00000000 irq0-223: accepted irq224-225: grabbed, virtual irq226: passed, virtual Tue Apr 18 23:02:56 EDT 2006 running: cat /proc/ipipe/version 1.2-04 Tue Apr 18 23:02:56 EDT 2006 running: cat /proc/ipipe/Xenomai Priority=200, Id=0x58454e4f irq0-215: passed irq216: grabbed irq217-223: passed irq224-225: passed, virtual irq226: grabbed, virtual Tue Apr 18 23:02:56 EDT 2006 running: generate_loads 1 dd workload started, pids 4270 Tue Apr 18 23:02:56 EDT 2006 running: cat /proc/interrupts CPU0 0: 55281 XT-PIC timer, rthal_broadcast_timer 1: 100 XT-PIC i8042 2: 0 XT-PIC cascade 3: 3 XT-PIC ehci_hcd:usb1, ohci1394 4: 0 XT-PIC uhci_hcd:usb6 7: 2469 XT-PIC ipw2200, ehci_hcd:usb2, uhci_hcd:usb7 8: 1 XT-PIC rtc 9: 499 XT-PIC acpi, Intel 82801DB-ICH4, Intel 82801DB-ICH4 Modem, ohci_hcd:usb3, ohci_hcd:usb4, uhci_hcd:usb5, yenta, eth0 11: 5 XT-PIC sonypi 12: 490 XT-PIC i8042 14: 17005 XT-PIC ide0 15: 1514 XT-PIC ide1 NMI: 0 LOC: 55249 ERR: 0 MIS: 0 Tue Apr 18 23:02:56 EDT 2006 running: cat /proc/loadavg 0.59 0.69 0.31 5/148 4274 Tue Apr 18 23:02:56 EDT 2006 running: top -bn1c top - 23:02:57 up 3 min, 14 users, load average: 0.59, 0.69, 0.31 Tasks: 138 total, 2 running, 136 sleeping, 0 stopped, 0 zombie Cpu(s): 6.4% us, 2.1% sy, 0.2% ni, 50.3% id, 41.0% wa, 0.0% hi, 0.0% si Mem: 514872k total, 354280k used, 160592k free, 43412k buffers Swap: 1048280k total, 0k used, 1048280k free, 187884k cached PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND 4270 root 18 0 3608 416 352 R 19.8 0.1 0:00.13 dd if /dev/hda1 of /dev/null 1 root 16 0 1736 572 496 S 0.0 0.1 0:00.53 init [5] 2 root 34 19 0 0 0 S 0.0 0.0 0:00.00 [ksoftirqd/0] Tue Apr 18 23:02:57 EDT 2006 running: ./run -- -l 30 -h -s -T 120 -t0 * * * Type ^C to stop this application. * * == Sampling period: 100 us == Test mode: periodic user-mode task == All results in microseconds warming up... RTT| 00:00:01 (periodic user-mode task, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -0.966| -0.771| 3.850| 0| -0.966| 3.850 RTD| -0.975| -0.759| 4.203| 0| -0.975| 4.203 RTD| -0.986| -0.755| 3.445| 0| -0.986| 4.203 RTD| -0.965| -0.758| 3.305| 0| -0.986| 4.203 RTD| -0.974| -0.759| 3.426| 0| -0.986| 4.203 RTD| -0.966| -0.753| 4.038| 0| -0.986| 4.203 RTD| -0.970| -0.757| 3.619| 0| -0.986| 4.203 RTD| -0.961| -0.757| 3.381| 0| -0.986| 4.203 RTD| -0.984| -0.761| 3.393| 0| -0.986| 4.203 RTD| -0.971| -0.758| 3.383| 0| -0.986| 4.203 RTD| -1.004| -0.756| 3.429| 0| -1.004| 4.203 RTD| -0.966| -0.752| 3.425| 0| -1.004| 4.203 RTD| -0.974| -0.756| 4.146| 0| -1.004| 4.203 RTD| -0.973| -0.757| 3.582| 0| -1.004| 4.203 RTD| -0.971| -0.754| 3.343| 0| -1.004| 4.203 RTD| -0.975| -0.755| 3.978| 0| -1.004| 4.203 RTD| -0.967| -0.757| 3.834| 0| -1.004| 4.203 RTD| -0.969| -0.751| 4.283| 0| -1.004| 4.283 RTD| -0.973| -0.753| 4.527| 0| -1.004| 4.527 RTD| -0.984| -0.752| 3.891| 0| -1.004| 4.527 RTD| -0.988| -0.754| 3.393| 0| -1.004| 4.527 RTD| -0.975| -0.752| 3.385| 0| -1.004| 4.527 RTD| -0.968| -0.758| 3.313| 0| -1.004| 4.527 RTD| -0.979| -0.755| 3.622| 0| -1.004| 4.527 RTD| -0.961| -0.751| 3.529| 0| -1.004| 4.527 RTD| -0.987| -0.755| 3.228| 0| -1.004| 4.527 RTD| -0.966| -0.751| 3.451| 0| -1.004| 4.527 RTD| -0.960| -0.751| 3.460| 0| -1.004| 4.527 RTD| -0.993| -0.755| 3.393| 0| -1.004| 4.527 RTD| -0.983| -0.739| 3.753| 0| -1.004| 4.527 RTT| 00:00:31 (periodic user-mode task, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -0.965| -0.755| 3.934| 0| -1.004| 4.527 RTD| -0.970| -0.752| 3.465| 0| -1.004| 4.527 RTD| -0.965| -0.750| 4.374| 0| -1.004| 4.527 RTD| -0.964| -0.752| 3.840| 0| -1.004| 4.527 RTD| -0.975| -0.756| 4.403| 0| -1.004| 4.527 RTD| -0.972| -0.752| 3.916| 0| -1.004| 4.527 RTD| -0.996| -0.757| 4.122| 0| -1.004| 4.527 RTD| -0.968| -0.752| 3.417| 0| -1.004| 4.527 RTD| -0.977| -0.754| 3.363| 0| -1.004| 4.527 RTD| -0.958| -0.754| 3.446| 0| -1.004| 4.527 RTD| -0.980| -0.754| 3.404| 0| -1.004| 4.527 RTD| -0.966| -0.751| 3.501| 0| -1.004| 4.527 RTD| -0.971| -0.751| 3.616| 0| -1.004| 4.527 RTD| -0.976| -0.750| 3.434| 0| -1.004| 4.527 RTD| -0.974| -0.752| 4.162| 0| -1.004| 4.527 RTD| -0.967| -0.751| 3.478| 0| -1.004| 4.527 RTD| -0.951| -0.752| 3.451| 0| -1.004| 4.527 RTD| -0.974| -0.750| 3.569| 0| -1.004| 4.527 RTD| -0.959| -0.754| 3.290| 0| -1.004| 4.527 RTD| -0.979| -0.751| 3.552| 0| -1.004| 4.527 RTD| -0.967| -0.751| 3.441| 0| -1.004| 4.527 RTD| -0.986| -0.749| 4.161| 0| -1.004| 4.527 RTD| -0.968| -0.752| 3.575| 0| -1.004| 4.527 RTD| -0.971| -0.748| 4.389| 0| -1.004| 4.527 RTD| -0.977| -0.757| 3.317| 0| -1.004| 4.527 RTD| -0.971| -0.747| 3.399| 0| -1.004| 4.527 RTD| -0.966| -0.751| 3.516| 0| -1.004| 4.527 RTD| -0.968| -0.751| 3.336| 0| -1.004| 4.527 RTD| -0.963| -0.753| 3.854| 0| -1.004| 4.527 RTD| -0.985| -0.752| 3.438| 0| -1.004| 4.527 RTT| 00:01:01 (periodic user-mode task, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -0.987| -0.752| 3.366| 0| -1.004| 4.527 RTD| -0.961| -0.750| 3.455| 0| -1.004| 4.527 RTD| -0.977| -0.752| 3.357| 0| -1.004| 4.527 RTD| -0.998| -0.749| 3.707| 0| -1.004| 4.527 RTD| -0.956| -0.752| 5.049| 0| -1.004| 5.049 RTD| -0.961| -0.752| 3.448| 0| -1.004| 5.049 RTD| -0.992| -0.754| 3.373| 0| -1.004| 5.049 RTD| -0.957| -0.752| 3.416| 0| -1.004| 5.049 RTD| -0.981| -0.752| 4.316| 0| -1.004| 5.049 RTD| -0.965| -0.749| 4.575| 0| -1.004| 5.049 RTD| -1.003| -0.749| 3.591| 0| -1.004| 5.049 RTD| -0.980| -0.751| 3.592| 0| -1.004| 5.049 RTD| -0.962| -0.750| 3.391| 0| -1.004| 5.049 RTD| -0.988| -0.746| 3.496| 0| -1.004| 5.049 RTD| -0.980| -0.752| 3.291| 0| -1.004| 5.049 RTD| -0.965| -0.751| 3.543| 0| -1.004| 5.049 RTD| -0.975| -0.753| 3.250| 0| -1.004| 5.049 RTD| -0.960| -0.750| 3.541| 0| -1.004| 5.049 RTD| -0.965| -0.751| 3.385| 0| -1.004| 5.049 RTD| -0.956| -0.751| 4.803| 0| -1.004| 5.049 RTD| -0.960| -0.750| 4.277| 0| -1.004| 5.049 RTD| -0.964| -0.749| 3.579| 0| -1.004| 5.049 RTD| -0.964| -0.752| 3.021| 0| -1.004| 5.049 RTD| -0.972| -0.755| 2.248| 0| -1.004| 5.049 RTD| -0.970| -0.754| 3.457| 0| -1.004| 5.049 RTD| -0.965| -0.755| 3.270| 0| -1.004| 5.049 RTD| -0.981| -0.751| 3.481| 0| -1.004| 5.049 RTD| -0.969| -0.752| 2.907| 0| -1.004| 5.049 RTD| -0.968| -0.748| 3.732| 0| -1.004| 5.049 RTD| -0.960| -0.752| 3.561| 0| -1.004| 5.049 RTT| 00:01:31 (periodic user-mode task, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -0.983| -0.745| 4.390| 0| -1.004| 5.049 RTD| -0.961| -0.749| 4.129| 0| -1.004| 5.049 RTD| -0.962| -0.741| 3.460| 0| -1.004| 5.049 RTD| -0.964| -0.752| 4.117| 0| -1.004| 5.049 RTD| -0.993| -0.752| 2.266| 0| -1.004| 5.049 RTD| -0.976| -0.754| 2.707| 0| -1.004| 5.049 RTD| -0.960| -0.749| 3.850| 0| -1.004| 5.049 RTD| -0.961| -0.750| 3.487| 0| -1.004| 5.049 RTD| -0.968| -0.752| 3.845| 0| -1.004| 5.049 RTD| -0.961| -0.751| 3.428| 0| -1.004| 5.049 RTD| -0.977| -0.755| 3.777| 0| -1.004| 5.049 RTD| -0.962| -0.749| 3.784| 0| -1.004| 5.049 RTD| -0.982| -0.745| 3.499| 0| -1.004| 5.049 RTD| -0.970| -0.754| 3.357| 0| -1.004| 5.049 RTD| -0.962| -0.747| 3.743| 0| -1.004| 5.049 RTD| -0.993| -0.749| 3.636| 0| -1.004| 5.049 RTD| -0.958| -0.752| 3.414| 0| -1.004| 5.049 RTD| -0.967| -0.749| 4.432| 0| -1.004| 5.049 RTD| -0.972| -0.751| 4.824| 0| -1.004| 5.049 RTD| -0.951| -0.754| 3.434| 0| -1.004| 5.049 RTD| -0.962| -0.749| 6.490| 0| -1.004| 6.490 RTD| -0.964| -0.752| 3.592| 0| -1.004| 6.490 RTD| -0.968| -0.748| 3.445| 0| -1.004| 6.490 RTD| -0.995| -0.751| 4.946| 0| -1.004| 6.490 RTD| -0.970| -0.755| 4.952| 0| -1.004| 6.490 RTD| -0.976| -0.757| 3.412| 0| -1.004| 6.490 RTD| -0.958| -0.755| 4.858| 0| -1.004| 6.490 RTD| -0.954| -0.753| 3.422| 0| -1.004| 6.490 RTD| -0.963| -0.757| 3.432| 0| -1.004| 6.490 ---|--param|----range-|--samples HSD| min| 0 - 1 | 117 HSD| min| 1 - 2 | 2 ---|--param|----range-|--samples HSD| avg| 0 - 1 | 1197037 HSD| avg| 1 - 2 | 1782 HSD| avg| 2 - 3 | 469 HSD| avg| 3 - 4 | 506 HSD| avg| 4 - 5 | 27 HSD| avg| 5 - 6 | 1 HSD| avg| 6 - 7 | 1 ---|--param|----range-|--samples HSD| max| 2 - 3 | 4 HSD| max| 3 - 4 | 90 HSD| max| 4 - 5 | 23 HSD| max| 5 - 6 | 1 HSD| max| 6 - 7 | 1 HSH|--param|--samples-|--average--|---stddev-- HSS| min| 119| 0.017| 0.129 HSS| avg| 1199823| 0.004| 0.085 HSS| max| 119| 3.202| 0.546 ---|------------|------------|------------|--------|------------------------- RTS| -1.004| -0.752| 6.490| 0| 00:02:00/00:02:00 Tue Apr 18 23:05:13 EDT 2006 running: ./run -- -l 30 -h -s -T 120 -t1 * * * Type ^C to stop this application. * * == Sampling period: 100 us == Test mode: in-kernel periodic task == All results in microseconds warming up... RTT| 00:00:01 (in-kernel periodic task, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.090| -1.964| 2.254| 0| -2.090| 2.254 RTD| -2.090| -1.958| 1.504| 0| -2.090| 2.254 RTD| -2.090| -1.949| 1.454| 0| -2.090| 2.254 RTD| -2.090| -1.942| 1.987| 0| -2.090| 2.254 RTD| -2.090| -1.953| 2.347| 0| -2.090| 2.347 RTD| -2.090| -1.944| 2.379| 0| -2.090| 2.379 RTD| -2.091| -1.946| 1.689| 0| -2.091| 2.379 RTD| -2.090| -1.953| 2.162| 0| -2.091| 2.379 RTD| -2.090| -1.945| 2.485| 0| -2.091| 2.485 RTD| -2.090| -1.957| 2.415| 0| -2.091| 2.485 RTD| -2.090| -1.943| 3.082| 0| -2.091| 3.082 RTD| -2.090| -1.954| 2.809| 0| -2.091| 3.082 RTD| -2.090| -1.953| 2.763| 0| -2.091| 3.082 RTD| -2.090| -1.954| 2.265| 0| -2.091| 3.082 RTD| -2.090| -1.949| 2.722| 0| -2.091| 3.082 RTD| -2.090| -1.952| 2.124| 0| -2.091| 3.082 RTD| -2.089| -1.950| 2.208| 0| -2.091| 3.082 RTD| -2.090| -1.951| 2.230| 0| -2.091| 3.082 RTD| -2.090| -1.950| 2.239| 0| -2.091| 3.082 RTD| -2.091| -1.956| 3.893| 0| -2.091| 3.893 RTD| -2.090| -1.961| 2.203| 0| -2.091| 3.893 RTD| -2.090| -1.950| 3.133| 0| -2.091| 3.893 RTD| -2.089| -1.916| 2.994| 0| -2.091| 3.893 RTD| -2.090| -1.945| 2.254| 0| -2.091| 3.893 RTD| -2.090| -1.955| 2.006| 0| -2.091| 3.893 RTD| -2.090| -1.952| 2.139| 0| -2.091| 3.893 RTD| -2.090| -1.953| 1.815| 0| -2.091| 3.893 RTD| -2.090| -1.958| 2.208| 0| -2.091| 3.893 RTD| -2.090| -1.958| 4.154| 0| -2.091| 4.154 RTD| -2.090| -1.955| 2.183| 0| -2.091| 4.154 RTT| 00:00:31 (in-kernel periodic task, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.090| -1.841| 2.425| 0| -2.091| 4.154 RTD| -2.089| -1.645| 2.841| 0| -2.091| 4.154 RTD| -2.090| -1.911| 2.191| 0| -2.091| 4.154 RTD| -2.090| -1.941| 2.356| 0| -2.091| 4.154 RTD| -2.090| -1.929| 2.153| 0| -2.091| 4.154 RTD| -2.090| -1.953| 2.084| 0| -2.091| 4.154 RTD| -2.090| -1.940| 2.349| 0| -2.091| 4.154 RTD| -2.090| -1.951| 2.125| 0| -2.091| 4.154 RTD| -2.090| -1.945| 2.701| 0| -2.091| 4.154 RTD| -2.090| -1.945| 2.271| 0| -2.091| 4.154 RTD| -2.090| -1.937| 4.089| 0| -2.091| 4.154 RTD| -2.090| -1.952| 2.699| 0| -2.091| 4.154 RTD| -2.090| -1.946| 2.568| 0| -2.091| 4.154 RTD| -2.090| -1.948| 1.820| 0| -2.091| 4.154 RTD| -2.091| -1.854| 2.491| 0| -2.091| 4.154 RTD| -2.089| -1.911| 2.323| 0| -2.091| 4.154 RTD| -2.090| -1.950| 2.431| 0| -2.091| 4.154 RTD| -2.090| -1.944| 2.306| 0| -2.091| 4.154 RTD| -2.090| -1.954| 1.875| 0| -2.091| 4.154 RTD| -2.090| -1.957| 2.146| 0| -2.091| 4.154 RTD| -2.090| -1.950| 2.346| 0| -2.091| 4.154 RTD| -2.090| -1.937| 2.170| 0| -2.091| 4.154 RTD| -2.091| -1.947| 2.352| 0| -2.091| 4.154 RTD| -2.090| -1.947| 2.190| 0| -2.091| 4.154 RTD| -2.090| -1.946| 2.337| 0| -2.091| 4.154 RTD| -2.090| -1.955| 2.051| 0| -2.091| 4.154 RTD| -2.090| -1.943| 1.898| 0| -2.091| 4.154 RTD| -2.090| -1.954| 1.805| 0| -2.091| 4.154 RTD| -2.090| -1.948| 2.398| 0| -2.091| 4.154 RTD| -2.090| -1.948| 2.346| 0| -2.091| 4.154 RTT| 00:01:01 (in-kernel periodic task, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.091| -1.956| 1.662| 0| -2.091| 4.154 RTD| -2.090| -1.952| 2.319| 0| -2.091| 4.154 RTD| -2.089| -1.962| 3.513| 0| -2.091| 4.154 RTD| -2.090| -1.957| 2.270| 0| -2.091| 4.154 RTD| -2.090| -1.948| 3.549| 0| -2.091| 4.154 RTD| -2.090| -1.948| 1.684| 0| -2.091| 4.154 RTD| -2.090| -1.952| 2.242| 0| -2.091| 4.154 RTD| -2.090| -1.940| 2.713| 0| -2.091| 4.154 RTD| -2.090| -1.942| 2.208| 0| -2.091| 4.154 RTD| -2.091| -1.933| 2.098| 0| -2.091| 4.154 RTD| -2.090| -1.935| 2.256| 0| -2.091| 4.154 RTD| -2.090| -1.936| 3.369| 0| -2.091| 4.154 RTD| -2.090| -1.929| 2.549| 0| -2.091| 4.154 RTD| -2.090| -1.924| 2.160| 0| -2.091| 4.154 RTD| -2.089| -1.889| 1.821| 0| -2.091| 4.154 RTD| -2.089| -1.674| 2.705| 0| -2.091| 4.154 RTD| -2.090| -1.938| 2.428| 0| -2.091| 4.154 RTD| -2.090| -1.899| 4.916| 0| -2.091| 4.916 RTD| -2.091| -1.951| 2.183| 0| -2.091| 4.916 RTD| -2.090| -1.917| 2.545| 0| -2.091| 4.916 RTD| -2.090| -1.905| 2.775| 0| -2.091| 4.916 RTD| -2.090| -1.701| 2.912| 0| -2.091| 4.916 RTD| -2.090| -1.885| 2.468| 0| -2.091| 4.916 RTD| -2.090| -1.857| 3.099| 0| -2.091| 4.916 RTD| -2.091| -1.853| 2.390| 0| -2.091| 4.916 RTD| -2.090| -1.834| 3.078| 0| -2.091| 4.916 RTD| -2.090| -1.922| 2.395| 0| -2.091| 4.916 RTD| -2.090| -1.917| 2.767| 0| -2.091| 4.916 RTD| -2.090| -1.914| 3.136| 0| -2.091| 4.916 RTD| -2.090| -1.915| 3.108| 0| -2.091| 4.916 RTT| 00:01:31 (in-kernel periodic task, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.090| -1.909| 3.345| 0| -2.091| 4.916 RTD| -2.090| -1.904| 2.743| 0| -2.091| 4.916 RTD| -2.090| -1.911| 2.507| 0| -2.091| 4.916 RTD| -2.090| -1.919| 2.305| 0| -2.091| 4.916 RTD| -2.090| -1.925| 2.761| 0| -2.091| 4.916 RTD| -2.089| -1.888| 2.642| 0| -2.091| 4.916 RTD| -2.090| -1.913| 2.198| 0| -2.091| 4.916 RTD| -2.090| -1.931| 1.917| 0| -2.091| 4.916 RTD| -2.092| -1.929| 2.845| 0| -2.092| 4.916 RTD| -2.090| -1.924| 3.357| 0| -2.092| 4.916 RTD| -2.090| -1.913| 2.540| 0| -2.092| 4.916 RTD| -2.090| -1.901| 2.519| 0| -2.092| 4.916 RTD| -2.090| -1.916| 2.115| 0| -2.092| 4.916 RTD| -2.092| -1.931| 2.345| 0| -2.092| 4.916 RTD| -2.090| -1.918| 2.466| 0| -2.092| 4.916 RTD| -2.090| -1.914| 2.772| 0| -2.092| 4.916 RTD| -2.090| -1.924| 2.128| 0| -2.092| 4.916 RTD| -2.090| -1.916| 2.903| 0| -2.092| 4.916 RTD| -2.092| -1.904| 2.250| 0| -2.092| 4.916 RTD| -2.090| -1.923| 2.574| 0| -2.092| 4.916 RTD| -2.090| -1.916| 2.582| 0| -2.092| 4.916 RTD| -2.091| -1.908| 2.142| 0| -2.092| 4.916 RTD| -2.090| -1.921| 2.664| 0| -2.092| 4.916 RTD| -2.090| -1.919| 4.276| 0| -2.092| 4.916 RTD| -2.090| -1.933| 2.503| 0| -2.092| 4.916 RTD| -2.090| -1.915| 2.288| 0| -2.092| 4.916 RTD| -2.090| -1.916| 2.524| 0| -2.092| 4.916 RTD| -2.090| -1.911| 2.490| 0| -2.092| 4.916 RTD| -2.090| -1.917| 2.956| 0| -2.092| 4.916 ---|--param|----range-|--samples HSD| min| 2 - 3 | 119 ---|--param|----range-|--samples HSD| avg| 0 - 1 | 8353 HSD| avg| 1 - 2 | 713371 HSD| avg| 2 - 3 | 478073 HSD| avg| 3 - 4 | 14 HSD| avg| 4 - 5 | 4 ---|--param|----range-|--samples HSD| max| 1 - 2 | 13 HSD| max| 2 - 3 | 90 HSD| max| 3 - 4 | 12 HSD| max| 4 - 5 | 4 HSH|--param|--samples-|--average--|---stddev-- HSS| min| 119| 2.000| 0.000 HSS| avg| 1199815| 1.392| 0.502 HSS| max| 119| 2.059| 0.586 ---|------------|------------|------------|--------|------------------------- RTS| -2.092| -1.925| 4.916| 0| 00:02:00/00:02:00 Tue Apr 18 23:07:39 EDT 2006 running: ./run -- -l 30 -h -s -T 120 -t2 * * * Type ^C to stop this application. * * == Sampling period: 100 us == Test mode: in-kernel timer handler == All results in microseconds warming up... RTT| 00:00:01 (in-kernel timer handler, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.427| -2.315| 1.436| 0| -2.427| 1.436 RTD| -2.427| -2.300| 2.087| 0| -2.427| 2.087 RTD| -2.427| -2.306| 1.550| 0| -2.427| 2.087 RTD| -2.427| -2.284| 1.696| 0| -2.427| 2.087 RTD| -2.427| -2.193| 1.858| 0| -2.427| 2.087 RTD| -2.427| -2.301| 2.342| 0| -2.427| 2.342 RTD| -2.427| -2.304| 1.704| 0| -2.427| 2.342 RTD| -2.427| -2.307| 2.022| 0| -2.427| 2.342 RTD| -2.427| -2.302| 2.156| 0| -2.427| 2.342 RTD| -2.426| -2.306| 1.489| 0| -2.427| 2.342 RTD| -2.426| -2.301| 1.992| 0| -2.427| 2.342 RTD| -2.427| -2.306| 2.452| 0| -2.427| 2.452 RTD| -2.427| -2.302| 2.376| 0| -2.427| 2.452 RTD| -2.427| -2.302| 1.598| 0| -2.427| 2.452 RTD| -2.427| -2.300| 2.157| 0| -2.427| 2.452 RTD| -2.429| -2.307| 1.555| 0| -2.429| 2.452 RTD| -2.427| -2.307| 1.900| 0| -2.429| 2.452 RTD| -2.427| -2.304| 1.689| 0| -2.429| 2.452 RTD| -2.427| -2.305| 1.738| 0| -2.429| 2.452 RTD| -2.427| -2.266| 2.121| 0| -2.429| 2.452 RTD| -2.427| -2.303| 2.198| 0| -2.429| 2.452 RTD| -2.427| -2.306| 2.565| 0| -2.429| 2.565 RTD| -2.427| -2.299| 1.732| 0| -2.429| 2.565 RTD| -2.427| -2.289| 1.671| 0| -2.429| 2.565 RTD| -2.427| -2.298| 1.898| 0| -2.429| 2.565 RTD| -2.427| -2.194| 1.183| 0| -2.429| 2.565 RTD| -2.426| -1.985| 2.235| 0| -2.429| 2.565 RTD| -2.427| -2.303| 1.234| 0| -2.429| 2.565 RTD| -2.427| -2.256| 1.862| 0| -2.429| 2.565 RTD| -2.427| -2.301| 1.822| 0| -2.429| 2.565 RTT| 00:00:31 (in-kernel timer handler, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.427| -2.204| 1.266| 0| -2.429| 2.565 RTD| -2.426| -2.201| 1.861| 0| -2.429| 2.565 RTD| -2.427| -2.291| 1.815| 0| -2.429| 2.565 RTD| -2.427| -2.296| 1.378| 0| -2.429| 2.565 RTD| -2.427| -2.267| 1.416| 0| -2.429| 2.565 RTD| -2.427| -2.127| 1.722| 0| -2.429| 2.565 RTD| -2.427| -2.308| 1.275| 0| -2.429| 2.565 RTD| -2.427| -2.295| 1.243| 0| -2.429| 2.565 RTD| -2.427| -2.294| 1.499| 0| -2.429| 2.565 RTD| -2.427| -2.290| 1.622| 0| -2.429| 2.565 RTD| -2.427| -2.276| 1.882| 0| -2.429| 2.565 RTD| -2.427| -2.280| 1.819| 0| -2.429| 2.565 RTD| -2.427| -2.264| 1.797| 0| -2.429| 2.565 RTD| -2.426| -1.952| 1.646| 0| -2.429| 2.565 RTD| -2.427| -2.281| 2.032| 0| -2.429| 2.565 RTD| -2.427| -2.299| 1.613| 0| -2.429| 2.565 RTD| -2.427| -2.294| 1.457| 0| -2.429| 2.565 RTD| -2.427| -2.291| 1.807| 0| -2.429| 2.565 RTD| -2.427| -2.289| 1.771| 0| -2.429| 2.565 RTD| -2.427| -2.301| 1.449| 0| -2.429| 2.565 RTD| -2.427| -2.304| 1.485| 0| -2.429| 2.565 RTD| -2.427| -2.305| 1.613| 0| -2.429| 2.565 RTD| -2.427| -2.295| 1.959| 0| -2.429| 2.565 RTD| -2.427| -2.298| 0.774| 0| -2.429| 2.565 RTD| -2.427| -2.292| 1.807| 0| -2.429| 2.565 RTD| -2.427| -2.291| 2.193| 0| -2.429| 2.565 RTD| -2.427| -2.302| 2.368| 0| -2.429| 2.565 RTD| -2.427| -2.298| 1.467| 0| -2.429| 2.565 RTD| -2.427| -2.301| 1.893| 0| -2.429| 2.565 RTD| -2.427| -2.299| 1.259| 0| -2.429| 2.565 RTT| 00:01:01 (in-kernel timer handler, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.427| -2.293| 1.509| 0| -2.429| 2.565 RTD| -2.427| -2.204| 1.578| 0| -2.429| 2.565 RTD| -2.427| -2.207| 1.728| 0| -2.429| 2.565 RTD| -2.427| -2.290| 1.564| 0| -2.429| 2.565 RTD| -2.427| -2.248| 1.632| 0| -2.429| 2.565 RTD| -2.427| -2.081| 1.096| 0| -2.429| 2.565 RTD| -2.428| -2.293| 1.064| 0| -2.429| 2.565 RTD| -2.426| -2.298| 1.782| 0| -2.429| 2.565 RTD| -2.427| -2.288| 1.974| 0| -2.429| 2.565 RTD| -2.427| -2.301| 0.939| 0| -2.429| 2.565 RTD| -2.427| -2.297| 1.449| 0| -2.429| 2.565 RTD| -2.427| -2.294| 2.194| 0| -2.429| 2.565 RTD| -2.427| -2.290| 1.868| 0| -2.429| 2.565 RTD| -2.427| -2.293| 2.850| 0| -2.429| 2.850 RTD| -2.427| -2.277| 2.187| 0| -2.429| 2.850 RTD| -2.427| -2.042| 1.918| 0| -2.429| 2.850 RTD| -2.427| -2.298| 1.046| 0| -2.429| 2.850 RTD| -2.427| -2.300| 1.044| 0| -2.429| 2.850 RTD| -2.427| -2.298| 1.365| 0| -2.429| 2.850 RTD| -2.427| -2.296| 1.722| 0| -2.429| 2.850 RTD| -2.427| -2.298| 1.577| 0| -2.429| 2.850 RTD| -2.427| -2.300| 1.463| 0| -2.429| 2.850 RTD| -2.427| -2.295| 2.109| 0| -2.429| 2.850 RTD| -2.428| -2.293| 1.745| 0| -2.429| 2.850 RTD| -2.426| -2.298| 1.550| 0| -2.429| 2.850 RTD| -2.427| -2.295| 2.441| 0| -2.429| 2.850 RTD| -2.426| -2.298| 1.012| 0| -2.429| 2.850 RTD| -2.427| -2.299| 1.082| 0| -2.429| 2.850 RTD| -2.426| -2.298| 1.296| 0| -2.429| 2.850 RTD| -2.427| -2.302| 1.576| 0| -2.429| 2.850 RTT| 00:01:31 (in-kernel timer handler, 100 us period) RTH|-----lat min|-----lat avg|-----lat max|-overrun|----lat best|---lat worst RTD| -2.427| -2.270| 1.425| 0| -2.429| 2.850 RTD| -2.427| -2.181| 1.414| 0| -2.429| 2.850 RTD| -2.427| -2.299| 0.873| 0| -2.429| 2.850 RTD| -2.428| -2.301| 1.362| 0| -2.429| 2.850 RTD| -2.427| -2.278| 1.446| 0| -2.429| 2.850 RTD| -2.427| -2.111| 1.780| 0| -2.429| 2.850 RTD| -2.427| -2.298| 1.891| 0| -2.429| 2.850 RTD| -2.427| -2.299| 1.801| 0| -2.429| 2.850 RTD| -2.427| -2.298| 1.009| 0| -2.429| 2.850 RTD| -2.427| -2.235| 1.869| 0| -2.429| 2.850 RTD| -2.426| -2.006| 1.020| 0| -2.429| 2.850 RTD| -2.427| -2.294| 1.242| 0| -2.429| 2.850 RTD| -2.427| -2.292| 1.794| 0| -2.429| 2.850 RTD| -2.427| -2.294| 1.615| 0| -2.429| 2.850 RTD| -2.427| -2.275| 1.866| 0| -2.429| 2.850 RTD| -2.427| -1.955| 1.743| 0| -2.429| 2.850 RTD| -2.427| -2.275| 1.615| 0| -2.429| 2.850 RTD| -2.427| -2.056| 1.400| 0| -2.429| 2.850 RTD| -2.427| -2.298| 2.256| 0| -2.429| 2.850 RTD| -2.427| -2.299| 2.210| 0| -2.429| 2.850 RTD| -2.426| -2.092| 0.621| 0| -2.429| 2.850 RTD| -2.427| -2.156| 1.802| 0| -2.429| 2.850 RTD| -2.427| -2.296| 1.790| 0| -2.429| 2.850 RTD| -2.427| -2.300| 1.330| 0| -2.429| 2.850 RTD| -2.427| -2.184| 0.676| 0| -2.429| 2.850 RTD| -2.426| -1.984| 1.417| 0| -2.429| 2.850 RTD| -2.427| -2.296| 3.137| 0| -2.429| 3.137 RTD| -2.427| -2.232| 1.189| 0| -2.429| 3.137 RTD| -2.427| -2.298| 1.453| 0| -2.429| 3.137 ---|--param|----range-|--samples HSD| min| 2 - 3 | 119 ---|--param|----range-|--samples HSD| avg| 0 - 1 | 6251 HSD| avg| 1 - 2 | 84543 HSD| avg| 2 - 3 | 1109040 HSD| avg| 3 - 4 | 1 ---|--param|----range-|--samples HSD| max| 0 - 1 | 5 HSD| max| 1 - 2 | 92 HSD| max| 2 - 3 | 21 HSD| max| 3 - 4 | 1 HSH|--param|--samples-|--average--|---stddev-- HSS| min| 119| 2.000| 0.000 HSS| avg| 1199835| 1.919| 0.291 HSS| max| 119| 1.151| 0.481 ---|------------|------------|------------|--------|------------------------- RTS| -2.429| -2.261| 3.137| 0| 00:02:00/00:02:00 Tue Apr 18 23:09:56 EDT 2006 running: cat /proc/interrupts CPU0 0: 160342 XT-PIC timer, rthal_broadcast_timer 1: 455 XT-PIC i8042 2: 0 XT-PIC cascade 3: 3 XT-PIC ehci_hcd:usb1, ohci1394 4: 0 XT-PIC uhci_hcd:usb6 7: 7558 XT-PIC ipw2200, ehci_hcd:usb2, uhci_hcd:usb7 8: 1 XT-PIC rtc 9: 1265 XT-PIC acpi, Intel 82801DB-ICH4, Intel 82801DB-ICH4 Modem, ohci_hcd:usb3, ohci_hcd:usb4, uhci_hcd:usb5, yenta, eth0 11: 5 XT-PIC sonypi 12: 3778 XT-PIC i8042 14: 100007 XT-PIC ide0 15: 5258 XT-PIC ide1 NMI: 0 LOC: 160310 ERR: 0 MIS: 0 Tue Apr 18 23:09:57 EDT 2006 running: cat /proc/loadavg 2.11 1.81 0.96 5/154 4611 Tue Apr 18 23:09:57 EDT 2006 running: top -bn1c top - 23:10:01 up 10 min, 14 users, load average: 2.18, 1.83, 0.97 Tasks: 141 total, 4 running, 137 sleeping, 0 stopped, 0 zombie Cpu(s): 8.3% us, 9.8% sy, 0.1% ni, 17.3% id, 64.5% wa, 0.0% hi, 0.0% si Mem: 514872k total, 508824k used, 6048k free, 247532k buffers Swap: 1048280k total, 164k used, 1048116k free, 54468k cached PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND 4270 root 18 0 3608 416 352 R 14.8 0.1 0:58.32 dd if /dev/hda1 of /dev/null 1 root 16 0 1736 572 496 S 0.0 0.1 0:00.53 init [5] 2 root 34 19 0 0 0 S 0.0 0.0 0:00.00 [ksoftirqd/0] Script done on Tue 18 Apr 2006 11:10:01 PM EDT --------------070402020802030703090004--