Kopieren führt zu hoher CPU Auslastung

Karpath · 26 Mai 2012

Hallo zusammen,

ich hoffe das hier ist das richtige Unterforum und vielleicht kann man mir helfen.
ich bin vor einiger Zeit von Xubuntu zu OpenSUSE 12.1 umgestiegen.
Ich habe das Problem das ein Kopiervorgang bei mir direkt 100% Prozessorlast hervorruft. Bei Xubuntu ging die Last zwar auch ab und an hoch, aber eben nicht so extrem.

Zunächst dachte ich das es an NTFS liegt, da ich Daten auf eine NTFS Partition kopiert habe. Nachdem ich diese in ext4 formatiert habe war es allerdings auch nicht besser. Die Partitionen befinden sich alle auf einer Festplatte.
Beim Kopieren von einem USB Datenträger besteht das Problem ebenfalls.
Ebenso beim Kopieren in der Konsole, ist also kein reines Problem von Dolphin.

Die hohe Prozessorlast wird durch den Systemmonitor angezeigt. Ausserdem wird das System recht zäh beim kopieren.

Meine Hardware:
Acer Travelmate 5730
Core2Duo T6670@2,2GHz
4 GB DDR2 Ram 800 MHz

TOP zeigt während des Kopierens folgendes an:

Code:

Tasks: 159 total, 2 running, 157 sleeping, 0 stopped, 0 zombie
Cpu(s): 4.5%us, 2.0%sy, 0.0%ni, 50.4%id, 43.1%wa, 0.0%hi, 0.0%si, 0.0%st
Mem: 3984688k total, 3867684k used, 117004k free, 29184k buffers
Swap: 2108412k total, 56k used, 2108356k free, 3269904k cached

 PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND 
 1161 root 20 0 172m 31m 10m S 5 0.8 0:47.86 Xorg 
25172 karpath 20 0 463m 26m 16m S 4 0.7 0:01.00 ksysguard 
25123 karpath 20 0 463m 25m 15m S 2 0.7 0:01.03 konsole 
 1673 karpath 20 0 910m 55m 23m S 1 1.4 0:20.80 plasma-desktop 
25144 karpath 20 0 297m 9916 4316 D 1 0.2 0:05.90 kio_file 
25168 karpath 20 0 463m 26m 16m S 1 0.7 0:01.63 ksysguard 
 775 root 20 0 0 0 0 S 1 0.0 0:00.54 jbd2/sda7-8 
 1573 karpath 20 0 20992 1880 736 S 0 0.0 0:00.98 dbus-daemon 
 1942 karpath 20 0 327m 19m 16m S 0 0.5 0:00.16 akonadi_nepomuk 
 2050 karpath 20 0 382m 19m 11m S 0 0.5 0:00.18 klipper 
25140 karpath 20 0 467m 32m 20m R 0 0.8 0:04.78 dolphin 
25174 karpath 20 0 9052 1184 836 S 0 0.0 0:00.02 ksysguardd 
 1 root 20 0 37264 4012 1532 S 0 0.1 0:00.83 systemd 
 2 root 20 0 0 0 0 S 0 0.0 0:00.00 kthreadd 
 3 root 20 0 0 0 0 S 0 0.0 0:00.06 ksoftirqd/0 
 6 root RT 0 0 0 0 S 0 0.0 0:00.00 migration/0 
 7 root -2 19 0 0 0 S 0 0.0 0:01.09 rcuc0 
 8 root RT 0 0 0 0 S 0 0.0 0:00.00 rcun0 
 9 root -2 0 0 0 0 S 0 0.0 0:00.00 rcub0 
 10 root RT 0 0 0 0 S 0 0.0 0:00.01 rcun1 
 11 root -2 0 0 0 0 S 0 0.0 0:00.00 rcub1 
 12 root RT 0 0 0 0 S 0 0.0 0:00.00 watchdog/0 
 13 root RT 0 0 0 0 S 0 0.0 0:00.00 migration/1 
 15 root -2 19 0 0 0 S 0 0.0 0:00.36 rcuc1 
 16 root 20 0 0 0 0 S 0 0.0 0:00.05 ksoftirqd/1 
 17 root 20 0 0 0 0 S 0 0.0 0:00.41 kworker/0:1 
 18 root RT 0 0 0 0 S 0 0.0 0:00.00 watchdog/1 
 19 root 0 -20 0 0 0 S 0 0.0 0:00.00 cpuset 
 20 root 0 -20 0 0 0 S 0 0.0 0:00.00 khelper 
 21 root 20 0 0 0 0 S 0 0.0 0:00.00 kdevtmpfs 
 22 root 0 -20 0 0 0 S 0 0.0 0:00.00 netns 
 23 root 20 0 0 0 0 S 0 0.0 0:00.00 sync_supers 
 24 root 20 0 0 0 0 S 0 0.0 0:00.00 bdi-default

Mein System habe ich auch schon neu installiert. Der Fehler war nach wie vor vorhanden.

Ich hoffe jemand weiss Rat

spoensche · 26 Mai 2012

Karpath schrieb:
Cpu(s): 4.5%us, 2.0%sy, 0.0%ni, 50.4%id, 43.1%wa, 0.0%hi, 0.0%si, 0.0%st

Deine CPU arbeitet nicht unter Vollast. 4,5%us = Userspace, 2,0%sy = System besser Kernelspace, 50,4%id (Leerlauf) und 43.1%wa = Wait.
Deine CPU langweilt sich weil sie die meiste Zeit auf andere Komponenten wartet, die aber mit den Daten nicht herbei kommen.

Wieviel Umdrehungen pro Min., wie groß ist der Plattencache, wie alt sind die Platten und wie siehts mit dem Füllstand aus?

RME · 26 Mai 2012

Hallo,

Kannst Du mal eine grosse Datei mit dem 'time' command kopieren:

Code:

