Heavy lag/delay - 13.1 64bit KDE - solved by iotop? - did I catch a worm/virus?

[ratzi]

This story is a bit longer.

Weeks ago my 1.5 years old 2TB Hitachi hard disk showed signs of failure.

The System is a core i5 2500 desktop with intel onboard graphics.

I purchased a Seagate Enterprise hard disk of exactly the same size (2TB) to replace the old hard disk,
and managed to copy the contents 1:1 using dd.

Win7 which I wanted to keep (triple boot) worked fine after that from the new hard disk without complaining in any way

I made a fresh install of openSUSE 13.1 on one of the 2 partitions earmarked for / (root partitions) of openSUSE,
and a /home partition, both formated with ReiserFS for that install.

Then first the problem occurred that the system did freeze during copies of the large number of files to and from external hard disks.
That problem was solved !
See thread
https://forums.opensuse.org/showthread.php/498487-13-1-freezes-while-copying-files

Later on I copied the contents of my old /home to the new one.

Now, after using it for a few minutes, the system again showed considerable lag,
making it almost unusable:
sometimes it e.g. took 2 minutes or more until the open/save file dialogue appeared,
after saying ‘save page as’ in Firefox.
Or Kwrite (yes, this simple app) took 3-4 minutes to load a short text file!

I thought that choosing ReiserFS may not have been a good idea.
So I made a second install of openSUSE 13.1 64bit with all updates included
on another / partition formatted ext3 (again triple boot, like on the old hard disk),
choosing /home as the original (or dd’ed) ext3 partition,
from which I deleted all hidden folders and files (the ones whose names start with a period ‘.’)
before the install.

No improvement.
Just today one of my kids wanted to have a look at older private photos on a NTFS partition (“drive” D: ) - due to the lag that was almost impossible.

The disk light notoriously flashed at a low rate, and the noise of the hard disk never stopped.

A second, older PC (pentium dual core, smaller hard disk, less RAM, intel onboard graphics as well),
which kept me online and which I used for daily tasks,
which had the same openSUSE 13.1 64bit on it - installed from the same DVD -
and which did get the same online updates, didn’t show these symptoms.

OK, that one didn’t get a copy of my old /home - due to the lack of space on it’s hard disk.

Now inspired by the posting https://forums.opensuse.org/showthread.php/498487-13-1-freezes-while-copying-files?p=2647284#post2647284
by Carlos, and because I began to run out of ideas,
about 2 hours ago I installed ‘iotop’ and did run it as root.

I was stunned, and still am: no more lag since then.
No more disk light flickering all the time.

I’m not paranoid,
but to me that looks a bit like a worm or a virus that tries to evade being detected.

Or could a call to ‘iotop’ from the terminal as root have similar effects for other reasons?

Still I didn’t yet reboot, in order to see if the behaviour would be the same as before the call to ‘iotop’ then.

Mike

[F_Sauce]

Hello.

I suppose that if this were a case of indexing, the indexing might have completed at exactly the time you installed/ran iotop?

If lagging occurs again run ‘top’ from terminal and watch for processing, and memory, intensive processes.

[ratzi]

@F_Sauce

Hello.

I suppose that if this were a case of indexing, the indexing might have completed at exactly the time you installed/ran iotop?

If lagging occurs again run ‘top’ from terminal and watch for processing, and memory, intensive processes.

Hi F_Sauce,

I rebooted - yes, lag is back, while running iotop.

Almost seems like it in fact happened by coincidence, that the lag stopped after calling iotop in the previous session.
A bit strange but likely, as it seems.
Before, I never got to the point where lag stopped.

‘iotop -o’ now says that IO load is mainly due to kworker and jbd2, sometimes up to 40%.

‘top’ has a bit more apps, but %CPU usually is below 4% for the single threads,
which are iotop, firefox, Xorg, konsole, kwin, plasma-desktop, systemd, … in a changing order.

Perhaps I should try to re-install 12.3 again, or 13.1, without Kernel updates,
because I never experienced this lag before the crash of my hard disk.

Thanks
Mike

[ratzi]

Just to say:

This is not a memory problem, or caused by use of swap

user@lHost:~> free
             total       used       free     shared    buffers     cached
Mem:       7891596    2091024    5800572     135488      93056    1077732
-/+ buffers/cache:     920236    6971360
Swap:     16777212          0   16777212
user@lHost:~> 

[hendersj]

On Tue, 01 Jul 2014 22:46:02 +0000, ratzi wrote:

> I’m not paranoid,
> but to me that looks a bit like a worm or a virus that tries to evade
> being detected.
>
> Or could a call to ‘iotop’ from the terminal as root have similar
> effects for other reasons?
>
> Still I didn’t yet reboot, in order to see if the behaviour would be the
> same as before the call to ‘iotop’ then.

I have to admit that while you say you’re not paranoid, this sounds like
“I don’t understand what is happening, so I’m going to assume the worst”

• and assuming the worst could be seen as a form of paranoia.

I would concur with F Sauce’s guess that this is probably the indexing
daemon, and you happened to catch it with iotop just after it finished
its process.

I know of no worm or virus for Linux (or any *nix) that specifically
looks for a user running iotop and shuts itself down. Such a process
would also have to hide itself from the process table, which IIRC isn’t
a trivial thing to do on a normal kernel.

You could, however, use clamscan to run a virus scan, just to assure
yourself that you haven’t been infected somehow. Viruses and worms are
extremely rare on Linux systems (so much so that the only ones out
there are really proof of concept), so I would be very surprised if your
worst-case suspicions bore any fruit.

Jim

–
Jim Henderson
openSUSE Forums Administrator
Forum Use Terms & Conditions at http://tinyurl.com/openSUSE-T-C

[ratzi]

@hendersj

On Tue, 01 Jul 2014 22:46:02 +0000, ratzi wrote:

> I’m not paranoid,
> but to me that looks a bit like a worm or a virus that tries to evade
> being detected.
>
> Or could a call to ‘iotop’ from the terminal as root have similar
> effects for other reasons?
>
> Still I didn’t yet reboot, in order to see if the behaviour would be the
> same as before the call to ‘iotop’ then.

I have to admit that while you say you’re not paranoid, this sounds like
“I don’t understand what is happening, so I’m going to assume the worst”

• and assuming the worst could be seen as a form of paranoia.

I would concur with F Sauce’s guess that this is probably the indexing
daemon, and you happened to catch it with iotop just after it finished
its process.

I know of no worm or virus for Linux (or any *nix) that specifically
looks for a user running iotop and shuts itself down. Such a process
would also have to hide itself from the process table, which IIRC isn’t
a trivial thing to do on a normal kernel.

You could, however, use clamscan to run a virus scan, just to assure
yourself that you haven’t been infected somehow. Viruses and worms are
extremely rare on Linux systems (so much so that the only ones out
there are really proof of concept), so I would be very surprised if your
worst-case suspicions bore any fruit.

Jim

–
Jim Henderson
openSUSE Forums Administrator
Forum Use Terms & Conditions at http://tinyurl.com/openSUSE-T-C

Hi Jim!

I already thought of installing clamav and running clamscan.

I didn’t do that yet because my usual experience for years was that the releases of openSUSE (and Linux systems in general) are running stable.

Using the same machine, openSUSE (then 12.3 64bit) ran without a problem before the failure of my hard disk occurred.

The only change since then was that I plugged an internal IDE DVD burner in the PC.
Perhaps I’ll even remove that one again to make sure that it doesn’t cause the problems.

Thanks
Mike

[robin_listas]

On 2014-07-02 02:16, ratzi wrote:
>
> F_Sauce;2651962 Wrote:
>> Hello.
>>
>> I suppose that if this were a case of indexing, the indexing might have
>> completed at exactly the time you installed/ran iotop?

Polite indexing daemons stop indexing when they see cpu load above a
certain value by other processes. This is to help avoiding loading the
computer heavily with a background task, just when the user wants to do
something.

So it might be “not mere chance”.

> I rebooted - yes, lag is back, while running iotop.

Oops.

> Almost seems like it in fact happened by coincidence, that the lag
> stopped after calling iotop in the previous session.
> A bit strange but likely, as it seems.
> Before, I never got to the point where lag stopped.
>
> ‘iotop -o’ now says that IO load is mainly due to kworker and jbd2,
> sometimes up to 40%.

Quite a bit. In K/s, what’s the figure?

Both kworker and jbd2 are kernel things, not applications.

I googled a bit (“what is kworker process in linux”), found these:

http://stackoverflow.com/questions/10846747/origin-of-a-kworker-thread

http://unix.stackexchange.com/questions/22851/why-is-kworker-consuming-so-many-resources-on-linux-3-0-0-12-server/65270#65270

https://lkml.org/lkml/2011/3/31/68

They describe a procedure for finding out the culprit, and it can be
many, Some ounf it on the Intel video drivers, others on sleep functions…

Apparently, “kworker is a kernel thread which processes workqueues. This
thread is created in the file linux/kernel/workqueue.c file.”

> Perhaps I should try to re-install 12.3 again, or 13.1, without Kernel
> updates,
> because I never experienced this lag before the crash of my hard disk.

I don’t think it will help.

Perhaps your new disk uses a particular driver that has a bug. It could
be on the usb subsystem.

About jdb2, I found other hits. For instance:

http://www.linuxquestions.org/questions/linux-software-2/jbd2-sda1-8-what-exactly-is-this-process-that-keeps-running-every-few-seconds-788810/

“The JBD is the journaling block device that sits between the file
system and the block device driver. The jbd2 version is for ext4, as you
found. If you are seeing a lot of activity when nothing much is running,
check if any of the logs are getting a lot of updates.”

So, question: are you getting many log entries? Check it.

–
Cheers / Saludos,

Carlos E. R.
(from 13.1 x86_64 “Bottle” at Telcontar)

[robin_listas]

On 2014-07-02 03:23, Carlos E. R. wrote:
> On 2014-07-02 02:16, ratzi wrote:

> About jdb2, I found other hits. For instance:
>
> http://www.linuxquestions.org/questions/linux-software-2/jbd2-sda1-8-what-exactly-is-this-process-that-keeps-running-every-few-seconds-788810/
>
> “The JBD is the journaling block device that sits between the file
> system and the block device driver. The jbd2 version is for ext4, as you
> found. If you are seeing a lot of activity when nothing much is running,
> check if any of the logs are getting a lot of updates.”
>
>
> So, question: are you getting many log entries? Check it.
>

And this one, very interesting:

https://bbs.archlinux.org/viewtopic.php?id=113516
Re: jbd2 process (ext4 journal) is writing on disk all the time

