LinuxLists.cc - [PROBLEM] Machine Freezes while Running Crossover Office

[permalink] [raw]

Subject: Re: [PROBLEM] Machine Freezes while Running Crossover Office

On Sat, 28 May 2005, Pekka Enberg wrote:
>
> I did a binary search and found out that 2.6.10-bk10 introduced this
> bug. The kernel includes Linus' changes for pipes to use circular buffers.
> A oprofile run shows that kernel is spending lots of time in poll_pipe. I
> also have Alt-Sysrq-P traces that indicate to the same direction. I have
> included vmstat, Alt-SysRq-P, and oprofile traces in this mail (see below
> for section X.).

Can you try to see if you can get an "strace" snippet of X (or the window
manager) when this happens, since it seems to reproducible by you..

Also, I actually find it very surprising that you see X doing anything
_at_all_ with a pipe, since all the X connections should be just normal
sockets. There are no pipes involved anywhere afaik.

Your description sounds exactly like X is busy processing some slow
operation (or possibly the window manager, but I think virtual console
switches happen without the WM being involved). The most common such slow
operation is a new font being generated, but I don't see why that would
have anything to do with pipes either...

Linus

2005-05-29 18:21:44

[permalink] [raw]

Subject: Re: [PROBLEM] Machine Freezes while Running Crossover Office

Hi Linus,

On Sat, 28 May 2005, Pekka Enberg wrote:
> > I did a binary search and found out that 2.6.10-bk10 introduced this
> > bug. The kernel includes Linus' changes for pipes to use circular buffers.
> > A oprofile run shows that kernel is spending lots of time in poll_pipe. I
> > also have Alt-Sysrq-P traces that indicate to the same direction. I have
> > included vmstat, Alt-SysRq-P, and oprofile traces in this mail (see below
> > for section X.).

On 5/29/05, Linus Torvalds <[email protected]> wrote:
> Can you try to see if you can get an "strace" snippet of X (or the window
> manager) when this happens, since it seems to reproducible by you..
>
> Also, I actually find it very surprising that you see X doing anything
> _at_all_ with a pipe, since all the X connections should be just normal
> sockets. There are no pipes involved anywhere afaik.
>
> Your description sounds exactly like X is busy processing some slow
> operation (or possibly the window manager, but I think virtual console
> switches happen without the WM being involved). The most common such slow
> operation is a new font being generated, but I don't see why that would
> have anything to do with pipes either...

Looking at output of lsof, I can see that Crossover is using pipes. I
am not very familiar with wine internals but there seems to be two
processes, wine_preloader and wine, that talk to each other through
pipes. Unfortunately, stracing either one of the processes masks the
problem. That is, I cannot reproduce the hang while doing strace.

Pekka

2005-05-29 18:48:30

[permalink] [raw]

Subject: Re: [PROBLEM] Machine Freezes while Running Crossover Office

On Sun, 29 May 2005, Pekka Enberg wrote:
>
> Looking at output of lsof, I can see that Crossover is using pipes. I
> am not very familiar with wine internals but there seems to be two
> processes, wine_preloader and wine, that talk to each other through
> pipes. Unfortunately, stracing either one of the processes masks the
> problem. That is, I cannot reproduce the hang while doing strace.

Ahh.

I suspect that the _real_ change is that the pipe can now fill up with
sixteen times more data (ie 64kB of data in one read() or write()
operation rather than 4kB), and that as a result Crossover may be doing a
lot bigger requests to the X server too.

Alternatively, it's possible that Crossover has taken mouse focus (does
the mouse move around while the machine is "frozen"?) and Crossover itself
is confused by the bigger buffers and pauses due to some bug while it is
holding on to the mouse focus - making the system unusable. It's probably
some race that triggers this (getting data at the right speed), and
tracing it just changes timing enough that you won't see it.

(Btw, if you didn't already, I'd suggest forcing strace output to a file,
not the screen, since that at least changes timings and X interactions
_less_)

The pipe_poll() thing was possibly true even before - if the two main
processes are communicating over a pipe, it's quite possible that
pipe_poll() ends up being the most common op by far. See if the poll
timeout changes (or is zero).

Linus

2005-05-29 20:52:55

[permalink] [raw]

Subject: Re: [PROBLEM] Machine Freezes while Running Crossover Office

Hi Linus,

On Sun, 2005-05-29 at 11:49 -0700, Linus Torvalds wrote:
> I suspect that the _real_ change is that the pipe can now fill up with
> sixteen times more data (ie 64kB of data in one read() or write()
> operation rather than 4kB), and that as a result Crossover may be doing a
> lot bigger requests to the X server too.
>
> Alternatively, it's possible that Crossover has taken mouse focus (does
> the mouse move around while the machine is "frozen"?) and Crossover itself
> is confused by the bigger buffers and pauses due to some bug while it is
> holding on to the mouse focus - making the system unusable. It's probably
> some race that triggers this (getting data at the right speed), and
> tracing it just changes timing enough that you won't see it.
>
> (Btw, if you didn't already, I'd suggest forcing strace output to a file,
> not the screen, since that at least changes timings and X interactions
> _less_)
>
> The pipe_poll() thing was possibly true even before - if the two main
> processes are communicating over a pipe, it's quite possible that
> pipe_poll() ends up being the most common op by far. See if the poll
> timeout changes (or is zero).

The mouse cursor does not move and the screen does not refresh. The
machine locks up completely for few seconds (actually more like 5-10 s)
and then the system comes back up (after which it can be used normally).
I cannot even switch virtual consoles. Please note that I can
immediately reproduce the problem again as many times as I want by doing
the test scenario.

Forcing strace to output to a file does not change anything. I still
cannot reproduce the problem. I've included lsof output, oprofile, and
snippet of strace for a run where I am doing the same test scenario but
failing to reproduce the bug. The real strace is quite large (4 MBs for
five to ten seconds of running application) but the bits I have included
here seem to repeat itself. I am tracing the process that actually runs
WINWORD.EXE.

Is it possible that your changes for pipes to fill up to 64 KB confuses
pipe_poll and friends? The funny thing is that when I am stracing (and
thus not hitting the problem), I do not see _any_ calls to sys_poll but
when I _do_ hit the bug, pipe_poll clearly shows up in oprofile. Also,
don't ptrace calls use signals? I can see plenty of paths in the pipe
code doing signal_pending() and exiting when the condition is true.

Pekka

COMMAND PID USER FD TYPE DEVICE SIZE NODE NAME
wine-prel 9368 penberg cwd DIR 3,3 4096 311341 /home/penberg
wine-prel 9368 penberg rtd DIR 3,3 4096 2 /
wine-prel 9368 penberg txt REG 3,3 9076 1881956 /opt/cxoffice/bin/wine-preloader
wine-prel 9368 penberg mem REG 3,3 1867306 /home/penberg/.cxoffice/dotwine/fake_windows/Program (stat: No such file or directory)
wine-prel 9368 penberg mem REG 3,3 2779548 366172 /home/penberg/.cxoffice/dotwine/fake_windows/Windows/System/msi.dll
wine-prel 9368 penberg mem REG 3,3 1086080 /home/penberg/.cxoffice/dotwine/fake_windows/Program (stat: No such file or directory)
wine-prel 9368 penberg mem REG 3,3 1086064 /home/penberg/.cxoffice/dotwine/fake_windows/Program (stat: No such file or directory)
wine-prel 9368 penberg mem REG 3,3 421888 1772930 /home/penberg/.cxoffice/dotwine/fake_windows/Windows/System/RICHED20.DLL
wine-prel 9368 penberg mem REG 3,3 1866707 /home/penberg/.cxoffice/dotwine/fake_windows/Program (stat: No such file or directory)
wine-prel 9368 penberg mem REG 3,3 1866785 /home/penberg/.cxoffice/dotwine/fake_windows/Program (stat: No such file or directory)
wine-prel 9368 penberg mem REG 3,3 18192 366453 /home/penberg/.cxoffice/dotwine/fake_windows/Windows/System/sage.dll
wine-prel 9368 penberg mem REG 3,3 614672 366194 /home/penberg/.cxoffice/dotwine/fake_windows/Windows/System/OLEAUT32.DLL
wine-prel 9368 penberg mem REG 3,3 775952 366234 /home/penberg/.cxoffice/dotwine/fake_windows/Windows/System/ole32.dll
wine-prel 9368 penberg DEL REG 3,3 3093908 /tmp/.wine-1000/server-5962d/anonmap.ZEpqO4
wine-prel 9368 penberg mem REG 3,3 321296 366240 /home/penberg/.cxoffice/dotwine/fake_windows/Windows/System/rpcrt4.dll
wine-prel 9368 penberg mem REG 3,3 391168 364896 /home/penberg/.cxoffice/dotwine/fake_windows/Windows/System/SHLWAPI.DLL
wine-prel 9368 penberg mem REG 3,3 456388 1898134 /opt/cxoffice/lib/wine/ntdll.dll.so
wine-prel 9368 penberg mem REG 3,3 6472 1881957 /opt/cxoffice/bin/wine-pthread
wine-prel 9368 penberg mem REG 0,0 0 [heap] (stat: No such file or directory)
wine-prel 9368 penberg mem REG 3,3 257636 1086001 /home/penberg/.cxoffice/dotwine/fake_windows/Windows/Fonts/Tahoma.TTF
wine-prel 9368 penberg mem REG 3,3 333900 2852689 /usr/share/fonts/corefonts/timesbd.ttf
wine-prel 9368 penberg mem REG 3,3 142312 1898158 /opt/cxoffice/lib/wine/msacm32.dll.so
wine-prel 9368 penberg mem REG 3,3 249028 1898044 /opt/cxoffice/lib/wine/wineoss.drv.so
wine-prel 9368 penberg mem REG 3,3 468580 1898059 /opt/cxoffice/lib/wine/winmm.dll.so
wine-prel 9368 penberg mem REG 3,3 105944 1898076 /opt/cxoffice/lib/wine/version.dll.so
wine-prel 9368 penberg mem REG 3,3 6528 1079723 /home/penberg/.cxoffice/dotwine/fake_windows/Windows/Fonts/wine_vgasys.fon
wine-prel 9368 penberg mem REG 3,3 286620 2208495 /usr/share/fonts/corefonts/arialbd.ttf
wine-prel 9368 penberg mem REG 3,3 305220 1898064 /opt/cxoffice/lib/wine/winedos.dll.so
wine-prel 9368 penberg mem REG 3,3 275572 2208494 /usr/share/fonts/corefonts/arial.ttf
wine-prel 9368 penberg mem REG 3,3 302688 2208501 /usr/share/fonts/corefonts/cour.ttf
wine-prel 9368 penberg mem REG 3,3 63684 1085990 /home/penberg/.cxoffice/dotwine/fake_windows/Windows/Fonts/VeraIt.ttf
wine-prel 9368 penberg DEL REG 3,3 3093919 /tmp/.wine-1000/server-5962d/anonmap.5J7DDs
wine-prel 9368 penberg mem REG 3,3 98272 1898049 /opt/cxoffice/lib/wine/msacm.drv.so
wine-prel 9368 penberg mem REG 3,3 252384 1086000 /home/penberg/.cxoffice/dotwine/fake_windows/Windows/Fonts/TahomaBD.TTF
wine-prel 9368 penberg DEL REG 3,3 3093917 /tmp/.wine-1000/server-5962d/anonmap.O8NFES
wine-prel 9368 penberg DEL REG 3,3 3093915 /tmp/.wine-1000/server-5962d/anonmap.rddWfB
wine-prel 9368 penberg mem REG 3,3 85416 1898050 /opt/cxoffice/lib/wine/midimap.drv.so
wine-prel 9368 penberg mem REG 3,3 93156 1898165 /opt/cxoffice/lib/wine/lz32.dll.so
wine-prel 9368 penberg DEL REG 3,3 3093918 /tmp/.wine-1000/server-5962d/anonmap.CWi6XR
wine-prel 9368 penberg mem REG 3,3 20112 1081110 /home/penberg/.cxoffice/dotwine/fake_windows/Windows/Fonts/wine_sserife.fon
wine-prel 9368 penberg mem REG 3,3 330412 2852688 /usr/share/fonts/corefonts/times.ttf
wine-prel 9368 penberg DEL REG 3,3 3093916 /tmp/.wine-1000/server-5962d/anonmap.tvXNbP
wine-prel 9368 penberg DEL REG 3,3 3093913 /tmp/.wine-1000/server-5962d/anonmap.6SK7BF
wine-prel 9368 penberg DEL REG 3,3 3093911 /tmp/.wine-1000/server-5962d/anonmap.0kzLDZ
wine-prel 9368 penberg mem REG 3,3 1086139 /home/penberg/.cxoffice/dotwine/fake_windows/Program (stat: No such file or directory)
wine-prel 9368 penberg DEL REG 3,3 3093909 /tmp/.wine-1000/server-5962d/anonmap.UpYvef
wine-prel 9368 penberg mem REG 3,3 1086147 /home/penberg/.cxoffice/dotwine/fake_windows/Program (stat: No such file or directory)
wine-prel 9368 penberg mem REG 3,3 1075024 932291 /usr/lib/libcrypto.so.0.9.7
wine-prel 9368 penberg mem REG 3,3 197760 967730 /usr/lib/libssl.so.0.9.7
wine-prel 9368 penberg mem REG 3,3 467516 1898062 /opt/cxoffice/lib/wine/wineps.dll.so
wine-prel 9368 penberg DEL REG 0,7 983066 /SYSV00000000
wine-prel 9368 penberg DEL REG 0,7 950300 /SYSV00000000
wine-prel 9368 penberg mem REG 3,3 114100 1407967 /usr/lib/libcups.so.2
wine-prel 9368 penberg DEL REG 3,3 3093914 /tmp/.wine-1000/server-5962d/anonmap.ifO9K1
wine-prel 9368 penberg mem REG 3,3 122084 1898176 /opt/cxoffice/lib/wine/imm32.dll.so
wine-prel 9368 penberg mem REG 3,3 142134 1948180 /usr/lib/X11/locale/lib/common/ximcp.so.2
wine-prel 9368 penberg mem REG 3,3 543927 2110396 /usr/lib/opengl/xorg-x11/lib/libGL.so.1.2
wine-prel 9368 penberg mem REG 3,3 937728 1833443 /usr/lib/libX11.so.6.2
wine-prel 9368 penberg mem REG 3,3 494724 1898026 /opt/cxoffice/lib/wine/x11drv.dll.so
wine-prel 9368 penberg mem REG 3,3 130732 1145690 /usr/lib/libexpat.so.0.5.0
wine-prel 9368 penberg mem REG 3,3 552344 1015017 /usr/lib/libfreetype.so.6.3.7
wine-prel 9368 penberg mem REG 3,3 152672 1804882 /usr/lib/libfontconfig.so.1.0.4
wine-prel 9368 penberg mem REG 3,3 98982 1833580 /usr/lib/libICE.so.6.3
wine-prel 9368 penberg mem REG 3,3 65928 2192834 /lib/libz.so.1.2.2
wine-prel 9368 penberg mem REG 3,3 37056 1249025 /usr/lib/gcc-lib/i686-pc-linux-gnu/3.3.4/libgcc_s.so.1
wine-prel 9368 penberg mem REG 3,3 9780 3402055 /usr/lib/gconv/ISO8859-15.so
wine-prel 9368 penberg mem REG 3,3 445516 1898034 /opt/cxoffice/lib/libcxfreetype.so.6.3.7
wine-prel 9368 penberg mem REG 3,3 143932 1898039 /opt/cxoffice/lib/wine/winspool.drv.so
wine-prel 9368 penberg mem REG 3,3 633124 1898218 /opt/cxoffice/lib/wine/comctl32.dll.so
wine-prel 9368 penberg mem REG 3,3 266293 366272 /home/penberg/.cxoffice/dotwine/fake_windows/Windows/System/MSVCRT.DLL
wine-prel 9368 penberg mem REG 3,3 575428 1898099 /opt/cxoffice/lib/wine/shell32.dll.so
wine-prel 9368 penberg mem REG 3,3 911140 1898086 /opt/cxoffice/lib/wine/user32.dll.so
wine-prel 9368 penberg mem REG 3,3 451236 1898186 /opt/cxoffice/lib/wine/gdi32.dll.so
wine-prel 9368 penberg mem REG 3,3 201604 1898231 /opt/cxoffice/lib/wine/advapi32.dll.so
wine-prel 9368 penberg mem REG 3,3 21544 278085 /usr/lib/gconv/gconv-modules.cache
wine-prel 9368 penberg mem REG 3,3 35170 1833584 /usr/lib/libXrender.so.1.2.2
wine-prel 9368 penberg mem REG 3,3 41579 1833612 /usr/lib/libXcursor.so.1.0.2
wine-prel 9368 penberg mem REG 3,3 178728 2882009 /usr/lib/locale/fi_FI@euro/LC_CTYPE
wine-prel 9368 penberg mem REG 3,3 1073956 1898173 /opt/cxoffice/lib/wine/kernel32.dll.so
wine-prel 9368 penberg mem REG 3,3 19005 1833471 /usr/lib/libXxf86vm.so.1.0
wine-prel 9368 penberg mem REG 3,3 35136 3304756 /lib/libnss_compat-2.3.5.so
wine-prel 9368 penberg mem REG 3,3 65868 1833600 /usr/lib/libXext.so.6.4
wine-prel 9368 penberg mem REG 3,3 37964 1833530 /usr/lib/libSM.so.6.0
wine-prel 9368 penberg mem REG 3,3 165848 2665726 /lib/tls/libm-2.3.5.so
wine-prel 9368 penberg mem REG 3,3 12586 1948177 /usr/lib/X11/locale/lib/common/xlcDef.so.2
wine-prel 9368 penberg mem REG 3,3 84024 3304441 /lib/libnsl-2.3.5.so
wine-prel 9368 penberg mem REG 3,3 994768 1897964 /opt/cxoffice/lib/libwine_unicode.so.1
wine-prel 9368 penberg DEL REG 3,3 3093907 /tmp/.wine-1000/server-5962d/anonmap.873qK8
wine-prel 9368 penberg mem REG 3,3 47760 3304426 /lib/libnss_files-2.3.5.so
wine-prel 9368 penberg mem REG 3,3 10672 3304415 /lib/libdl-2.3.5.so
wine-prel 9368 penberg mem REG 3,3 1302712 2668469 /lib/tls/libc-2.3.5.so
wine-prel 9368 penberg mem REG 3,3 166679 2569324 /lib/tls/libpthread-2.3.5.so
wine-prel 9368 penberg mem REG 3,3 39772 3304442 /lib/libnss_nis-2.3.5.so
wine-prel 9368 penberg mem REG 3,3 22556 1897963 /opt/cxoffice/lib/libwine.so.1
wine-prel 9368 penberg mem REG 3,3 94932 3304229 /lib/ld-2.3.5.so
wine-prel 9368 penberg 0u CHR 136,0 2 /dev/pts/0
wine-prel 9368 penberg 1u CHR 136,0 2 /dev/pts/0
wine-prel 9368 penberg 2u CHR 136,0 2 /dev/pts/0
wine-prel 9368 penberg 3u unix 0xd16a2980 16826 socket
wine-prel 9368 penberg 4w FIFO 0,5 16828 pipe
wine-prel 9368 penberg 5r FIFO 0,5 16829 pipe
wine-prel 9368 penberg 6u REG 3,3 1024 3093919 /tmp/.wine-1000/server-5962d/anonmap.5J7DDs (deleted)
wine-prel 9368 penberg 7r FIFO 0,5 16830 pipe
wine-prel 9368 penberg 8w FIFO 0,5 16830 pipe
wine-prel 9368 penberg 9u unix 0xd16a2380 16838 socket
wine-prel 9368 penberg 10u unix 0xcbd7ee00 16840 socket
wine-prel 9368 penberg 11u CHR 136,0 2 /dev/pts/0
wine-prel 9368 penberg 12u CHR 136,0 2 /dev/pts/0
wine-prel 9368 penberg 13u CHR 136,0 2 /dev/pts/0
wine-prel 9368 penberg 14u REG 3,3 126972 3093909 /tmp/.wine-1000/server-5962d/anonmap.UpYvef (deleted)
wine-prel 9368 penberg 15u REG 3,3 4 3093910 /tmp/.wine-1000/server-5962d/anonmap.e1t1BA (deleted)
wine-prel 9368 penberg 16u REG 3,3 126972 3093911 /tmp/.wine-1000/server-5962d/anonmap.0kzLDZ (deleted)
wine-prel 9368 penberg 17u REG 3,3 1024 3093913 /tmp/.wine-1000/server-5962d/anonmap.6SK7BF (deleted)
wine-prel 9368 penberg 18u REG 3,3 1024 3093914 /tmp/.wine-1000/server-5962d/anonmap.ifO9K1 (deleted)
wine-prel 9368 penberg 19u REG 3,3 27648 2131469 /home/penberg/.cxoffice/dotwine/fake_windows/Windows/Application Data/Microsoft/Mallit/Normal.dot
wine-prel 9368 penberg 20u REG 3,3 1024 3093913 /tmp/.wine-1000/server-5962d/anonmap.6SK7BF (deleted)
wine-prel 9368 penberg 21u REG 3,3 364 3093916 /tmp/.wine-1000/server-5962d/anonmap.tvXNbP (deleted)
wine-prel 9368 penberg 22u REG 3,3 126972 3093917 /tmp/.wine-1000/server-5962d/anonmap.O8NFES (deleted)
wine-prel 9368 penberg 23u REG 3,3 1024 3093918 /tmp/.wine-1000/server-5962d/anonmap.CWi6XR (deleted)
wine-prel 9368 penberg 24r REG 3,3 20480 331083 /home/penberg/anna/Annan ammattiraportti.doc
wine-prel 9368 penberg 25u REG 3,3 4 3093915 /tmp/.wine-1000/server-5962d/anonmap.rddWfB (deleted)
wine-prel 9368 penberg 26r REG 3,3 751534 1867273 /home/penberg/.cxoffice/dotwine/fake_windows/Program Files/Common Files/Microsoft Shared/Proof/MSSP_FI.LEX
wine-prel 9368 penberg 41r DIR 3,3 4096 311341 /home/penberg