time cp <input> <output>

Dann berechne: (Grösse der Datei in Bytes) / (real + use + rsys CPU Zeit)

Andere Variante:

Code:

dd if=/dev/zero of=speed_test.txt count=100000

Als 'count' Parameter (im Beispiel = 100000) verwendest Du (zum einfachen Vergleich mit dem ersten Test):

(Grösse der im ersten Test verwendeten Datei in Bytes) / 512

Was bekommst Du für Geschwindigkeiten (Bytes/sec)?

Wenn diese >= 100 MB/s sind, dann ist Dein Problem anderswo zu suchen.

Gruss,
Roland

P.S. "speed_test.txt" kannst Du dann wieder löschen

Karpath · 27 Mai 2012

spoensche schrieb:
Karpath schrieb:

Cpu(s): 4.5%us, 2.0%sy, 0.0%ni, 50.4%id, 43.1%wa, 0.0%hi, 0.0%si, 0.0%st

Zum Vergrößern anklicken....

Deine CPU arbeitet nicht unter Vollast. 4,5%us = Userspace, 2,0%sy = System besser Kernelspace, 50,4%id (Leerlauf) und 43.1%wa = Wait.
Deine CPU langweilt sich weil sie die meiste Zeit auf andere Komponenten wartet, die aber mit den Daten nicht herbei kommen.

Wieviel Umdrehungen pro Min., wie groß ist der Plattencache, wie alt sind die Platten und wie siehts mit dem Füllstand aus?

Es ist nur eine Standart Notebookplatte mit 5400 rpm, Cache dürfte nicht allzu hoch sein. Füllstand etwa 50%. Alter ca. 1,5 Jahre

Aber verhält sich da Suse so viel anders wie Win 7 oder Xubuntu?

@ RME: Ich werds nachher testen

Karpath · 27 Mai 2012

RME schrieb:
Hallo,

Kannst Du mal eine grosse Datei mit dem 'time' command kopieren:

Code:

time cp <input> <output>

Dann berechne: (Grösse der Datei in Bytes) / (real + use + rsys CPU Zeit)

Dateigröße 1 283 004 416 Bytes

Code:

real    0m43.242s
user    0m0.015s
sys     0m2.977s

1 283 004 416 Bytes / ( 43.242s + 0.015s + 2.977s) = 27 750 236,1033 Bytes/s

RME schrieb:
Andere Variante:

Code:

dd if=/dev/zero of=speed_test.txt count=100000

Als 'count' Parameter (im Beispiel = 100000) verwendest Du (zum einfachen Vergleich mit dem ersten Test):

(Grösse der im ersten Test verwendeten Datei in Bytes) / 512

Was bekommst Du für Geschwindigkeiten (Bytes/sec)?

Wenn diese >= 100 MB/s sind, dann ist Dein Problem anderswo zu suchen.

Gruss,
Roland

P.S. "speed_test.txt" kannst Du dann wieder löschen

Code:

dd if=/dev/zero of=speed_test.txt count=100000
100000+0 Datensätze ein
100000+0 Datensätze aus
51200000 Bytes (51 MB) kopiert, 0,385342 s, 133 MB/s

Sind recht unterschiedliche Werte

RME · 27 Mai 2012

Code:

Der 1. Test (lesen + schreiben): ca.  28 MB/sec

Der 2. Test (nur schreiben):     ca. 133 MB/sec

Ist dies für copy auf Festplatte (nicht usb)?

Ich würde erwarten dass der 1. Test etwa halb so schnell ist wie der zweite. 133 MB/sec ist nicht so schlecht.

Das lesen scheint mir aber schon eher langsam :???:

Ist das eine IDE Platte oder SATA?

Wenn IDE, ist dma eingestellt (set 'on')? -- sollte 'on' sein. Ich glaube dies kannst Du abfragen/setzen mit "hdparm" (siehe manpage).

Wenn SATA, weiss ich im Moment nicht weiter.

Aber wie auch immer:

Karpath » 26. Mai 2012 schrieb:
Die hohe Prozessorlast wird durch den Systemmonitor angezeigt. Ausserdem wird das System recht zäh beim kopieren.

Die Prozessorlast ist während dem Kopiervorgang nicht hoch (gemäss 'top') -- allenfalls für kurze Zeit (gemäss Deiner 'Systemmonitor' Beobachtung). Es muss noch einen anderen Grund geben für die starke(!) Verlangsamung des Systems.

Übrigens: schau Dir nochmals 'top' an während dem Kopieren. Wenn 'top' läuft, drücke die '1' Taste (mit dem Fokus auf Fenster wo 'top' läuft); dann siehst Du die Prozessor-Belastung der einzelnen Kerne.

-----
P.S.

Den 2. Test hättest Du (gemäss meiner Anleitung) etwa so:

Code:

dd if=/dev/zero of=speed_test.txt count=2500000

machen sollen/können, damit die Menge der Daten etwa dem 1. Test entsprechen. Also:

1 283 004 416 Bytes / 512 ~ 2500000 (daher count=2500000).

-----
P.P.S.

Was ist die Ausgabe von (als root, mit möglichst keinen aktiven tasks):

Code:

hdparm -t /dev/sda

sowie:

Code:

hdparm -i /dev/sda

(siehe manual page)

-/-

RME · 27 Mai 2012

Nachtrag:

Irgendetwas ist schon nicht wie es sein sollte:

von Karpath » 26. Mai 2012, 12:38

Code:

Tasks: 159 total, 2 running, 157 sleeping, 0 stopped, 0 zombie
Cpu(s): 4.5%us, 2.0%sy, 0.0%ni, 50.4%id, 43.1%wa, 0.0%hi, 0.0%si, 0.0%st
Mem: 3984688k total, 3867684k used, 117004k free, 29184k buffers
Swap: 2108412k total, 56k used, 2108356k free, 3269904k cached

  PID USER    PR  NI   VIRT   RES   SHR  S  %CPU  %MEM   TIME+   COMMAND 
 1161 root    20   0   172m   31m   10m  S     5   0.8  0:47.86  Xorg 