(2011-02-15 16:08:10, fredre

“I just reinstallled on all ext3 partitions in hopes of alleviating the
problem, but the same issue occurs. The only change is now it happens
due to kjournald accessing it every few seconds. I still get the
clicking and the constant spin-downs. I had been using ext4 prior to
this. Once again, noatime and commit=60 make no difference.”

Notice it mentions “clicks”. There is a suggestion to adjust hdparm on
the next answer. And a kernel fix (that was year 2011). Someone
(adr3nal1n) says:

“Same issue here too using ext4, disk is access every 2 seconds.
If I format the disk using ext3 or XFS the issue goes away.”

Another one says that using UEFI instead of BIOS solved it for him. And
this post:

“Has anyone confirmed that the newer kernel actually fixes the
underlying issue? (being that journalling shouldn’t occur continually
when disk i/o activity is otherwise idle, which is what prevents disk
from spinning down).”

Ie, they think that, the kernel trying to write the journal just when
the disk is going to sleep, which causes it to wake up and click, while
making the entire system wait for the disk to spin up.

I’m not sure it matches your problem :-?

Then they point to a another thread, KDE related (with nepomuk involved)

https://bbs.archlinux.org/viewtopic.php?pid=903779

–
Cheers / Saludos,

Carlos E. R.
(from 13.1 x86_64 “Bottle” at Telcontar)

[hendersj]

On Wed, 02 Jul 2014 00:56:01 +0000, ratzi wrote:

> The only change since then was that I plugged an internal IDE DVD burner
> in the PC.
> Perhaps I’ll even remove that one again to make sure that it doesn’t
> cause the problems.

That’s a good idea. While it’s pretty unlikely with modern hardware, an
interrupt conflict certainly could cause weird behaviour like this. Bus
contention (if, for example, the device was set as the primary on a bus
that already had a primary device) might also cause an issue.

Jim

–
Jim Henderson
openSUSE Forums Administrator
Forum Use Terms & Conditions at http://tinyurl.com/openSUSE-T-C

[ratzi]

Hi Carlos!

Thank you very much for taking your time and for the links!

@robin_listas

On 2014-07-02 02:16, ratzi wrote:
>
> F_Sauce;2651962 Wrote:
>> Hello.
>>
>> I suppose that if this were a case of indexing, the indexing might have
>> completed at exactly the time you installed/ran iotop?

Polite indexing daemons stop indexing when they see cpu load above a
certain value by other processes. This is to help avoiding loading the
computer heavily with a background task, just when the user wants to do
something.

So it might be “not mere chance”.

To disable Nepomuk was among the first things that I did after installing this 13.1
But didn’t you recently write about another indexer that you came across?

@robin_listas

> I rebooted - yes, lag is back, while running iotop.

Oops.

> Almost seems like it in fact happened by coincidence, that the lag
> stopped after calling iotop in the previous session.
> A bit strange but likely, as it seems.
> Before, I never got to the point where lag stopped.
>
> ‘iotop -o’ now says that IO load is mainly due to kworker and jbd2,
> sometimes up to 40%.

Quite a bit. In K/s, what’s the figure?

I’ll give numbers below.

The behaviour is not the same all the time.
Today my internet connection was down for several hours
and I had the time to make some tests.

I turned on the PC and booted the same openSUSE.
I mounted the volume with the photos on it.
I started to enter folders with photos on that volume and clicked on the first (of 200-300) respectively,
in order to start Gwenview.
In Gwenview I clicked on ‘Browse’ in order to see the thumbnails of all photos.
These were shown quickly in different of these folders.
So far so good.

Two hours later things looked very different:
Clicking ‘Browse’ in Gwenview then, it took about 5 seconds from thumnail to thumnail
to be displayed!
And the system showed further lag:
entering a folder with e.g. 50 sub-folders in it,
Dolphin now took up to 5 seconds for each sub-folder to display the number of items it contains!!

And I’m not talking about a flashing of the disk light every 2 seconds.
I’m talking about a frequency of about 10 short flashes per second when lag is huge
(i.e. when the system almost freezes).

@robin_listas

Both kworker and jbd2 are kernel things, not applications.

I googled a bit (“what is kworker process in linux”), found these:

linux - Origin of a kworker-thread - Stack Overflow

kernel - Why is kworker consuming so many resources on Linux 3.0.0-12-server? - Unix & Linux Stack Exchange

LKML: Tejun Heo: Re: excessive kworker activity when idle. (was Re: vma corruption in today's -git)

They describe a procedure for finding out the culprit, and it can be
many, Some ounf it on the Intel video drivers, others on sleep functions…

Apparently, “kworker is a kernel thread which processes workqueues. This
thread is created in the file linux/kernel/workqueue.c file.”

Since mit internet connection was down I didn’t have a close look at these yet.

@robin_listas

> Perhaps I should try to re-install 12.3 again, or 13.1, without Kernel
> updates,
> because I never experienced this lag before the crash of my hard disk.

I don’t think it will help.

Perhaps your new disk uses a particular driver that has a bug. It could
be on the usb subsystem.

That can not explain why the system did run fast without lag after power on today.

@robin_listas

About jdb2, I found other hits. For instance:

http://www.linuxquestions.org/questions/linux-software-2/jbd2-sda1-8-what-exactly-is-this-process-that-keeps-running-every-few-seconds-788810/

“The JBD is the journaling block device that sits between the file
system and the block device driver. The jbd2 version is for ext4, as you
found. If you are seeing a lot of activity when nothing much is running,
check if any of the logs are getting a lot of updates.”

The openSUSE 13.1 in question is running from ext3 volumes instead of ext4 volumes,
see my /etc/fstab:

/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part6 /                    ext3       acl,user_xattr        1 1
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part5 swap                 swap       defaults              0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part1 /MSsystem            ntfs-3g    ro,user,noauto,users,gid=users,fmask=133,dmask=022,locale=en_US.UTF-8 0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part8 /home                ext3       defaults              1 2
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part9 /home131             reiserfs   user,noauto,acl       0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part7 /sys131              reiserfs   user,noauto,acl       0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part3 /windows/C           ntfs-3g    user,noauto,users,gid=users,fmask=133,dmask=022,locale=en_US.UTF-8 0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part4 /windows/D           ntfs-3g    user,noauto,users,gid=users,fmask=133,dmask=022,locale=en_US.UTF-8    0 0

part7 and part9 (with an older installation of 13.1 on it) as well as part1 and part3 are not mounted.

@robin_listas

So, question: are you getting many log entries? Check it.

I will do later. First I would like to give a bit more of information.

For the following 2 listings I clicked ‘Browse’ in Gwenview as described above,
and the thumbnails were displayed only very slowly.
I then immediately switched to 2 terminal windows.

In that situation ‘iotop -b -o -k -n 50 >iotop2.lis’ gave

Total DISK READ :       0.00 K/s | Total DISK WRITE :       0.00 K/s
Actual DISK READ:       0.00 K/s | Actual DISK WRITE:       0.00 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
Total DISK READ :    1515.93 K/s | Total DISK WRITE :       7.90 K/s
Actual DISK READ:     757.96 K/s | Actual DISK WRITE:     130.27 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
 6969 be/4 alltag    757.96 K/s    0.00 K/s  0.00 % 78.89 % gwenview /windows/D/Bilder/Bilder_FAT/20081019_Bilder/2008_1018/2008_10190065.JPG -caption Gwenview --icon gwenview [Gwenview::Thumb]
 3150 be/4 root      757.96 K/s    0.00 K/s  0.00 % 78.84 % mount.ntfs-3g /dev/sda4 /windows/D -o rw,noexec,nosuid,nodev,gid=100,fmask=133,dmask=022,locale=en_US.UTF-8,user,users
  456 be/3 root        0.00 K/s    0.00 K/s  0.00 % 49.35 % [jbd2/sda8-8]
  329 be/4 root        0.00 K/s    0.00 K/s  0.00 % 21.72 % [kworker/3:2]
 3014 be/4 alltag      0.00 K/s    3.95 K/s  0.00 % 19.73 % kactivitymanagerd [QThread]
   54 be/4 root        0.00 K/s    0.00 K/s  0.00 %  0.06 % [kworker/3:1]
 6978 be/4 root        0.00 K/s    3.95 K/s  0.00 %  0.00 % python /usr/sbin/iotop -b -o -k -n 50
Total DISK READ :     943.67 K/s | Total DISK WRITE :      32.44 K/s
Actual DISK READ:     471.84 K/s | Actual DISK WRITE:      20.64 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
  456 be/3 root        0.00 K/s   23.59 K/s  0.00 % 99.99 % [jbd2/sda8-8]
 6969 be/4 alltag    471.84 K/s    0.00 K/s  0.00 % 99.99 % gwenview /windows/D/Bilder/Bilder_FAT/20081019_Bilder/2008_1018/2008_10190065.JPG -caption Gwenview --icon gwenview [Gwenview::Thumb]
 3150 be/4 root      471.84 K/s    0.00 K/s  0.00 % 99.99 % mount.ntfs-3g /dev/sda4 /windows/D -o rw,noexec,nosuid,nodev,gid=100,fmask=133,dmask=022,locale=en_US.UTF-8,user,users
 6978 be/4 root        0.00 K/s    2.95 K/s  0.00 % 25.12 % python /usr/sbin/iotop -b -o -k -n 50
 3014 be/4 alltag      0.00 K/s    5.90 K/s  0.00 % 22.11 % kactivitymanagerd [QThread]
 6395 be/4 root        0.00 K/s    0.00 K/s  0.00 %  0.60 % [kworker/0:1]
Total DISK READ :    1365.31 K/s | Total DISK WRITE :      16.00 K/s
Actual DISK READ:     682.66 K/s | Actual DISK WRITE:       2.67 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
 6969 be/4 alltag    682.66 K/s    0.00 K/s  0.00 % 99.96 % gwenview /windows/D/Bilder/Bilder_FAT/20081019_Bilder/2008_1018/2008_10190065.JPG -caption Gwenview --icon gwenview [Gwenview::Thumb]
 3150 be/4 root      682.66 K/s    0.00 K/s  0.00 % 99.93 % mount.ntfs-3g /dev/sda4 /windows/D -o rw,noexec,nosuid,nodev,gid=100,fmask=133,dmask=022,locale=en_US.UTF-8,user,users
  456 be/3 root        0.00 K/s    0.00 K/s  0.00 % 73.32 % [jbd2/sda8-8]
 6978 be/4 root        0.00 K/s    2.67 K/s  0.00 % 32.09 % python /usr/sbin/iotop -b -o -k -n 50
  329 be/4 root        0.00 K/s    0.00 K/s  0.00 % 17.38 % [kworker/3:2]
 6395 be/4 root        0.00 K/s    0.00 K/s  0.00 %  4.01 % [kworker/0:1]
 6976 be/4 root        0.00 K/s   13.33 K/s  0.00 %  0.00 % top -b -n 50
Total DISK READ :    2368.82 K/s | Total DISK WRITE :       3.91 K/s
Actual DISK READ:    1282.14 K/s | Actual DISK WRITE:       0.00 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
 6969 be/4 alltag   1086.69 K/s    0.00 K/s  0.00 % 97.23 % gwenview /windows/D/Bilder/Bilder_FAT/20081019_Bilder/2008_1018/2008_10190065.JPG -caption Gwenview --icon gwenview [Gwenview::Thumb]
 3150 be/4 root     1282.14 K/s    3.91 K/s  0.00 % 97.15 % mount.ntfs-3g /dev/sda4 /windows/D -o rw,noexec,nosuid,nodev,gid=100,fmask=133,dmask=022,locale=en_US.UTF-8,user,users
  329 be/4 root        0.00 K/s    0.00 K/s  0.00 %  0.06 % [kworker/3:2]
.....
Total DISK READ :    1751.54 K/s | Total DISK WRITE :      27.37 K/s
Actual DISK READ:     875.77 K/s | Actual DISK WRITE:       2.49 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
 6969 be/4 alltag    875.77 K/s    0.00 K/s  0.00 % 99.99 % gwenview /windows/D/Bilder/Bilder_FAT/20081019_Bilder/2008_1018/2008_10190065.JPG -caption Gwenview --icon gwenview [Gwenview::Thumb]
 3150 be/4 root      875.77 K/s    0.00 K/s  0.00 % 99.99 % mount.ntfs-3g /dev/sda4 /windows/D -o rw,noexec,nosuid,nodev,gid=100,fmask=133,dmask=022,locale=en_US.UTF-8,user,users
  456 be/3 root        0.00 K/s   12.44 K/s  0.00 % 99.99 % [jbd2/sda8-8]
 6978 be/4 root        0.00 K/s    2.49 K/s  0.00 % 36.57 % python /usr/sbin/iotop -b -o -k -n 50
  329 be/4 root        0.00 K/s    0.00 K/s  0.00 % 14.93 % [kworker/3:2]
 6395 be/4 root        0.00 K/s    0.00 K/s  0.00 %  1.00 % [kworker/0:1]
   54 be/4 root        0.00 K/s    0.00 K/s  0.00 %  0.04 % [kworker/3:1]
 6976 be/4 root        0.00 K/s   12.44 K/s  0.00 %  0.00 % top -b -n 50
Total DISK READ :    1905.68 K/s | Total DISK WRITE :       0.00 K/s
Actual DISK READ:    1030.94 K/s | Actual DISK WRITE:       0.00 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
 6969 be/4 alltag    874.74 K/s    0.00 K/s  0.00 % 87.80 % gwenview /windows/D/Bilder/Bilder_FAT/20081019_Bilder/2008_1018/2008_10190065.JPG -caption Gwenview --icon gwenview [Gwenview::Thumb]
 3150 be/4 root     1030.94 K/s    0.00 K/s  0.00 % 87.75 % mount.ntfs-3g /dev/sda4 /windows/D -o rw,noexec,nosuid,nodev,gid=100,fmask=133,dmask=022,locale=en_US.UTF-8,user,users
  329 be/4 root        0.00 K/s    0.00 K/s  0.00 %  0.05 % [kworker/3:2]
.....

Output of ‘top’ in next post because this one gets too big.

[ratzi]

(continued)

In the same situation ‘top -b -n 50 >top2.lis’ gave

top - 17:37:55 up  2:04,  4 users,  load average: 2,87, 2,40, 1,55
Tasks: 178 total,   1 running, 177 sleeping,   0 stopped,   0 zombie
%Cpu(s):  1,0 us,  0,3 sy,  0,0 ni, 38,2 id, 60,6 wa,  0,0 hi,  0,0 si,  0,0 st
KiB Mem:   7891596 total,  6117132 used,  1774464 free,  2251580 buffers
KiB Swap: 16777212 total,        0 used, 16777212 free,  3138772 cached

  PID USER      PR  NI    VIRT    RES    SHR S  %CPU  %MEM     TIME+ COMMAND
 6465 alltag    20   0  553020  33888  21532 S 1,122 0,429   0:03.77 konsole
 2427 root      20   0  440788  59644  43816 S 0,997 0,756   0:34.44 Xorg
 6978 root      20   0   70300  10644   3392 D 0,873 0,135   0:00.07 iotop
 2875 alltag    20   0  573124  43132  30940 S 0,374 0,547   0:05.16 kwin
 6957 alltag    20   0  809088 116404  36228 S 0,374 1,475   0:01.00 gwenview
 2880 alltag    20   0 3100084 128956  42560 S 0,249 1,634   0:17.73 plasma-desktop
   12 root      20   0       0      0      0 S 0,125 0,000   0:00.44 rcuop/1
 2906 alltag    20   0  861504  44572  26516 S 0,125 0,565   0:07.26 dolphin
    1 root      20   0   48264   4100   2292 S 0,000 0,052   0:00.78 systemd
    2 root      20   0       0      0      0 S 0,000 0,000   0:00.00 kthreadd
    3 root      20   0       0      0      0 S 0,000 0,000   0:00.04 ksoftirqd/0
    5 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 kworker/0:0H
    7 root      rt   0       0      0      0 S 0,000 0,000   0:00.01 migration/0
    8 root      -2   0       0      0      0 S 0,000 0,000   0:00.00 rcuc/0
    9 root      -2   0       0      0      0 S 0,000 0,000   0:00.00 rcub/0
   10 root      20   0       0      0      0 S 0,000 0,000   0:00.75 rcu_preempt
   11 root      20   0       0      0      0 S 0,000 0,000   0:00.57 rcuop/0
   13 root      20   0       0      0      0 S 0,000 0,000   0:00.34 rcuop/2
   14 root      20   0       0      0      0 S 0,000 0,000   0:00.36 rcuop/3
   15 root      20   0       0      0      0 S 0,000 0,000   0:00.00 rcu_bh
   16 root      20   0       0      0      0 S 0,000 0,000   0:00.00 rcuob/0
   17 root      20   0       0      0      0 S 0,000 0,000   0:00.00 rcuob/1
   18 root      20   0       0      0      0 S 0,000 0,000   0:00.00 rcuob/2
   19 root      20   0       0      0      0 S 0,000 0,000   0:00.00 rcuob/3
   20 root      20   0       0      0      0 S 0,000 0,000   0:00.00 rcu_sched
   21 root      20   0       0      0      0 S 0,000 0,000   0:00.00 rcuos/0
   22 root      20   0       0      0      0 S 0,000 0,000   0:00.00 rcuos/1
   23 root      20   0       0      0      0 S 0,000 0,000   0:00.00 rcuos/2
   24 root      20   0       0      0      0 S 0,000 0,000   0:00.00 rcuos/3
   25 root      rt   0       0      0      0 S 0,000 0,000   0:00.02 watchdog/0
   26 root      rt   0       0      0      0 S 0,000 0,000   0:00.02 watchdog/1
   27 root      -2   0       0      0      0 S 0,000 0,000   0:00.00 rcuc/1
   28 root      rt   0       0      0      0 S 0,000 0,000   0:00.01 migration/1
   29 root      20   0       0      0      0 S 0,000 0,000   0:00.00 ksoftirqd/1
   31 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 kworker/1:0H
   32 root      rt   0       0      0      0 S 0,000 0,000   0:00.01 watchdog/2
   33 root      -2   0       0      0      0 S 0,000 0,000   0:00.00 rcuc/2
   34 root      rt   0       0      0      0 S 0,000 0,000   0:00.01 migration/2
   35 root      20   0       0      0      0 S 0,000 0,000   0:00.00 ksoftirqd/2
   37 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 kworker/2:0H
   38 root      rt   0       0      0      0 S 0,000 0,000   0:00.01 watchdog/3
   39 root      -2   0       0      0      0 S 0,000 0,000   0:00.00 rcuc/3
   40 root      rt   0       0      0      0 S 0,000 0,000   0:00.01 migration/3
   41 root      20   0       0      0      0 S 0,000 0,000   0:00.00 ksoftirqd/3
   43 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 kworker/3:0H
   44 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 khelper
   45 root      20   0       0      0      0 S 0,000 0,000   0:00.00 kdevtmpfs
   46 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 netns
   47 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 writeback
   48 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 kintegrityd
   49 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 bioset
   50 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 kblockd
   51 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 ata_sff
   52 root      20   0       0      0      0 S 0,000 0,000   0:00.03 khubd
   53 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 md
   54 root      20   0       0      0      0 S 0,000 0,000   0:00.33 kworker/3:1
   56 root      20   0       0      0      0 S 0,000 0,000   0:00.11 kworker/2:1
   57 root      20   0       0      0      0 S 0,000 0,000   0:00.00 khungtaskd
   58 root      20   0       0      0      0 S 0,000 0,000   0:00.00 kswapd0
   59 root      25   5       0      0      0 S 0,000 0,000   0:00.00 ksmd
   60 root      39  19       0      0      0 S 0,000 0,000   0:00.07 khugepaged
   61 root      20   0       0      0      0 S 0,000 0,000   0:00.00 fsnotify_mark
   62 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 crypto
   67 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 kthrotld
   68 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 kpsmoused
   69 root      20   0       0      0      0 S 0,000 0,000   0:00.00 kworker/2:2
   70 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 deferwq
  100 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 kmpath_rdacd
  141 root      20   0       0      0      0 S 0,000 0,000   0:00.01 scsi_eh_0
  142 root      20   0       0      0      0 S 0,000 0,000   0:00.01 scsi_eh_1
  143 root      20   0       0      0      0 S 0,000 0,000   0:00.00 kworker/u8:2
  144 root      20   0       0      0      0 S 0,000 0,000   0:00.22 kworker/u8:3
  145 root      20   0       0      0      0 S 0,000 0,000   0:00.01 scsi_eh_2
  146 root      20   0       0      0      0 S 0,000 0,000   0:00.02 scsi_eh_3
  150 root       0 -20       0      0      0 S 0,000 0,000   0:00.06 kworker/3:1H
  154 root       0 -20       0      0      0 S 0,000 0,000   0:00.76 kworker/0:1H
  156 root      20   0       0      0      0 S 0,000 0,000   0:00.00 kworker/1:2
  222 root      20   0       0      0      0 S 0,000 0,000   0:00.31 jbd2/sda6-8
  223 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 ext4-rsv-conver
  224 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 ext4-unrsv-conv
  253 root       0 -20       0      0      0 S 0,000 0,000   0:00.01 kworker/1:1H
  254 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 kworker/2:1H
  265 root      20   0  180292  16744  16412 S 0,000 0,212   0:00.15 systemd-journal
  266 root      20   0       0      0      0 S 0,000 0,000   0:00.00 kauditd
  298 root      20   0   47356   2336   1228 S 0,000 0,030   0:00.14 systemd-udevd
  329 root      20   0       0      0      0 S 0,000 0,000   0:00.52 kworker/3:2
  340 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 firewire
  341 root     -51   0       0      0      0 S 0,000 0,000   0:00.00 irq/49-mei_me
  342 root      20   0       0      0      0 S 0,000 0,000   0:00.00 scsi_eh_4
  344 root      20   0       0      0      0 S 0,000 0,000   0:00.00 scsi_eh_5
  394 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 led_workqueue
  403 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 hd-audio0
  435 root      20   0       0      0      0 S 0,000 0,000   0:00.00 scsi_eh_6
  456 root      20   0       0      0      0 D 0,000 0,000   0:00.31 jbd2/sda8-8
  457 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 ext4-rsv-conver
  458 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 ext4-unrsv-conv
  623 root      20   0   73892   3764   2720 S 0,000 0,048   0:00.00 cupsd
  624 avahi     20   0   28100   1804   1524 S 0,000 0,023   0:00.01 avahi-daemon
  626 root      20   0   28112   1980   1588 S 0,000 0,025   0:00.00 wpa_supplicant
  629 root      20   0  320060   6348   3228 S 0,000 0,080   0:00.00 ModemManager
  634 nscd      20   0  802396   1680   1204 S 0,000 0,021   0:00.07 nscd
  648 message+  20   0   25588   2380   1300 S 0,000 0,030   0:00.29 dbus-daemon
  650 root      20   0   28648   1660   1356 S 0,000 0,021   0:00.02 systemd-logind
  652 root      20   0  323344   1928   1356 S 0,000 0,024   0:00.02 rsyslogd
  661 polkitd   20   0  515580  20052   4720 S 0,000 0,254   0:00.26 polkitd
 2284 root      20   0    4464    744    648 S 0,000 0,009   0:00.00 agetty
 2306 ntp       20   0   28016   2164   1596 S 0,000 0,027   0:00.26 ntpd
 2377 root      20   0   24612    856    636 S 0,000 0,011   0:00.00 kdm
 2569 root      20   0   19632   1568   1304 S 0,000 0,020   0:00.01 master
 2584 postfix   20   0   21536   1400   1164 S 0,000 0,018   0:00.00 qmgr
 2592 root      20   0   18824   1476    888 S 0,000 0,019   0:00.00 cron
 2634 root      20   0   73912   2280   1732 S 0,000 0,029   0:00.00 kdm
 2672 avahi-a+  20   0    6748    524    416 S 0,000 0,007   0:00.00 avahi-autoipd
 2674 root      20   0    6324     96      0 S 0,000 0,001   0:00.00 avahi-autoipd
 2702 alltag    20   0   38116   2328   1728 S 0,000 0,029   0:00.00 systemd
 2703 alltag    20   0   68548   1844     72 S 0,000 0,023   0:00.00 (sd-pam)
 2704 alltag    20   0   13092   1760   1420 S 0,000 0,022   0:00.04 startkde
 2761 alltag    20   0   14076    780    544 S 0,000 0,010   0:00.00 dbus-launch
 2762 alltag    20   0   25068   1616    824 S 0,000 0,020   0:00.29 dbus-daemon
 2799 root      20   0    4088     80      0 S 0,000 0,001   0:00.00 start_kdeinit
 2800 alltag    20   0  361592  23324  17744 S 0,000 0,296   0:00.09 kdeinit4
 2801 alltag    20   0  367308  11980   5992 S 0,000 0,152   0:00.10 klauncher
 2803 alltag    20   0 1264584  44728  22704 S 0,000 0,567   0:00.69 kded4
 2806 alltag    20   0  449940  21008  12284 S 0,000 0,266   0:00.22 kglobalaccel
 2812 alltag    20   0  452240  19296  11660 S 0,000 0,245   0:00.06 kwalletd
 2817 alltag    20   0  912124  22504  16864 S 0,000 0,285   0:00.63 kactivitymanage
 2818 root      20   0  219560   4640   3648 S 0,000 0,059   0:00.02 upowerd
 2853 alltag    20   0    4224    352    280 S 0,000 0,004   0:00.00 kwrapper4
 2854 alltag    20   0  534748  22556  13624 S 0,000 0,286   0:00.67 ksmserver
 2862 root      20   0  354080   4752   3436 S 0,000 0,060   0:01.39 udisksd
 2884 alltag    20   0    9888   1312    848 S 0,000 0,017   0:00.00 ksysguardd
 2887 alltag    20   0  288336  19212  14488 S 0,000 0,243   0:00.08 kuiserver
 2901 alltag    20   0  813744  39224  25220 S 0,000 0,497   0:01.88 krunner
 2904 alltag    20   0  691476  31400  17720 S 0,000 0,398   0:00.42 kmix
 2909 alltag     9 -11  379048   6816   4384 S 0,000 0,086   0:00.10 pulseaudio
 2910 rtkit     21   1  162664   1188   1004 S 0,000 0,015   0:00.07 rtkit-daemon
 2913 alltag    20   0  629992  40160  25116 S 0,000 0,509   0:03.36 dolphin
 2926 alltag    20   0  459784  26480  19028 S 0,000 0,336   0:00.27 polkit-kde-auth
 2931 alltag    20   0  455680  20832  12880 S 0,000 0,264   0:00.08 klipper
 2972 root      20   0   12880    168      0 S 0,000 0,002   0:00.00 dhcpcd
 2975 root      20   0   19120   1804   1560 S 0,000 0,023   0:00.00 bluetoothd
 2995 alltag    20   0 1055252  31340  21136 S 0,000 0,397   0:00.25 knotify4
 3034 alltag    20   0  111644   3000   1696 S 0,000 0,038   0:00.00 kdesud
 3076 root      20   0   14208    792    520 S 0,000 0,010   0:00.00 dbus-launch
 3077 root      20   0   24564    680    452 S 0,000 0,009   0:00.00 dbus-daemon
 3150 root      20   0   14240   1392    724 D 0,000 0,018   0:03.21 mount.ntfs-3g
 3242 root      20   0   38120   2352   1744 S 0,000 0,030   0:00.00 systemd
 3243 root      20   0   69252   1956     72 S 0,000 0,025   0:00.00 (sd-pam)
 5563 root      20   0       0      0      0 S 0,000 0,000   0:00.09 kworker/1:0
 6040 alltag    20   0 1233888 227636  53004 S 0,000 2,885   0:25.02 firefox
 6051 alltag    20   0  182188   2908   2436 S 0,000 0,037   0:00.00 gvfsd
 6055 alltag    20   0  271400   3000   2444 S 0,000 0,038   0:00.00 gvfsd-fuse
 6073 alltag    20   0  528776  36956  26252 S 0,000 0,468   0:02.48 kmozillahelper
 6076 alltag    20   0  261400   2744   2284 S 0,000 0,035   0:00.00 at-spi-bus-laun
 6282 alltag    20   0  110328   2788   2324 S 0,000 0,035   0:00.00 gvfsd-metadata
 6354 root      20   0   24664   1136    792 S 0,000 0,014   0:00.01 dbus-daemon
 6357 root      20   0  168008   6968   4776 S 0,000 0,088   0:00.00 kdeinit4
 6358 root      20   0  203228  10912   8028 S 0,000 0,138   0:00.01 klauncher
 6360 root      20   0  299728  19528  14520 S 0,000 0,247   0:00.07 kded4
 6365 root      20   0  912020  24200  16636 S 0,000 0,307   0:00.15 kactivitymanage
 6370 root      20   0  293988  17704  12032 S 0,000 0,224   0:00.15 kglobalaccel
 6395 root      20   0       0      0      0 S 0,000 0,000   0:00.04 kworker/0:1
 6436 postfix   20   0   21488   1388   1152 S 0,000 0,018   0:00.00 pickup
 6467 alltag    20   0   14548   3148   1700 S 0,000 0,040   0:00.00 bash
 6488 root      20   0   65868   2108   1624 S 0,000 0,027   0:00.01 su
 6491 root      20   0   14416   3188   1748 S 0,000 0,040   0:00.03 bash
 6712 root      20   0       0      0      0 S 0,000 0,000   0:00.00 kworker/3:3
 6716 root      20   0       0      0      0 S 0,000 0,000   0:00.00 kworker/0:0
 6720 alltag    20   0   14548   3148   1700 S 0,000 0,040   0:00.01 bash
 6742 root      20   0   65864   2108   1624 S 0,000 0,027   0:00.01 su
 6746 root      20   0   14416   3196   1752 S 0,000 0,040   0:00.02 bash
 6905 root      20   0       0      0      0 S 0,000 0,000   0:00.00 kworker/3:0
 6906 root      20   0       0      0      0 S 0,000 0,000   0:00.00 kworker/0:2
 6932 alltag    20   0  364592  10124   4304 S 0,000 0,128   0:00.01 kio_file
 6934 alltag    20   0  459504  24412  14588 S 0,000 0,309   0:00.38 kio_thumbnail
 6966 alltag    20   0  438568  11104   4916 S 0,000 0,141   0:00.04 kio_trash
 6968 alltag    20   0  364724  10136   4284 S 0,000 0,128   0:00.00 kio_file
 6976 root      20   0   15228   1568   1124 R 0,000 0,020   0:00.00 top

(see next post)

[ratzi]

(continued)

or

top - 17:38:01 up  2:04,  4 users,  load average: 2,80, 2,39, 1,55
Tasks: 178 total,   1 running, 177 sleeping,   0 stopped,   0 zombie
%Cpu(s):  0,7 us,  0,1 sy,  0,0 ni, 52,6 id, 46,7 wa,  0,0 hi,  0,0 si,  0,0 st
KiB Mem:   7891596 total,  6129180 used,  1762416 free,  2257668 buffers
KiB Swap: 16777212 total,        0 used, 16777212 free,  3144592 cached

  PID USER      PR  NI    VIRT    RES    SHR S  %CPU  %MEM     TIME+ COMMAND
 6978 root      20   0   70300  10656   3392 S 1,995 0,135   0:00.18 iotop
 2427 root      20   0  440788  59644  43816 S 0,665 0,756   0:34.46 Xorg
    3 root      20   0       0      0      0 S 0,332 0,000   0:00.05 ksoftirqd/0
 6465 alltag    20   0  553020  33888  21532 S 0,332 0,429   0:03.80 konsole
 6957 alltag    20   0  809088 116400  36228 S 0,332 1,475   0:01.03 gwenview
    1 root      20   0   48264   4100   2292 S 0,000 0,052   0:00.78 systemd
    2 root      20   0       0      0      0 S 0,000 0,000   0:00.00 kthreadd
    5 root       0 -20       0      0      0 S 0,000 0,000   0:00.00 kworker/0:0H
.....

I currently have 2 suspicions:

(1) Removing parameters like ‘gid=users,fmask=133,dmask=022’ (see fstab listing in above post)
did solve the issue in the other thread, of freezing during large copys.

(2) The sub-folder ‘normal’ in ‘/home/alltag/.thumbnails/’ currently has 13’642 elements !
This will probably rather slow down the system than speed it up!

Thanks & Greets
Mike

[ratzi]

Perhaps interesting as well: while Dolphin needed that much time to count the items in sub-folders
the output of iotop was

Total DISK READ :       0.00 K/s | Total DISK WRITE :       0.00 K/s
Actual DISK READ:       0.00 K/s | Actual DISK WRITE:       0.00 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
Total DISK READ :     515.86 K/s | Total DISK WRITE :       3.94 K/s
Actual DISK READ:     515.86 K/s | Actual DISK WRITE:      11.81 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
 7668 be/4 alltag    515.86 K/s    0.00 K/s  0.00 % 99.99 % kdeinit4: kio_file [kdeinit] file local:/tmp/ksocket-alltag/kl
  222 be/3 root        0.00 K/s    0.00 K/s  0.00 % 32.29 % [jbd2/sda6-8]
  329 be/4 root        0.00 K/s    0.00 K/s  0.00 %  0.05 % [kworker/3:2]
 7793 be/4 root        0.00 K/s    3.94 K/s  0.00 %  0.00 % python /usr/sbin/iotop -b -o -k -n 50
Total DISK READ :     376.95 K/s | Total DISK WRITE :       3.93 K/s
Actual DISK READ:     376.95 K/s | Actual DISK WRITE:       0.00 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
 7668 be/4 alltag    376.95 K/s    0.00 K/s  0.00 % 88.28 % kdeinit4: kio_file [kdeinit] file local:/tmp/ksocket-alltag/kl
  222 be/3 root        0.00 K/s    3.93 K/s  0.00 % 58.89 % [jbd2/sda6-8]
  329 be/4 root        0.00 K/s    0.00 K/s  0.00 % 27.49 % [kworker/3:2]
 7555 be/4 root        0.00 K/s    0.00 K/s  0.00 %  6.68 % [kworker/0:2]
Total DISK READ :     372.40 K/s | Total DISK WRITE :       3.92 K/s
Actual DISK READ:     372.40 K/s | Actual DISK WRITE:       3.92 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
 7668 be/4 alltag    372.40 K/s    0.00 K/s  0.00 % 88.07 % kdeinit4: kio_file [kdeinit] file local:/tmp/ksocket-alltag/kl
  329 be/4 root        0.00 K/s    0.00 K/s  0.00 %  0.04 % [kworker/3:2]
 2817 be/4 alltag      0.00 K/s    3.92 K/s  0.00 %  0.00 % kactivitymanagerd
Total DISK READ :      15.73 K/s | Total DISK WRITE :       0.00 K/s
Actual DISK READ:      15.73 K/s | Actual DISK WRITE:      98.28 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
 2913 be/4 alltag     15.73 K/s    0.00 K/s  0.00 % 70.84 % kdeinit4: dolphin [kdeinit] -session 10e0486f73000140425728900
  456 be/3 root        0.00 K/s    0.00 K/s  0.00 % 58.96 % [jbd2/sda8-8]
 2817 be/4 alltag      0.00 K/s    0.00 K/s  0.00 % 37.50 % kactivitymanagerd
 7668 be/4 alltag      0.00 K/s    0.00 K/s  0.00 % 29.47 % kdeinit4: kio_file [kdeinit] file local:/tmp/ksocket-alltag/kl
Total DISK READ :      15.76 K/s | Total DISK WRITE :      55.17 K/s
Actual DISK READ:      15.76 K/s | Actual DISK WRITE:      47.29 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
  456 be/3 root        0.00 K/s   31.53 K/s  0.00 % 99.99 % [jbd2/sda8-8]
 2913 be/4 alltag     15.76 K/s    0.00 K/s  0.00 % 99.99 % kdeinit4: dolphin [kdeinit] -session 10e0486f73000140425728900
  329 be/4 root        0.00 K/s    0.00 K/s  0.00 % 21.73 % [kworker/3:2]
 7555 be/4 root        0.00 K/s    0.00 K/s  0.00 %  0.80 % [kworker/0:2]
 7793 be/4 root        0.00 K/s    3.94 K/s  0.00 %  0.00 % python /usr/sbin/iotop -b -o -k -n 50
 7796 be/4 root        0.00 K/s   19.70 K/s  0.00 %  0.00 % top -b -n 50
Total DISK READ :      15.73 K/s | Total DISK WRITE :      15.73 K/s
Actual DISK READ:      15.73 K/s | Actual DISK WRITE:      27.52 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
 2913 be/4 alltag     15.73 K/s    0.00 K/s  0.00 % 97.68 % kdeinit4: dolphin [kdeinit] -session 10e0486f73000140425728900
  456 be/3 root        0.00 K/s    7.86 K/s  0.00 % 78.62 % [jbd2/sda8-8]
 2817 be/4 alltag      0.00 K/s    3.93 K/s  0.00 % 19.65 % kactivitymanagerd
 7793 be/4 root        0.00 K/s    3.93 K/s  0.00 %  0.00 % python /usr/sbin/iotop -b -o -k -n 50
Total DISK READ :      11.78 K/s | Total DISK WRITE :      15.71 K/s
Actual DISK READ:      11.78 K/s | Actual DISK WRITE:      23.57 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
 2913 be/4 alltag     11.78 K/s    0.00 K/s  0.00 % 87.89 % kdeinit4: dolphin [kdeinit] -session 10e0486f73000140425728900
 2817 be/4 alltag      0.00 K/s    7.86 K/s  0.00 % 58.91 % kactivitymanagerd
  456 be/3 root        0.00 K/s    3.93 K/s  0.00 % 49.10 % [jbd2/sda8-8]
  329 be/4 root        0.00 K/s    0.00 K/s  0.00 % 25.59 % [kworker/3:2]
 7555 be/4 root        0.00 K/s    0.00 K/s  0.00 %  5.90 % [kworker/0:2]
 7793 be/4 root        0.00 K/s    3.93 K/s  0.00 %  0.00 % python /usr/sbin/iotop -b -o -k -n 50
Total DISK READ :      15.99 K/s | Total DISK WRITE :      22.39 K/s
Actual DISK READ:      15.99 K/s | Actual DISK WRITE:      63.97 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
 2913 be/4 alltag     15.99 K/s    0.00 K/s  0.00 % 95.28 % kdeinit4: dolphin [kdeinit] -session 10e0486f73000140425728900
  456 be/3 root        0.00 K/s    0.00 K/s  0.00 % 87.95 % [jbd2/sda8-8]
 7271 be/4 alltag      0.00 K/s    0.00 K/s  0.00 % 19.96 % dolphin
 7793 be/4 root        0.00 K/s    6.40 K/s  0.00 % 18.25 % python /usr/sbin/iotop -b -o -k -n 50
 2817 be/4 alltag      0.00 K/s    3.20 K/s  0.00 % 15.96 % kactivitymanagerd
 7796 be/4 root        0.00 K/s   12.79 K/s  0.00 %  0.00 % top -b -n 50
Total DISK READ :      12.31 K/s | Total DISK WRITE :       3.08 K/s
Actual DISK READ:      12.31 K/s | Actual DISK WRITE:      18.46 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
  456 be/3 root        0.00 K/s    0.00 K/s  0.00 % 99.99 % [jbd2/sda8-8]
 2913 be/4 alltag     12.31 K/s    0.00 K/s  0.00 % 99.12 % kdeinit4: dolphin [kdeinit] -session 10e0486f73000140425728900
 7271 be/4 alltag      0.00 K/s    0.00 K/s  0.00 % 22.95 % dolphin
 7793 be/4 root        0.00 K/s    3.08 K/s  0.00 % 21.29 % python /usr/sbin/iotop -b -o -k -n 50
  329 be/4 root        0.00 K/s    0.00 K/s  0.00 % 15.39 % [kworker/3:2]
 7555 be/4 root        0.00 K/s    0.00 K/s  0.00 %  7.69 % [kworker/0:2]
   54 be/4 root        0.00 K/s    0.00 K/s  0.00 %  0.03 % [kworker/3:1]
Total DISK READ :      15.71 K/s | Total DISK WRITE :      27.49 K/s
Actual DISK READ:      15.71 K/s | Actual DISK WRITE:      54.97 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
 2913 be/4 alltag     15.71 K/s    0.00 K/s  0.00 % 97.32 % kdeinit4: dolphin [kdeinit] -session 10e0486f73000140425728900
  456 be/3 root        0.00 K/s    0.00 K/s  0.00 % 78.53 % [jbd2/sda8-8]
 2817 be/4 alltag      0.00 K/s    3.93 K/s  0.00 % 19.62 % kactivitymanagerd
 7793 be/4 root        0.00 K/s    3.93 K/s  0.00 %  0.00 % python /usr/sbin/iotop -b -o -k -n 50
 7796 be/4 root        0.00 K/s   19.63 K/s  0.00 %  0.00 % top -b -n 50
Total DISK READ :      12.49 K/s | Total DISK WRITE :      12.49 K/s
Actual DISK READ:      12.49 K/s | Actual DISK WRITE:      21.85 K/s
  TID  PRIO  USER     DISK READ  DISK WRITE  SWAPIN      IO    COMMAND
 2913 be/4 alltag     12.49 K/s    0.00 K/s  0.00 % 84.32 % kdeinit4: dolphin [kdeinit] -session 10e0486f73000140425728900
  456 be/3 root        0.00 K/s    3.12 K/s  0.00 % 62.43 % [jbd2/sda8-8]
 2817 be/4 alltag      0.00 K/s    6.24 K/s  0.00 % 39.01 % kactivitymanagerd
 7271 be/4 alltag      0.00 K/s    0.00 K/s  0.00 % 21.89 % dolphin
 7793 be/4 root        0.00 K/s    3.12 K/s  0.00 % 20.47 % python /usr/sbin/iotop -b -o -k -n 50
  329 be/4 root        0.00 K/s    0.00 K/s  0.00 % 18.77 % [kworker/3:2]
 7555 be/4 root        0.00 K/s    0.00 K/s  0.00 %  3.13 % [kworker/0:2]

[ratzi]

Hi all,

perhaps solved.

Similarly as described in
http://forums.opensuse.org/showthread.php/498487-13-1-freezes-while-copying-files?p=2647270#post2647270
I removed the extra parameters users,gid=users,fmask= etc. from fstab here again.

The reason that they were present was that this system is a new fresh install of openSUSE 13.1
(I have a triple boot), so the default values assigned by the openSUSE installer still were in place.

As a result of removing these parms from fstab, lag doesn’t seem to develop anymore.

As a side effect, on the other hand, the standard user can now mount all windows partitions automaticly,
once the root password is entered when mounting the first of these 3 volumes.

Now I’ll re-introduce parameters ‘users,gid=users,fmask=133,dmask=022’ in fstab again,
to see if lag develops again (i.e. to see if that behaviour is repeatable).

Mike

[ratzi]

After re-introducing parameters ‘users,gid=users,fmask=133,dmask=022’ for the windows volumes in fstab again
the PC ran without problems for about 1.5 hours.

My fstab then looked

/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part6 /                    ext3       acl,user_xattr        1 1
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part5 swap                 swap       defaults              0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part1 /MSsystem            ntfs-3g    ro,user,noauto,users,gid=users,fmask=133,dmask=022        0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part8 /home                ext3       defaults              1 2
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part9 /home131             reiserfs   user,noauto,acl       0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part7 /sys131              reiserfs   user,noauto,acl       0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part3 /windows/C           ntfs-3g    ro,user,noauto,users,gid=users,fmask=133,dmask=022        0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part4 /windows/D           ntfs-3g    user,noauto,users,gid=users,fmask=133,dmask=022           0 0

I shut down the PC, because I had to leave.

Because it ran well for quite some time, at the next boot I added ‘,locale=en_US.UTF-8’ to the parms for the windows volumes in fstab,
and fstab now looked

/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part6 /                    ext3       acl,user_xattr        1 1
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part5 swap                 swap       defaults              0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part1 /MSsystem            ntfs-3g    ro,user,noauto,users,gid=users,fmask=133,dmask=022,locale=en_US.UTF-8        0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part8 /home                ext3       defaults              1 2
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part9 /home131             reiserfs   user,noauto,acl       0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part7 /sys131              reiserfs   user,noauto,acl       0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part3 /windows/C           ntfs-3g    ro,user,noauto,users,gid=users,fmask=133,dmask=022,locale=en_US.UTF-8        0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part4 /windows/D           ntfs-3g    user,noauto,users,gid=users,fmask=133,dmask=022,locale=en_US.UTF-8           0 0

I re-booted.
It took about 20-30 minutes that lag started again.

I then used the commands from the page that Carlos forwareded to me in post #7 of this thread:
https://lkml.org/lkml/2011/3/31/68
means

lHost:/home/alltag # echo workqueue:workqueue_queue_work > /sys/kernel/debug/tracing/set_event
lHost:/home/alltag # cat /sys/kernel/debug/tracing/trace_pipe > trace.txt
^C
lHost:/home/alltag # 

and got http://susepaste.org/45545168 as the result.
The later part of that is while Dolphin was slowly counting the numbers of items in the sub-folders
of a just opened folder.

Again shut down.

At next power on I edited fstab to remove option ‘,locale=en_US.UTF-8’ for the windows volumes again, and re-booted.

The fstab then again looked

/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part6 /                    ext3       acl,user_xattr        1 1
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part5 swap                 swap       defaults              0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part1 /MSsystem             ntfs-3g    ro,user,noauto,users,gid=users,fmask=133,dmask=022         0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part8 /home                ext3       defaults              1 2
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part9 /home131             reiserfs   user,noauto,acl       0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part7 /sys131              reiserfs   user,noauto,acl       0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part3 /windows/C            ntfs-3g    ro,user,noauto,users,gid=users,fmask=133,dmask=022         0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part4 /windows/D            ntfs-3g    user,noauto,users,gid=users,fmask=133,dmask=022           0  0

I thought that I got it.

No.

Re-booting, I got the syslog message ‘Disabling IRQ #19’ (I have seen this frequently as well under openSUSE 12.3),
but this time just after 2-3 minutes after boot, while starting Firefox
(I have ‘Receive System Mail’ enabled for my standard user account).
Directly after that the problems occurred again.

So I again changed my fstab, which now again looks

/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part6 /                    ext3       acl,user_xattr        1 1
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part5 swap                 swap       defaults              0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part1 /MSsystem            ntfs-3g    ro,user,noauto        0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part8 /home                ext3       defaults              1 2
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part9 /home131             reiserfs   user,noauto,acl       0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part7 /sys131              reiserfs   user,noauto,acl       0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part3 /windows/C           ntfs-3g    ro,user,noauto        0 0
/dev/disk/by-id/ata-ST2000NM0033-9ZM175_Z1X0TDA9-part4 /windows/D           ntfs-3g    user,noauto           0 0

Re-booted.

Since then I let the machine alone and am waiting that the message ‘Disabling IRQ #19’ would occur again.

It’s 3-4 hours now - and I may be waiting for Godot.

Greets to all
Mike

[kerijan2003]

Running clamav or any other anti-virus would be futile, as they are to detect virii in Windows programs, or documents. Maybe I’m (if only partially) wrong, and maybe someone might correct me if that is the case.

[hendersj]

On Thu, 03 Jul 2014 23:16:01 +0000, kerijan2003 wrote:

> Running clamav or any other anti-virus would be futile, as they are to
> detect virii in Windows programs, or documents. Maybe I’m (if only
> partially) wrong, and maybe someone might correct me if that is the
> case.

ClamAV, like most virus scanners, matches based on signatures. The
definitions files can (and IIRC, do) include Linux-based viruses, but
infecting Linux remotely is quite difficult, and a security update
generally is released quickly to address exploits in the wild.

Having an up-to-date system is more important, but that doesn’t mean that
you couldn’t detect something with clamav.

Keep in mind as well that some forms of Windows virus (like macro
viruses) could affect Linux systems if macros are enabled in (for
example) LibreOffice.

Of course, it’s database is going to be mostly Windows viruses, since
that’s the most targeted platform for virus writers. That doesn’t mean
that’s all it detects, and it would be naive to assume that that’s all it
does.

Jim

Jim Henderson
openSUSE Forums Administrator
Forum Use Terms & Conditions at http://tinyurl.com/openSUSE-T-C

[ratzi]

Hi all again !

It took me time to observe the system and to do some tests - an a bit nasty task given such a kind of behaviour.

Summary

No matter whatever parameters I enter in fstab for the windows volumes,
sooner or later lag occurs just after in a terminal I get the message:

Message from syslogd@lHost at Jul  4 11:37:15 ...
 kernel: 1792.350267] Disabling IRQ #19

(I enabled ‘Receive System Mail’ for the standard user).

irq 19 - according to /var/log/messages this is an ata_bmdma_interrupt
(see more detailed listings below).

This has a history:
As described in thread
http://forums.opensuse.org/showthread.php/497445-no-boot-when-IDE-drives-plugged
I could not boot openSUSE 13.1 64Bit
if I had an IDE device connected to my ASUS P8H67-V motherboard,
unless I entered the parameter ide=nodma at boot.

This obviously has changed with the latest updates of 13.1,
which included more than one Kernel update:
I now can boot without that parameter ide=nodma passed to legacy grub.

But as a side effect I now always get the problem with serious lag
whenever irq 19 is disabled by the Kernel.

A virus?
Not really likely.
I have only the 4 official repos:
13.1 OSS, NON-OSS, OSS update, NON-OSS update.
Only exception: I installed as root through YaST OpenOffice,
downloaded from an official OpenOffice mirror.
Nothing else.
I didn’t install anything as the standard user I’m running.
I didn’t run any macros in OpenOffice.
I neither use my win7 for surfing the internet, nor for eMail.
Nothing.
Running win7, my usual tasks are updating win7 itself on the one hand,
and updating the anti virus software for win7 on the other hand.
Just boring.
I still need win7 for a scanner for old photos (no drivers for Linux),
as well as for educational software for my kids which doesn’t run under Linux (perhaps I should try wine).

At the end of the day, with respect to openSUSE 13.1, it would have to be a virus within the official openSUSE 13.1 software
(see the list of the 4 repos above),
or within OpenOffice software itself (not within files with macros, which I do not have and which I did not open).

I will now unplug the NEC internal IDE DVD burner, the last device left connected to the IDE adapter/controller,
which in turn is on the ASUS P8H67-V motherboard - which further means that in no way I can remove it.

Thanks
Mike

Examples

Boot
WITHOUT ide=nodma
WITHOUT parameters ‘users,gid=users,fmask=133,dmask=022,locale=en_US.UTF-8’ in fstab for win7 volumes

Boot: 11:07

Message from syslogd@lHost at Jul 4 11:37:15 …
kernel: 1792.350267] Disabling IRQ #19