CPU: CPU with timer interrupt, speed 0 MHz (estimated)
Profiling through timer interrupt
samples % symbol name
980 13.3953 default_idle
682 9.3220 schedule
253 3.4582 do_notify_parent_cldstop
218 2.9798 sysenter_past_esp
206 2.8157 try_to_wake_up
187 2.5560 fget_light
159 2.1733 dnotify_parent
155 2.1186 do_wait
154 2.1050 sys_rt_sigprocmask
145 1.9820 generic_file_buffered_write
143 1.9546 sys_ptrace
115 1.5719 fget
111 1.5172 sock_poll
111 1.5172 walk_page_buffers
110 1.5036 find_vma
105 1.4352 __copy_from_user_ll
99 1.3532 __wake_up
99 1.3532 find_lock_page
99 1.3532 rw_verify_area
98 1.3395 pipe_poll
97 1.3259 ext3_prepare_write
96 1.3122 unix_poll
89 1.2165 wait_task_stopped
85 1.1618 __generic_file_aio_write_nolock
85 1.1618 ext3_get_inode_block
77 1.0525 start_this_handle
73 0.9978 remove_wait_queue
66 0.9021 __mark_inode_dirty
65 0.8885 journal_add_journal_head
63 0.8611 copy_from_user
60 0.8201 kmem_cache_alloc
58 0.7928 balance_dirty_pages_ratelimited
57 0.7791 sigprocmask
54 0.7381 sys_poll
53 0.7244 access_process_vm
52 0.7108 __block_commit_write
52 0.7108 sys_write
51 0.6971 ptrace_check_attach
47 0.6424 do_pollfd
47 0.6424 pipe_writev
46 0.6288 current_fs_time
46 0.6288 kmem_cache_free
46 0.6288 ptrace_notify
44 0.6014 __find_get_block
44 0.6014 find_pid
43 0.5878 mark_page_accessed
38 0.5194 bit_waitqueue
37 0.5057 __copy_to_user_ll
34 0.4647 __follow_page
34 0.4647 ext3_ordered_commit_write
34 0.4647 inode_update_time
33 0.4511 add_wait_queue
32 0.4374 ptrace_stop
30 0.4101 __alloc_pages
29 0.3964 get_task_mm
28 0.3827 do_poll
28 0.3827 handle_IRQ_event
28 0.3827 wake_up_bit
27 0.3691 __block_prepare_write
27 0.3691 mmput
27 0.3691 new_handle
26 0.3554 journal_stop
26 0.3554 resume_userspace
26 0.3554 update_atime
25 0.3417 getreg
23 0.3144 __kmalloc
23 0.3144 pipe_readv
22 0.3007 ext3_do_update_inode
21 0.2870 get_user_pages
21 0.2870 vfs_read
20 0.2734 __wake_up_bit
20 0.2734 find_task_by_pid_type
19 0.2597 sys_wait4
18 0.2460 kfree
17 0.2324 delay_tsc
15 0.2050 ext3_journal_start_sb
15 0.2050 ide_do_request
15 0.2050 vfs_write
14 0.1914 do_wp_page
14 0.1914 eligible_child
14 0.1914 ext3_file_write
14 0.1914 sched_clock
13 0.1777 kill_fasync
13 0.1777 sys_gettimeofday
13 0.1777 sys_read
12 0.1640 find_extend_vma
11 0.1504 generic_file_aio_write
11 0.1504 prep_new_page
10 0.1367 __mod_timer
10 0.1367 do_readv_writev
10 0.1367 fput
10 0.1367 free_pages
10 0.1367 get_offset_tsc
10 0.1367 journal_dirty_data
9 0.1230 __copy_user_zeroing_intel
9 0.1230 __put_user_4
9 0.1230 do_get_write_access
9 0.1230 do_gettimeofday
9 0.1230 do_select
9 0.1230 do_sync_write
9 0.1230 ext3_mark_inode_dirty
9 0.1230 free_hot_cold_page
9 0.1230 page_waitqueue
8 0.1093 buffered_rmqueue
8 0.1093 find_get_page
8 0.1093 generic_commit_write
8 0.1093 journal_dirty_metadata
8 0.1093 normal_poll
8 0.1093 profile_hit
8 0.1093 schedule_timeout
8 0.1093 unlock_buffer
7 0.0957 do_syscall_trace
7 0.0957 ext3_dirty_inode
7 0.0957 follow_page
6 0.0820 cond_resched_lock
6 0.0820 copy_to_user
6 0.0820 do_page_fault
6 0.0820 ext3_writepage_trans_blocks
6 0.0820 free_hot_page
6 0.0820 journal_get_write_access
6 0.0820 mark_buffer_dirty
6 0.0820 tty_ldisc_deref
6 0.0820 unix_stream_sendmsg
5 0.0683 __journal_file_buffer
5 0.0683 ext3_reserve_inode_write
5 0.0683 input_event
5 0.0683 journal_blocks_per_page
5 0.0683 max_select_fd
5 0.0683 poll_freewait
4 0.0547 __get_free_pages
4 0.0547 __link_path_walk
4 0.0547 __log_space_left
4 0.0547 __mod_page_state
4 0.0547 __pollwait
4 0.0547 anon_pipe_buf_release
4 0.0547 ext3_journal_dirty_data
4 0.0547 ext3_new_inode
4 0.0547 journal_cancel_revoke
4 0.0547 journal_start
4 0.0547 pool_find_page
4 0.0547 syscall_trace_entry
4 0.0547 unix_peer_get
4 0.0547 unix_stream_recvmsg
4 0.0547 unlock_page
4 0.0547 work_resched
3 0.0410 __d_lookup
3 0.0410 __ext3_get_inode_loc
3 0.0410 __ext3_journal_stop
3 0.0410 __pagevec_lru_add
3 0.0410 bad_range
3 0.0410 block_prepare_write
3 0.0410 cond_resched
3 0.0410 do_ioctl
3 0.0410 do_no_page
3 0.0410 ext3_get_block_handle
3 0.0410 ext3_get_inode_loc
3 0.0410 ext3_test_allocatable
3 0.0410 journal_put_journal_head
3 0.0410 sock_alloc_send_pskb
3 0.0410 sock_def_readable
3 0.0410 sock_wfree
3 0.0410 tty_paranoia_check
3 0.0410 unix_write_space
2 0.0273 __insert_inode_hash
2 0.0273 activate_page
2 0.0273 alloc_skb
2 0.0273 anon_pipe_buf_map
2 0.0273 con_chars_in_buffer
2 0.0273 convert_fxsr_to_user
2 0.0273 do_mmap_pgoff
2 0.0273 dput
2 0.0273 ext3_get_block
2 0.0273 ext3_new_block
2 0.0273 find_next_usable_block
2 0.0273 get_signal_to_deliver
2 0.0273 group_send_sig_info
2 0.0273 handle_signal
2 0.0273 kfree_skbmem
2 0.0273 kref_put
2 0.0273 math_state_restore
2 0.0273 setup_frame
2 0.0273 skb_release_data
2 0.0273 sock_aio_read
2 0.0273 sock_aio_write
2 0.0273 sock_ioctl
2 0.0273 sys_ioctl
2 0.0273 sys_writev
2 0.0273 syscall_exit_work
2 0.0273 tty_poll
2 0.0273 unix_ioctl
2 0.0273 vfs_writev
2 0.0273 zone_watermark_ok
1 0.0137 __copy_user_intel
1 0.0137 __find_get_block_slow
1 0.0137 __fput
1 0.0137 __free_pages
1 0.0137 __getblk
1 0.0137 __set_page_dirty_nobuffers
1 0.0137 account
1 0.0137 add_dirent_to_buf
1 0.0137 add_input_randomness
1 0.0137 con_write_room
1 0.0137 copy_mm
1 0.0137 copy_pte_range
1 0.0137 copy_strings
1 0.0137 create_empty_buffers
1 0.0137 del_timer
1 0.0137 device_not_available
1 0.0137 do_setitimer
1 0.0137 ext3_find_goal
1 0.0137 ext3_get_branch
1 0.0137 ext3_getblk
1 0.0137 ext3_mark_iloc_dirty
1 0.0137 ext3_truncate
1 0.0137 follow_mount
1 0.0137 generic_file_llseek
1 0.0137 ide_outb
1 0.0137 ide_outl
1 0.0137 ide_wait_stat
1 0.0137 init_fpu
1 0.0137 journal_get_undo_access
1 0.0137 kcalloc
1 0.0137 kmem_getpages
1 0.0137 load_elf_binary
1 0.0137 lookup_create
1 0.0137 lookup_hash
1 0.0137 memcpy_fromiovec
1 0.0137 memcpy_toiovec
1 0.0137 path_lookup
1 0.0137 pipe_write
1 0.0137 randomize_stack_top
1 0.0137 read_block_bitmap
1 0.0137 remove_vm_struct
1 0.0137 restore_fpu
1 0.0137 restore_i387_fxsave
1 0.0137 save_i387
1 0.0137 sock_alloc_send_skb
1 0.0137 sock_readv_writev
1 0.0137 sock_sendmsg
1 0.0137 strncpy_from_user
1 0.0137 sys_close
1 0.0137 sys_fcntl64
1 0.0137 sys_futex
1 0.0137 sys_newuname
1 0.0137 sys_sched_yield
1 0.0137 sys_select
1 0.0137 tty_ldisc_try
1 0.0137 vfs_ioctl
1 0.0137 wake_up_process
1 0.0137 zone_statistics