25172 karpath 20   0   463m   26m   16m  S     4   0.7  0:01.00  ksysguard 
25123 karpath 20   0   463m   25m   15m  S     2   0.7  0:01.03  konsole 
 1673 karpath 20   0   910m   55m   23m  S     1   1.4  0:20.80  plasma-desktop 
25144 karpath 20   0   297m  9916  4316  D     1   0.2  0:05.90  kio_file 
25168 karpath 20   0   463m   26m   16m  S     1   0.7  0:01.63  ksysguard 
  775 root    20   0      0     0     0  S     1   0.0  0:00.54  jbd2/sda7-8 
 1573 karpath 20   0  20992  1880   736  S     0   0.0  0:00.98  dbus-daemon 
 1942 karpath 20   0   327m   19m   16m  S     0   0.5  0:00.16  akonadi_nepomuk 
 2050 karpath 20   0   382m   19m   11m  S     0   0.5  0:00.18  klipper 
25140 karpath 20   0   467m   32m   20m  R     0   0.8  0:04.78  dolphin 
25174 karpath 20   0   9052  1184   836  S     0   0.0  0:00.02  ksysguardd 
    1 root    20   0  37264  4012  1532  S     0   0.1  0:00.83  systemd 
    2 root    20   0      0     0     0  S     0   0.0  0:00.00  kthreadd 
...

Es fällt auf dass:

-1- die CPU viel Zeit mit warten verbraucht (43.1%wa -- cpu is idle because it waits for IO to complete).

-2- der RAM Speicher voll genutzt wird und sogar geswapt wird (wenig, aber immerhin); swapping kostet Zeit.

Aber was bedeutet dies alles?

spoensche · 27 Mai 2012

Die 43% I/O wait sind merkwürdig, daher auch meine Fragen zur Platte.

56k an Swap sind i.d.R. nicht wirklich tragisch und führen nicht zu 43% I/O Wait.

Entweder entsteht unterwegs am Bus oder zwischen Controller (Board) und Magnetscheibe ein Stau.

Karpath · 28 Mai 2012

RME schrieb:
Ist das eine IDE Platte oder SATA?

Wenn IDE, ist dma eingestellt (set 'on')? -- sollte 'on' sein. Ich glaube dies kannst Du abfragen/setzen mit "hdparm" (siehe manpage).

Wenn SATA, weiss ich im Moment nicht weiter.

Ist Sata

RME schrieb:
Code:

hdparm -t /dev/sda

Code:

hdparm -t /dev/sda

/dev/sda:
 Timing buffered disk reads: 230 MB in  3.02 seconds =  76.27 MB/sec

RME schrieb:
sowie:

Code:

hdparm -i /dev/sda

(siehe manual page)

Code:

hdparm -i /dev/sda

/dev/sda:

 Model=WDC WD2500BEVT-22A23T0, FwRev=01.01A01, SerialNo=WD-WXQ1A80M7434
 Config={ HardSect NotMFM HdSw>15uSec SpinMotCtl Fixed DTR>5Mbs FmtGapReq }
 RawCHS=16383/16/63, TrkSize=0, SectSize=0, ECCbytes=50
 BuffType=unknown, BuffSize=8192kB, MaxMultSect=16, MultSect=off
 CurCHS=16383/16/63, CurSects=16514064, LBA=yes, LBAsects=488397168
 IORDY=on/off, tPIO={min:120,w/IORDY:120}, tDMA={min:120,rec:120}
 PIO modes:  pio0 pio3 pio4 
 DMA modes:  mdma0 mdma1 mdma2 
 UDMA modes: udma0 udma1 udma2 udma3 udma4 udma5 *udma6 
 AdvancedPM=yes: unknown setting WriteCache=enabled
 Drive conforms to: Unspecified:  ATA/ATAPI-1,2,3,4,5,6,7

 * signifies the current active mode

spoensche schrieb:
Die 43% I/O wait sind merkwürdig, daher auch meine Fragen zur Platte.

56k an Swap sind i.d.R. nicht wirklich tragisch und führen nicht zu 43% I/O Wait.

Entweder entsteht unterwegs am Bus oder zwischen Controller (Board) und Magnetscheibe ein Stau.

Könnte das ein Schaden an der Festplatte sein?

spoensche · 28 Mai 2012

Karpath schrieb:
Könnte das ein Schaden an der Festplatte sein?

Nein, eigentlich nicht.

Du könntest dir aber mal die smartmontools installieren und die Ausgabe von

Code:

smartctl -x /dev/sda

Wir könnten mal das Verhalten bei unterschiedlichen Blockgrößen übeprüfen, Poste mal bitte die Ausgabe von

Code:

cat /proc/interrupts

Wir können den dd Test mal mit einer externen Platte oder Stick ausführen und testen, ob der hohe I/O Wait wirklich der int. Platte zuzuordnen ist.

Karpath · 29 Mai 2012

spoensche schrieb:
Du könntest dir aber mal die smartmontools installieren und die Ausgabe von

Code:

smartctl -x /dev/sda

Code:

smartctl -x /dev/sda
smartctl 5.42 2011-10-20 r3458 [x86_64-linux-3.1.10-1.9-desktop] (SUSE RPM)
Copyright (C) 2002-11 by Bruce Allen, http://smartmontools.sourceforge.net