Just after that lag.

In ‘/var/log/messages’:

2014-07-04T11:37:15.879251+02:00 lHost kernel:  1792.350164] irq 19: nobody cared (try booting with the "irqpoll" option)
2014-07-04T11:37:15.879265+02:00 lHost kernel:  1792.350168] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 3.11.10-17-desktop #1
2014-07-04T11:37:15.879266+02:00 lHost kernel:  1792.350170] Hardware name: System manufacturer System Product Name/P8H67-V, BIOS 3707 07/12/2013
2014-07-04T11:37:15.879267+02:00 lHost kernel:  1792.350171]  ffff88021466eabc ffffffff815a0402 ffff88021466ea00 ffffffff810e4ec8
2014-07-04T11:37:15.879267+02:00 lHost kernel:  1792.350174]  ffff88021466ea00 0000000000000013 0000000000000000 ffffffff810e52ea
2014-07-04T11:37:15.879268+02:00 lHost kernel:  1792.350176]  0000000000000000 0000000000000000 0000000000000013 0000000000000000
2014-07-04T11:37:15.879269+02:00 lHost kernel:  1792.350179] Call Trace:
2014-07-04T11:37:15.879279+02:00 lHost kernel:  1792.350189]  <ffffffff81004a28>] dump_trace+0x88/0x310
2014-07-04T11:37:15.879280+02:00 lHost kernel:  1792.350192]  <ffffffff81004d80>] show_stack_log_lvl+0xd0/0x1d0
2014-07-04T11:37:15.879280+02:00 lHost kernel:  1792.350195]  <ffffffff810061bc>] show_stack+0x1c/0x50
2014-07-04T11:37:15.879281+02:00 lHost kernel:  1792.350199]  <ffffffff815a0402>] dump_stack+0x50/0x89
2014-07-04T11:37:15.879281+02:00 lHost kernel:  1792.350202]  <ffffffff810e4ec8>] __report_bad_irq+0x28/0xc0
2014-07-04T11:37:15.879282+02:00 lHost kernel:  1792.350205]  <ffffffff810e52ea>] note_interrupt+0x13a/0x200
2014-07-04T11:37:15.879282+02:00 lHost kernel:  1792.350211]  <ffffffff810e2dbe>] handle_irq_event_percpu+0xee/0x240
2014-07-04T11:37:15.879283+02:00 lHost kernel:  1792.350214]  <ffffffff810e2f49>] handle_irq_event+0x39/0x60
2014-07-04T11:37:15.879283+02:00 lHost kernel:  1792.350216]  <ffffffff810e5d9c>] handle_fasteoi_irq+0x5c/0x100
2014-07-04T11:37:15.879283+02:00 lHost kernel:  1792.350219]  <ffffffff810048f8>] handle_irq+0x18/0x30
2014-07-04T11:37:15.879284+02:00 lHost kernel:  1792.350221]  <ffffffff810043f0>] do_IRQ+0x40/0xb0
2014-07-04T11:37:15.879284+02:00 lHost kernel:  1792.350224]  <ffffffff815a746d>] common_interrupt+0x6d/0x6d
2014-07-04T11:37:15.879285+02:00 lHost kernel:  1792.350231]  <ffffffff814742aa>] cpuidle_enter_state+0x4a/0xc0
2014-07-04T11:37:15.879285+02:00 lHost kernel:  1792.350234]  <ffffffff814743da>] cpuidle_idle_call+0xba/0x280
2014-07-04T11:37:15.879286+02:00 lHost kernel:  1792.350238]  <ffffffff8100ba55>] arch_cpu_idle+0x5/0x30
2014-07-04T11:37:15.879286+02:00 lHost kernel:  1792.350241]  <ffffffff810a170e>] cpu_startup_entry+0xde/0x2f0
2014-07-04T11:37:15.879287+02:00 lHost kernel:  1792.350245]  <ffffffff81cd8eb5>] start_kernel+0x443/0x44e
2014-07-04T11:37:15.879287+02:00 lHost kernel:  1792.350249]  <ffffffff81cd86b0>] x86_64_start_kernel+0x10c/0x11b
2014-07-04T11:37:15.879287+02:00 lHost kernel:  1792.350250] handlers:
2014-07-04T11:37:15.879288+02:00 lHost kernel:  1792.350253] <ffffffff81400eb0>] ata_bmdma_interrupt
2014-07-04T11:37:15.879288+02:00 lHost kernel:  1792.350255] <ffffffff81400eb0>] ata_bmdma_interrupt
2014-07-04T11:37:15.879289+02:00 lHost kernel:  1792.350266] <ffffffffa027b580>] ahc_linux_isr [aic7xxx]
2014-07-04T11:37:15.879289+02:00 lHost kernel:  1792.350267] Disabling IRQ #19