read(7, "X\371\303\177\1\0\0\0", 8) = 8
ioctl(10, FIONREAD, [1280]) = 0
read(10, "\fHy\16\320\0 \3\0\0\0\0f\2\5\0\4\0`\10\3\0\0\0\377\377"..., 1280) = 1280
rt_sigprocmask(SIG_BLOCK, [HUP INT USR1 USR2 ALRM CHLD IO], [], 8) = 0
write(4, "\222\0\0\0\0\0\0\0\0\0\0\0v\0\1\0\1\0\0\0\0\0\0\0\0\0\0"..., 64) = 64
read(5, "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 64) = 64
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
rt_sigprocmask(SIG_BLOCK, [HUP INT USR1 USR2 ALRM CHLD IO], [], 8) = 0
write(4, "\214\0\0\0\0\0\0\0|\0\0\0v\0\1\0\0\0\0\0\0\0\0\0\0\0\0"..., 64) = 64
read(5, "\0\0\0\0\10\0\0\0\2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 64) = 64
read(5, "\214\0\1\0\212\0\1\0", 8) = 8
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
rt_sigprocmask(SIG_BLOCK, [HUP INT USR1 USR2 ALRM CHLD IO], [], 8) = 0
write(4, "\222\0\0\0\0\0\0\0\0\0\0\0\214\0\1\0\1\0\0\0\0\0\0\0\0"..., 64) = 64
read(5, "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 64) = 64
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
rt_sigprocmask(SIG_BLOCK, [HUP INT USR1 USR2 ALRM CHLD IO], [], 8) = 0
write(4, "\214\0\0\0\0\0\0\0|\0\0\0\214\0\1\0\0\0\0\0\0\0\0\0\0\0"..., 64) = 64
read(5, "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 64) = 64
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
rt_sigprocmask(SIG_BLOCK, [HUP INT USR1 USR2 ALRM CHLD IO], [], 8) = 0
write(4, "\214\0\0\0\0\0\0\0|\0\0\0\214\0\1\0\0\0\0\0\0\0\0\0\0\0"..., 64) = 64
read(5, "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 64) = 64
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
rt_sigprocmask(SIG_BLOCK, [HUP INT USR1 USR2 ALRM CHLD IO], [], 8) = 0
write(4, "\214\0\0\0\0\0\0\0|\0\0\0v\0\1\0\0\0\0\0\0\0\0\0\0\0\0"..., 64) = 64
read(5, "\0\0\0\0\10\0\0\0\2\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 64) = 64
read(5, "\214\0\1\0\212\0\1\0", 8) = 8
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
rt_sigprocmask(SIG_BLOCK, [HUP INT USR1 USR2 ALRM CHLD IO], [], 8) = 0
write(4, "\214\0\0\0\0\0\0\0|\0\0\0\214\0\1\0\0\0\0\0\0\0\0\0\0\0"..., 64) = 64
read(5, "\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0"..., 64) = 64
rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
rt_sigprocmask(SIG_BLOCK, [HUP INT USR1 USR2 ALRM CHLD IO], [], 8) = 0
write(4, "\214\0\0\0\0\0\0\0|\0\0\0v\0\1\0\0\0\0\0\0\0\0\0\0\0\0"..., 64) = 64

2005-05-29 22:57:44

[permalink] [raw]

Subject: Re: [PROBLEM] Machine Freezes while Running Crossover Office

On Sun, 29 May 2005, Pekka Enberg wrote:
>
> The mouse cursor does not move and the screen does not refresh. The
> machine locks up completely for few seconds (actually more like 5-10 s)
> and then the system comes back up (after which it can be used normally).
> I cannot even switch virtual consoles. Please note that I can
> immediately reproduce the problem again as many times as I want by doing
> the test scenario.

The thing is, your sysrq-P output clearly shows that it's all in wine, and
I'd be very surprised if this is not a codeweavers/wine bug. The pipe poll
code is literally a couple of lines long, and it's hard to introduce a bug
there. Especially a transient bug that goes away.

However, I don't understand how wine can block the X server from doing
even cursor updates. It might be a scheduler bug, of course. The one thing
a bigger pipe buffer does is end up changing scheduling behaviour.

(On the other hand, I would not be surprised if Wine does something that
makes X pause, like use DGA or whatever and tells X not to update the
screen, including cursors).

> Is it possible that your changes for pipes to fill up to 64 KB confuses
> pipe_poll and friends?

pipe_poll shouldn't get confused, but apps certainly could. If an app
"knows" that a pipe read can only return 4kB of data, it would obviously
get confused when that's no longer true.

> The funny thing is that when I am stracing (and
> thus not hitting the problem), I do not see _any_ calls to sys_poll but
> when I _do_ hit the bug, pipe_poll clearly shows up in oprofile.

Are you sure your oprofile PC map is correct?

Linus

2005-05-29 23:07:22

by Kyle Moffett

[permalink] [raw]

Subject: Re: [PROBLEM] Machine Freezes while Running Crossover Office

On May 29, 2005, at 18:59:03, Linus Torvalds wrote:
> On Sun, 29 May 2005, Pekka Enberg wrote:
>> The mouse cursor does not move and the screen does not refresh. The
>> machine locks up completely for few seconds (actually more like
>> 5-10 s)
>> and then the system comes back up (after which it can be used
>> normally).
>> I cannot even switch virtual consoles. Please note that I can
>> immediately reproduce the problem again as many times as I want by
>> doing
>> the test scenario.
>
> However, I don't understand how wine can block the X server from doing
> even cursor updates. It might be a scheduler bug, of course. The
> one thing
> a bigger pipe buffer does is end up changing scheduling behaviour.
>
> (On the other hand, I would not be surprised if Wine does something
> that
> makes X pause, like use DGA or whatever and tells X not to update the
> screen, including cursors).

If the application captured the mouse/keyboard and did not pass them
on to
X for some period of time, then X would appear to not respond to VT-
switch
requests, because those keys go through the same handler loop
(depending on
the way your X is configured, of course).

I've had incidents where a user forkbomb was able to hang X (and
therefore
the console) while our SCHED_RR rate-limited SSH daemon was running fine
and able to successfully kill the process.

Cheers,
Kyle Moffett

-----BEGIN GEEK CODE BLOCK-----
Version: 3.12
GCM/CS/IT/U d- s++: a18 C++++>$ UB/L/X/*++++(+)>$ P+++(++++)>$
L++++(+++) E W++(+) N+++(++) o? K? w--- O? M++ V? PS+() PE+(-) Y+
PGP+++ t+(+++) 5 X R? tv-(--) b++++(++) DI+ D+ G e->++++$ h!*()>++$
r !y?(-)
------END GEEK CODE BLOCK------

2005-05-30 15:26:14

[permalink] [raw]

Subject: Re: [PROBLEM] Machine Freezes while Running Crossover Office

Hi,

On Sun, 2005-05-29 at 15:59 -0700, Linus Torvalds wrote:
> However, I don't understand how wine can block the X server from doing
> even cursor updates. It might be a scheduler bug, of course. The one thing
> a bigger pipe buffer does is end up changing scheduling behaviour.
>
> (On the other hand, I would not be surprised if Wine does something that
> makes X pause, like use DGA or whatever and tells X not to update the
> screen, including cursors).

It is not just X. Running the following shell script when hitting the
bug:

#!/bin/sh

while : ; do
date
sleep 1
done

shows the following output:

penberg ~/pipe-test 49 ./show-date
Mon May 30 18:16:52 EEST 2005
Mon May 30 18:16:53 EEST 2005
Mon May 30 18:16:54 EEST 2005
Mon May 30 18:16:55 EEST 2005
Mon May 30 18:17:15 EEST 2005
Mon May 30 18:17:16 EEST 2005

It looks like no other processes other than wineserver and
wine-preloader get any CPU time (also evident from Sysrq-P traces).

On Sun, 2005-05-29 at 15:59 -0700, Linus Torvalds wrote:
> Are you sure your oprofile PC map is correct?

Yes, the pipe_poll calls come from wineserver actually, not
wine-preloader (of which I showed strace output before).

Any suggestions on how to debug this further? I am not sure I understood
your point about watching poll timeouts.

Pekka

2005-05-30 17:29:47

[permalink] [raw]

Subject: Re: [PROBLEM] Machine Freezes while Running Crossover Office

On Mon, 30 May 2005, Pekka Enberg wrote:
>
> On Sun, 2005-05-29 at 15:59 -0700, Linus Torvalds wrote:
> > However, I don't understand how wine can block the X server from doing
> > even cursor updates. It might be a scheduler bug, of course. The one thing
> > a bigger pipe buffer does is end up changing scheduling behaviour.
> >
> > (On the other hand, I would not be surprised if Wine does something that
> > makes X pause, like use DGA or whatever and tells X not to update the
> > screen, including cursors).
>
> It is not just X. Running the following shell script when hitting the
> bug:

Ok, this implies that the scheduler is really screwed up, we're not
scheduling anything else during that time.

Ingo, this sounds like you need to take a look.

Pekka, can you confirm that the SysRQ output in your original email was
from a "hung" time? Because that clearly shows that stuff is happening in
user space, which means that it's definitely not a kernel loop.

Also, pipes are a bit special from a scheduling standpoint because they
use the magic "synchronous wakeup" thing, and it might be worthwhile
trying to just change the two calls to "wake_up_interruptible_sync()" in
fs/pipe.c to the non-sync version (ie just remove the "_sync" part).

> It looks like no other processes other than wineserver and
> wine-preloader get any CPU time (also evident from Sysrq-P traces).

Indeed.

One more thing: are those processes given RT priority? Because if so, it
likely boils down to a wine bug again - a busy-looping high-priority
process is _supposed_ to do what you see.

Linus

2005-05-30 18:11:31

by Kyle Moffett

[permalink] [raw]

Subject: Re: [PROBLEM] Machine Freezes while Running Crossover Office

On May 30, 2005, at 13:31:07, Linus Torvalds wrote:
> On Mon, 30 May 2005, Pekka Enberg wrote:
>> It is not just X. Running the following shell script when hitting the
>> bug:
>
> Ok, this implies that the scheduler is really screwed up, we're not
> scheduling anything else during that time.

If X is hung and not accepting data on any of its sockets, then this
could hang the Xterm in the background, and therefore hang the printout
from the "date" process. What happens if you do this?

Switch to VT 1:
# while true; do date; sleep 1; done

Switch to X and trigger hang

Switch back to VT 1 and look at output

Cheers,
Kyle Moffett

-----BEGIN GEEK CODE BLOCK-----
Version: 3.12
GCM/CS/IT/U d- s++: a18 C++++>$ UB/L/X/*++++(+)>$ P+++(++++)>$
L++++(+++) E W++(+) N+++(++) o? K? w--- O? M++ V? PS+() PE+(-) Y+
PGP+++ t+(+++) 5 X R? tv-(--) b++++(++) DI+ D+ G e->++++$ h!*()>++$
r !y?(-)
------END GEEK CODE BLOCK------

2005-05-30 18:21:41

[permalink] [raw]

Subject: Re: [PROBLEM] Machine Freezes while Running Crossover Office

On Mon, 30 May 2005, Kyle Moffett wrote:
>
> If X is hung and not accepting data on any of its sockets, then this
> could hang the Xterm in the background, and therefore hang the printout
> from the "date" process.

Nope. There's a pty in between, and the pty buffer is much bigger than
just a few lines, so even if an xterm is hung, the program displaing on an
xterm wouldn't be affected normally (unless it reads from the tty or
outputs several kB of data).

So it's either a kernel scheduling problem, or Crossover running with RT
priority and screwing up.

Linus

2005-05-30 18:24:16

[permalink] [raw]

Subject: Re: [PROBLEM] Machine Freezes while Running Crossover Office

On Mon, 30 May 2005, Linus Torvalds wrote:
>
> So it's either a kernel scheduling problem, or Crossover running with RT
> priority and screwing up.

Btw, crossover being screwed up and runnign with RT priority would also
explain why stracing it makes the problem go away - the tracing will cause
the RT process to halt at system calls and yield to the tracer, which
isn't RT.

Of course, the same goes for a scheduler bug, so it's not like this proves
anything one way or the other, but considering that others aren't
reporting this problem with other programs..

Linus

2005-05-30 18:25:28

[permalink] [raw]

Subject: Re: [PROBLEM] Machine Freezes while Running Crossover Office

* Kyle Moffett <[email protected]> wrote:

> On May 30, 2005, at 13:31:07, Linus Torvalds wrote:
> >On Mon, 30 May 2005, Pekka Enberg wrote:
> >>It is not just X. Running the following shell script when hitting the
> >>bug:
> >
> >Ok, this implies that the scheduler is really screwed up, we're not
> >scheduling anything else during that time.
>
> If X is hung and not accepting data on any of its sockets, then this
> could hang the Xterm in the background, and therefore hang the
> printout from the "date" process. What happens if you do this?
>
> Switch to VT 1:
> # while true; do date; sleep 1; done
>
> Switch to X and trigger hang
>
> Switch back to VT 1 and look at output

yes, this would be an important test. Even better, do:

while true; do date; sleep 1; done > log.txt

to exclude any sort of console output as a source of delay.

Ingo

2005-05-30 19:14:57

[permalink] [raw]

Subject: Re: [PROBLEM] Machine Freezes while Running Crossover Office

* Linus Torvalds <[email protected]> wrote:

> On Mon, 30 May 2005, Linus Torvalds wrote:
> >
> > So it's either a kernel scheduling problem, or Crossover running with RT
> > priority and screwing up.
>
> Btw, crossover being screwed up and runnign with RT priority would
> also explain why stracing it makes the problem go away - the tracing
> will cause the RT process to halt at system calls and yield to the
> tracer, which isn't RT.
>
> Of course, the same goes for a scheduler bug, so it's not like this
> proves anything one way or the other, but considering that others
> aren't reporting this problem with other programs..

Pekka, if none of the previous methods helps in better debugging this,
then one of the easiest ways to catch scheduler latency bugs (e.g.
related to sync wakeups, etc.) would be to try the -RT tree.

It has a built-in kernel/scheduler tracer that gets started upon wakeup
and is stopped when the task finally runs. If we lose a preemption
somewhere then this kernel should catch it. The -RT tree is development
code, but should work fine on most systems. Here's a QuickStart:

1)
download the latest patch from http://redhat.com/~mingo/realtime-preempt/

2)
patch your 2.6.12-rc5 tree with it

3)
copy your usual .config into this tree and do 'make oldconfig' - just
accept the default options it offers, except for the following two
cases: when it says "Preemption Mode", pick #1:

1. No Forced Preemption (Server) (PREEMPT_NONE) (NEW)

when it asks:

Interrupts-off critical section latency timing (CRITICAL_IRQSOFF_TIMING)

pick 'y'.

when it asks:

Latency tracing (LATENCY_TRACE) [N/y/?] (NEW) y

pick 'y' too.

4) compile & install your kernel as ususal and reboot into it.

now you'll be running a kernel with tracing built-in. The kernel comes
with the 'wakeup-timing' feature enabled by default, which can be
started via:

echo 0 > /proc/sys/kernel/preempt_max_latency

after this point you should get the maximum scheduling latencies
reported to the syslog:

[root@saturn ~]# echo 0 > /proc/sys/kernel/preempt_max_latency
[root@saturn ~]# dmesg | tail
( IRQ 17-776 |#0): new 1 us maximum-latency wakeup.
( ksoftirqd/0-2 |#0): new 1 us maximum-latency wakeup.
( kjournald-820 |#0): new 1 us maximum-latency wakeup.
( IRQ 14-781 |#0): new 3 us maximum-latency wakeup.
( ksoftirqd/0-2 |#0): new 3 us maximum-latency wakeup.
( IRQ 17-776 |#0): new 12 us maximum-latency wakeup.
( IRQ 17-776 |#0): new 36 us maximum-latency wakeup.
( IRQ 17-776 |#0): new 38 us maximum-latency wakeup.
( IRQ 17-776 |#0): new 40 us maximum-latency wakeup.
( IRQ 17-776 |#0): new 70 us maximum-latency wakeup.
[root@saturn ~]#

the kernel function trace of the largest latency will be under
/proc/latency_trace:

[root@saturn ~]# cat /proc/latency_trace
preemption latency trace v1.1.4 on 2.6.12-rc5-RT-V0.7.47-15
--------------------------------------------------------------------
latency: 3048 us, #471/471, CPU#0 | (M:server VP:0, KP:0, SP:1 HP:1 #P:1)
-----------------
| task: ksoftirqd/0-2 (uid:0 nice:-10 policy:0 rt_prio:0)
-----------------

_------=> CPU#
/ _-----=> irqs-off
| / _----=> need-resched
|| / _---=> hardirq/softirq
||| / _--=> preempt-depth
|||| /
||||| delay
cmd pid ||||| time | caller
\ / ||||| \ | /
head-3704 0dn.. 0us : try_to_wake_up (wake_up_process)
head-3704 0dn.. 0us : try_to_wake_up <<...>-2> (6a 75):
head-3704 0dn.. 0us : wake_up_process (do_softirq)
head-3704 0dn.. 0us+< (608)
cat-3709 0dnh. 46us+: do_nmi (memcpy)
cat-3709 0dnh. 48us+: profile_hit (nmi_watchdog_tick)
cat-3709 0dnh. 148us+: do_nmi (memcpy)
cat-3709 0dnh. 150us+: profile_hit (nmi_watchdog_tick)
cat-3709 0dn.. 182us : smp_apic_timer_interrupt (c01b3b35 0 0)
cat-3709 0dnh. 182us : irq_exit (apic_timer_interrupt)
cat-3709 0dn.. 182us : do_softirq (apic_timer_interrupt)
cat-3709 0dn.. 182us : __do_softirq (do_softirq)
cat-3709 0dn.. 183us+< (608)
cat-3709 0dnh. 251us : do_nmi (memcpy)
[...]

(if you have the APIC code and the NMI watchdog enabled then you'll also
get a trace of userspace code looping in irqs-off sections.)

at this point try to reproduce the X hang. Do you get any large
(3000-5000 usecs) latency reported? (assuming you can reproduce the hang
under the -RT kernel)

(if you dont get any large latency reported by the tracer but the hangs
still happen then there's still a way to get this debugged, by running
the tracer in a free-running manually-triggered mode - i'll tell more
about this if it becomes necessary.)

Ingo

2005-05-31 06:15:23

[permalink] [raw]

Subject: Re: Machine Freezes while Running Crossover Office

On Mon, 2005-05-30 at 10:31 -0700, Linus Torvalds wrote:
> Pekka, can you confirm that the SysRQ output in your original email was
> from a "hung" time? Because that clearly shows that stuff is happening in
> user space, which means that it's definitely not a kernel loop.

Yes. What I did was I booted to 2.6.12-rc5 and did all the traces (vmstat,
oprofile, and Sysrq-P) in a row and saved them for the email. I do have
other Sysrq-P traces where pipe_poll shows up quite a lot.

Thanks for all the suggestions. I'll try them out hopefully later today and
report back.

Pekka

2005-05-31 06:35:53

[permalink] [raw]

Subject: Re: Machine Freezes while Running Crossover Office

On Mon, 2005-05-30 at 10:31 -0700, Linus Torvalds wrote:
> Also, pipes are a bit special from a scheduling standpoint because they
> use the magic "synchronous wakeup" thing, and it might be worthwhile
> trying to just change the two calls to "wake_up_interruptible_sync()" in
> fs/pipe.c to the non-sync version (ie just remove the "_sync" part).

Btw, I was looking at this yesterday but noticed there are no
__wake_up_sync() calls in the oprofile report. So I don't think we're
hitting the sync wakeup paths at all.

I also read Crossover sources a bit and I think the setup is something like
this:

wineserver:

- Polls a 'reply' pipe to see if there's something to read.
- If the pipe has something for us, read it.
- Do something and the write results to another pipe.

wine-preloader:

- Write to the 'reply' pipe.
- Read from the another pipe.

Looks if the processes keep on waking up each other and thus eat up all CPU
time. (Even more so if Crossover uses RT priority, have to check that.)

P.S. I can also verify that it is indeed the 64 KB change that breaks
Crossover. I changed PIPE_BUFFERS to 1 and could not reproduce the hang.
Increasing it to 8 makes the problem come up again.

Pekka

2005-05-31 06:55:47

[permalink] [raw]

Subject: Re: Machine Freezes while Running Crossover Office

* Pekka J Enberg <[email protected]> wrote:

> Looks if the processes keep on waking up each other and thus eat up
> all CPU time. (Even more so if Crossover uses RT priority, have to
> check that.)

another possible effect would be the interactivity code: the Wine
processes gaining high priority via their scheduling pattern. I've
attached a patch to make it runtime switchable (see more details below).

> P.S. I can also verify that it is indeed the 64 KB change that breaks
> Crossover. I changed PIPE_BUFFERS to 1 and could not reproduce the
> hang. Increasing it to 8 makes the problem come up again.

very interesting. This should not have any direct effect on scheduling
correctness - but it might change the patterns and hence the priorities.
A couple of things to try:

- run the Wine processes with nice +19 priority? (just to check
whether it's the interactivity code. After you've made sure they dont
have RT priorities.)

- renice -20 the X server process

- run a shell-script with RT priority that captures 'ps aux' every
second or so:

chrt -f -p 1 $$ # switch shell to RT-SCHED_FIFO-prio-1
rm -f log.txt
while sleep 1; do
date
ps -eo pid,tid,class,rtprio,ni,pri,psr,pcpu,stat,wchan:14,comm
done > log.txt

(chrt comes from the schedutils package) Then send us log.txt and
identify the date where the 'hang' occured. (the hang will probably not
occur in this measurement script running at RT priority)

- apply the patch below and check whether doing:

echo 0 > /proc/sys/kernel/interactive

makes the hang go away.

- if the hang goes away then could you check whether removing the 2 new
sched_interactive lines in kernel/sched.c's effective_prio() function
(but keeping other portions of the patch applied) hang or not.

Ingo

--

hack to make the interactivity code runtime-switchable.

From: Con Kolivas <[email protected]>
Signed-off-by: Ingo Molnar <[email protected]>

--- linux/kernel/sched.c.orig
+++ linux/kernel/sched.c
@@ -144,8 +144,8 @@
#define DELTA(p) \
(SCALE(TASK_NICE(p), 40, MAX_BONUS) + INTERACTIVE_DELTA)

-#define TASK_INTERACTIVE(p) \
- ((p)->prio <= (p)->static_prio - DELTA(p))
+#define TASK_INTERACTIVE(p) ((sched_interactive) && \
+ ((p)->prio <= (p)->static_prio - DELTA(p)))

#define INTERACTIVE_SLEEP(p) \
(JIFFIES_TO_NS(MAX_SLEEP_AVG * \
@@ -574,6 +574,13 @@ static inline void enqueue_task_head(str
}

/*
+ * This is a sysctl which allows dynamic priorities to be applied based on
+ * interactive behaviour, and selective interactive reinsertion into the
+ * active array despite timeslice expiration.
+ */
+int sched_interactive = 0;
+
+/*
* effective_prio - return the priority that is based on the static
* priority but is modified by bonuses/penalties.
*
@@ -594,6 +601,10 @@ static int effective_prio(task_t *p)
if (rt_task(p))
return p->prio;

+ /* Dynamic priorities are not used with interactive mode off */
+ if (!sched_interactive)
+ return p->static_prio;
+
bonus = CURRENT_BONUS(p) - MAX_BONUS / 2;

prio = p->static_prio - bonus;
--- linux/kernel/sysctl.c.orig
+++ linux/kernel/sysctl.c
@@ -836,6 +836,14 @@ static ctl_table vm_table[] = {
.strategy = &sysctl_jiffies,
},
#endif
+ {
+ .ctl_name = KERN_INTERACTIVE,
+ .procname = "interactive",
+ .data = &sched_interactive,
+ .maxlen = sizeof (int),
+ .mode = 0644,
+ .proc_handler = &proc_dointvec,
+ },
{ .ctl_name = 0 }
};

--- linux/include/linux/sysctl.h.orig
+++ linux/include/linux/sysctl.h
@@ -136,6 +136,7 @@ enum
KERN_UNKNOWN_NMI_PANIC=66, /* int: unknown nmi panic flag */
KERN_BOOTLOADER_TYPE=67, /* int: boot loader type */
KERN_RANDOMIZE=68, /* int: randomize virtual address space */
+ KERN_INTERACTIVE=69, /* int: dynamic priorities */
};

--- linux/include/linux/sched.h.orig
+++ linux/include/linux/sched.h
@@ -839,6 +839,7 @@ extern int task_curr(const task_t *p);
extern int idle_cpu(int cpu);
extern int sched_setscheduler(struct task_struct *, int, struct sched_param *);
extern task_t *idle_task(int cpu);
+extern int sched_interactive;

void yield(void);

2005-05-31 13:54:34

by Greg Stark

[permalink] [raw]

Subject: Re: [PROBLEM] Machine Freezes while Running Crossover Office

Linus Torvalds <[email protected]> writes:

> On Mon, 30 May 2005, Kyle Moffett wrote:
> >
> > If X is hung and not accepting data on any of its sockets, then this
> > could hang the Xterm in the background, and therefore hang the printout
> > from the "date" process.
>
> Nope. There's a pty in between, and the pty buffer is much bigger than
> just a few lines, so even if an xterm is hung, the program displaing on an
> xterm wouldn't be affected normally (unless it reads from the tty or
> outputs several kB of data).

Well "date" won't hang but you won't actually see any output for it on the
XTerm.

But I'm unclear how switching to VT1 is going to work since that requires that
X capture the VT keypress. If X is hung and not responding to keyboard and
mouse inputs it's not going to see that keypress either and isn't going to
switch.

I think the best way to test in this situation is to SSH in from another
machine and run commands like this over SSH. Even better would be a serial
console.

--
greg

2005-05-31 17:10:46

[permalink] [raw]

Subject: Re: Machine Freezes while Running Crossover Office

Hi,

I did a full strace of wine and its child processes and it does not call
sys_sched_setscheduler. Furthermore, top shows that wineserver and
wine-preloader are running on the same priority as X so I don't think
it's using RT priorities.

On Tue, 2005-05-31 at 08:54 +0200, Ingo Molnar wrote:
> - run the Wine processes with nice +19 priority? (just to check
> whether it's the interactivity code. After you've made sure they dont
> have RT priorities.)

nice +19 -> no hang

On Tue, 2005-05-31 at 08:54 +0200, Ingo Molnar wrote:
> - renice -20 the X server process

renice -20 -> no hang

On Tue, 2005-05-31 at 08:54 +0200, Ingo Molnar wrote:
> - run a shell-script with RT priority that captures 'ps aux' every
> second or so:

Crossover hangs but script produces output. I've included it below.

On Tue, 2005-05-31 at 08:54 +0200, Ingo Molnar wrote:
> - apply the patch below and check whether doing:
>
> echo 0 > /proc/sys/kernel/interactive
>
> makes the hang go away.

It's actually /proc/sys/vm/interactive but yes, 0 makes the hang go away
while 1 makes it come back.

On Tue, 2005-05-31 at 08:54 +0200, Ingo Molnar wrote:
> - if the hang goes away then could you check whether removing the 2 new
> sched_interactive lines in kernel/sched.c's effective_prio() function
> (but keeping other portions of the patch applied) hang or not.

Exactly the same results as the previous, 0 -> no hang, 1 -> hangs.

Also, I tried the old date script in virtual console. It worked exactly
the same as in xterm. I did not try your RT patch, Ingo. Do you want me
to try it out?

Pekka

The hang starts almost immediately (from the first or second date):

Tue May 31 18:40:29 EEST 2005
PID TID CLS RTPRIO NI PRI PSR %CPU STAT WCHAN COMMAND
1 1 TS - 0 23 0 0.0 S select init
2 2 TS - 19 5 0 0.0 SN ksoftirqd ksoftirqd/0
3 3 TS - -5 29 0 0.0 S< worker_thread events/0
4 4 TS - -5 25 0 0.0 S< worker_thread khelper
9 9 TS - -5 28 0 0.0 S< worker_thread kthread
18 18 TS - -5 29 0 0.0 S< worker_thread kacpid
90 90 TS - -5 29 0 0.0 S< worker_thread kblockd/0
139 139 TS - 0 24 0 0.0 S pdflush pdflush
140 140 TS - 0 24 0 0.0 S pdflush pdflush
142 142 TS - -5 27 0 0.0 S< worker_thread aio/0
141 141 TS - 0 24 0 0.0 S kswapd kswapd0
734 734 TS - 0 23 0 0.0 S serio_thread kseriod
929 929 TS - 0 24 0 0.0 S hub_thread khubd
1338 1338 TS - 0 24 0 0.0 S kjournald kjournald
5849 5849 TS - -4 25 0 0.0 S<s select udevd
8145 8145 TS - 0 24 0 0.0 Ss poll syslog-ng
8196 8196 TS - 0 23 0 0.0 Ss poll acpid
8685 8685 TS - 0 23 0 0.0 Ss nanosleep dhcpcd
8828 8828 TS - 0 21 0 0.0 Ss select sshd
8865 8865 TS - 0 24 0 0.0 Ss nanosleep cron
8930 8930 TS - 0 23 0 0.0 Ss+ read_chan agetty
8931 8931 TS - 0 22 0 0.0 Ss+ read_chan agetty
8932 8932 TS - 0 22 0 0.0 Ss+ read_chan agetty
8933 8933 TS - 0 23 0 0.0 Ss+ read_chan agetty
9014 9014 TS - 0 23 0 0.0 Ss+ read_chan agetty
9092 9092 TS - 0 23 0 0.0 Ss select kdm
9095 9095 TS - 0 23 0 0.5 R - X
28934 28934 TS - 0 23 0 0.0 Ss+ read_chan agetty
29050 29050 TS - 0 23 0 0.0 S wait kdm
29057 29057 TS - 0 24 0 0.0 S poll gnome-session
29062 29062 TS - 0 24 0 0.1 S poll gconfd-2
29065 29065 TS - 0 20 0 0.0 S poll gnome-keyring-d
29067 29067 TS - 0 23 0 0.0 Ss poll bonobo-activati
29069 29069 TS - 0 24 0 0.2 S poll gnome-settings-
29078 29078 TS - 0 24 0 0.0 S select xscreensaver
29096 29096 TS - 0 24 0 0.0 Ss select gnome-smproxy
29104 29104 TS - 0 24 0 0.6 Ss poll metacity
29106 29106 TS - 0 24 0 0.2 Ssl poll nautilus
29108 29108 TS - 0 24 0 0.2 Ssl poll gnome-panel
29112 29112 TS - 0 24 0 0.1 Rs - gnome-volume-co
29114 29114 TS - 0 24 0 0.0 Ssl poll evolution-alarm
29117 29117 TS - 0 24 0 1.0 S poll wnck-applet
29120 29120 TS - 0 23 0 0.0 Sl poll gnome-vfs-daemo
29122 29122 TS - 0 23 0 0.0 Sl poll evolution-data-
29147 29147 TS - 0 23 0 0.0 S poll mapping-daemon
29152 29152 TS - 0 24 0 0.0 S poll battstat-applet
29154 29154 TS - 0 24 0 0.1 S poll gnome-netstatus
29156 29156 TS - 0 24 0 0.2 Sl futex_wait mono
29158 29158 TS - 0 24 0 0.1 S poll clock-applet
29160 29160 TS - 0 24 0 0.0 S poll multiload-apple
29162 29162 TS - 0 24 0 0.0 Sl poll gweather-applet
29163 29163 TS - 0 24 0 0.0 Ss poll mono
29169 29169 TS - 0 24 0 1.0 Sl poll gnome-terminal
29170 29170 TS - 0 22 0 0.0 S unix_stream_da gnome-pty-helpe
29171 29171 TS - 0 23 0 0.0 Ss wait bash
29175 29175 TS - 0 24 0 2.6 Sl poll evolution-2.2
29211 29211 TS - 0 24 0 0.0 Ss wait bash
29246 29246 TS - 0 23 0 0.0 Ss wait bash
29250 29250 TS - 0 22 0 0.0 S wait mozilla-launche
29261 29261 TS - 0 24 0 1.9 Sl poll firefox-bin
29391 29391 TS - 0 23 0 0.0 S wait su
29399 29399 TS - 0 23 0 0.0 S+ read_chan bash
29415 29415 TS - 0 23 0 0.0 S wait su
29418 29418 FF 1 - 41 0 0.0 S wait bash
29594 29594 TS - 0 23 0 0.0 S+ wait bash
29595 29595 TS - 0 23 0 0.2 S+ pipe_wait wine-preloader
29602 29602 TS - 0 24 0 12.4 Ss poll wineserver
29604 29604 TS - 0 24 0 16.0 R+ - wine-preloader
29714 29714 FF 1 - 41 0 0.0 R+ - ps
Tue May 31 18:40:30 EEST 2005
PID TID CLS RTPRIO NI PRI PSR %CPU STAT WCHAN COMMAND
1 1 TS - 0 23 0 0.0 S select init
2 2 TS - 19 5 0 0.0 SN ksoftirqd ksoftirqd/0
3 3 TS - -5 29 0 0.0 R< - events/0
4 4 TS - -5 25 0 0.0 S< worker_thread khelper
9 9 TS - -5 28 0 0.0 S< worker_thread kthread
18 18 TS - -5 29 0 0.0 S< worker_thread kacpid
90 90 TS - -5 29 0 0.0 S< worker_thread kblockd/0
139 139 TS - 0 24 0 0.0 S pdflush pdflush
140 140 TS - 0 24 0 0.0 S pdflush pdflush
142 142 TS - -5 27 0 0.0 S< worker_thread aio/0
141 141 TS - 0 24 0 0.0 S kswapd kswapd0
734 734 TS - 0 23 0 0.0 S serio_thread kseriod
929 929 TS - 0 24 0 0.0 S hub_thread khubd
1338 1338 TS - 0 24 0 0.0 S kjournald kjournald
5849 5849 TS - -4 25 0 0.0 S<s select udevd
8145 8145 TS - 0 24 0 0.0 Ss poll syslog-ng
8196 8196 TS - 0 23 0 0.0 Ss poll acpid
8685 8685 TS - 0 23 0 0.0 Ss nanosleep dhcpcd
8828 8828 TS - 0 21 0 0.0 Ss select sshd
8865 8865 TS - 0 24 0 0.0 Ss nanosleep cron
8930 8930 TS - 0 23 0 0.0 Ss+ read_chan agetty
8931 8931 TS - 0 22 0 0.0 Ss+ read_chan agetty
8932 8932 TS - 0 22 0 0.0 Ss+ read_chan agetty
8933 8933 TS - 0 23 0 0.0 Ss+ read_chan agetty
9014 9014 TS - 0 23 0 0.0 Ss+ read_chan agetty
9092 9092 TS - 0 23 0 0.0 Ss select kdm
9095 9095 TS - 0 23 0 0.5 R - X
28934 28934 TS - 0 23 0 0.0 Ss+ read_chan agetty
29050 29050 TS - 0 23 0 0.0 S wait kdm
29057 29057 TS - 0 24 0 0.0 S poll gnome-session
29062 29062 TS - 0 24 0 0.1 S poll gconfd-2
29065 29065 TS - 0 20 0 0.0 S poll gnome-keyring-d
29067 29067 TS - 0 23 0 0.0 Ss poll bonobo-activati
29069 29069 TS - 0 24 0 0.2 S poll gnome-settings-
29078 29078 TS - 0 24 0 0.0 S select xscreensaver
29096 29096 TS - 0 24 0 0.0 Ss select gnome-smproxy
29104 29104 TS - 0 24 0 0.6 Ss poll metacity
29106 29106 TS - 0 24 0 0.2 Ssl poll nautilus
29108 29108 TS - 0 24 0 0.2 Ssl poll gnome-panel
29112 29112 TS - 0 24 0 0.1 Ss poll gnome-volume-co
29114 29114 TS - 0 24 0 0.0 Ssl poll evolution-alarm
29117 29117 TS - 0 24 0 1.0 S poll wnck-applet
29120 29120 TS - 0 23 0 0.0 Sl poll gnome-vfs-daemo
29122 29122 TS - 0 23 0 0.0 Sl poll evolution-data-
29147 29147 TS - 0 23 0 0.0 S poll mapping-daemon
29152 29152 TS - 0 24 0 0.0 S poll battstat-applet
29154 29154 TS - 0 24 0 0.1 S poll gnome-netstatus
29156 29156 TS - 0 24 0 0.2 Sl futex_wait mono
29158 29158 TS - 0 24 0 0.1 S poll clock-applet
29160 29160 TS - 0 24 0 0.0 S poll multiload-apple
29162 29162 TS - 0 24 0 0.0 Sl poll gweather-applet
29163 29163 TS - 0 24 0 0.0 Ss poll mono
29169 29169 TS - 0 24 0 1.0 Sl poll gnome-terminal
29170 29170 TS - 0 22 0 0.0 S unix_stream_da gnome-pty-helpe
29171 29171 TS - 0 23 0 0.0 Ss wait bash
29175 29175 TS - 0 24 0 2.6 Sl poll evolution-2.2
29211 29211 TS - 0 24 0 0.0 Ss wait bash
29246 29246 TS - 0 23 0 0.0 Ss wait bash
29250 29250 TS - 0 22 0 0.0 S wait mozilla-launche
29261 29261 TS - 0 24 0 1.9 Sl poll firefox-bin
29391 29391 TS - 0 23 0 0.0 S wait su
29399 29399 TS - 0 23 0 0.0 S+ read_chan bash
29415 29415 TS - 0 23 0 0.0 S wait su
29418 29418 FF 1 - 41 0 0.0 S wait bash
29594 29594 TS - 0 23 0 0.0 S+ wait bash
29595 29595 TS - 0 23 0 0.2 S+ pipe_wait wine-preloader
29602 29602 TS - 0 24 0 12.6 Rs - wineserver
29604 29604 TS - 0 24 0 16.3 S+ pipe_wait wine-preloader
29717 29717 FF 1 - 41 0 0.0 R+ - ps
Tue May 31 18:40:31 EEST 2005
PID TID CLS RTPRIO NI PRI PSR %CPU STAT WCHAN COMMAND
1 1 TS - 0 23 0 0.0 R - init
2 2 TS - 19 5 0 0.0 SN ksoftirqd ksoftirqd/0
3 3 TS - -5 29 0 0.0 S< worker_thread events/0
4 4 TS - -5 25 0 0.0 S< worker_thread khelper
9 9 TS - -5 28 0 0.0 S< worker_thread kthread
18 18 TS - -5 29 0 0.0 S< worker_thread kacpid
90 90 TS - -5 29 0 0.0 S< worker_thread kblockd/0
139 139 TS - 0 24 0 0.0 S pdflush pdflush
140 140 TS - 0 24 0 0.0 S pdflush pdflush
142 142 TS - -5 27 0 0.0 S< worker_thread aio/0
141 141 TS - 0 24 0 0.0 S kswapd kswapd0
734 734 TS - 0 23 0 0.0 S serio_thread kseriod
929 929 TS - 0 24 0 0.0 S hub_thread khubd
1338 1338 TS - 0 24 0 0.0 S kjournald kjournald
5849 5849 TS - -4 25 0 0.0 S<s select udevd
8145 8145 TS - 0 24 0 0.0 Ss poll syslog-ng
8196 8196 TS - 0 23 0 0.0 Ss poll acpid
8685 8685 TS - 0 23 0 0.0 Ss nanosleep dhcpcd
8828 8828 TS - 0 21 0 0.0 Ss select sshd
8865 8865 TS - 0 24 0 0.0 Ss nanosleep cron
8930 8930 TS - 0 23 0 0.0 Ss+ read_chan agetty
8931 8931 TS - 0 22 0 0.0 Ss+ read_chan agetty
8932 8932 TS - 0 22 0 0.0 Ss+ read_chan agetty
8933 8933 TS - 0 23 0 0.0 Ss+ read_chan agetty
9014 9014 TS - 0 23 0 0.0 Ss+ read_chan agetty
9092 9092 TS - 0 23 0 0.0 Ss select kdm
9095 9095 TS - 0 23 0 0.5 R - X
28934 28934 TS - 0 23 0 0.0 Ss+ read_chan agetty
29050 29050 TS - 0 23 0 0.0 S wait kdm
29057 29057 TS - 0 24 0 0.0 S poll gnome-session
29062 29062 TS - 0 24 0 0.1 S poll gconfd-2
29065 29065 TS - 0 20 0 0.0 S poll gnome-keyring-d
29067 29067 TS - 0 23 0 0.0 Ss poll bonobo-activati
29069 29069 TS - 0 24 0 0.2 S poll gnome-settings-
29078 29078 TS - 0 23 0 0.0 R - xscreensaver
29096 29096 TS - 0 24 0 0.0 Ss select gnome-smproxy
29104 29104 TS - 0 24 0 0.6 Ss poll metacity
29106 29106 TS - 0 24 0 0.2 Ssl poll nautilus
29108 29108 TS - 0 24 0 0.2 Ssl poll gnome-panel
29112 29112 TS - 0 24 0 0.1 Ss poll gnome-volume-co
29114 29114 TS - 0 24 0 0.0 Ssl poll evolution-alarm
29117 29117 TS - 0 24 0 1.0 S poll wnck-applet
29120 29120 TS - 0 23 0 0.0 Sl poll gnome-vfs-daemo
29122 29122 TS - 0 23 0 0.0 Sl poll evolution-data-
29147 29147 TS - 0 23 0 0.0 R - mapping-daemon
29152 29152 TS - 0 23 0 0.0 R - battstat-applet
29154 29154 TS - 0 24 0 0.1 S poll gnome-netstatus
29156 29156 TS - 0 24 0 0.2 Sl futex_wait mono
29158 29158 TS - 0 23 0 0.1 R - clock-applet
29160 29160 TS - 0 24 0 0.0 S poll multiload-apple
29162 29162 TS - 0 24 0 0.0 Sl poll gweather-applet
29163 29163 TS - 0 24 0 0.0 Ss poll mono
29169 29169 TS - 0 24 0 1.0 Sl poll gnome-terminal
29170 29170 TS - 0 22 0 0.0 S unix_stream_da gnome-pty-helpe
29171 29171 TS - 0 23 0 0.0 Ss wait bash
29175 29175 TS - 0 24 0 2.6 Sl poll evolution-2.2
29211 29211 TS - 0 24 0 0.0 Ss wait bash
29246 29246 TS - 0 23 0 0.0 Ss wait bash
29250 29250 TS - 0 22 0 0.0 S wait mozilla-launche
29261 29261 TS - 0 24 0 1.9 Sl poll firefox-bin
29391 29391 TS - 0 23 0 0.0 S wait su
29399 29399 TS - 0 23 0 0.0 S+ read_chan bash
29415 29415 TS - 0 23 0 0.0 S wait su
29418 29418 FF 1 - 41 0 0.0 S wait bash
29594 29594 TS - 0 23 0 0.0 S+ wait bash
29595 29595 TS - 0 23 0 0.2 S+ pipe_wait wine-preloader
29602 29602 TS - 0 24 0 12.8 Ss poll wineserver
29604 29604 TS - 0 24 0 16.7 R+ - wine-preloader
29720 29720 FF 1 - 41 0 0.0 R+ - ps
Tue May 31 18:40:32 EEST 2005
PID TID CLS RTPRIO NI PRI PSR %CPU STAT WCHAN COMMAND
1 1 TS - 0 23 0 0.0 R - init
2 2 TS - 19 5 0 0.0 SN ksoftirqd ksoftirqd/0
3 3 TS - -5 29 0 0.0 S< worker_thread events/0
4 4 TS - -5 25 0 0.0 S< worker_thread khelper
9 9 TS - -5 28 0 0.0 S< worker_thread kthread
18 18 TS - -5 29 0 0.0 S< worker_thread kacpid
90 90 TS - -5 29 0 0.0 S< worker_thread kblockd/0
139 139 TS - 0 24 0 0.0 S pdflush pdflush
140 140 TS - 0 24 0 0.0 S pdflush pdflush
142 142 TS - -5 27 0 0.0 S< worker_thread aio/0
141 141 TS - 0 24 0 0.0 S kswapd kswapd0
734 734 TS - 0 23 0 0.0 S serio_thread kseriod
929 929 TS - 0 24 0 0.0 S hub_thread khubd
1338 1338 TS - 0 24 0 0.0 S kjournald kjournald
5849 5849 TS - -4 25 0 0.0 S<s select udevd
8145 8145 TS - 0 24 0 0.0 Ss poll syslog-ng
8196 8196 TS - 0 23 0 0.0 Ss poll acpid
8685 8685 TS - 0 23 0 0.0 Ss nanosleep dhcpcd
8828 8828 TS - 0 21 0 0.0 Ss select sshd
8865 8865 TS - 0 24 0 0.0 Ss nanosleep cron
8930 8930 TS - 0 23 0 0.0 Ss+ read_chan agetty
8931 8931 TS - 0 22 0 0.0 Ss+ read_chan agetty
8932 8932 TS - 0 22 0 0.0 Ss+ read_chan agetty
8933 8933 TS - 0 23 0 0.0 Ss+ read_chan agetty
9014 9014 TS - 0 23 0 0.0 Ss+ read_chan agetty
9092 9092 TS - 0 23 0 0.0 Ss select kdm
9095 9095 TS - 0 23 0 0.5 R - X
28934 28934 TS - 0 23 0 0.0 Ss+ read_chan agetty
29050 29050 TS - 0 23 0 0.0 S wait kdm
29057 29057 TS - 0 24 0 0.0 S poll gnome-session
29062 29062 TS - 0 23 0 0.1 R - gconfd-2
29065 29065 TS - 0 20 0 0.0 S poll gnome-keyring-d
29067 29067 TS - 0 23 0 0.0 Ss poll bonobo-activati
29069 29069 TS - 0 24 0 0.2 S poll gnome-settings-
29078 29078 TS - 0 23 0 0.0 R - xscreensaver
29096 29096 TS - 0 24 0 0.0 Ss select gnome-smproxy
29104 29104 TS - 0 24 0 0.6 Ss poll metacity
29106 29106 TS - 0 23 0 0.2 Rsl - nautilus
29108 29108 TS - 0 24 0 0.2 Ssl poll gnome-panel
29112 29112 TS - 0 24 0 0.1 Ss poll gnome-volume-co
29114 29114 TS - 0 24 0 0.0 Ssl poll evolution-alarm
29117 29117 TS - 0 24 0 1.0 S poll wnck-applet
29120 29120 TS - 0 23 0 0.0 Rl - gnome-vfs-daemo
29122 29122 TS - 0 23 0 0.0 Sl poll evolution-data-
29147 29147 TS - 0 23 0 0.0 R - mapping-daemon
29152 29152 TS - 0 23 0 0.0 R - battstat-applet
29154 29154 TS - 0 24 0 0.1 S poll gnome-netstatus
29156 29156 TS - 0 24 0 0.2 Sl futex_wait mono
29158 29158 TS - 0 23 0 0.1 R - clock-applet
29160 29160 TS - 0 24 0 0.0 S poll multiload-apple
29162 29162 TS - 0 24 0 0.0 Sl poll gweather-applet
29163 29163 TS - 0 24 0 0.0 Ss poll mono
29169 29169 TS - 0 24 0 1.0 Sl poll gnome-terminal
29170 29170 TS - 0 22 0 0.0 S unix_stream_da gnome-pty-helpe
29171 29171 TS - 0 23 0 0.0 Ss wait bash
29175 29175 TS - 0 24 0 2.6 Sl poll evolution-2.2
29211 29211 TS - 0 24 0 0.0 Ss wait bash
29246 29246 TS - 0 23 0 0.0 Ss wait bash
29250 29250 TS - 0 22 0 0.0 S wait mozilla-launche
29261 29261 TS - 0 24 0 1.9 Sl poll firefox-bin
29391 29391 TS - 0 23 0 0.0 S wait su
29399 29399 TS - 0 23 0 0.0 S+ read_chan bash
29415 29415 TS - 0 23 0 0.0 S wait su
29418 29418 FF 1 - 41 0 0.0 S wait bash
29594 29594 TS - 0 23 0 0.0 S+ wait bash
29595 29595 TS - 0 23 0 0.2 S+ pipe_wait wine-preloader
29602 29602 TS - 0 24 0 13.0 Ss poll wineserver
29604 29604 TS - 0 24 0 17.1 R+ - wine-preloader
29723 29723 FF 1 - 41 0 0.0 R+ - ps
Tue May 31 18:40:33 EEST 2005
PID TID CLS RTPRIO NI PRI PSR %CPU STAT WCHAN COMMAND
1 1 TS - 0 23 0 0.0 R - init
2 2 TS - 19 5 0 0.0 SN ksoftirqd ksoftirqd/0
3 3 TS - -5 29 0 0.0 S< worker_thread events/0
4 4 TS - -5 25 0 0.0 S< worker_thread khelper
9 9 TS - -5 28 0 0.0 S< worker_thread kthread
18 18 TS - -5 29 0 0.0 S< worker_thread kacpid
90 90 TS - -5 29 0 0.0 S< worker_thread kblockd/0
139 139 TS - 0 24 0 0.0 S pdflush pdflush
140 140 TS - 0 24 0 0.0 S pdflush pdflush
142 142 TS - -5 27 0 0.0 S< worker_thread aio/0
141 141 TS - 0 24 0 0.0 S kswapd kswapd0
734 734 TS - 0 23 0 0.0 S serio_thread kseriod
929 929 TS - 0 24 0 0.0 S hub_thread khubd
1338 1338 TS - 0 24 0 0.0 S kjournald kjournald
5849 5849 TS - -4 25 0 0.0 S<s select udevd
8145 8145 TS - 0 24 0 0.0 Ss poll syslog-ng
8196 8196 TS - 0 23 0 0.0 Ss poll acpid
8685 8685 TS - 0 23 0 0.0 Ss nanosleep dhcpcd
8828 8828 TS - 0 21 0 0.0 Ss select sshd
8865 8865 TS - 0 24 0 0.0 Ss nanosleep cron
8930 8930 TS - 0 23 0 0.0 Ss+ read_chan agetty
8931 8931 TS - 0 22 0 0.0 Ss+ read_chan agetty
8932 8932 TS - 0 22 0 0.0 Ss+ read_chan agetty
8933 8933 TS - 0 23 0 0.0 Ss+ read_chan agetty
9014 9014 TS - 0 23 0 0.0 Ss+ read_chan agetty
9092 9092 TS - 0 23 0 0.0 Ss select kdm
9095 9095 TS - 0 23 0 0.5 R - X
28934 28934 TS - 0 23 0 0.0 Ss+ read_chan agetty
29050 29050 TS - 0 23 0 0.0 S wait kdm
29057 29057 TS - 0 24 0 0.0 S poll gnome-session
29062 29062 TS - 0 23 0 0.1 R - gconfd-2
29065 29065 TS - 0 20 0 0.0 S poll gnome-keyring-d
29067 29067 TS - 0 23 0 0.0 Ss poll bonobo-activati
29069 29069 TS - 0 24 0 0.2 S poll gnome-settings-
29078 29078 TS - 0 23 0 0.0 R - xscreensaver
29096 29096 TS - 0 24 0 0.0 Ss select gnome-smproxy
29104 29104 TS - 0 24 0 0.6 Ss poll metacity
29106 29106 TS - 0 23 0 0.2 Rsl - nautilus
29108 29108 TS - 0 24 0 0.2 Ssl poll gnome-panel
29112 29112 TS - 0 24 0 0.1 Ss poll gnome-volume-co
29114 29114 TS - 0 24 0 0.0 Ssl poll evolution-alarm
29117 29117 TS - 0 24 0 1.0 S poll wnck-applet
29120 29120 TS - 0 23 0 0.0 Rl - gnome-vfs-daemo
29122 29122 TS - 0 23 0 0.0 Sl poll evolution-data-
29147 29147 TS - 0 23 0 0.0 R - mapping-daemon
29152 29152 TS - 0 23 0 0.0 R - battstat-applet
29154 29154 TS - 0 24 0 0.1 S poll gnome-netstatus
29156 29156 TS - 0 24 0 0.2 Sl futex_wait mono
29158 29158 TS - 0 23 0 0.1 R - clock-applet
29160 29160 TS - 0 24 0 0.0 S poll multiload-apple
29162 29162 TS - 0 24 0 0.0 Sl poll gweather-applet
29163 29163 TS - 0 24 0 0.0 Ss poll mono
29169 29169 TS - 0 24 0 1.0 Sl poll gnome-terminal
29170 29170 TS - 0 22 0 0.0 S unix_stream_da gnome-pty-helpe
29171 29171 TS - 0 23 0 0.0 Ss wait bash
29175 29175 TS - 0 24 0 2.6 Sl poll evolution-2.2
29211 29211 TS - 0 24 0 0.0 Ss wait bash
29246 29246 TS - 0 23 0 0.0 Ss wait bash
29250 29250 TS - 0 22 0 0.0 S wait mozilla-launche
29261 29261 TS - 0 24 0 1.9 Sl poll firefox-bin
29391 29391 TS - 0 23 0 0.0 S wait su
29399 29399 TS - 0 23 0 0.0 S+ read_chan bash
29415 29415 TS - 0 23 0 0.0 S wait su
29418 29418 FF 1 - 41 0 0.0 S wait bash
29594 29594 TS - 0 23 0 0.0 S+ wait bash
29595 29595 TS - 0 23 0 0.2 S+ pipe_wait wine-preloader
29602 29602 TS - 0 24 0 13.2 Rs - wineserver
29604 29604 TS - 0 24 0 17.4 S+ pipe_wait wine-preloader
29726 29726 FF 1 - 41 0 0.0 R+ - ps
Tue May 31 18:40:34 EEST 2005
PID TID CLS RTPRIO NI PRI PSR %CPU STAT WCHAN COMMAND
1 1 TS - 0 23 0 0.0 R - init
2 2 TS - 19 5 0 0.0 SN ksoftirqd ksoftirqd/0
3 3 TS - -5 29 0 0.0 S< worker_thread events/0
4 4 TS - -5 25 0 0.0 S< worker_thread khelper
9 9 TS - -5 28 0 0.0 S< worker_thread kthread
18 18 TS - -5 29 0 0.0 S< worker_thread kacpid
90 90 TS - -5 29 0 0.0 S< worker_thread kblockd/0
139 139 TS - 0 24 0 0.0 S pdflush pdflush
140 140 TS - 0 24 0 0.0 S pdflush pdflush
142 142 TS - -5 27 0 0.0 S< worker_thread aio/0
141 141 TS - 0 24 0 0.0 S kswapd kswapd0
734 734 TS - 0 23 0 0.0 S serio_thread kseriod
929 929 TS - 0 24 0 0.0 S hub_thread khubd
1338 1338 TS - 0 24 0 0.0 S kjournald kjournald
5849 5849 TS - -4 25 0 0.0 S<s select udevd
8145 8145 TS - 0 24 0 0.0 Ss poll syslog-ng
8196 8196 TS - 0 23 0 0.0 Ss poll acpid
8685 8685 TS - 0 23 0 0.0 Ss nanosleep dhcpcd
8828 8828 TS - 0 21 0 0.0 Ss select sshd
8865 8865 TS - 0 24 0 0.0 Ss nanosleep cron
8930 8930 TS - 0 23 0 0.0 Ss+ read_chan agetty
8931 8931 TS - 0 22 0 0.0 Ss+ read_chan agetty
8932 8932 TS - 0 22 0 0.0 Ss+ read_chan agetty
8933 8933 TS - 0 23 0 0.0 Ss+ read_chan agetty
9014 9014 TS - 0 23 0 0.0 Ss+ read_chan agetty
9092 9092 TS - 0 23 0 0.0 Ss select kdm
9095 9095 TS - 0 23 0 0.5 R - X
28934 28934 TS - 0 23 0 0.0 Ss+ read_chan agetty
29050 29050 TS - 0 23 0 0.0 S wait kdm
29057 29057 TS - 0 24 0 0.0 S poll gnome-session
29062 29062 TS - 0 23 0 0.1 R - gconfd-2
29065 29065 TS - 0 20 0 0.0 S poll gnome-keyring-d
29067 29067 TS - 0 23 0 0.0 Ss poll bonobo-activati
29069 29069 TS - 0 24 0 0.2 S poll gnome-settings-
29078 29078 TS - 0 23 0 0.0 R - xscreensaver
29096 29096 TS - 0 24 0 0.0 Ss select gnome-smproxy
29104 29104 TS - 0 24 0 0.6 Ss poll metacity
29106 29106 TS - 0 23 0 0.2 Rsl - nautilus
29108 29108 TS - 0 24 0 0.2 Ssl poll gnome-panel
29112 29112 TS - 0 24 0 0.1 Rs - gnome-volume-co
29114 29114 TS - 0 24 0 0.0 Ssl poll evolution-alarm
29117 29117 TS - 0 24 0 1.0 S poll wnck-applet
29120 29120 TS - 0 23 0 0.0 Rl - gnome-vfs-daemo
29122 29122 TS - 0 23 0 0.0 Sl poll evolution-data-
29147 29147 TS - 0 23 0 0.0 R - mapping-daemon
29152 29152 TS - 0 23 0 0.0 R - battstat-applet
29154 29154 TS - 0 24 0 0.1 S poll gnome-netstatus
29156 29156 TS - 0 24 0 0.2 Rl - mono
29158 29158 TS - 0 23 0 0.1 R - clock-applet
29160 29160 TS - 0 24 0 0.0 S poll multiload-apple
29162 29162 TS - 0 24 0 0.0 Sl poll gweather-applet
29163 29163 TS - 0 24 0 0.0 Ss poll mono
29169 29169 TS - 0 24 0 1.0 Sl poll gnome-terminal
29170 29170 TS - 0 22 0 0.0 S unix_stream_da gnome-pty-helpe
29171 29171 TS - 0 23 0 0.0 Ss wait bash
29175 29175 TS - 0 24 0 2.6 Sl poll evolution-2.2
29211 29211 TS - 0 24 0 0.0 Ss wait bash
29246 29246 TS - 0 23 0 0.0 Ss wait bash
29250 29250 TS - 0 22 0 0.0 S wait mozilla-launche
29261 29261 TS - 0 24 0 1.9 Sl poll firefox-bin
29391 29391 TS - 0 23 0 0.0 S wait su
29399 29399 TS - 0 23 0 0.0 S+ read_chan bash
29415 29415 TS - 0 23 0 0.0 S wait su
29418 29418 FF 1 - 41 0 0.0 S wait bash
29594 29594 TS - 0 23 0 0.0 S+ wait bash
29595 29595 TS - 0 23 0 0.2 S+ pipe_wait wine-preloader
29602 29602 TS - 0 24 0 13.4 Ss poll wineserver
29604 29604 TS - 0 24 0 17.7 R+ - wine-preloader
29729 29729 FF 1 - 41 0 0.0 R+ - ps
Tue May 31 18:40:35 EEST 2005
PID TID CLS RTPRIO NI PRI PSR %CPU STAT WCHAN COMMAND
1 1 TS - 0 23 0 0.0 R - init
2 2 TS - 19 5 0 0.0 SN ksoftirqd ksoftirqd/0
3 3 TS - -5 29 0 0.0 S< worker_thread events/0
4 4 TS - -5 25 0 0.0 S< worker_thread khelper
9 9 TS - -5 28 0 0.0 S< worker_thread kthread
18 18 TS - -5 29 0 0.0 S< worker_thread kacpid
90 90 TS - -5 29 0 0.0 S< worker_thread kblockd/0
139 139 TS - 0 24 0 0.0 S pdflush pdflush
140 140 TS - 0 24 0 0.0 S pdflush pdflush
142 142 TS - -5 27 0 0.0 S< worker_thread aio/0
141 141 TS - 0 24 0 0.0 S kswapd kswapd0
734 734 TS - 0 23 0 0.0 S serio_thread kseriod
929 929 TS - 0 24 0 0.0 S hub_thread khubd
1338 1338 TS - 0 24 0 0.0 S kjournald kjournald
5849 5849 TS - -4 25 0 0.0 S<s select udevd
8145 8145 TS - 0 24 0 0.0 Ss poll syslog-ng
8196 8196 TS - 0 23 0 0.0 Ss poll acpid
8685 8685 TS - 0 23 0 0.0 Ss nanosleep dhcpcd
8828 8828 TS - 0 21 0 0.0 Ss select sshd
8865 8865 TS - 0 24 0 0.0 Ss nanosleep cron
8930 8930 TS - 0 23 0 0.0 Ss+ read_chan agetty
8931 8931 TS - 0 22 0 0.0 Ss+ read_chan agetty
8932 8932 TS - 0 22 0 0.0 Ss+ read_chan agetty
8933 8933 TS - 0 23 0 0.0 Ss+ read_chan agetty
9014 9014 TS - 0 23 0 0.0 Ss+ read_chan agetty
9092 9092 TS - 0 23 0 0.0 Ss select kdm
9095 9095 TS - 0 23 0 0.5 R - X
28934 28934 TS - 0 23 0 0.0 Ss+ read_chan agetty
29050 29050 TS - 0 23 0 0.0 S wait kdm
29057 29057 TS - 0 24 0 0.0 S poll gnome-session
29062 29062 TS - 0 23 0 0.1 R - gconfd-2
29065 29065 TS - 0 20 0 0.0 S poll gnome-keyring-d
29067 29067 TS - 0 23 0 0.0 Ss poll bonobo-activati
29069 29069 TS - 0 24 0 0.2 S poll gnome-settings-
29078 29078 TS - 0 23 0 0.0 R - xscreensaver
29096 29096 TS - 0 24 0 0.0 Ss select gnome-smproxy
29104 29104 TS - 0 24 0 0.6 Ss poll metacity
29106 29106 TS - 0 23 0 0.2 Rsl - nautilus
29108 29108 TS - 0 24 0 0.2 Ssl poll gnome-panel
29112 29112 TS - 0 24 0 0.1 Rs - gnome-volume-co
29114 29114 TS - 0 24 0 0.0 Ssl poll evolution-alarm
29117 29117 TS - 0 24 0 1.0 S poll wnck-applet
29120 29120 TS - 0 23 0 0.0 Rl - gnome-vfs-daemo
29122 29122 TS - 0 23 0 0.0 Sl poll evolution-data-
29147 29147 TS - 0 23 0 0.0 R - mapping-daemon
29152 29152 TS - 0 23 0 0.0 R - battstat-applet
29154 29154 TS - 0 24 0 0.1 S poll gnome-netstatus
29156 29156 TS - 0 24 0 0.2 Rl - mono
29158 29158 TS - 0 23 0 0.1 R - clock-applet
29160 29160 TS - 0 24 0 0.0 S poll multiload-apple
29162 29162 TS - 0 24 0 0.0 Sl poll gweather-applet
29163 29163 TS - 0 24 0 0.0 Ss poll mono
29169 29169 TS - 0 24 0 1.0 Sl poll gnome-terminal
29170 29170 TS - 0 22 0 0.0 S unix_stream_da gnome-pty-helpe
29171 29171 TS - 0 23 0 0.0 Ss wait bash
29175 29175 TS - 0 24 0 2.6 Sl poll evolution-2.2
29211 29211 TS - 0 24 0 0.0 Ss wait bash
29246 29246 TS - 0 23 0 0.0 Ss wait bash
29250 29250 TS - 0 22 0 0.0 S wait mozilla-launche
29261 29261 TS - 0 24 0 1.9 Sl poll firefox-bin
29391 29391 TS - 0 23 0 0.0 S wait su
29399 29399 TS - 0 23 0 0.0 S+ read_chan bash
29415 29415 TS - 0 23 0 0.0 S wait su
29418 29418 FF 1 - 41 0 0.0 S wait bash
29594 29594 TS - 0 23 0 0.0 S+ wait bash
29595 29595 TS - 0 23 0 0.2 S+ pipe_wait wine-preloader
29602 29602 TS - 0 24 0 13.7 Rs - wineserver
29604 29604 TS - 0 24 0 18.1 S+ pipe_wait wine-preloader
29732 29732 FF 1 - 41 0 0.0 R+ - ps
Tue May 31 18:40:36 EEST 2005
PID TID CLS RTPRIO NI PRI PSR %CPU STAT WCHAN COMMAND
1 1 TS - 0 23 0 0.0 R - init
2 2 TS - 19 5 0 0.0 SN ksoftirqd ksoftirqd/0
3 3 TS - -5 29 0 0.0 S< worker_thread events/0
4 4 TS - -5 25 0 0.0 S< worker_thread khelper
9 9 TS - -5 28 0 0.0 S< worker_thread kthread
18 18 TS - -5 29 0 0.0 S< worker_thread kacpid
90 90 TS - -5 29 0 0.0 S< worker_thread kblockd/0
139 139 TS - 0 24 0 0.0 S pdflush pdflush
140 140 TS - 0 24 0 0.0 S pdflush pdflush
142 142 TS - -5 27 0 0.0 S< worker_thread aio/0
141 141 TS - 0 24 0 0.0 S kswapd kswapd0
734 734 TS - 0 23 0 0.0 S serio_thread kseriod
929 929 TS - 0 24 0 0.0 S hub_thread khubd
1338 1338 TS - 0 24 0 0.0 S kjournald kjournald
5849 5849 TS - -4 25 0 0.0 S<s select udevd
8145 8145 TS - 0 24 0 0.0 Ss poll syslog-ng
8196 8196 TS - 0 23 0 0.0 Ss poll acpid
8685 8685 TS - 0 23 0 0.0 Ss nanosleep dhcpcd
8828 8828 TS - 0 21 0 0.0 Ss select sshd
8865 8865 TS - 0 24 0 0.0 Ss nanosleep cron
8930 8930 TS - 0 23 0 0.0 Ss+ read_chan agetty
8931 8931 TS - 0 22 0 0.0 Ss+ read_chan agetty
8932 8932 TS - 0 22 0 0.0 Ss+ read_chan agetty
8933 8933 TS - 0 23 0 0.0 Ss+ read_chan agetty
9014 9014 TS - 0 23 0 0.0 Ss+ read_chan agetty
9092 9092 TS - 0 23 0 0.0 Ss select kdm
9095 9095 TS - 0 23 0 0.5 R - X
28934 28934 TS - 0 23 0 0.0 Ss+ read_chan agetty
29050 29050 TS - 0 23 0 0.0 S wait kdm
29057 29057 TS - 0 24 0 0.0 S poll gnome-session
29062 29062 TS - 0 23 0 0.1 R - gconfd-2
29065 29065 TS - 0 20 0 0.0 S poll gnome-keyring-d
29067 29067 TS - 0 23 0 0.0 Ss poll bonobo-activati
29069 29069 TS - 0 24 0 0.2 S poll gnome-settings-
29078 29078 TS - 0 23 0 0.0 R - xscreensaver
29096 29096 TS - 0 24 0 0.0 Ss select gnome-smproxy
29104 29104 TS - 0 24 0 0.6 Ss poll metacity
29106 29106 TS - 0 23 0 0.2 Rsl - nautilus
29108 29108 TS - 0 24 0 0.2 Ssl poll gnome-panel
29112 29112 TS - 0 24 0 0.1 Ss poll gnome-volume-co
29114 29114 TS - 0 24 0 0.0 Ssl poll evolution-alarm
29117 29117 TS - 0 24 0 1.0 S poll wnck-applet
29120 29120 TS - 0 23 0 0.0 Rl - gnome-vfs-daemo
29122 29122 TS - 0 23 0 0.0 Sl poll evolution-data-
29147 29147 TS - 0 23 0 0.0 R - mapping-daemon
29152 29152 TS - 0 23 0 0.0 R - battstat-applet
29154 29154 TS - 0 24 0 0.1 S poll gnome-netstatus
29156 29156 TS - 0 24 0 0.2 Rl - mono
29158 29158 TS - 0 23 0 0.1 R - clock-applet
29160 29160 TS - 0 24 0 0.0 S poll multiload-apple
29162 29162 TS - 0 24 0 0.0 Sl poll gweather-applet
29163 29163 TS - 0 24 0 0.0 Ss poll mono
29169 29169 TS - 0 24 0 1.0 Sl poll gnome-terminal
29170 29170 TS - 0 22 0 0.0 S unix_stream_da gnome-pty-helpe
29171 29171 TS - 0 23 0 0.0 Ss wait bash
29175 29175 TS - 0 24 0 2.6 Sl poll evolution-2.2
29211 29211 TS - 0 24 0 0.0 Ss wait bash
29246 29246 TS - 0 23 0 0.0 Ss wait bash
29250 29250 TS - 0 22 0 0.0 S wait mozilla-launche
29261 29261 TS - 0 24 0 1.9 Sl poll firefox-bin
29391 29391 TS - 0 23 0 0.0 S wait su
29399 29399 TS - 0 23 0 0.0 S+ read_chan bash
29415 29415 TS - 0 23 0 0.0 S wait su
29418 29418 FF 1 - 41 0 0.0 S wait bash
29594 29594 TS - 0 23 0 0.0 S+ wait bash
29595 29595 TS - 0 23 0 0.2 S+ pipe_wait wine-preloader
29602 29602 TS - 0 24 0 13.7 Ss poll wineserver
29604 29604 TS - 0 24 0 18.3 R+ - wine-preloader
29735 29735 FF 1 - 41 0 0.0 R+ - ps
Tue May 31 18:40:37 EEST 2005
PID TID CLS RTPRIO NI PRI PSR %CPU STAT WCHAN COMMAND
1 1 TS - 0 23 0 0.0 R - init
2 2 TS - 19 5 0 0.0 SN ksoftirqd ksoftirqd/0
3 3 TS - -5 29 0 0.0 S< worker_thread events/0
4 4 TS - -5 25 0 0.0 S< worker_thread khelper
9 9 TS - -5 28 0 0.0 S< worker_thread kthread
18 18 TS - -5 29 0 0.0 S< worker_thread kacpid
90 90 TS - -5 29 0 0.0 S< worker_thread kblockd/0
139 139 TS - 0 24 0 0.0 S pdflush pdflush
140 140 TS - 0 24 0 0.0 S pdflush pdflush
142 142 TS - -5 27 0 0.0 S< worker_thread aio/0
141 141 TS - 0 24 0 0.0 S kswapd kswapd0
734 734 TS - 0 23 0 0.0 S serio_thread kseriod
929 929 TS - 0 24 0 0.0 S hub_thread khubd
1338 1338 TS - 0 24 0 0.0 S kjournald kjournald
5849 5849 TS - -4 25 0 0.0 S<s select udevd
8145 8145 TS - 0 24 0 0.0 Ss poll syslog-ng
8196 8196 TS - 0 23 0 0.0 Ss poll acpid
8685 8685 TS - 0 23 0 0.0 Ss nanosleep dhcpcd
8828 8828 TS - 0 21 0 0.0 Ss select sshd
8865 8865 TS - 0 24 0 0.0 Ss nanosleep cron
8930 8930 TS - 0 23 0 0.0 Ss+ read_chan agetty
8931 8931 TS - 0 22 0 0.0 Ss+ read_chan agetty
8932 8932 TS - 0 22 0 0.0 Ss+ read_chan agetty
8933 8933 TS - 0 23 0 0.0 Ss+ read_chan agetty
9014 9014 TS - 0 23 0 0.0 Ss+ read_chan agetty
9092 9092 TS - 0 23 0 0.0 Ss select kdm
9095 9095 TS - 0 23 0 0.5 R - X
28934 28934 TS - 0 23 0 0.0 Ss+ read_chan agetty
29050 29050 TS - 0 23 0 0.0 S wait kdm
29057 29057 TS - 0 24 0 0.0 S poll gnome-session
29062 29062 TS - 0 23 0 0.1 R - gconfd-2
29065 29065 TS - 0 20 0 0.0 S poll gnome-keyring-d
29067 29067 TS - 0 23 0 0.0 Ss poll bonobo-activati
29069 29069 TS - 0 24 0 0.2 S poll gnome-settings-
29078 29078 TS - 0 23 0 0.0 R - xscreensaver
29096 29096 TS - 0 24 0 0.0 Ss select gnome-smproxy
29104 29104 TS - 0 24 0 0.6 Ss poll metacity
29106 29106 TS - 0 23 0 0.2 Rsl - nautilus
29108 29108 TS - 0 24 0 0.2 Ssl poll gnome-panel
29112 29112 TS - 0 24 0 0.1 Ss poll gnome-volume-co
29114 29114 TS - 0 24 0 0.0 Ssl poll evolution-alarm
29117 29117 TS - 0 24 0 1.0 S poll wnck-applet
29120 29120 TS - 0 23 0 0.0 Rl - gnome-vfs-daemo
29122 29122 TS - 0 23 0 0.0 Sl poll evolution-data-
29147 29147 TS - 0 23 0 0.0 R - mapping-daemon
29152 29152 TS - 0 23 0 0.0 R - battstat-applet
29154 29154 TS - 0 24 0 0.1 S poll gnome-netstatus
29156 29156 TS - 0 24 0 0.2 Rl - mono
29158 29158 TS - 0 23 0 0.1 R - clock-applet
29160 29160 TS - 0 24 0 0.0 S poll multiload-apple
29162 29162 TS - 0 24 0 0.0 Sl poll gweather-applet
29163 29163 TS - 0 24 0 0.0 Ss poll mono
29169 29169 TS - 0 24 0 1.0 Sl poll gnome-terminal
29170 29170 TS - 0 22 0 0.0 S unix_stream_da gnome-pty-helpe
29171 29171 TS - 0 23 0 0.0 Ss wait bash
29175 29175 TS - 0 24 0 2.6 Sl poll evolution-2.2
29211 29211 TS - 0 24 0 0.0 Ss wait bash
29246 29246 TS - 0 23 0 0.0 Ss wait bash
29250 29250 TS - 0 22 0 0.0 S wait mozilla-launche
29261 29261 TS - 0 24 0 1.9 Sl poll firefox-bin
29391 29391 TS - 0 23 0 0.0 S wait su
29399 29399 TS - 0 23 0 0.0 S+ read_chan bash
29415 29415 TS - 0 23 0 0.0 S wait su
29418 29418 FF 1 - 41 0 0.0 S wait bash
29594 29594 TS - 0 23 0 0.0 S+ wait bash
29595 29595 TS - 0 23 0 0.2 S+ pipe_wait wine-preloader
29602 29602 TS - 0 24 0 13.9 Ss poll wineserver
29604 29604 TS - 0 24 0 18.6 R+ - wine-preloader
29738 29738 FF 1 - 41 0 0.0 R+ - ps
Tue May 31 18:40:38 EEST 2005
PID TID CLS RTPRIO NI PRI PSR %CPU STAT WCHAN COMMAND
1 1 TS - 0 23 0 0.0 R - init
2 2 TS - 19 5 0 0.0 SN ksoftirqd ksoftirqd/0
3 3 TS - -5 29 0 0.0 S< worker_thread events/0
4 4 TS - -5 25 0 0.0 S< worker_thread khelper
9 9 TS - -5 28 0 0.0 S< worker_thread kthread
18 18 TS - -5 29 0 0.0 S< worker_thread kacpid
90 90 TS - -5 29 0 0.0 S< worker_thread kblockd/0
139 139 TS - 0 24 0 0.0 S pdflush pdflush
140 140 TS - 0 24 0 0.0 S pdflush pdflush
142 142 TS - -5 27 0 0.0 S< worker_thread aio/0
141 141 TS - 0 24 0 0.0 S kswapd kswapd0
734 734 TS - 0 23 0 0.0 S serio_thread kseriod
929 929 TS - 0 24 0 0.0 S hub_thread khubd
1338 1338 TS - 0 24 0 0.0 D sync_buffer kjournald
5849 5849 TS - -4 25 0 0.0 S<s select udevd
8145 8145 TS - 0 24 0 0.0 Ss poll syslog-ng
8196 8196 TS - 0 23 0 0.0 Ss poll acpid
8685 8685 TS - 0 23 0 0.0 Ss nanosleep dhcpcd
8828 8828 TS - 0 21 0 0.0 Ss select sshd
8865 8865 TS - 0 24 0 0.0 Ss nanosleep cron
8930 8930 TS - 0 23 0 0.0 Ss+ read_chan agetty
8931 8931 TS - 0 22 0 0.0 Ss+ read_chan agetty
8932 8932 TS - 0 22 0 0.0 Ss+ read_chan agetty
8933 8933 TS - 0 23 0 0.0 Ss+ read_chan agetty
9014 9014 TS - 0 23 0 0.0 Ss+ read_chan agetty
9092 9092 TS - 0 23 0 0.0 Ss select kdm
9095 9095 TS - 0 23 0 0.5 R - X
28934 28934 TS - 0 23 0 0.0 Ss+ read_chan agetty
29050 29050 TS - 0 23 0 0.0 S wait kdm
29057 29057 TS - 0 24 0 0.0 S poll gnome-session
29062 29062 TS - 0 23 0 0.1 R - gconfd-2
29065 29065 TS - 0 20 0 0.0 S poll gnome-keyring-d
29067 29067 TS - 0 23 0 0.0 Ss poll bonobo-activati
29069 29069 TS - 0 24 0 0.2 S poll gnome-settings-
29078 29078 TS - 0 23 0 0.0 R - xscreensaver
29096 29096 TS - 0 24 0 0.0 Ss select gnome-smproxy
29104 29104 TS - 0 24 0 0.6 Ss poll metacity
29106 29106 TS - 0 23 0 0.2 Rsl - nautilus
29108 29108 TS - 0 24 0 0.2 Ssl poll gnome-panel
29112 29112 TS - 0 24 0 0.1 Ss poll gnome-volume-co
29114 29114 TS - 0 24 0 0.0 Ssl poll evolution-alarm
29117 29117 TS - 0 24 0 1.0 S poll wnck-applet
29120 29120 TS - 0 23 0 0.0 Rl - gnome-vfs-daemo
29122 29122 TS - 0 23 0 0.0 Sl poll evolution-data-
29147 29147 TS - 0 23 0 0.0 R - mapping-daemon
29152 29152 TS - 0 23 0 0.0 R - battstat-applet
29154 29154 TS - 0 24 0 0.1 S poll gnome-netstatus
29156 29156 TS - 0 24 0 0.2 Rl - mono
29158 29158 TS - 0 23 0 0.1 R - clock-applet
29160 29160 TS - 0 24 0 0.0 S poll multiload-apple
29162 29162 TS - 0 24 0 0.0 Sl poll gweather-applet
29163 29163 TS - 0 24 0 0.0 Ss poll mono
29169 29169 TS - 0 24 0 1.0 Sl poll gnome-terminal
29170 29170 TS - 0 22 0 0.0 S unix_stream_da gnome-pty-helpe
29171 29171 TS - 0 23 0 0.0 Ss wait bash
29175 29175 TS - 0 24 0 2.6 Sl poll evolution-2.2
29211 29211 TS - 0 24 0 0.0 Ss wait bash
29246 29246 TS - 0 23 0 0.0 Ss wait bash
29250 29250 TS - 0 22 0 0.0 S wait mozilla-launche
29261 29261 TS - 0 24 0 1.9 Sl poll firefox-bin
29391 29391 TS - 0 23 0 0.0 S wait su
29399 29399 TS - 0 23 0 0.0 S+ read_chan bash
29415 29415 TS - 0 23 0 0.0 S wait su
29418 29418 FF 1 - 41 0 0.0 S wait bash
29594 29594 TS - 0 23 0 0.0 S+ wait bash
29595 29595 TS - 0 23 0 0.2 S+ pipe_wait wine-preloader
29602 29602 TS - 0 24 0 14.1 Rs - wineserver
29604 29604 TS - 0 24 0 19.0 S+ pipe_wait wine-preloader
29741 29741 FF 1 - 41 0 0.0 R+ - ps
Tue May 31 18:40:39 EEST 2005
PID TID CLS RTPRIO NI PRI PSR %CPU STAT WCHAN COMMAND
1 1 TS - 0 23 0 0.0 R - init
2 2 TS - 19 5 0 0.0 SN ksoftirqd ksoftirqd/0
3 3 TS - -5 29 0 0.0 S< worker_thread events/0
4 4 TS - -5 25 0 0.0 S< worker_thread khelper
9 9 TS - -5 28 0 0.0 S< worker_thread kthread
18 18 TS - -5 29 0 0.0 S< worker_thread kacpid
90 90 TS - -5 29 0 0.0 S< worker_thread kblockd/0
139 139 TS - 0 24 0 0.0 S pdflush pdflush
140 140 TS - 0 24 0 0.0 S pdflush pdflush
142 142 TS - -5 27 0 0.0 S< worker_thread aio/0
141 141 TS - 0 24 0 0.0 S kswapd kswapd0
734 734 TS - 0 23 0 0.0 S serio_thread kseriod
929 929 TS - 0 24 0 0.0 S hub_thread khubd
1338 1338 TS - 0 24 0 0.0 S kjournald kjournald
5849 5849 TS - -4 25 0 0.0 S<s select udevd
8145 8145 TS - 0 24 0 0.0 Ss poll syslog-ng
8196 8196 TS - 0 23 0 0.0 Ss poll acpid
8685 8685 TS - 0 23 0 0.0 Ss nanosleep dhcpcd
8828 8828 TS - 0 21 0 0.0 Ss select sshd
8865 8865 TS - 0 24 0 0.0 Ss nanosleep cron
8930 8930 TS - 0 23 0 0.0 Ss+ read_chan agetty
8931 8931 TS - 0 22 0 0.0 Ss+ read_chan agetty
8932 8932 TS - 0 22 0 0.0 Ss+ read_chan agetty
8933 8933 TS - 0 23 0 0.0 Ss+ read_chan agetty
9014 9014 TS - 0 23 0 0.0 Ss+ read_chan agetty
9092 9092 TS - 0 23 0 0.0 Ss select kdm
9095 9095 TS - 0 23 0 0.5 R - X
28934 28934 TS - 0 23 0 0.0 Ss+ read_chan agetty
29050 29050 TS - 0 23 0 0.0 S wait kdm
29057 29057 TS - 0 24 0 0.0 S poll gnome-session
29062 29062 TS - 0 23 0 0.1 R - gconfd-2
29065 29065 TS - 0 20 0 0.0 S poll gnome-keyring-d
29067 29067 TS - 0 23 0 0.0 Ss poll bonobo-activati
29069 29069 TS - 0 24 0 0.2 S poll gnome-settings-
29078 29078 TS - 0 23 0 0.0 R - xscreensaver
29096 29096 TS - 0 24 0 0.0 Ss select gnome-smproxy
29104 29104 TS - 0 24 0 0.6 Ss poll metacity
29106 29106 TS - 0 23 0 0.2 Rsl - nautilus
29108 29108 TS - 0 24 0 0.2 Ssl poll gnome-panel
29112 29112 TS - 0 24 0 0.1 Ss poll gnome-volume-co
29114 29114 TS - 0 24 0 0.0 Ssl poll evolution-alarm
29117 29117 TS - 0 24 0 1.0 S poll wnck-applet
29120 29120 TS - 0 23 0 0.0 Rl - gnome-vfs-daemo
29122 29122 TS - 0 23 0 0.0 Sl poll evolution-data-
29147 29147 TS - 0 23 0 0.0 R - mapping-daemon
29152 29152 TS - 0 23 0 0.0 R - battstat-applet
29154 29154 TS - 0 24 0 0.1 S poll gnome-netstatus
29156 29156 TS - 0 24 0 0.2 Rl - mono
29158 29158 TS - 0 23 0 0.1 R - clock-applet
29160 29160 TS - 0 24 0 0.0 S poll multiload-apple
29162 29162 TS - 0 24 0 0.0 Sl poll gweather-applet
29163 29163 TS - 0 24 0 0.0 Ss poll mono
29169 29169 TS - 0 24 0 1.0 Sl poll gnome-terminal
29170 29170 TS - 0 22 0 0.0 S unix_stream_da gnome-pty-helpe
29171 29171 TS - 0 23 0 0.0 Ss wait bash
29175 29175 TS - 0 24 0 2.6 Sl poll evolution-2.2
29211 29211 TS - 0 24 0 0.0 Ss wait bash
29246 29246 TS - 0 23 0 0.0 Ss wait bash
29250 29250 TS - 0 22 0 0.0 S wait mozilla-launche
29261 29261 TS - 0 24 0 1.9 Sl poll firefox-bin
29391 29391 TS - 0 23 0 0.0 S wait su
29399 29399 TS - 0 23 0 0.0 S+ read_chan bash
29415 29415 TS - 0 23 0 0.0 S wait su
29418 29418 FF 1 - 41 0 0.0 S wait bash
29594 29594 TS - 0 23 0 0.0 S+ wait bash
29595 29595 TS - 0 23 0 0.2 S+ pipe_wait wine-preloader
29602 29602 TS - 0 24 0 14.3 Ss poll wineserver
29604 29604 TS - 0 24 0 19.3 R+ - wine-preloader
29744 29744 FF 1 - 41 0 0.0 R+ - ps
Tue May 31 18:40:40 EEST 2005
PID TID CLS RTPRIO NI PRI PSR %CPU STAT WCHAN COMMAND
1 1 TS - 0 23 0 0.0 R - init
2 2 TS - 19 5 0 0.0 SN ksoftirqd ksoftirqd/0
3 3 TS - -5 29 0 0.0 S< worker_thread events/0
4 4 TS - -5 25 0 0.0 S< worker_thread khelper
9 9 TS - -5 28 0 0.0 S< worker_thread kthread
18 18 TS - -5 29 0 0.0 S< worker_thread kacpid
90 90 TS - -5 29 0 0.0 S< worker_thread kblockd/0
139 139 TS - 0 24 0 0.0 S pdflush pdflush
140 140 TS - 0 24 0 0.0 S pdflush pdflush
142 142 TS - -5 27 0 0.0 S< worker_thread aio/0
141 141 TS - 0 24 0 0.0 S kswapd kswapd0
734 734 TS - 0 23 0 0.0 S serio_thread kseriod
929 929 TS - 0 24 0 0.0 S hub_thread khubd
1338 1338 TS - 0 24 0 0.0 S kjournald kjournald
5849 5849 TS - -4 25 0 0.0 S<s select udevd
8145 8145 TS - 0 24 0 0.0 Ss poll syslog-ng
8196 8196 TS - 0 23 0 0.0 Ss poll acpid
8685 8685 TS - 0 23 0 0.0 Ss nanosleep dhcpcd
8828 8828 TS - 0 21 0 0.0 Ss select sshd
8865 8865 TS - 0 24 0 0.0 Ss nanosleep cron
8930 8930 TS - 0 23 0 0.0 Ss+ read_chan agetty
8931 8931 TS - 0 22 0 0.0 Ss+ read_chan agetty
8932 8932 TS - 0 22 0 0.0 Ss+ read_chan agetty
8933 8933 TS - 0 23 0 0.0 Ss+ read_chan agetty
9014 9014 TS - 0 23 0 0.0 Ss+ read_chan agetty
9092 9092 TS - 0 23 0 0.0 Ss select kdm
9095 9095 TS - 0 23 0 0.5 R - X
28934 28934 TS - 0 23 0 0.0 Ss+ read_chan agetty
29050 29050 TS - 0 23 0 0.0 S wait kdm
29057 29057 TS - 0 24 0 0.0 S poll gnome-session
29062 29062 TS - 0 23 0 0.1 R - gconfd-2
29065 29065 TS - 0 20 0 0.0 S poll gnome-keyring-d
29067 29067 TS - 0 23 0 0.0 Ss poll bonobo-activati
29069 29069 TS - 0 24 0 0.2 S poll gnome-settings-
29078 29078 TS - 0 23 0 0.0 R - xscreensaver
29096 29096 TS - 0 24 0 0.0 Ss select gnome-smproxy
29104 29104 TS - 0 24 0 0.6 Ss poll metacity
29106 29106 TS - 0 23 0 0.2 Rsl - nautilus
29108 29108 TS - 0 24 0 0.2 Ssl poll gnome-panel
29112 29112 TS - 0 24 0 0.1 Rs - gnome-volume-co
29114 29114 TS - 0 24 0 0.0 Ssl poll evolution-alarm
29117 29117 TS - 0 24 0 1.0 S poll wnck-applet
29120 29120 TS - 0 23 0 0.0 Rl - gnome-vfs-daemo
29122 29122 TS - 0 23 0 0.0 Sl poll evolution-data-
29147 29147 TS - 0 23 0 0.0 R - mapping-daemon
29152 29152 TS - 0 23 0 0.0 R - battstat-applet
29154 29154 TS - 0 24 0 0.1 S poll gnome-netstatus
29156 29156 TS - 0 24 0 0.2 Rl - mono
29158 29158 TS - 0 23 0 0.1 R - clock-applet
29160 29160 TS - 0 24 0 0.0 S poll multiload-apple
29162 29162 TS - 0 24 0 0.0 Sl poll gweather-applet
29163 29163 TS - 0 24 0 0.0 Ss poll mono
29169 29169 TS - 0 24 0 1.0 Sl poll gnome-terminal
29170 29170 TS - 0 22 0 0.0 S unix_stream_da gnome-pty-helpe
29171 29171 TS - 0 23 0 0.0 Ss wait bash
29175 29175 TS - 0 24 0 2.6 Sl poll evolution-2.2
29211 29211 TS - 0 24 0 0.0 Ss wait bash
29246 29246 TS - 0 23 0 0.0 Ss wait bash
29250 29250 TS - 0 22 0 0.0 S wait mozilla-launche
29261 29261 TS - 0 24 0 1.9 Sl poll firefox-bin
29391 29391 TS - 0 23 0 0.0 S wait su
29399 29399 TS - 0 23 0 0.0 S+ read_chan bash
29415 29415 TS - 0 23 0 0.0 S wait su
29418 29418 FF 1 - 41 0 0.0 S wait bash
29594 29594 TS - 0 23 0 0.0 S+ wait bash
29595 29595 TS - 0 23 0 0.2 S+ pipe_wait wine-preloader
29602 29602 TS - 0 24 0 14.4 Ss poll wineserver
29604 29604 TS - 0 24 0 19.6 R+ - wine-preloader
29747 29747 FF 1 - 41 0 0.0 R+ - ps
Tue May 31 18:40:41 EEST 2005
PID TID CLS RTPRIO NI PRI PSR %CPU STAT WCHAN COMMAND
1 1 TS - 0 23 0 0.0 R - init
2 2 TS - 19 5 0 0.0 SN ksoftirqd ksoftirqd/0
3 3 TS - -5 29 0 0.0 S< worker_thread events/0
4 4 TS - -5 25 0 0.0 S< worker_thread khelper
9 9 TS - -5 28 0 0.0 S< worker_thread kthread
18 18 TS - -5 29 0 0.0 S< worker_thread kacpid
90 90 TS - -5 29 0 0.0 S< worker_thread kblockd/0
139 139 TS - 0 24 0 0.0 S pdflush pdflush
140 140 TS - 0 24 0 0.0 S pdflush pdflush
142 142 TS - -5 27 0 0.0 S< worker_thread aio/0
141 141 TS - 0 24 0 0.0 S kswapd kswapd0
734 734 TS - 0 23 0 0.0 S serio_thread kseriod
929 929 TS - 0 24 0 0.0 S hub_thread khubd
1338 1338 TS - 0 24 0 0.0 S kjournald kjournald
5849 5849 TS - -4 25 0 0.0 S<s select udevd
8145 8145 TS - 0 24 0 0.0 Ss poll syslog-ng
8196 8196 TS - 0 23 0 0.0 Ss poll acpid
8685 8685 TS - 0 23 0 0.0 Ss nanosleep dhcpcd
8828 8828 TS - 0 21 0 0.0 Ss select sshd
8865 8865 TS - 0 24 0 0.0 Ss nanosleep cron
8930 8930 TS - 0 23 0 0.0 Ss+ read_chan agetty
8931 8931 TS - 0 22 0 0.0 Ss+ read_chan agetty
8932 8932 TS - 0 22 0 0.0 Ss+ read_chan agetty
8933 8933 TS - 0 23 0 0.0 Ss+ read_chan agetty
9014 9014 TS - 0 23 0 0.0 Ss+ read_chan agetty
9092 9092 TS - 0 23 0 0.0 Ss select kdm
9095 9095 TS - 0 23 0 0.5 R - X
28934 28934 TS - 0 23 0 0.0 Ss+ read_chan agetty
29050 29050 TS - 0 23 0 0.0 S wait kdm
29057 29057 TS - 0 24 0 0.0 S poll gnome-session
29062 29062 TS - 0 23 0 0.1 R - gconfd-2
29065 29065 TS - 0 20 0 0.0 S poll gnome-keyring-d
29067 29067 TS - 0 23 0 0.0 Ss poll bonobo-activati
29069 29069 TS - 0 24 0 0.2 S poll gnome-settings-
29078 29078 TS - 0 23 0 0.0 R - xscreensaver
29096 29096 TS - 0 24 0 0.0 Ss select gnome-smproxy
29104 29104 TS - 0 24 0 0.6 Ss poll metacity
29106 29106 TS - 0 23 0 0.2 Rsl - nautilus
29108 29108 TS - 0 24 0 0.2 Ssl poll gnome-panel
29112 29112 TS - 0 24 0 0.1 Rs - gnome-volume-co
29114 29114 TS - 0 24 0 0.0 Ssl poll evolution-alarm
29117 29117 TS - 0 24 0 1.0 S poll wnck-applet
29120 29120 TS - 0 23 0 0.0 Rl - gnome-vfs-daemo
29122 29122 TS - 0 23 0 0.0 Sl poll evolution-data-
29147 29147 TS - 0 23 0 0.0 R - mapping-daemon
29152 29152 TS - 0 23 0 0.0 R - battstat-applet
29154 29154 TS - 0 24 0 0.1 S poll gnome-netstatus
29156 29156 TS - 0 24 0 0.2 Rl - mono
29158 29158 TS - 0 23 0 0.1 R - clock-applet
29160 29160 TS - 0 24 0 0.0 S poll multiload-apple
29162 29162 TS - 0 24 0 0.0 Sl poll gweather-applet
29163 29163 TS - 0 24 0 0.0 Ss poll mono
29169 29169 TS - 0 24 0 1.0 Sl poll gnome-terminal
29170 29170 TS - 0 22 0 0.0 S unix_stream_da gnome-pty-helpe
29171 29171 TS - 0 23 0 0.0 Ss wait bash
29175 29175 TS - 0 24 0 2.6 Sl poll evolution-2.2
29211 29211 TS - 0 24 0 0.0 Ss wait bash
29246 29246 TS - 0 23 0 0.0 Ss wait bash
29250 29250 TS - 0 22 0 0.0 S wait mozilla-launche
29261 29261 TS - 0 24 0 1.9 Sl poll firefox-bin
29391 29391 TS - 0 23 0 0.0 S wait su
29399 29399 TS - 0 23 0 0.0 S+ read_chan bash
29415 29415 TS - 0 23 0 0.0 S wait su
29418 29418 FF 1 - 41 0 0.0 S wait bash
29594 29594 TS - 0 23 0 0.0 S+ wait bash
29595 29595 TS - 0 23 0 0.2 S+ pipe_wait wine-preloader
29602 29602 TS - 0 24 0 14.6 Rs - wineserver
29604 29604 TS - 0 24 0 19.9 S+ pipe_wait wine-preloader
29750 29750 FF 1 - 41 0 0.0 R+ - ps
Tue May 31 18:40:42 EEST 2005
PID TID CLS RTPRIO NI PRI PSR %CPU STAT WCHAN COMMAND
1 1 TS - 0 23 0 0.0 S select init
2 2 TS - 19 5 0 0.0 SN ksoftirqd ksoftirqd/0
3 3 TS - -5 29 0 0.0 S< worker_thread events/0
4 4 TS - -5 25 0 0.0 S< worker_thread khelper
9 9 TS - -5 28 0 0.0 S< worker_thread kthread
18 18 TS - -5 29 0 0.0 S< worker_thread kacpid
90 90 TS - -5 29 0 0.0 S< worker_thread kblockd/0
139 139 TS - 0 24 0 0.0 S pdflush pdflush
140 140 TS - 0 24 0 0.0 S pdflush pdflush
142 142 TS - -5 27 0 0.0 S< worker_thread aio/0
141 141 TS - 0 24 0 0.0 S kswapd kswapd0
734 734 TS - 0 23 0 0.0 S serio_thread kseriod
929 929 TS - 0 24 0 0.0 S hub_thread khubd
1338 1338 TS - 0 24 0 0.0 S kjournald kjournald
5849 5849 TS - -4 25 0 0.0 S<s select udevd
8145 8145 TS - 0 24 0 0.0 Ss poll syslog-ng
8196 8196 TS - 0 23 0 0.0 Ss poll acpid
8685 8685 TS - 0 23 0 0.0 Ss nanosleep dhcpcd
8828 8828 TS - 0 21 0 0.0 Ss select sshd
8865 8865 TS - 0 24 0 0.0 Ss nanosleep cron
8930 8930 TS - 0 23 0 0.0 Ss+ read_chan agetty
8931 8931 TS - 0 22 0 0.0 Ss+ read_chan agetty
8932 8932 TS - 0 22 0 0.0 Ss+ read_chan agetty
8933 8933 TS - 0 23 0 0.0 Ss+ read_chan agetty
9014 9014 TS - 0 23 0 0.0 Ss+ read_chan agetty
9092 9092 TS - 0 23 0 0.0 Ss select kdm
9095 9095 TS - 0 23 0 0.5 S select X
28934 28934 TS - 0 23 0 0.0 Ss+ read_chan agetty
29050 29050 TS - 0 23 0 0.0 S wait kdm
29057 29057 TS - 0 24 0 0.0 S poll gnome-session
29062 29062 TS - 0 23 0 0.1 S poll gconfd-2
29065 29065 TS - 0 20 0 0.0 S poll gnome-keyring-d
29067 29067 TS - 0 23 0 0.0 Ss poll bonobo-activati
29069 29069 TS - 0 24 0 0.2 S poll gnome-settings-
29078 29078 TS - 0 24 0 0.0 S select xscreensaver
29096 29096 TS - 0 24 0 0.0 Ss select gnome-smproxy
29104 29104 TS - 0 24 0 0.6 Ss poll metacity
29106 29106 TS - 0 24 0 0.2 Ssl poll nautilus
29108 29108 TS - 0 24 0 0.2 Ssl poll gnome-panel
29112 29112 TS - 0 24 0 0.1 Ss poll gnome-volume-co
29114 29114 TS - 0 24 0 0.0 Ssl poll evolution-alarm
29117 29117 TS - 0 24 0 1.0 S poll wnck-applet
29120 29120 TS - 0 23 0 0.0 Sl poll gnome-vfs-daemo
29122 29122 TS - 0 23 0 0.0 Sl poll evolution-data-
29147 29147 TS - 0 23 0 0.0 S poll mapping-daemon
29152 29152 TS - 0 24 0 0.0 S poll battstat-applet
29154 29154 TS - 0 24 0 0.1 S poll gnome-netstatus
29156 29156 TS - 0 24 0 0.2 Sl futex_wait mono
29158 29158 TS - 0 24 0 0.1 S poll clock-applet
29160 29160 TS - 0 24 0 0.0 S poll multiload-apple
29162 29162 TS - 0 24 0 0.0 Sl poll gweather-applet
29163 29163 TS - 0 24 0 0.0 Ss poll mono
29169 29169 TS - 0 24 0 1.0 Sl poll gnome-terminal
29170 29170 TS - 0 22 0 0.0 S unix_stream_da gnome-pty-helpe
29171 29171 TS - 0 23 0 0.0 Ss wait bash
29175 29175 TS - 0 24 0 2.6 Sl poll evolution-2.2
29211 29211 TS - 0 24 0 0.0 Ss wait bash
29246 29246 TS - 0 23 0 0.0 Ss wait bash
29250 29250 TS - 0 22 0 0.0 S wait mozilla-launche
29261 29261 TS - 0 24 0 1.9 Sl poll firefox-bin
29391 29391 TS - 0 23 0 0.0 S wait su
29399 29399 TS - 0 23 0 0.0 S+ read_chan bash
29415 29415 TS - 0 23 0 0.0 S wait su
29418 29418 FF 1 - 41 0 0.0 S wait bash
29594 29594 TS - 0 23 0 0.0 S+ wait bash
29595 29595 TS - 0 23 0 0.2 S+ pipe_wait wine-preloader
29602 29602 TS - 0 24 0 14.6 Ss poll wineserver
29604 29604 TS - 0 24 0 19.9 S+ pipe_wait wine-preloader
29753 29753 FF 1 - 41 0 0.0 R+ - ps

2005-05-31 17:27:48

[permalink] [raw]

Subject: Re: Machine Freezes while Running Crossover Office

On Tue, 31 May 2005, Pekka Enberg wrote:
>
> On Tue, 2005-05-31 at 08:54 +0200, Ingo Molnar wrote:
> > - apply the patch below and check whether doing:
> >
> > echo 0 > /proc/sys/kernel/interactive
> >
> > makes the hang go away.
>
> It's actually /proc/sys/vm/interactive but yes, 0 makes the hang go away
> while 1 makes it come back.

Ok, it's a scheduler bug.

The pipe thing is probably implicated only because it ends up changing
some timing just enough to make the interactivity tester trigger (ie doing
reads/writes in bigger blocks makes the frequency of the ping-pong between
wineserver and wine different, and then it ends up hitting some harmonic
sweet spot with the timer that makes the scheduler believe it's
interactive).

In fact, I suspect it ends up marking things "interactive" because they do
sleep longer - both of the parties sleep longer because the other end ends
up spending more time handling the work, since the pipe buffers are bigger
(so they sleep longer because they are _awake_ longer).

Ingo, any ideas? This is bothersome, because it could hit any number of
people, and we'd never have realized because it's not usually repeatable
and not usually quite that extreme. But if it can trigger 15-second dead
periods in very specific circumstances, it can probably trigger
half-second dead periods much more easily. Things that people would assume
were due to disk IO or VM badness..

Linus

2005-05-31 18:53:35

[permalink] [raw]

Subject: Re: Machine Freezes while Running Crossover Office

* Linus Torvalds <[email protected]> wrote:

> Ingo, any ideas? This is bothersome, because it could hit any number
> of people, and we'd never have realized because it's not usually
> repeatable and not usually quite that extreme. [...]

it's the fundamental unfairness of the interactivity code (or any
interactivity code that plays favorites between tasks), and the larger
pipe buffers made this more common it seems. It's quite dangerous to
tune the scheduler so close to 2.6.12, but the issue seems serious
enough. I think we could try to change INTERACTIVE_DELTA from 2 to 3 (or
even 4) instead of turning off the back-into-the-active-array logic
altogether.

Pekka, i've attached a quick hack that turns INTERACTIVE_DELTA into a
runtime tunable. It defaults to the stock value of 2, but a value of '5'
should be equivalent to the other patch you tried (but is less hacky).

Now, assuming you can confirm that doing:

echo 5 > /proc/sys/kernel/INTERACTIVE_DELTA

fixes the problem (all other things should be back to the default, i.e.
no renicing of anything), then it would be interesting to find the
minimum value where the problem goes away. I.e. we know that 2 is bad,
but how bad are values 3 and 4?

Ingo

--- linux/kernel/sched.c.orig
+++ linux/kernel/sched.c
@@ -90,7 +90,7 @@
#define EXIT_WEIGHT 3
#define PRIO_BONUS_RATIO 25
#define MAX_BONUS (MAX_USER_PRIO * PRIO_BONUS_RATIO / 100)
-#define INTERACTIVE_DELTA 2
+int INTERACTIVE_DELTA = 2;
#define MAX_SLEEP_AVG (DEF_TIMESLICE * MAX_BONUS)
#define STARVATION_LIMIT (MAX_SLEEP_AVG)
#define NS_MAX_SLEEP_AVG (JIFFIES_TO_NS(MAX_SLEEP_AVG))
--- linux/kernel/sysctl.c.orig
+++ linux/kernel/sysctl.c
@@ -51,6 +51,8 @@

#if defined(CONFIG_SYSCTL)

+extern int INTERACTIVE_DELTA;
+
/* External variables not in a header file. */
extern int C_A_D;
extern int sysctl_overcommit_memory;
@@ -275,6 +277,14 @@ static ctl_table kern_table[] = {
.proc_handler = &proc_dointvec,
},
{
+ .ctl_name = KERN_PANIC,
+ .procname = "INTERACTIVE_DELTA",
+ .data = &INTERACTIVE_DELTA,
+ .maxlen = sizeof(int),
+ .mode = 0644,
+ .proc_handler = &proc_dointvec,
+ },
+ {
.ctl_name = KERN_CORE_USES_PID,
.procname = "core_uses_pid",
.data = &core_uses_pid,

2005-05-31 21:22:41