=== START OF INFORMATION SECTION ===
Model Family:     Western Digital Scorpio Blue Serial ATA
Device Model:     WDC WD2500BEVT-22A23T0
Serial Number:    WD-WXQ1A80M7434
LU WWN Device Id: 5 0014ee 2afbb2a17
Firmware Version: 01.01A01
User Capacity:    250.059.350.016 bytes [250 GB]
Sector Size:      512 bytes logical/physical
Device is:        In smartctl database [for details use: -P show]
ATA Version is:   8
ATA Standard is:  Exact ATA specification draft version not indicated
Local Time is:    Tue May 29 18:23:53 2012 CEST
SMART support is: Available - device has SMART capability.
SMART support is: Enabled

=== START OF READ SMART DATA SECTION ===
SMART overall-health self-assessment test result: PASSED

General SMART Values:
Offline data collection status:  (0x00) Offline data collection activity
                                        was never started.
                                        Auto Offline Data Collection: Disabled.
Self-test execution status:      (   0) The previous self-test routine completed
                                        without error or no self-test has ever 
                                        been run.
Total time to complete Offline 
data collection:                ( 6780) seconds.
Offline data collection
capabilities:                    (0x7b) SMART execute Offline immediate.
                                        Auto Offline data collection on/off support.
                                        Suspend Offline collection upon new
                                        command.
                                        Offline surface scan supported.
                                        Self-test supported.
                                        Conveyance Self-test supported.
                                        Selective Self-test supported.
SMART capabilities:            (0x0003) Saves SMART data before entering
                                        power-saving mode.
                                        Supports SMART auto save timer.
Error logging capability:        (0x01) Error logging supported.
                                        General Purpose Logging supported.
Short self-test routine 
recommended polling time:        (   2) minutes.
Extended self-test routine
recommended polling time:        (  82) minutes.
Conveyance self-test routine
recommended polling time:        (   5) minutes.
SCT capabilities:              (0x7037) SCT Status supported.
                                        SCT Feature Control supported.
                                        SCT Data Table supported.

SMART Attributes Data Structure revision number: 16
Vendor Specific SMART Attributes with Thresholds:
ID# ATTRIBUTE_NAME          FLAGS    VALUE WORST THRESH FAIL RAW_VALUE
  1 Raw_Read_Error_Rate     POSR-K   200   200   051    -    0
  3 Spin_Up_Time            POS--K   154   150   021    -    1300
  4 Start_Stop_Count        -O--CK   092   092   000    -    8294
  5 Reallocated_Sector_Ct   PO--CK   200   200   140    -    0
  7 Seek_Error_Rate         -OSR-K   100   253   000    -    0
  9 Power_On_Hours          -O--CK   099   099   000    -    980
 10 Spin_Retry_Count        -O--CK   100   100   000    -    0
 11 Calibration_Retry_Count -O--CK   100   100   000    -    0
 12 Power_Cycle_Count       -O--CK   100   100   000    -    912
191 G-Sense_Error_Rate      -O--CK   001   001   000    -    214
192 Power-Off_Retract_Count -O--CK   200   200   000    -    43
193 Load_Cycle_Count        -O--CK   158   158   000    -    126502
194 Temperature_Celsius     -O---K   108   088   000    -    35
196 Reallocated_Event_Count -O--CK   200   200   000    -    0
197 Current_Pending_Sector  -O--CK   200   200   000    -    0
198 Offline_Uncorrectable   ----CK   100   253   000    -    0
199 UDMA_CRC_Error_Count    -O--CK   200   200   000    -    0
200 Multi_Zone_Error_Rate   ---R--   100   253   000    -    0
                            ||||||_ K auto-keep
                            |||||__ C event count
                            ||||___ R error rate
                            |||____ S speed/performance
                            ||_____ O updated online
                            |______ P prefailure warning

General Purpose Log Directory Version 1
SMART           Log Directory Version 1 [multi-sector log support]
GP/S  Log at address 0x00 has    1 sectors [Log Directory]
SMART Log at address 0x01 has    1 sectors [Summary SMART error log]
SMART Log at address 0x02 has    5 sectors [Comprehensive SMART error log]
GP    Log at address 0x03 has    6 sectors [Ext. Comprehensive SMART error log]
SMART Log at address 0x06 has    1 sectors [SMART self-test log]
GP    Log at address 0x07 has    1 sectors [Extended self-test log]
SMART Log at address 0x09 has    1 sectors [Selective self-test log]
GP    Log at address 0x10 has    1 sectors [NCQ Command Error log]
GP    Log at address 0x11 has    1 sectors [SATA Phy Event Counters]
GP/S  Log at address 0x80 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x81 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x82 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x83 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x84 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x85 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x86 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x87 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x88 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x89 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x8a has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x8b has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x8c has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x8d has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x8e has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x8f has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x90 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x91 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x92 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x93 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x94 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x95 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x96 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x97 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x98 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x99 has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x9a has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x9b has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x9c has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x9d has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x9e has   16 sectors [Host vendor specific log]
GP/S  Log at address 0x9f has   16 sectors [Host vendor specific log]
GP/S  Log at address 0xa0 has   16 sectors [Device vendor specific log]
GP/S  Log at address 0xa1 has   16 sectors [Device vendor specific log]
GP/S  Log at address 0xa2 has   16 sectors [Device vendor specific log]
GP/S  Log at address 0xa3 has   16 sectors [Device vendor specific log]
GP/S  Log at address 0xa4 has   16 sectors [Device vendor specific log]
GP/S  Log at address 0xa5 has   16 sectors [Device vendor specific log]
GP/S  Log at address 0xa6 has   16 sectors [Device vendor specific log]
GP/S  Log at address 0xa7 has   16 sectors [Device vendor specific log]
GP/S  Log at address 0xa8 has    1 sectors [Device vendor specific log]
GP/S  Log at address 0xa9 has    1 sectors [Device vendor specific log]
GP/S  Log at address 0xaa has    1 sectors [Device vendor specific log]
GP/S  Log at address 0xab has    1 sectors [Device vendor specific log]
GP/S  Log at address 0xac has    1 sectors [Device vendor specific log]
GP/S  Log at address 0xad has    1 sectors [Device vendor specific log]
GP/S  Log at address 0xae has    1 sectors [Device vendor specific log]
GP/S  Log at address 0xaf has    1 sectors [Device vendor specific log]
GP/S  Log at address 0xb0 has    1 sectors [Device vendor specific log]
GP/S  Log at address 0xb1 has    1 sectors [Device vendor specific log]
GP/S  Log at address 0xb2 has    1 sectors [Device vendor specific log]
GP/S  Log at address 0xb3 has    1 sectors [Device vendor specific log]
GP/S  Log at address 0xb4 has    1 sectors [Device vendor specific log]
GP/S  Log at address 0xb5 has    1 sectors [Device vendor specific log]
GP/S  Log at address 0xb6 has    1 sectors [Device vendor specific log]
GP/S  Log at address 0xb7 has    1 sectors [Device vendor specific log]
GP/S  Log at address 0xbd has    1 sectors [Device vendor specific log]
GP/S  Log at address 0xc0 has    1 sectors [Device vendor specific log]
GP    Log at address 0xc1 has   24 sectors [Device vendor specific log]
GP/S  Log at address 0xe0 has    1 sectors [SCT Command/Status]
GP/S  Log at address 0xe1 has    1 sectors [SCT Data Transfer]