(continued next post)

[ratzi]

(continued)

---

Boot
WITH ide=nodma
WITH parameters ‘users,gid=users,fmask=133,dmask=022,locale=en_US.UTF-8’ in fstab

Boot 00:06

Message from syslogd@lHost at Jul 5 01:33:11 …
kernel: 5210.095308] Disabling IRQ #19

/var/log/messages

2014-07-05T00:06:52.834988+02:00 lHost systemd[1]: Started RealtimeKit Scheduling Policy Service.
2014-07-05T00:06:52.835394+02:00 lHost rtkit-daemon[2214]: Successfully called chroot.
2014-07-05T00:06:52.835575+02:00 lHost rtkit-daemon[2214]: Successfully dropped privileges.
2014-07-05T00:06:52.835737+02:00 lHost rtkit-daemon[2214]: Successfully limited resources.
2014-07-05T00:06:52.835904+02:00 lHost rtkit-daemon[2214]: Running.
2014-07-05T00:06:52.836308+02:00 lHost rtkit-daemon[2214]: Watchdog thread running.
2014-07-05T00:06:52.836464+02:00 lHost rtkit-daemon[2214]: Canary thread running.
2014-07-05T00:06:52.838045+02:00 lHost rtkit-daemon[2214]: Successfully made thread 2213 of process 2213 (/usr/bin/pulseaudio) owned by 'alltag' high priority at nice level -11.
2014-07-05T00:06:52.838270+02:00 lHost rtkit-daemon[2214]: Supervising 1 threads of 1 processes of 1 users.
2014-07-05T00:06:52.958514+02:00 lHost rtkit-daemon[2214]: Supervising 1 threads of 1 processes of 1 users.
2014-07-05T00:06:52.960319+02:00 lHost rtkit-daemon[2214]: Successfully made thread 2229 of process 2213 (/usr/bin/pulseaudio) owned by 'alltag' RT at priority 5.
2014-07-05T00:06:52.960598+02:00 lHost rtkit-daemon[2214]: Supervising 2 threads of 1 processes of 1 users.
2014-07-05T00:06:52.962826+02:00 lHost rtkit-daemon[2214]: Supervising 2 threads of 1 processes of 1 users.
2014-07-05T00:06:52.964523+02:00 lHost rtkit-daemon[2214]: Successfully made thread 2230 of process 2213 (/usr/bin/pulseaudio) owned by 'alltag' RT at priority 5.
2014-07-05T00:06:52.964803+02:00 lHost rtkit-daemon[2214]: Supervising 3 threads of 1 processes of 1 users.
2014-07-05T00:06:52.967489+02:00 lHost dbus[646]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service'
2014-07-05T00:06:52.967921+02:00 lHost systemd[1]: Starting Bluetooth service...
2014-07-05T00:06:52.969130+02:00 lHost bluetoothd[2231]: Bluetooth daemon 5.8
2014-07-05T00:06:52.970389+02:00 lHost dbus[646]: [system] Successfully activated service 'org.bluez'
2014-07-05T00:06:52.970611+02:00 lHost systemd[1]: Started Bluetooth service.
2014-07-05T00:06:52.975219+02:00 lHost kernel:    35.746673] Bluetooth: Core ver 2.16
2014-07-05T00:06:52.975231+02:00 lHost kernel:    35.746687] NET: Registered protocol family 31
2014-07-05T00:06:52.975232+02:00 lHost kernel:    35.746688] Bluetooth: HCI device and connection manager initialized
2014-07-05T00:06:52.975232+02:00 lHost kernel:    35.746695] Bluetooth: HCI socket layer initialized
2014-07-05T00:06:52.975232+02:00 lHost kernel:    35.746697] Bluetooth: L2CAP socket layer initialized
2014-07-05T00:06:52.975233+02:00 lHost kernel:    35.746701] Bluetooth: SCO socket layer initialized
2014-07-05T00:06:52.974877+02:00 lHost bluetoothd[2231]: Starting SDP server
2014-07-05T00:06:52.984328+02:00 lHost bluetoothd[2231]: Bluetooth management interface 1.3 initialized
2014-07-05T00:06:52.984644+02:00 lHost dbus[646]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service'
2014-07-05T00:06:52.985932+02:00 lHost kernel:    35.756006] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
2014-07-05T00:06:52.985943+02:00 lHost kernel:    35.756009] Bluetooth: BNEP filters: protocol multicast
2014-07-05T00:06:52.985944+02:00 lHost kernel:    35.756018] Bluetooth: BNEP socket layer initialized
2014-07-05T00:06:52.986047+02:00 lHost systemd[1]: Starting Hostname Service...
2014-07-05T00:06:52.987166+02:00 lHost dbus[646]: [system] Successfully activated service 'org.freedesktop.hostname1'
2014-07-05T00:06:52.987642+02:00 lHost systemd[1]: Started Hostname Service.
2014-07-05T00:06:53.121990+02:00 lHost rtkit-daemon[2214]: Successfully made thread 2250 of process 2250 (/usr/bin/pulseaudio) owned by 'alltag' high priority at nice level -11.
2014-07-05T00:06:53.122278+02:00 lHost rtkit-daemon[2214]: Supervising 4 threads of 2 processes of 1 users.
2014-07-05T00:06:53.122471+02:00 lHost pulseaudio[2250]: [pulseaudio] pid.c: Daemon already running.
2014-07-05T00:06:53.146087+02:00 lHost rtkit-daemon[2214]: Successfully made thread 2261 of process 2261 (/usr/bin/pulseaudio) owned by 'alltag' high priority at nice level -11.
2014-07-05T00:06:53.146406+02:00 lHost rtkit-daemon[2214]: Supervising 4 threads of 2 processes of 1 users.
2014-07-05T00:06:53.147248+02:00 lHost pulseaudio[2261]: [pulseaudio] pid.c: Daemon already running.
2014-07-05T00:06:53.455469+02:00 lHost polkitd[888]: Registered Authentication Agent for unix-session:1 (system bus name :1.34 [/usr/lib64/kde4/libexec/polkit-kde-authentication-agent-1], object path /org/kde/PolicyKit1/AuthenticationAgent, locale de_DE.UTF-8)
2014-07-05T00:06:54.358400+02:00 lHost systemd[1]: Mounting FUSE Control File System...
2014-07-05T00:06:54.358896+02:00 lHost kernel:    37.129076] fuse init (API version 7.22)
2014-07-05T00:06:54.360605+02:00 lHost systemd[1]: Mounted FUSE Control File System.
2014-07-05T00:07:02.421543+02:00 lHost systemd[1]: Starting Stop Read-Ahead Data Collection...
2014-07-05T00:07:02.423426+02:00 lHost systemd[1]: Started Stop Read-Ahead Data Collection.
2014-07-05T00:15:01.456720+02:00 lHost /usr/sbin/cron[2477]: pam_unix(crond:session): session opened for user root by (uid=0)
2014-07-05T00:15:01.458317+02:00 lHost systemd[1]: Starting user-0.slice.
2014-07-05T00:15:01.458579+02:00 lHost systemd[1]: Created slice user-0.slice.
2014-07-05T00:15:01.459023+02:00 lHost systemd[1]: Starting User Manager for 0...
2014-07-05T00:15:01.459735+02:00 lHost systemd[1]: Starting Session 2 of user root.
2014-07-05T00:15:01.459956+02:00 lHost systemd[1]: Started Session 2 of user root.
2014-07-05T00:15:01.460539+02:00 lHost systemd: pam_unix(systemd-user:session): session opened for user root by (uid=0)
2014-07-05T00:15:01.462164+02:00 lHost systemd[2478]: Failed to open private bus connection: Failed to connect to socket /run/user/0/dbus/user_bus_socket: No such file or directory
2014-07-05T00:15:01.480857+02:00 lHost systemd[2478]: Mounted /sys/fs/fuse/connections.
2014-07-05T00:15:01.481132+02:00 lHost systemd[2478]: Stopped target Printer.
2014-07-05T00:15:01.481299+02:00 lHost systemd[2478]: Stopped target Sound Card.
2014-07-05T00:15:01.481471+02:00 lHost systemd[2478]: Starting Default.
2014-07-05T00:15:01.481659+02:00 lHost systemd[2478]: Reached target Default.
2014-07-05T00:15:01.481833+02:00 lHost systemd[2478]: Startup finished in 19ms.
2014-07-05T00:15:01.482031+02:00 lHost systemd[1]: Started User Manager for 0.
2014-07-05T00:15:01.538901+02:00 lHost /USR/SBIN/CRON[2477]: pam_unix(crond:session): session closed for user root
2014-07-05T00:21:17.209222+02:00 lHost systemd[1]: Starting Cleanup of Temporary Directories...
2014-07-05T00:21:17.212319+02:00 lHost systemd-tmpfiles[2522]: stat(/run/user/1000/gvfs) failed: Permission denied
2014-07-05T00:21:17.293981+02:00 lHost systemd[1]: Started Cleanup of Temporary Directories.
2014-07-05T00:30:01.543321+02:00 lHost /usr/sbin/cron[2555]: pam_unix(crond:session): session opened for user root by (uid=0)
2014-07-05T00:30:01.544870+02:00 lHost systemd[1]: Starting Session 3 of user root.
2014-07-05T00:30:01.545108+02:00 lHost systemd[1]: Started Session 3 of user root.
2014-07-05T00:30:01.557920+02:00 lHost /USR/SBIN/CRON[2555]: pam_unix(crond:session): session closed for user root
2014-07-05T00:45:01.562211+02:00 lHost /usr/sbin/cron[2646]: pam_unix(crond:session): session opened for user root by (uid=0)
2014-07-05T00:45:01.563805+02:00 lHost systemd[1]: Starting Session 4 of user root.
2014-07-05T00:45:01.564056+02:00 lHost systemd[1]: Started Session 4 of user root.
2014-07-05T00:45:01.576195+02:00 lHost /USR/SBIN/CRON[2646]: pam_unix(crond:session): session closed for user root
2014-07-05T01:00:01.580538+02:00 lHost /usr/sbin/cron[2731]: pam_unix(crond:session): session opened for user root by (uid=0)
2014-07-05T01:00:01.582516+02:00 lHost systemd[1]: Starting Session 5 of user root.
2014-07-05T01:00:01.582856+02:00 lHost systemd[1]: Started Session 5 of user root.
2014-07-05T01:00:01.598501+02:00 lHost /USR/SBIN/CRON[2731]: pam_unix(crond:session): session closed for user root
2014-07-05T01:15:01.603287+02:00 lHost /usr/sbin/cron[2854]: pam_unix(crond:session): session opened for user root by (uid=0)
2014-07-05T01:15:01.604759+02:00 lHost systemd[1]: Starting Session 6 of user root.
2014-07-05T01:15:01.604981+02:00 lHost systemd[1]: Started Session 6 of user root.
2014-07-05T01:15:01.619941+02:00 lHost /USR/SBIN/CRON[2854]: pam_unix(crond:session): session closed for user root
2014-07-05T01:26:34.111855+02:00 lHost su: (to alltag) alltag on none
2014-07-05T01:26:34.112211+02:00 lHost su: pam_unix(su:session): session opened for user root by (uid=1000)
2014-07-05T01:26:34.113601+02:00 lHost su: pam_systemd(su:session): pam_putenv: delete non-existent entry; XDG_RUNTIME_DIR
2014-07-05T01:26:34.128836+02:00 lHost su: pam_unix(su:session): session closed for user root
2014-07-05T01:26:34.247067+02:00 lHost su: (to alltag) alltag on none
2014-07-05T01:26:34.247368+02:00 lHost su: pam_unix(su:session): session opened for user root by (uid=1000)
2014-07-05T01:26:34.248452+02:00 lHost su: pam_systemd(su:session): pam_putenv: delete non-existent entry; XDG_RUNTIME_DIR
2014-07-05T01:30:01.624401+02:00 lHost /usr/sbin/cron[3015]: pam_unix(crond:session): session opened for user root by (uid=0)
2014-07-05T01:30:01.626288+02:00 lHost systemd[1]: Starting Session 7 of user root.
2014-07-05T01:30:01.626599+02:00 lHost systemd[1]: Started Session 7 of user root.
2014-07-05T01:30:01.641061+02:00 lHost /USR/SBIN/CRON[3015]: pam_unix(crond:session): session closed for user root
2014-07-05T01:33:11.481895+02:00 lHost kernel:  5210.095223] irq 19: nobody cared (try booting with the "irqpoll" option)
2014-07-05T01:33:11.481904+02:00 lHost kernel:  5210.095227] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 3.11.10-17-desktop #1
2014-07-05T01:33:11.481905+02:00 lHost kernel:  5210.095229] Hardware name: System manufacturer System Product Name/P8H67-V, BIOS 3707 07/12/2013
2014-07-05T01:33:11.481905+02:00 lHost kernel:  5210.095230]  ffff88021466d4bc ffffffff815a0402 ffff88021466d400 ffffffff810e4ec8
2014-07-05T01:33:11.481906+02:00 lHost kernel:  5210.095232]  ffff88021466d400 0000000000000013 0000000000000000 ffffffff810e52ea
2014-07-05T01:33:11.481906+02:00 lHost kernel:  5210.095233]  0000000000000000 0000000000000000 0000000000000013 0000000000000000
2014-07-05T01:33:11.481907+02:00 lHost kernel:  5210.095235] Call Trace:
2014-07-05T01:33:11.481914+02:00 lHost kernel:  5210.095244]  <ffffffff81004a28>] dump_trace+0x88/0x310
2014-07-05T01:33:11.481915+02:00 lHost kernel:  5210.095246]  <ffffffff81004d80>] show_stack_log_lvl+0xd0/0x1d0
2014-07-05T01:33:11.481915+02:00 lHost kernel:  5210.095248]  <ffffffff810061bc>] show_stack+0x1c/0x50
2014-07-05T01:33:11.481915+02:00 lHost kernel:  5210.095251]  <ffffffff815a0402>] dump_stack+0x50/0x89
2014-07-05T01:33:11.481916+02:00 lHost kernel:  5210.095254]  <ffffffff810e4ec8>] __report_bad_irq+0x28/0xc0
2014-07-05T01:33:11.481916+02:00 lHost kernel:  5210.095257]  <ffffffff810e52ea>] note_interrupt+0x13a/0x200
2014-07-05T01:33:11.481916+02:00 lHost kernel:  5210.095261]  <ffffffff810e2dbe>] handle_irq_event_percpu+0xee/0x240
2014-07-05T01:33:11.481917+02:00 lHost kernel:  5210.095263]  <ffffffff810e2f49>] handle_irq_event+0x39/0x60
2014-07-05T01:33:11.481917+02:00 lHost kernel:  5210.095265]  <ffffffff810e5d9c>] handle_fasteoi_irq+0x5c/0x100
2014-07-05T01:33:11.481917+02:00 lHost kernel:  5210.095267]  <ffffffff810048f8>] handle_irq+0x18/0x30
2014-07-05T01:33:11.481918+02:00 lHost kernel:  5210.095269]  <ffffffff810043f0>] do_IRQ+0x40/0xb0
2014-07-05T01:33:11.481918+02:00 lHost kernel:  5210.095271]  <ffffffff815a746d>] common_interrupt+0x6d/0x6d
2014-07-05T01:33:11.481918+02:00 lHost kernel:  5210.095276]  <ffffffff814742aa>] cpuidle_enter_state+0x4a/0xc0
2014-07-05T01:33:11.481919+02:00 lHost kernel:  5210.095279]  <ffffffff814743da>] cpuidle_idle_call+0xba/0x280
2014-07-05T01:33:11.481919+02:00 lHost kernel:  5210.095282]  <ffffffff8100ba55>] arch_cpu_idle+0x5/0x30
2014-07-05T01:33:11.481919+02:00 lHost kernel:  5210.095285]  <ffffffff810a170e>] cpu_startup_entry+0xde/0x2f0
2014-07-05T01:33:11.481920+02:00 lHost kernel:  5210.095289]  <ffffffff81cd8eb5>] start_kernel+0x443/0x44e
2014-07-05T01:33:11.481920+02:00 lHost kernel:  5210.095291]  <ffffffff81cd86b0>] x86_64_start_kernel+0x10c/0x11b
2014-07-05T01:33:11.481921+02:00 lHost kernel:  5210.095293] handlers:
2014-07-05T01:33:11.481921+02:00 lHost kernel:  5210.095295] <ffffffff81400eb0>] ata_bmdma_interrupt
2014-07-05T01:33:11.481921+02:00 lHost kernel:  5210.095296] <ffffffff81400eb0>] ata_bmdma_interrupt
2014-07-05T01:33:11.481922+02:00 lHost kernel:  5210.095307] <ffffffffa0287580>] ahc_linux_isr [aic7xxx]
2014-07-05T01:33:11.481922+02:00 lHost kernel:  5210.095308] Disabling IRQ #19
2014-07-05T01:45:01.645225+02:00 lHost /usr/sbin/cron[3096]: pam_unix(crond:session): session opened for user root by (uid=0)
2014-07-05T01:45:01.646904+02:00 lHost systemd[1]: Starting Session 8 of user root.
2014-07-05T01:45:01.647200+02:00 lHost systemd[1]: Started Session 8 of user root.
2014-07-05T01:45:01.660340+02:00 lHost /USR/SBIN/CRON[3096]: pam_unix(crond:session): session closed for user root
2014-07-05T01:52:26.226548+02:00 lHost su: pam_unix(su:session): session closed for user root
2014-07-05T02:00:01.665059+02:00 lHost /usr/sbin/cron[3213]: pam_unix(crond:session): session opened for user root by (uid=0)
2014-07-05T02:00:01.666780+02:00 lHost systemd[1]: Starting Session 9 of user root.
2014-07-05T02:00:01.667014+02:00 lHost systemd[1]: Started Session 9 of user root.
2014-07-05T02:00:01.681700+02:00 lHost /USR/SBIN/CRON[3213]: pam_unix(crond:session): session closed for user root
2014-07-05T02:01:37.693483+02:00 lHost su: (to alltag) alltag on none
2014-07-05T02:01:37.693857+02:00 lHost su: pam_unix(su:session): session opened for user root by (uid=1000)
2014-07-05T02:01:37.695080+02:00 lHost su: pam_systemd(su:session): pam_putenv: delete non-existent entry; XDG_RUNTIME_DIR
2014-07-05T02:01:37.699126+02:00 lHost su: pam_unix(su:session): session closed for user root
2014-07-05T02:01:37.815435+02:00 lHost su: (to alltag) alltag on none
2014-07-05T02:01:37.815987+02:00 lHost su: pam_unix(su:session): session opened for user root by (uid=1000)
2014-07-05T02:01:37.817248+02:00 lHost su: pam_systemd(su:session): pam_putenv: delete non-existent entry; XDG_RUNTIME_DIR