SMART Extended Comprehensive Error Log Version: 1 (6 sectors)
No Errors Logged

SMART Extended Self-test Log Version: 1 (1 sectors)
No self-tests have been logged.  [To run self-tests, use: smartctl -t]

SMART Selective self-test log data structure revision number 1
 SPAN  MIN_LBA  MAX_LBA  CURRENT_TEST_STATUS
    1        0        0  Not_testing
    2        0        0  Not_testing
    3        0        0  Not_testing
    4        0        0  Not_testing
    5        0        0  Not_testing
Selective self-test flags (0x0):
  After scanning selected spans, do NOT read-scan remainder of disk.
If Selective self-test is pending on power-up, resume after 0 minute delay.

SCT Status Version:                  3
SCT Version (vendor specific):       258 (0x0102)
SCT Support Level:                   1
Device State:                        Active (0)
Current Temperature:                    35 Celsius
Power Cycle Min/Max Temperature:     25/35 Celsius
Lifetime    Min/Max Temperature:     26/54 Celsius
Under/Over Temperature Limit Count:   0/0
SCT Temperature History Version:     2
Temperature Sampling Period:         1 minute
Temperature Logging Interval:        1 minute
Min/Max recommended Temperature:      0/60 Celsius
Min/Max Temperature Limit:           -41/85 Celsius
Temperature History Size (Index):    128 (12)

Index    Estimated Time   Temperature Celsius
  13    2012-05-29 16:16    32  *************
  14    2012-05-29 16:17    32  *************
  15    2012-05-29 16:18    33  **************
  16    2012-05-29 16:19    33  **************
  17    2012-05-29 16:20    35  ****************
  18    2012-05-29 16:21    35  ****************
  19    2012-05-29 16:22    35  ****************
  20    2012-05-29 16:23    36  *****************
 ...    ..(  3 skipped).    ..  *****************
  24    2012-05-29 16:27    36  *****************
  25    2012-05-29 16:28     ?  -
  26    2012-05-29 16:29    37  ******************
  27    2012-05-29 16:30    37  ******************
  28    2012-05-29 16:31    37  ******************
  29    2012-05-29 16:32    38  *******************
 ...    ..(  2 skipped).    ..  *******************
  32    2012-05-29 16:35    38  *******************
  33    2012-05-29 16:36    39  ********************
  34    2012-05-29 16:37    38  *******************
  35    2012-05-29 16:38     ?  -
  36    2012-05-29 16:39    39  ********************
 ...    ..(  2 skipped).    ..  ********************
  39    2012-05-29 16:42    39  ********************
  40    2012-05-29 16:43    40  *********************
 ...    ..(  5 skipped).    ..  *********************
  46    2012-05-29 16:49    40  *********************
  47    2012-05-29 16:50     ?  -
  48    2012-05-29 16:51    41  **********************
  49    2012-05-29 16:52    40  *********************
 ...    ..(  2 skipped).    ..  *********************
  52    2012-05-29 16:55    40  *********************
  53    2012-05-29 16:56    41  **********************
 ...    ..(  2 skipped).    ..  **********************
  56    2012-05-29 16:59    41  **********************
  57    2012-05-29 17:00     ?  -
  58    2012-05-29 17:01    41  **********************
  59    2012-05-29 17:02    41  **********************
  60    2012-05-29 17:03     ?  -
  61    2012-05-29 17:04    41  **********************
  62    2012-05-29 17:05    41  **********************
  63    2012-05-29 17:06     ?  -
  64    2012-05-29 17:07    41  **********************
  65    2012-05-29 17:08     ?  -
  66    2012-05-29 17:09    41  **********************
  67    2012-05-29 17:10    41  **********************
  68    2012-05-29 17:11     ?  -
  69    2012-05-29 17:12    27  ********
  70    2012-05-29 17:13    27  ********
  71    2012-05-29 17:14    28  *********
  72    2012-05-29 17:15    28  *********
  73    2012-05-29 17:16    29  **********
  74    2012-05-29 17:17     ?  -
  75    2012-05-29 17:18    29  **********
  76    2012-05-29 17:19    30  ***********
  77    2012-05-29 17:20    30  ***********
  78    2012-05-29 17:21    31  ************
  79    2012-05-29 17:22    31  ************
  80    2012-05-29 17:23    31  ************
  81    2012-05-29 17:24     ?  -
  82    2012-05-29 17:25    32  *************
  83    2012-05-29 17:26    32  *************
  84    2012-05-29 17:27    34  ***************
  85    2012-05-29 17:28    33  **************
  86    2012-05-29 17:29    35  ****************
  87    2012-05-29 17:30     ?  -
  88    2012-05-29 17:31    35  ****************
  89    2012-05-29 17:32    35  ****************
  90    2012-05-29 17:33    35  ****************
  91    2012-05-29 17:34     ?  -
  92    2012-05-29 17:35    36  *****************
  93    2012-05-29 17:36    35  ****************
  94    2012-05-29 17:37    36  *****************
 ...    ..(  2 skipped).    ..  *****************
  97    2012-05-29 17:40    36  *****************
  98    2012-05-29 17:41     ?  -
  99    2012-05-29 17:42    37  ******************
 100    2012-05-29 17:43    37  ******************
 101    2012-05-29 17:44    37  ******************
 102    2012-05-29 17:45    38  *******************
 103    2012-05-29 17:46    38  *******************
 104    2012-05-29 17:47     ?  -
 105    2012-05-29 17:48    39  ********************
 106    2012-05-29 17:49    39  ********************
 107    2012-05-29 17:50     ?  -
 108    2012-05-29 17:51    39  ********************
 ...    ..(  3 skipped).    ..  ********************
 112    2012-05-29 17:55    39  ********************
 113    2012-05-29 17:56     ?  -
 114    2012-05-29 17:57    40  *********************
 ...    ..(  6 skipped).    ..  *********************
 121    2012-05-29 18:04    40  *********************
 122    2012-05-29 18:05    41  **********************
 123    2012-05-29 18:06    41  **********************
 124    2012-05-29 18:07     ?  -
 125    2012-05-29 18:08    25  ******
 126    2012-05-29 18:09    26  *******
 127    2012-05-29 18:10    27  ********
   0    2012-05-29 18:11    27  ********
   1    2012-05-29 18:12    28  *********
   2    2012-05-29 18:13    28  *********
   3    2012-05-29 18:14    30  ***********
   4    2012-05-29 18:15    30  ***********
   5    2012-05-29 18:16    31  ************
   6    2012-05-29 18:17    31  ************
   7    2012-05-29 18:18    32  *************
   8    2012-05-29 18:19    33  **************
   9    2012-05-29 18:20    33  **************
  10    2012-05-29 18:21    34  ***************
  11    2012-05-29 18:22    34  ***************
  12    2012-05-29 18:23    35  ****************

Warning: device does not support SCT Error Recovery Control command
SATA Phy Event Counters (GP Log 0x11)
ID      Size     Value  Description
0x0001  2            0  Command failed due to ICRC error
0x0002  2            0  R_ERR response for data FIS
0x0003  2            0  R_ERR response for device-to-host data FIS
0x0004  2            0  R_ERR response for host-to-device data FIS
0x0005  2            0  R_ERR response for non-data FIS
0x0006  2            0  R_ERR response for device-to-host non-data FIS
0x0007  2            0  R_ERR response for host-to-device non-data FIS
0x000a  2            3  Device-to-host register FISes sent due to a COMRESET
0x000b  2            0  CRC errors within host-to-device FIS
0x8000  4          949  Vendor specific

spoensche schrieb:
Wir könnten mal das Verhalten bei unterschiedlichen Blockgrößen übeprüfen, Poste mal bitte die Ausgabe von

Code:

cat /proc/interrupts

Code:

cat /proc/interrupts
           CPU0       CPU1       
  0:      90351      90630   IO-APIC-edge      timer
  1:        401        360   IO-APIC-edge      i8042
  8:          1          0   IO-APIC-edge      rtc0
  9:         20         21   IO-APIC-fasteoi   acpi
 12:       2347       2369   IO-APIC-edge      i8042
 17:          0          0   IO-APIC-fasteoi   uhci_hcd:usb7
 18:          0          0   IO-APIC-fasteoi   uhci_hcd:usb8
 20:         15         16   IO-APIC-fasteoi   ehci_hcd:usb1, uhci_hcd:usb3, uhci_hcd:usb4, uhci_hcd:usb5
 22:          4          3   IO-APIC-fasteoi   yenta, mmc0
 23:      12034      11832   IO-APIC-fasteoi   ehci_hcd:usb2, uhci_hcd:usb6
 43:      14598      14443   PCI-MSI-edge      ahci
 44:       4344       4365   PCI-MSI-edge      i915
 45:        129        109   PCI-MSI-edge      iwlagn
 46:        127        124   PCI-MSI-edge      snd_hda_intel
 47:      17013      17142   PCI-MSI-edge      eth0
NMI:        102         98   Non-maskable interrupts
LOC:     146318     146370   Local timer interrupts
SPU:          0          0   Spurious interrupts
PMI:        102         98   Performance monitoring interrupts
IWI:          0          0   IRQ work interrupts
RES:     198854     199387   Rescheduling interrupts
CAL:       1179       1225   Function call interrupts
TLB:      11610      11050   TLB shootdowns
TRM:          0          0   Thermal event interrupts
THR:          0          0   Threshold APIC interrupts
MCE:          0          0   Machine check exceptions
MCP:          3          3   Machine check polls
ERR:          0
MIS:          0

spoensche schrieb:
Wir können den dd Test mal mit einer externen Platte oder Stick ausführen und testen, ob der hohe I/O Wait wirklich der int. Platte zuzuordnen ist.

Das ganze tritt auf, egal ob ich von USB Platte auf int. Platte kopiere oder umgekehrt oder innerhalb der int. Platte kopiere.
Meist halt bei großen Dateien oder sehr vielen kleinen Dateien

spoensche · 29 Mai 2012

Code:

191 G-Sense_Error_Rate  001   001   000    -    214

Das macht mich sehr stutzig. Kannst du bitte mal die Ausgabe von

Code:

dmesg | egrep -i "ata|err|warn"

posten.

RME · 29 Mai 2012

Hallo,

Was mir nicht gefällt:

Code:

ID# ATTRIBUTE_NAME          FLAGS    VALUE WORST THRESH FAIL RAW_VALUE
193 Load_Cycle_Count        -O--CK   158   158   000    -    126502

In einem früheren Post:

Karpath » 28. Mai 2012 schrieb:
Code:

hdparm -i /dev/sda ... AdvancedPM=yes: unknown setting WriteCache=enabled ...

Warum "unknown setting"?

Bei mir (ich habe ebenfalls WD Platten) haben die Festplatten kein Advanced Power Management (AdvancedPM=no). Da gibts daher nichts einzustellen. Aber Du hast AdvancedPM=yes -- Vielleicht kannst Du das excessive Head-Parking (Load_Cycle_Count = 126502) abstellen mit (als root):

Code:

hdparm -B 254 /dev/sda

(Du kannst den momentanen Wert abfragen mit "hdparm -B /dev/sda")

Gruss,
Roland

Karpath · 29 Mai 2012

spoensche schrieb:
Code:

191 G-Sense_Error_Rate 001 001 000 - 214

Das macht mich sehr stutzig. Kannst du bitte mal die Ausgabe von

Code:

dmesg | egrep -i "ata|err|warn"

posten.

Code:

dmesg | egrep -i "ata|err|warn"
[    0.000000] Command line: root=/dev/disk/by-id/ata-WDC_WD2500BEVT-22A23T0_WD-WXQ1A80M7434-part6 resume=/dev/disk/by-id/ata-WDC_WD2500BEVT-22A23T0_WD-WXQ1A80M7434-part5 splash=silent quiet vga=0x361
[    0.000000]  BIOS-e820: 00000000bbbe2000 - 00000000bbbff000 (ACPI data)
[    0.000000]   NODE_DATA [000000013ffec000 - 000000013fffffff]
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ2 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] Kernel command line: root=/dev/disk/by-id/ata-WDC_WD2500BEVT-22A23T0_WD-WXQ1A80M7434-part6 resume=/dev/disk/by-id/ata-WDC_WD2500BEVT-22A23T0_WD-WXQ1A80M7434-part5 splash=silent quiet vga=0x361
[    0.000000] Memory: 3971032k/5242880k available (5802k kernel code, 1121388k absent, 150460k reserved, 7767k data, 940k init)
[    0.167076] ACPI: Using IOAPIC for interrupt routing
[    0.258516] ACPI: EC: GPE = 0x17, I/O: command/status = 0x66, data = 0x62
[    0.264553] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[    0.264679] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P0P1._PRT]
[    0.264763] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.RP01._PRT]
[    0.264809] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.RP02._PRT]
[    0.264858] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.RP05._PRT]
[    0.272692] ACPI: PCI Interrupt Link [LNKA] (IRQs 10 *11)
[    0.272750] ACPI: PCI Interrupt Link [LNKB] (IRQs 10 *11)
[    0.272805] ACPI: PCI Interrupt Link [LNKC] (IRQs *10 11)
[    0.272860] ACPI: PCI Interrupt Link [LNKD] (IRQs *10 11)
[    0.272915] ACPI: PCI Interrupt Link [LNKE] (IRQs 10 *11)
[    0.272976] ACPI: PCI Interrupt Link [LNKF] (IRQs *10 11)
[    0.273031] ACPI: PCI Interrupt Link [LNKG] (IRQs 10 *11)
[    0.273086] ACPI: PCI Interrupt Link [LNKH] (IRQs *10 11)
[    0.273176] libata version 3.00 loaded.
[    0.774359] ahci 0000:00:1f.2: AHCI 0001.0200 32 slots 4 ports 3 Gbps 0x33 impl SATA mode
[    0.780934] ata1: SATA max UDMA/133 abar m2048@0xf4a04000 port 0xf4a04100 irq 43
[    0.780937] ata2: SATA max UDMA/133 abar m2048@0xf4a04000 port 0xf4a04180 irq 43
[    0.780939] ata3: DUMMY
[    0.780940] ata4: DUMMY
[    0.780942] ata5: SATA max UDMA/133 abar m2048@0xf4a04000 port 0xf4a04300 irq 43
[    0.780945] ata6: SATA max UDMA/133 abar m2048@0xf4a04000 port 0xf4a04380 irq 43
[    0.809778] usbcore: registered new interface driver ums-datafab
[    0.830180] PM: Checking hibernation image partition /dev/disk/by-id/ata-WDC_WD2500BEVT-22A23T0_WD-WXQ1A80M7434-part5
[    1.085022] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[    1.085969] ata1.00: ATA-8: WDC WD2500BEVT-22A23T0, 01.01A01, max UDMA/133
[    1.085972] ata1.00: 488397168 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
[    1.087045] ata1.00: configured for UDMA/133
[    1.087170] scsi 0:0:0:0: Direct-Access     ATA      WDC WD2500BEVT-2 01.0 PQ: 0 ANSI: 5
[    1.392021] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[    1.408519] ata2.00: ATAPI: TSSTcorp CDDVDW TS-L633C, AC01, max UDMA/100
[    1.408522] ata2.00: applying bridge limits
[    1.424301] ata2.00: configured for UDMA/100
[    1.731013] ata5: SATA link down (SStatus 0 SControl 300)
[    2.036019] ata6: SATA link down (SStatus 0 SControl 300)
[    2.049905] Write protecting the kernel read-only data: 12288k
[    3.791467] EXT4-fs (sda6): mounted filesystem with ordered data mode. Opts: acl,user_xattr
[   10.993245] WARNING! power/level is deprecated; use power/control instead
[   12.456776] sdhci: Copyright(c) Pierre Ossman
[   18.351342] ntfs-3g[779]: Warning : using problematic uid==0 and gid!=0
[   18.558823] EXT4-fs (sda7): mounted filesystem with ordered data mode. Opts: acl,user_xattr
[ 1175.795080] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[ 1175.797047] ata6: SATA link down (SStatus 0 SControl 300)
[ 1175.799070] ata5: SATA link down (SStatus 0 SControl 300)
[ 1175.865270] ata2.00: configured for UDMA/100
[ 1178.036046] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 1178.177537] ata1.00: configured for UDMA/133
[ 1659.102050] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[ 1659.104043] ata5: SATA link down (SStatus 0 SControl 300)
[ 1659.106051] ata6: SATA link down (SStatus 0 SControl 300)
[ 1659.167973] ata2.00: configured for UDMA/100
[ 1661.383046] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 1661.497420] ata1.00: configured for UDMA/133