[ratzi]

/var/log/warn

2014-07-05T00:06:31.803484+02:00 lHost kdm[1905]: plymouth is running
2014-07-05T00:06:31.805026+02:00 lHost kdm[1905]: plymouth is active on VT 7, reusing for :0
2014-07-05T00:06:31.805195+02:00 lHost kdm[1905]: plymouth should quit after server startup
2014-07-05T00:06:32.114878+02:00 lHost kdm[1905]: Quitting Plymouth with transition
2014-07-05T00:06:32.137272+02:00 lHost kdm[1905]: Is Plymouth still running? no
2014-07-05T00:06:33.531409+02:00 lHost systemd[1998]: Failed to open private bus connection: Failed to connect to socket /run/user/1000/dbus/user_bus_socket: No such file or directory
2014-07-05T00:06:41.466898+02:00 lHost kernel:    24.247259] sr2: scsi3-mmc drive: 40x/40x writer cd/rw xa/form2 cdda tray
2014-07-05T00:06:52.792650+02:00 lHost systemd[1]: [/usr/lib/systemd/system/rtkit-daemon.service:32] Unknown lvalue 'ControlGroup' in section 'Service'
2014-07-05T00:06:53.122471+02:00 lHost pulseaudio[2250]: [pulseaudio] pid.c: Daemon already running.
2014-07-05T00:06:53.147248+02:00 lHost pulseaudio[2261]: [pulseaudio] pid.c: Daemon already running.
2014-07-05T00:15:01.462164+02:00 lHost systemd[2478]: Failed to open private bus connection: Failed to connect to socket /run/user/0/dbus/user_bus_socket: No such file or directory
2014-07-05T00:21:17.212319+02:00 lHost systemd-tmpfiles[2522]: stat(/run/user/1000/gvfs) failed: Permission denied
2014-07-05T01:26:34.113601+02:00 lHost su: pam_systemd(su:session): pam_putenv: delete non-existent entry; XDG_RUNTIME_DIR
2014-07-05T01:26:34.248452+02:00 lHost su: pam_systemd(su:session): pam_putenv: delete non-existent entry; XDG_RUNTIME_DIR
2014-07-05T01:33:11.481895+02:00 lHost kernel:  5210.095223] irq 19: nobody cared (try booting with the "irqpoll" option)
2014-07-05T01:33:11.481904+02:00 lHost kernel:  5210.095227] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 3.11.10-17-desktop #1
2014-07-05T01:33:11.481905+02:00 lHost kernel:  5210.095229] Hardware name: System manufacturer System Product Name/P8H67-V, BIOS 3707 07/12/2013
2014-07-05T01:33:11.481905+02:00 lHost kernel:  5210.095230]  ffff88021466d4bc ffffffff815a0402 ffff88021466d400 ffffffff810e4ec8
2014-07-05T01:33:11.481906+02:00 lHost kernel:  5210.095232]  ffff88021466d400 0000000000000013 0000000000000000 ffffffff810e52ea
2014-07-05T01:33:11.481906+02:00 lHost kernel:  5210.095233]  0000000000000000 0000000000000000 0000000000000013 0000000000000000
2014-07-05T01:33:11.481907+02:00 lHost kernel:  5210.095235] Call Trace:
2014-07-05T01:33:11.481914+02:00 lHost kernel:  5210.095244]  <ffffffff81004a28>] dump_trace+0x88/0x310
2014-07-05T01:33:11.481915+02:00 lHost kernel:  5210.095246]  <ffffffff81004d80>] show_stack_log_lvl+0xd0/0x1d0
2014-07-05T01:33:11.481915+02:00 lHost kernel:  5210.095248]  <ffffffff810061bc>] show_stack+0x1c/0x50
2014-07-05T01:33:11.481915+02:00 lHost kernel:  5210.095251]  <ffffffff815a0402>] dump_stack+0x50/0x89
2014-07-05T01:33:11.481916+02:00 lHost kernel:  5210.095254]  <ffffffff810e4ec8>] __report_bad_irq+0x28/0xc0
2014-07-05T01:33:11.481916+02:00 lHost kernel:  5210.095257]  <ffffffff810e52ea>] note_interrupt+0x13a/0x200
2014-07-05T01:33:11.481916+02:00 lHost kernel:  5210.095261]  <ffffffff810e2dbe>] handle_irq_event_percpu+0xee/0x240
2014-07-05T01:33:11.481917+02:00 lHost kernel:  5210.095263]  <ffffffff810e2f49>] handle_irq_event+0x39/0x60
2014-07-05T01:33:11.481917+02:00 lHost kernel:  5210.095265]  <ffffffff810e5d9c>] handle_fasteoi_irq+0x5c/0x100
2014-07-05T01:33:11.481917+02:00 lHost kernel:  5210.095267]  <ffffffff810048f8>] handle_irq+0x18/0x30
2014-07-05T01:33:11.481918+02:00 lHost kernel:  5210.095269]  <ffffffff810043f0>] do_IRQ+0x40/0xb0
2014-07-05T01:33:11.481918+02:00 lHost kernel:  5210.095271]  <ffffffff815a746d>] common_interrupt+0x6d/0x6d
2014-07-05T01:33:11.481918+02:00 lHost kernel:  5210.095276]  <ffffffff814742aa>] cpuidle_enter_state+0x4a/0xc0
2014-07-05T01:33:11.481919+02:00 lHost kernel:  5210.095279]  <ffffffff814743da>] cpuidle_idle_call+0xba/0x280
2014-07-05T01:33:11.481919+02:00 lHost kernel:  5210.095282]  <ffffffff8100ba55>] arch_cpu_idle+0x5/0x30
2014-07-05T01:33:11.481919+02:00 lHost kernel:  5210.095285]  <ffffffff810a170e>] cpu_startup_entry+0xde/0x2f0
2014-07-05T01:33:11.481920+02:00 lHost kernel:  5210.095289]  <ffffffff81cd8eb5>] start_kernel+0x443/0x44e
2014-07-05T01:33:11.481920+02:00 lHost kernel:  5210.095291]  <ffffffff81cd86b0>] x86_64_start_kernel+0x10c/0x11b
2014-07-05T01:33:11.481921+02:00 lHost kernel:  5210.095293] handlers:
2014-07-05T01:33:11.481921+02:00 lHost kernel:  5210.095295] <ffffffff81400eb0>] ata_bmdma_interrupt
2014-07-05T01:33:11.481921+02:00 lHost kernel:  5210.095296] <ffffffff81400eb0>] ata_bmdma_interrupt
2014-07-05T01:33:11.481922+02:00 lHost kernel:  5210.095307] <ffffffffa0287580>] ahc_linux_isr [aic7xxx]
2014-07-05T01:33:11.481922+02:00 lHost kernel:  5210.095308] Disabling IRQ #19
2014-07-05T02:01:37.695080+02:00 lHost su: pam_systemd(su:session): pam_putenv: delete non-existent entry; XDG_RUNTIME_DIR
2014-07-05T02:01:37.817248+02:00 lHost su: pam_systemd(su:session): pam_putenv: delete non-existent entry; XDG_RUNTIME_DIR

… and so on.