RME schrieb:
Hallo,

Bei mir (ich habe ebenfalls WD Platten) haben die Festplatten kein Advanced Power Management (AdvancedPM=no). Da gibts daher nichts einzustellen. Aber Du hast AdvancedPM=yes -- Vielleicht kannst Du das excessive Head-Parking (Load_Cycle_Count = 126502) abstellen mit (als root):

Code:

hdparm -B 254 /dev/sda

(Du kannst den momentanen Wert abfragen mit "hdparm -B /dev/sda")

Code:

hdparm -B /dev/sda

/dev/sda:
 APM_level      = 96

josef-wien · 29 Mai 2012

Karpath schrieb:
191 G-Sense_Error_Rate -O--CK 001 001 000 - 214

191 G-sense error rate: Fequency of mistakes as a result of impact loads. Das Notebook scheint schon viele harte Stöße erlitten zu haben, bedenklich ist, daß value schon sehr nahe an treshold ist. Einen Zusammenhang mit der CPU-Auslastung sehe ich aber nicht.

Karpath schrieb:
193 Load_Cycle_Count -O--CK 158 158 000 - 126502

193 Load/Unload cycle count: Number of cycles into landing zone position. Das Parken reduziert den Stromverbrauch, laut Spezifikationen ist die Platte für mindestens 600.000 Vorgänge ausgelegt (value ist auch noch weit von treshold entfernt).

Karpath schrieb:
Das ganze tritt auf, egal ob ich von USB Platte auf int. Platte kopiere oder umgekehrt oder innerhalb der int. Platte kopiere.

Wie sieht es aus, wenn Du von USB auf USB kopierst?

RME · 30 Mai 2012

Hallo,

RME » 29. Mai 2012 schrieb:
Was mir nicht gefällt:

Code:

ID# ATTRIBUTE_NAME FLAGS VALUE WORST THRESH FAIL RAW_VALUE 193 Load_Cycle_Count -O--CK 158 158 000 - 126502

josef-wien » 29. Mai 2012 schrieb:
193 Load/Unload cycle count: Number of cycles into landing zone position. Das Parken reduziert den Stromverbrauch, laut Spezifikationen ist die Platte für mindestens 600.000 Vorgänge ausgelegt (value ist auch noch weit von treshold entfernt).

Ja, ich habe vergessen dass es sich hier um ein Notebook handelt. Der Wert (Load_Cycle_Count), auch

Karpath » 29. Mai 2012 schrieb:
Code:

hdparm -B /dev/sda /dev/sda: APM_level = 96

ist daher ganz i.O.

Gruss,
Roland

RME · 30 Mai 2012

Ich habe noch etwas auf meinem Desktop Computer experimentiert.

- processor (Intel Quad Core i5 CPU 760 @ 2.80GHz, LGA 1156, 8MB Cache)
- 4GB memory (2x DDR3 2GB 1333MHz, 1.5V, 240Pin)
- Linux linux-ddia 2.6.37.6-0.11-desktop

Wenn ich ein Verzeichnis der Grösse ~5GB kopiere (disk to disk) dann ist (während dem kopieren):

- das RAM voll (100%) genutzt.
- top: %us ~ 1%, %sy max 3%, %wa bis zu 30%.
- das ausführen anderer Tasks ohne Beeinträchtigung möglich.

Die vom TO geposteten "top" Daten

Karpath » 26. Mai 2012 schrieb:
Code:

Cpu(s): 4.5%us, 2.0%sy, 0.0%ni, 50.4%id, 43.1%wa, 0.0%hi, 0.0%si, 0.0%st Mem: 3984688k total, 3867684k used, 117004k free, 29184k buffers

sind daher nicht alarmierend.

Das Problem muss einen anderen Grund haben.

P.S.

Hier: http://communities.vmware.com/thread/117827 im Beitrag von
Huub v E vom 4. Jan 7, 2008 6:20 AM
ist eine (ich denke) recht gute Beschreibung des wa Parameters.

-/-

Karpath · 30 Mai 2012

Nur welchen Grund?
Warum zeigt denn der Systemmonitor eine Auslastung an? Irgendwoher müssen diese Werte ja kommen

RME · 30 Mai 2012

Kannst Du bitte die Ausgabe posten von:

Code:

hdparm -I /dev/sda

Ich möchte die Daten mit denen meiner WD Platte vergleichen.

josef-wien · 30 Mai 2012

Karpath schrieb:
Warum zeigt denn der Systemmonitor eine Auslastung an?

Im Zweifel traue ich derartigen Programmen nicht über den Weg. Die grafische Anzeige ist zwar nett anzusehen, aber ich habe es schon erlebt, daß die eigene Aktivität Werte in die Höhe geschraubt hat.

Beobachte beim nächsten Kopiervorgang:

Karpath schrieb:
25144 karpath 20 0 297m 9916 4316 D 1 0.2 0:05.90 kio_file

Kopieren führt zu hoher CPU Auslastung

Newbie

Moderator

Advanced Hacker

Newbie

Newbie

Advanced Hacker

Advanced Hacker

Moderator

Newbie

Moderator

Newbie

Moderator

Advanced Hacker

Newbie

Ultimate Guru

Advanced Hacker

Advanced Hacker

Newbie

Advanced Hacker

Ultimate Guru