LinuxQuestions.org

LinuxQuestions.org (/questions/)
-   Slackware (https://www.linuxquestions.org/questions/slackware-14/)
-   -   Ripping Audio CDs slows down the system seriously (https://www.linuxquestions.org/questions/slackware-14/ripping-audio-cds-slows-down-the-system-seriously-703009/)

w1k0 02-07-2009 04:46 PM

Ripping Audio CDs slows down the system seriously
 
During ripping Audio CDs with cdparanoia my system slows down painfully: Window Maker refreshes application's windows slowly, the content of the windows scrolls with resistance, mouse cursor moves stepwise etc. The problem appears in console mode as well. In Window Maker the command top shows that X uses up to 16% of CPU and cdparanoia up to 2%. In the console mode Midnight Commander uses up to 5% of CPU. I tested CD-ROM with hdparm -tT command. The results were about 1200 MB/sec of cached reads and 1.70 MB/sec of buffered disk reads. All these parameters seem to look reasonably so I don't understand why ripping affects the system in such way.

The above problem concerns my new machine with Intel Core Duo 1.83 GHz CPU and 1042 MB RAM. Surprisingly the system works much more smoother when running cdparanoia on my old machine with Intel Pentium M 1.5 GHz CPU and 512 MB RAM. The command top shows in that case that X uses up to 9% of CPU and cdparanoia up to 3.5%. In the console mode Midnight Commander uses up to 7.5% of CPU. In that case hdparm -tT command shows about 380 MB/sec of cached reads and 2.15 MB/sec of buffered disk reads.

I switched CD-ROMs between these two machines and result was the same: new machine slows down significantly during ripping -- old machine works during ripping almost smoothly.

On both machines I use Slackware 12.2 with generic 2.6.27.7-smp kernel. The system configuration is similar in both cases because both these machines are ThinkPads -- new one it's T60 and old one it's T40.

The above problem concerns only ripping Audio CDs with cdparanoia. During ripping DVDs with mencoder the system works smoothly on both mentioned machines.

I have no idea what's wrong with my new machine. Any help will be welcomed.

cheftec 02-07-2009 05:09 PM

I suppose I don't know the answer, but I'm willing to try to figure it out with you. First thing i'd do is to try a different cd-burning application and see what the differences are on the system level. So do

strace -T cdparanoia &

Then

strace -T OTHERCDBURN &

and post what strace gives you, see if there is any significant differences. The T option will show the time spent in a system call, so if there is a large amount of time spent there, we can use that too.

w1k0 02-08-2009 09:41 AM

First I tested two commands:

strace -T cdparanoia -d /dev/cdrom -B 1

strace -T cdda2wav -D /dev/cdrom -x -paranoia -t 1

I suppose the latter is the equivalent of the former.

When I used cdparanoia it slowed down the first machine. When I used cdda2wav it worked smoothly on both machines.

I ripped first track from ``Dark Side of the Moon'' by Pink Floyd (``Speak to Me/Breathe''). That song lasts 3'57". For comparison I ripped that track on both machines with both mentioned programs. The output of strace -T is huge. Four outputs have together 1438216 bytes of size. It's impossible to put such big output in the message.

Then I tested two other commands:

strace -T cdparanoia -d /dev/cdrom -B 1 &

strace -T cdda2wav -D /dev/cdrom -x -paranoia -t 1 &

These two are exactly what you suggested in your message. In both cases strace displayed a lot of information but nor cdparanoia neither cdda2wav didn't read the Audio CD so I'm not sure these tests show valuable information.

New machine

strace -T cdparanoia -d /dev/cdrom -B 1 &
Code:

write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000015>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000025>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000020>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---

strace -T cdda2wav -D /dev/cdrom -x -paranoia -t 1 &
Code:

write(2, "Type: ROM, Vendor \'HL-DT-ST\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'HL-DT-ST\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'HL-DT-ST\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'HL-DT-ST\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'HL-DT-ST\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'HL-DT-ST\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'HL-DT-ST\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000020>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'HL-DT-ST\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'HL-DT-ST\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'HL-DT-ST\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'HL-DT-ST\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000028>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'HL-DT-ST\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'HL-DT-ST\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000016>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'HL-DT-ST\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000015>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---

Old machine

strace -T cdparanoia -d /dev/cdrom -B 1 &
Code:

write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000010>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000009>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000010>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000009>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000008>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000009>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000008>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000009>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000009>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000008>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000008>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000009>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000008>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "cdparanoia III release 10.2 (Sep"..., 49) = ? ERESTARTSYS (To be restarted) <0.000009>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---

strace -T cdda2wav -D /dev/cdrom -x -paranoia -t 1 &
Code:

write(2, "Type: ROM, Vendor \'MATSHITA\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000009>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'MATSHITA\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000009>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'MATSHITA\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000009>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'MATSHITA\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000008>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'MATSHITA\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000008>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'MATSHITA\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000009>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'MATSHITA\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000009>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'MATSHITA\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000009>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'MATSHITA\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000009>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'MATSHITA\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000009>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'MATSHITA\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000009>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'MATSHITA\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000008>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'MATSHITA\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000008>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
write(2, "Type: ROM, Vendor \'MATSHITA\' Mod"..., 70) = ? ERESTARTSYS (To be restarted) <0.000008>
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---
--- SIGTTOU (Stopped (tty output)) @ 0 (0) ---


cheftec 02-08-2009 01:38 PM

Hm. Well, when you ran strace, did you notice it hanging at a certain time? cdparanoia is trying to access a resource that is being used frequently. Is cda2wav gui based? can you run cdparanoia without the gui? If you can, I would try that, to see if it is a specific aspect of the program, i.e., video. Have you checked cdparanoia's page, see if there are any reported bugs? I'll check it later on today too.

campher 02-08-2009 03:08 PM

t 60
 
im using a t60, slackware 12.1 and the standard 2.6 kernel recompiled to throw out not used things.
at the moment i m using cdparanoia a lot because i have buyed some cds and didnt recognize in time that the output of lame is a terroristic attack to your ears (i was used to bladeenc, which produced hearable output, but is no longer developed)
here cdparanoia is working fine without any problem.

i know this is not really helpful but on some t60 machines cdparanoia is working normal. so it seems not to be a t60 special problem.

did you try another cdrom drive in your machine maybe your new one is in some way broken, or use another cd i had problems with a 3 year old cdrom with music.

campher

w1k0 02-08-2009 05:31 PM

cheftec:

When I ran strace with cdparanoia as well as with cdda2wav it displayed a lot of messages similar to these quoted in my previous post. It displayed them very fast so it's difficult to say if it did it smoothly but it seems to me that it worked without pauses in all cases.

Both cdparanoia and cdda2wav are regular Linux text based commands. I ran all these commands directly without any GUI.

I looked at http://www.xiph.org/paranoia/. I didn't find any bug report related to my problem but I found an interesting option: ``10.2 also includes a cache analysis option (-A) to do a slow an thorough offline check of the drive's cache behavior. The feature also dumps a detailed log to assist in debugging should either the test or cdparanoia's ripping go awry in any way. After all... better thoroughly safe than sorry''. I write about these tests below...

campher:

CD-ROM drive in my new machine is indeed invalid. It causes random problems when writing data, it can't read some DVDs, it works loudly, etc. I wrote about some of these problems in that thread: http://www.linuxquestions.org/questi...s-dead-691950/. I intend to call to service guys and ask them to replace my invalid combo drive.

However the problem described in the present thread seems to be connected with machine and not with CD-ROM drive. As I mentioned in the first message I swapped CD-ROM drives between both these machines and the result was the same: cdparanoia affected the work of the system on the new machine and worked smoothly on the old one.

I tested a lot of Audio CDs before I started that thread. My problem isn't related to some particular Audio CD. It occurs when I use my new machine.

Option -A

I checked both CD-ROM drives with cdparanoia -d /dev/cdrom -A command...

cdparanoia -d /dev/cdrom -A # new machine
Code:

cdparanoia III release 10.2 (September 11, 2008)

Using cdda library version: 10.2
Using paranoia library version: 10.2
Checking /dev/cdrom for cdrom...
        Testing /dev/cdrom for SCSI/MMC interface
                SG_IO device: /dev/hda

CDROM model sensed sensed: HL-DT-ST DVDRAM GSA-4083N 1.08

Checking for SCSI emulation...
        Drive is ATAPI (using SG_IO host adaptor emulation)

Checking for MMC style command set...
        Drive is MMC style
        DMA scatter/gather table entries: 1
        table entry size: 131072 bytes
        maximum theoretical transfer: 55 sectors
        Setting default read size to 27 sectors (63504 bytes).

Verifying CDDA command set...
        Expected command set reads OK.

Attempting to set cdrom to full speed...
        drive returned OK.

=================== Checking drive cache/timing behavior ===================

Seek/read timing:
        [42:41.27]:  49ms seek, 1.60ms/sec read [8.3x]               
        [40:00.33]:  50ms seek, 1.61ms/sec read [8.3x]               
        [30:00.33]:  57ms seek, 1.61ms/sec read [8.3x]               
        [20:00.33]:  54ms seek, 1.60ms/sec read [8.3x]               
        [10:00.33]:  61ms seek, 1.60ms/sec read [8.3x]               
        [00:00.33]:  74ms seek, 1.61ms/sec read [8.3x]               

Analyzing cache behavior...
        Approximate random access cache size: 16 sector(s)             
        Drive cache tests as contiguous                         
        Drive readahead past read cursor: 234 sector(s)               
        Cache tail cursor tied to read cursor                     
        Cache tail granularity: 1 sector(s)                     
                Cache read speed: 0.19ms/sector [70x]
                Access speed after backseek: 2.14ms/sector [6x]
        Backseek flushes the cache as expected

Drive tests OK with Paranoia.

cdparanoia -d /dev/cdrom -A # old machine
Code:

cdparanoia III release 10.2 (September 11, 2008)

Using cdda library version: 10.2
Using paranoia library version: 10.2
Checking /dev/cdrom for cdrom...
        Testing /dev/cdrom for SCSI/MMC interface
                SG_IO device: /dev/hdc

CDROM model sensed sensed: MATSHITA UJDA755zDVD/CDRW 1.20

Checking for SCSI emulation...
        Drive is ATAPI (using SG_IO host adaptor emulation)

Checking for MMC style command set...
        Drive is MMC style
        DMA scatter/gather table entries: 1
        table entry size: 131072 bytes
        maximum theoretical transfer: 55 sectors
        Setting default read size to 27 sectors (63504 bytes).

Verifying CDDA command set...
        Expected command set reads OK.

Attempting to set cdrom to full speed...
        drive returned OK.

=================== Checking drive cache/timing behavior ===================

Seek/read timing:
        [42:41.27]:  39ms seek, 0.80ms/sec read [16.7x]               
        [40:00.33]:  39ms seek, 0.80ms/sec read [16.7x]               
        [30:00.33]:  33ms seek, 0.90ms/sec read [14.9x]               
        [20:00.33]:  46ms seek, 1.01ms/sec read [13.2x]               
        [10:00.33]:  38ms seek, 1.20ms/sec read [11.1x]               
        [00:00.33]:  51ms seek, 1.58ms/sec read [8.5x]               

Analyzing cache behavior...
        Approximate random access cache size: 4 sector(s)             
        Drive cache tests as contiguous                         
        Drive readahead past read cursor: 683 sector(s)               
        Cache tail rollbehind: 3 sector(s)                       
        Cache tail granularity: 1 sector(s)                     
        Cache size (considering rollbehind) too small to test cache speed.

Drive tests OK with Paranoia.

According to these tests the drive in my new machine works slower in some cases then in the old one however I don't know how to interpret these results.

ErV 02-08-2009 06:00 PM

You could try to run "hdparm /dev/cdrom" and "hdparm -i /dev/cdrom" on old and new machine and compare outputs. Also you could run same command on harddrive and compare outputs again.
Also you should probably check dmesg for suspicious messages (they might appear when you are ripping
CDs). Also try to run ksysguard with default "graph" indicators - it will show cpu load in realtime, maybe (not sure about this, might be wrong) kernel wastes cpu time, not userspace program.

guanx 02-08-2009 07:40 PM

Quote:

Originally Posted by ErV (Post 3436557)
CDs). Also try to run ksysguard with default "graph" indicators - it will show cpu load in realtime, maybe (not sure about this, might be wrong) kernel wastes cpu time, not userspace program.

Or the command "vmstat 2", maybe.

w1k0 02-09-2009 09:49 AM

New machine

ThinkPad T60, Intel Core Duo 1.83 MHz CPU, 1024 MB RAM, 160 GB SATA HDD

hdparm tests:
Code:

root@home6:~# hdparm /dev/cdrom

/dev/cdrom:
 IO_support    =  0 (default)
 unmaskirq    =  0 (off)
 using_dma    =  1 (on)
 keepsettings  =  0 (off)
 readonly      =  0 (off)
 readahead    = 256 (on)
 HDIO_GETGEO failed: Inappropriate ioctl for device

root@home6:~# hdparm -i /dev/cdrom

/dev/cdrom:

 Model=HL-DT-ST DVDRAM GSA-4083N, FwRev=1.08, SerialNo=CAF8E68CFBC5
 Config={ Fixed Removeable DTR<=5Mbs DTR>10Mbs nonMagnetic }
 RawCHS=0/0/0, TrkSize=0, SectSize=0, ECCbytes=0
 BuffType=unknown, BuffSize=0kB, MaxMultSect=0
 (maybe): CurCHS=0/0/0, CurSects=0, LBA=yes, LBAsects=0
 IORDY=on/off, tPIO={min:120,w/IORDY:120}, tDMA={min:120,rec:120}
 PIO modes:  pio0 pio1 pio2 pio3 pio4
 DMA modes:  mdma0 mdma1 mdma2
 UDMA modes: udma0 udma1 *udma2
 AdvancedPM=no
 Drive conforms to: ATA/ATAPI-5 T13 1321D revision 3:  ATA/ATAPI-2,3,4,5

 * signifies the current active mode

root@home6:~# hdparm /dev/sda 

/dev/sda:
 IO_support    =  0 (default)
 readonly      =  0 (off)
 readahead    = 256 (on)
 geometry      = 19457/255/63, sectors = 312581808, start = 0

root@home6:~# hdparm -i /dev/sda

/dev/sda:

 Model=WDC WD1600BEVS-00VAT0                  , FwRev=11.01A11, SerialNo=    WD-WXE508CC2801
 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=?16?
 CurCHS=16383/16/63, CurSects=16514064, LBA=yes, LBAsects=312581808
 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

root@home6:~$ hdparm -tT /dev/sda

/dev/sda:
 Timing cached reads:  1862 MB in  2.00 seconds = 931.94 MB/sec
 Timing buffered disk reads:  176 MB in  3.03 seconds =  58.16 MB/sec

vmstat tests (before, during, and after running cdparanoia and lame):
Code:

user@home6:~$ vmstat 2
procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 0  0      0 555776  51052 238092    0    0    36    12  301  819  3  1 94  1
 0  0      0 555768  51052 238108    0    0    0    18  609 1945  0  0 99  0
 0  0      0 555768  51052 238108    0    0    0    0  612 1945  0  0 100  0
 1  0      0 555768  51052 238108    0    0    0    8  611 1885  0  0 100  0
 1  0      0 555768  51052 238108    0    0    0    0  612 1917  0  1 99  0
 2  0      0 555768  51052 238108    0    0    0    0  610 1847  0  1 100  0
 1  0      0 555768  51052 238108    0    0    0    8  617 1857  0  0 99  0
 1  0      0 555768  51052 238108    0    0    0    0  605 1863  0  1 100  0
 1  0      0 555908  51052 238108    0    0    0    8  599 1895  0  0 100  0
 0  0      0 555908  51052 238108    0    0    0    0  611 1965  0  0 100  0
 0  0      0 555908  51052 238108    0    0    0    0  614 1919  0  0 100  0
 0  0      0 555908  51052 238108    0    0    0    10  622 1967  0  1 100  0
 2  0      0 555908  51052 238108    0    0    0    0  619 1908  0  1 99  0
 2  0      0 555908  51052 238108    0    0    0    88  634 1930  0  0 100  0
 0  0      0 555916  51052 238108    0    0    0    0  615 1924  0  0 100  0
 3  0      0 555916  51052 238108    0    0    0    0  603 1958  0  0 99  0
 2  0      0 555916  51052 238108    0    0    0    20  609 1982  0  0 100  0
 0  0      0 555916  51052 238108    0    0    0    0  605 1991  0  0 100  0
 0  0      0 555916  51052 238108    0    0    0    8  600 2020  0  0 100  0
 0  0      0 555916  51052 238108    0    0    0    0  603 2020  0  0 99  0
 0  0      0 555916  51052 238108    0    0    0    0  603 2048  0  0 100  0
 0  0      0 555916  51052 238108    0    0    0    8  614 2025  0  0 99  0
 0  0      0 555916  51052 238108    0    0    0    0  620 2188  2  0 98  0
 0  0      0 555916  51052 238108    0    0    0    8  604 1961  0  0 100  0
 0  0      0 555916  51052 238108    0    0    0    0  604 1902  0  0 99  0
 2  0      0 555916  51052 238108    0    0    0    0  604 1909  0  0 100  0
 1  0      0 555712  51052 238108    0    0    0    8  661 2128  1  2 97  0
 3  0      0 557536  51056 234804    0    0    0    0  604 1823  2  6 92  0
10  0      0 553680  51056 234764    0    0    0    0  291 1068  5 37 59  0

procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 1  0      0 547152  51056 234864    0    0    0    0  287  674 37 16 47  0
 4  0      0 546772  51092 235104    0    0    0  228  598 1572 47  1 52  0
 1  0      0 542540  51092 235124    0    0    0    0  231  665  5 34 61  0
 3  0      0 538460  51092 235124    0    0    0    0  209  567 15 37 48  0
 1  0      0 538092  51092 235420    0    0    0    0  659 1721 50  1 50  0
 8  0      0 544156  51092 235464    0    0    0    0  322  813 33 14 53  0
 8  0      0 542728  51116 235440    0    0    0  292  244  713  2 29 53 15
 1  0      0 537904  51116 235648    0    0    0    0  484 1411 47  3 50  0
 7  0      0 544852  51116 235884    0    0    0    0  475 1413 43  5 52  0
 8  0      0 543288  51116 235852    0    0    0    0  235  679 10 32 58  0
 2  0      0 537360  51116 235924    0    0    0    0  337  950 42  9 49  0
 2  0      0 544796  51140 236216    0    0    0  318  617 1671 47  2 52  0
 1  0      0 543300  51140 236216    0    0    0    0  218  598 12 34 55  0
 7  0      0 540920  51140 236216    0    0    0    0  216  571  5 35 60  0
 1  0      0 536728  51140 236484    0    0    0    0  610 1646 50  1 50  0
 1  0      0 542656  51140 236540    0    0    0    0  307  787 31 14 56  0
 8  0      0 541160  51164 236612    0    0    0  304  241  677 10 32 47 10
 2  0      0 536356  51164 236816    0    0    0    0  506 1317 48  3 49  0
 7  0      0 543052  51164 236976    0    0    0    0  436 1145 40  7 53  0
10  0      0 541692  51164 236976    0    0    0    0  258  668 11 32 58  0
 3  0      0 536108  51164 237144    0    0    0    0  449 1131 44  7 49  0
11  0      0 543556  51188 237340    0    0    0  316  605 1590 45  4 51  0

procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 7  0      0 542060  51188 237440    0    0    0    0  248  658 12 34 55  0
 0  0      0 556528  51188 237360    0    0    0    0  437 1217  3  5 92  0
 1  0      0 556652  51188 237360    0    0    0    0  622 1646  0  1 100  0
 3  0      0 556652  51212 237360    0    0    0  154  593 1616  0  0 99  0
 0  0      0 556652  51212 237360    0    0    0    0  597 1611  0  1 100  0
 0  0      0 556652  51212 237360    0    0    0    84  614 1660  1  0 99  1
 0  0      0 556644  51212 237360    0    0    0    0  592 1612  0  1 99  0
 0  0      0 556652  51212 237360    0    0    0    0  597 1622  0  1 99  0
 0  0      0 556652  51260 237360    0    0    0    38  613 1594  0  0 100  0
 0  0      0 556652  51260 237360    0    0    0    0  595 1605  0  0 99  0
 1  0      0 556652  51260 237360    0    0    0  104  619 1636  0  1 100  0
 0  0      0 556652  51260 237360    0    0    0    0  603 1623  0  0 99  0
 0  0      0 556652  51260 237360    0    0    0    0  612 1626  0  0 100  0
 0  0      0 556652  51260 237360    0    0    0    8  624 1617  0  0 100  0
 0  0      0 556652  51260 237360    0    0    0    0  614 1666  0  0 100  0
 0  0      0 556652  51260 237360    0    0    0    8  621 1634  0  0 100  0
 0  0      0 556652  51260 237360    0    0    0    0  620 1653  0  1 99  0
 0  0      0 556652  51260 237360    0    0    0    0  620 1626  0  0 100  0
 0  0      0 556652  51260 237360    0    0    0    8  621 1622  0  0 100  0
 1  0      0 556652  51260 237360    0    0    0    0  639 1681  0  0 100  0
^C

Old machine

ThinkPad T40, Intel Pentium M 1.50 MHz CPU, 512 MB RAM, 80 GB IDE HDD

hdparm tests:
Code:

root@home4:~$ hdparm /dev/cdrom

/dev/cdrom:
 IO_support    =  0 (default)
 unmaskirq    =  0 (off)
 using_dma    =  1 (on)
 keepsettings  =  0 (off)
 readonly      =  0 (off)
 readahead    = 256 (on)
 HDIO_GETGEO failed: Inappropriate ioctl for device

root@home4:~$ hdparm -i /dev/cdrom

/dev/cdrom:

 Model=UJDA755zDVD/CDRW, FwRev=1.20, SerialNo=
 Config={ Fixed Removeable DTR<=5Mbs DTR>10Mbs nonMagnetic }
 RawCHS=0/0/0, TrkSize=0, SectSize=0, ECCbytes=0
 BuffType=unknown, BuffSize=512kB, MaxMultSect=0
 (maybe): CurCHS=0/0/0, CurSects=0, LBA=yes, LBAsects=0
 IORDY=yes, tPIO={min:180,w/IORDY:120}, tDMA={min:120,rec:120}
 PIO modes:  pio0 pio1 pio2 pio3 pio4
 DMA modes:  sdma0 sdma1 sdma2 mdma0 mdma1 mdma2
 UDMA modes: udma0 udma1 *udma2
 AdvancedPM=no
 Drive conforms to: ATA/ATAPI-5 T13 1321D revision 3:  ATA/ATAPI-1,2,3,4,5

 * signifies the current active mode

root@home4:~$ hdparm /dev/hda 

/dev/hda:
 multcount    =  0 (off)
 IO_support    =  0 (default)
 unmaskirq    =  0 (off)
 using_dma    =  1 (on)
 keepsettings  =  0 (off)
 readonly      =  0 (off)
 readahead    = 256 (on)
 geometry      = 65535/16/63, sectors = 156301488, start = 0

root@home4:~$ hdparm -i /dev/hda

/dev/hda:

 Model=TOSHIBA MK8026GAX, FwRev=PA001G, SerialNo=65TN3767T
 Config={ Fixed }
 RawCHS=16383/16/63, TrkSize=0, SectSize=0, ECCbytes=48
 BuffType=unknown, BuffSize=0kB, MaxMultSect=16, MultSect=off
 CurCHS=16383/16/63, CurSects=16514064, LBA=yes, LBAsects=156301488
 IORDY=on/off, tPIO={min:120,w/IORDY:120}, tDMA={min:120,rec:120}
 PIO modes:  pio0 pio1 pio2 pio3 pio4
 DMA modes:  sdma0 sdma1 sdma2 mdma0 mdma1 mdma2
 UDMA modes: udma0 udma1 udma2 udma3 udma4 *udma5
 AdvancedPM=yes: unknown setting WriteCache=enabled
 Drive conforms to: Unspecified:  ATA/ATAPI-1,2,3,4,5,6

 * signifies the current active mode

root@home4:~$ hdparm -tT /dev/hda

/dev/hda:
 Timing cached reads:  690 MB in  2.00 seconds = 344.76 MB/sec
 Timing buffered disk reads:  92 MB in  3.18 seconds =  28.96 MB/sec

vmstat tests (before, during, and after running cdparanoia and lame):
Code:

user@home4:~$ vmstat 2
procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 3  0      0  45224  78636 223488    0    0  146    56  324 1390  9  3 83  5
 0  0      0  45216  78672 223488    0    0    0    18  296 1163  0  0 100  0
 0  0      0  45216  78672 223488    0    0    0    0  294 1172  0  0 100  0
 5  0      0  60548  78688 223472    0    0    0  142  321 1316  0  0 100  0
 2  0      0  60376  78688 223488    0    0    0    0  294 1221  1  0 100  0
 0  0      0  60408  78688 223516    0    0    8    0  295 1244  1  3 93  3
 0  0      0  60408  78812 223516    0    0    0    64  296 1279  0  0 100  0
 0  0      0  60664  78812 223516    0    0    0    0  294 1490  0  0 100  0
 0  0      0  60664  78892 223512    0    0    0    44  296 1374  0  0 100  0
 0  0      0  60664  78892 223512    0    0    0    0  294 1343  0  0 100  0
 1  0      0  60656  78892 223516    0    0    0    0  294 1393  0  1 100  0
 0  0      0  60532  79028 223516    0    0    0    70  296 1379  0  0 97  3
 4  0      0  60532  79028 223516    0    0    0    0  298 1630 13  2 86  0
 1  0      0  60532  79044 223500    0    0    0    8  295 1599  0  0 100  0
 3  0      0  60532  79044 223516    0    0    0    0  294 1360  0  0 100  0
 5  0      0  60532  79044 223516    0    0    0    0  284 1172  0  0 100  0
 1  0      0  60532  79060 223516    0    0    0    8  295 1160  0  0 100  0
 6  0      0  60284  79060 223516    0    0    0    0  294 1161  2  0 98  0
 0  0      0  60284  79076 223500    0    0    0    8  295 1207  0  0 100  0
 3  0      0  60284  79076 223516    0    0    0    0  294 1390  0  0 100  0
 2  0      0  60284  79076 223516    0    0    0    0  298 1486  1  1 99  0
 0  0      0  59772  79276 223520    0    0    0  118  324 1452  1  0 96  3
 2  0      0  60276  79276 221320    0    0    0    0  320 1470  1  1 99  0
 9  0      0  59020  79444 221296    0    0    0    2  295 1329  2 10 87  0
 9  0      0  54828  79468 221304    0    0    0  242  322 1119  1 24 72  2

procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 1  0      0  48988  79468 221528    0    0    0    0  292 1360 63 11 26  0
 7  0      0  47012  79468 221664    0    0    0    0  289 1258 43 14 43  0
 1  0      0  42672  79540 221664    0    0    0  230  293  933  4 25 68  4
 4  0      0  40184  79540 221924    0    0    0    0  290 1539 77  7 16  0
 7  0      0  46380  79540 222028    0    0    0    0  290 1204 35 17 48  0
 9  0      0  44904  79600 222028    0    0    0  226  294  973  1 25 72  1
 2  0      0  39800  79600 222292    0    0    0    0  293 1650 82  6 11  0
 8  0      0  45652  79600 222396    0    0    0    0  290 1188 32 17 52  0
 9  0      0  43896  79760 222408    0    0    0  270  296 1105  8 26 64  3
 1  0      0  39180  79760 222724    0    0    0    0  297 1625 86  5  9  0
 0  0      0  44764  79760 222772    0    0    0    0  294 1280 20 22 58  0
 8  0      0  42912  79948 222780    0    0    0  290  290  923  9 26 62  3
 0  0      0  46260  79948 223144    0    0    0    0  296 1833 93  3  5  0
 0  0      0  44776  79948 223148    0    0    0    0  288  916  5 24 71  0
 3  0      0  38460  79988 223196    0    0    0  208  289  977 15 24 61  0
 0  0      0  45888  79988 223528    0    0    0    0  294 1753 86  4 10  0
 1  0      0  44276  79988 223528    0    0    0    0  278  888  6 24 70  0
 1  0      0  37952  79988 223608    0    0    0    0  285 1067 36 19 45  0
 9  0      0  44888  80012 223880    0    0    0  274  299 1446 74  6 20  0
 0  0      0  43276  80012 223896    0    0    0    0  288  966  5 26 69  0
 3  0      0  37456  80012 224080    0    0    0    0  291 1327 56 14 30  0
 1  0      0  43768  80056 224272    0    0    0  392  317 1499 57 11 31  0
 0  0      0  42176  80056 224280    0    0    0    0  290  918  6 27 68  0
 6  0      0  36948  80056 224512    0    0    0    0  296 1641 73  9 18  0
 6  0      0  42788  80080 224644    0    0    0  264  310 1318 38 17 44  0

procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 1  0      0  57936  80080 224644    0    0    0    0  300 1270  3  6 92  0
 0  1      0  57936  80136 224624    0    0    0    76  297 1225  1  0 99  1
 0  0      0  58048  80144 224640    0    0    0    34  297 1320  0  0 98  2
 1  0      0  58068  80144 224640    0    0    0    0  294 1385  0  0 100  0
 0  0      0  58068  80144 224640    0    0    0    30  296 1420  0  0 100  0
 2  0      0  58060  80144 224644    0    0    0    0  294 1382  0  0 100  0
 0  0      0  58060  80144 224644    0    0    0    0  294 1345  0  0 100  0
 0  0      0  58060  80144 224644    0    0    0    70  296 1408  0  0 97  3
 3  0      0  58060  80144 224644    0    0    0    0  294 1460  0  0 100  0
 0  0      0  58060  80144 224644    0    0    0    8  295 1449  0  0 100  0
 6  0      0  58060  80144 224644    0    0    0    0  284 1360  0  0 100  0
 0  0      0  58060  80144 224644    0    0    0    0  302 1439  0  0 100  0
 3  0      0  58060  80144 224644    0    0    0    18  328 1529  0  0 100  0
 0  0      0  58060  80144 224644    0    0    0    0  324 1344  0  0 100  0
^C

Summary

There are some differences in hdparm tests. New machine's CD-ROM has 0kB of BuffSize versus 512kB in the case of old machine's CD-ROM. New machine's HDD has 8192kB of BuffSize versus 0kB in the case of old machine's HDD. Because in the case of new machine's SATA HDD hdparm doesn't show that DMA is enabled, and I'm pretty sure it is, I did hdparm -tT tests to check the performances of both HDDs.

I divided each vmstat test into three parts: before, during, and after running cdparanoia and lame. The more dramatical changes I observed during ripping and encoding in three cases:

* io bo in the case of old machine jumps from 45-120 to 210-290 and in the case of new machine it jumps from 10-90 to 230-320.

* system in in the case of old machine jumps from 290-320 to 280-320 and in the case of new machine it jumps from 600-660 to 210-660.

* system cs in the case of old machine jumps from 1150-1600 to 950-1850 and in the case of new machine it jumps from 1850-2200 to 570-1720.

Of course there are also big changes in the case of cpu us/sy/id tests but these parameters seem to achieve reasonable values. In the case of old machine cpu wa test rests on 0-4 level while in the case of new machine it jumps from time to time up to 15.

I inspected dmesg during ripping Audio CD but it showed nothing interesting.

I don't know how I can interpret these results.

w1k0 02-09-2009 11:52 AM

I just realized that in the above tests I ran simultaneously cdparanoia and lame in Window Maker. So I repeated these tests in the console mode running cdparanoia only.

new machine (before, during, and after ripping)
Code:

user@home6:~$ vmstat 2

procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 2  0      0 654264  56068 255792    0    0    17    8  297  856  1  1 98  0
 0  0      0 654256  56068 255792    0    0    0    14  507  40  0  0 100  0
 0  0      0 654256  56068 255792    0    0    0    0  505  30  0  0 100  0
 0  0      0 654256  56068 255792    0    0    0    0  505  34  0  0 100  0
 0  0      0 654256  56068 255792    0    0    0    0  505  30  0  0 100  0
 0  0      0 654256  56068 255792    0    0    0    0  505  34  0  0 100  0
 0  0      0 654256  56068 255792    0    0    0    0  505  31  0  0 100  0
 0  0      0 654256  56068 255792    0    0    0    0  505  34  0  0 100  0
 0  0      0 654256  56068 255792    0    0    0    0  505  35  0  0 100  0
 0  0      0 654256  56068 255792    0    0    0    0  505  30  0  0 100  0
 0  0      0 654256  56068 255792    0    0    0    0  505  36  0  0 100  0
 0  0      0 654256  56068 255792    0    0    0    0  505  30  0  0 100  0
 0  0      0 654256  56068 255792    0    0    0    0  505  30  0  0 100  0
 0  0      0 654256  56068 255792    0    0    0    60  519  34  0  0 100  0
 0  0      0 654256  56068 255792    0    0    0    0  505  29  0  0 100  0

procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 0  0      0 653868  56068 255792    0    0    0    0  542  129  0  2 97  0
 0  0      0 669848  56068 239492    0    0    0    86  479  58  0  4 96  0
 0  0      0 665936  56068 239504    0    0    0    0  203  69  1 30 69  0
 0  0      0 656348  56068 242104    0    0    0    0  174  68  7 23 69  0
 0  0      0 652264  56068 242160    0    0    0    0  153  73  2 31 67  0
 0  0      0 653216  56068 244860    0    0    0    0  160  78  6 29 65  0
 0  0      0 652636  56068 244848    0    0    0    0  364  44  0  5 95  0
 0  0      0 651424  56112 244804    0    0    0  2696  173  89  1 31 63  5
 0  0      0 650336  56112 247504    0    0    0    0  174  68  5 26 69  0
 0  0      0 648844  56112 247536    0    0    0    0  163  70  1 30 69  0
 0  0      0 647688  56116 250232    0    0    0    0  180  71  7 25 68  0
 0  0      0 646220  56116 250288    0    0    0    0  169  78  1 27 71  0
 0  0      0 644928  56120 252984    0    0    0    0  184  67  6 22 72  0
 0  0      0 643288  56120 253040    0    0    0    0  156  70  2 27 71  0
 0  0      0 642200  56120 255740    0    0    0    0  153  69  7 27 66  0

procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 0  0      0 653956  56120 255792    0    0    0    0  341  111  0 10 89  0
 0  0      0 653852  56160 255792    0    0    0  5550  521  60  0  0 83 17
 0  0      0 653852  56160 255792    0    0    0    0  505  31  0  0 100  0
 0  0      0 653852  56160 255792    0    0    0    0  505  34  0  0 100  0
 0  0      0 653852  56160 255792    0    0    0    0  505  28  0  0 100  0
 0  0      0 653852  56160 255792    0    0    0    0  505  34  0  0 100  0
 0  0      0 653852  56160 255792    0    0    0    0  505  30  0  0 100  0
 0  0      0 653852  56160 255792    0    0    0    0  505  34  0  0 100  0
 0  0      0 653852  56160 255792    0    0    0    0  505  35  0  0 100  0
 0  0      0 653852  56160 255792    0    0    0    0  505  29  0  0 100  0
 0  0      0 653852  56160 255792    0    0    0    0  505  35  0  0 100  0
 0  0      0 653852  56160 255792    0    0    0    6  507  30  0  0 100  0
 0  0      0 653852  56160 255792    0    0    0    0  505  31  0  0 100  0
 0  0      0 653852  56160 255792    0    0    0    12  507  35  0  0 100  0
 0  0      0 653852  56160 255792    0    0    0    0  505  30  0  0 100  0
^C

old machine (before, during, and after ripping)
Code:

user@home4:~$ vmstat 2

procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 2  0    40 196660  66528 203916    0    0    35    28  311 1376  2  1 96  1
 0  0    40 196652  66528 203916    0    0    0    0  255  28  0  0 100  0
 0  0    40 196652  66528 203916    0    0    0    0  255  31  0  0 100  0
 0  0    40 196652  66528 203916    0    0    0    14  262  44  0  3 97  0
 0  0    40 196652  66528 203916    0    0    0    0  256  31  0  0 100  0
 0  0    40 196652  66528 203916    0    0    0    6  256  32  0  0 100  0
 0  0    40 196652  66528 203916    0    0    0    0  254  25  0  0 100  0
 0  0    40 196652  66528 203916    0    0    0    0  254  34  0  0 100  0
 0  0    40 196652  66528 203916    0    0    0    0  254  26  0  0 100  0
 0  0    40 196652  66528 203916    0    0    0    0  254  27  0  0 100  0
 0  0    40 196652  66528 203916    0    0    0    8  256  35  0  0 100  0
 0  0    40 196652  66528 203916    0    0    0    0  254  25  0  0 100  0
 0  0    40 196652  66528 203916    0    0    0    0  254  33  0  0 100  0
 0  0    40 196652  66528 203916    0    0    0  154  286  76  0  0 100  0
 0  0    40 196652  66528 203916    0    0    0    0  254  25  0  0 100  0

procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 0  0    40 196388  66528 203916    0    0    0    86  283  96  0  4 93  2
 0  0    40 196140  66528 203916    0    0    0    0  251  26  0  0 100  0
 0  0    40 209168  66528 190316    0    0    0    0  257  59  0  4 96  0
 0  0    40 205072  66560 190316    0    0    0    28  250  91  1 22 77  0
 0  0    40 195668  66560 192956    0    0    0    0  247  79  3 22 74  0
 0  0    40 191324  66560 192972    0    0    0    0  251  90  1 23 76  0
 2  1    40 192676  66588 195664    0    0    0  2674  250  90  3 23 71  3
 0  0    40 191188  66588 195660    0    0    0    42  256  139  1 26 70  3
 0  0    40 190076  66588 198348    0    0    0    0  251  87  3 23 74  0
 0  0    40 188336  66588 198388    0    0    0    0  250  88  1 25 75  0
 0  0    40 187096  66616 201100    0    0    0  1396  258  114  3 23 72  1
 0  0    40 185368  66616 201140    0    0    0    0  250  91  1 23 76  0
 0  0    40 184260  66616 203852    0    0    0    0  252  87  3 23 74  0
 0  0    40 182520  66616 203916    0    0    0    0  246  81  0 24 76  0
 0  0    40 196404  66648 203916    0    0    0  2774  271  163  1 18 77  5

procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 0  0    40 196404  66648 203916    0    0    0    0  254  27  0  0 100  0
 0  0    40 196404  66648 203916    0    0    0    56  256  31  0  0 100  0
 0  0    40 196404  66648 203916    0    0    0    0  254  30  0  0 100  0
 0  0    40 196404  66648 203916    0    0    0    0  254  26  0  0 100  0
 0  0    40 196404  66648 203916    0    0    0    6  256  31  0  0 100  0
 0  0    40 196404  66648 203916    0    0    0    0  254  25  0  0 100  0
 0  0    40 196404  66648 203916    0    0    0    0  254  27  0  0 100  0
 0  0    40 196404  66648 203916    0    0    0    0  254  33  0  0 100  0
 0  0    40 196404  66648 203916    0    0    0    0  254  26  0  0 100  0
 0  0    40 196404  66648 203916    0    0    0    0  254  32  0  0 100  0
 0  0    40 196404  66648 203916    0    0    0    0  254  25  0  0 100  0
 0  0    40 196404  66648 203916    0    0    0    16  258  31  0  0 100  0
 0  0    40 196404  66648 203916    0    0    0    0  254  29  0  0 100  0
 0  0    40 196404  66648 203916    0    0    0    0  254  26  0  0 100  0
^C

In the case of new machine system in jumps lower down, cpu us and cpu sy jump higher up, and cpu id jumps lower down then in the case of old machine.

In the case of old machine io bo and cpu wa jump significantly more frequently up then in the case of new machine.

I have no idea what all that means.

w1k0 02-10-2009 10:20 AM

I compared WAV files produced by both cdparanoia and cdda2wav. In the case of most disks resulting files were identical. In the case of disks with Copy Control technology and in some other cases there were differences between files produced by these two programs but in such cases there were also differences between the same track ripped twice with the same program.

Then I compared MP3 files produced by lame with bitrate 160 to original Audio CD. I used my new machine and CD-ROM and amplifier by Marantz. I didn't hear any difference. Then I did the same test using my very old Toshiba Satellite and I noticed distinct differences. It leads to the conclusion that differences between better and worse sound cards are clearly audible while differences between good MP3 files and original Audio CDs are practically inaudible.

Because I still don't know why cdparanoia affects the system in such serious manner in the case of my new machine I decided to use cdda2wav instead of cdparanoia.

w1k0 02-15-2009 10:17 AM

I just downgraded to Slackware's 12.1 cdparanoia-III10pre0 and it works much more fluently on my new machine -- almost the same as cdda2wav. So Slackware's 12.2 cdparanoia-III_10.2 has some bug that affects ThinkPad T60 though it don't affect ThinkPad T40.

w1k0 02-15-2009 12:03 PM

cdparanoia 10.2 slows down the system on some machines
 
I just sent that bug report to paranoia@xiph.org:

cdparanoia-III_10.2 has difficult to identify bug that affects some machines. I tested it with Slackware 12.2 on two ThinkPads: T60 and T40. On the first machine new cdparanoia slows down the system seriously... Window Maker refreshes application's windows slowly, the content of the windows scrolls with resistance, mouse cursor moves stepwise etc. Similar problems occurs in the console mode. On the second machine new cdparanoia works almost fluently -- the same as cdda2wav.

Then I downgraded from cdparanoia-III_10.2 to cdparanoia-III10pre0 and it worked on the first machine the same as on the second. So that bug appeared somewhere between 10pre0 and 10.2 versions of cdparanoia and it affects some machines (for example ThinkPad T60) but doesn't affect other ones (for example ThinkPad T40).

At the end I compared versions 10.2 and 10pre0 of cdparanoia on the same ThinkPad T60 using vmstat...

Vmstat report before, during, and after ripping Audio CD with cdparanoia-III_10.2:
Code:

$ vmstat 2

procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 0  0      0 838140  19684 121024    0    0  168    20  266  86  1  1 94  5
 0  0      0 838076  19712 121044    0    0    0    62  516  37  0  0 100  0
 0  0      0 838076  19712 121044    0    0    0    0  505  29  0  0 100  0
 0  0      0 838076  19712 121044    0    0    0    0  505  33  0  0 100  0
 0  0      0 838076  19712 121044    0    0    0    0  505  29  0  0 100  0
 0  0      0 838076  19712 121044    0    0    0    0  505  32  0  0 100  0
 0  0      0 838076  19712 121044    0    0    0    0  505  30  0  0 100  0
 0  0      0 838076  19712 121044    0    0    0    0  506  29  0  0 100  0
 0  0      0 838076  19712 121044    0    0    0    0  506  33  0  0 100  0
 0  0      0 838076  19712 121044    0    0    0    0  506  29  0  0 100  0
 0  0      0 838076  19712 121044    0    0    0    0  506  32  0  0 100  0
 0  0      0 838076  19712 121044    0    0    0    0  506  30  0  0 100  0
 0  0      0 838076  19712 121044    0    0    0    0  506  30  0  0 100  0

procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 0  0      0 837820  19756 121200    0    0    80    20  565  140  0  1 88 11
 0  0      0 835740  19764 121204    0    0    4    0  314  80  0 15 82  2
 0  0      0 831524  19764 121204    0    0    0    0  157  70  0 33 68  0
 0  0      0 822092  19768 123860    0    0    0    0  179  68  4 27 69  0
 0  0      0 817740  19768 123860    0    0    0    0  153  72  1 34 65  0
 0  0      0 810328  19768 123960    0    0    0    0  174  79  5 26 69  0
 0  0      0 809472  19772 126552    0    0    0    0  184  66  4 25 71  0
 0  0      0 807840  19772 126552    0    0    0    0  166  77  0 30 70  0
 0  0      0 810940  19772 129312    0    0    0    0  171  67  3 29 68  0
 0  0      0 809036  19824 129360    0    0    0  4106  174  90  1 28 69  2
 0  0      0 809940  19824 131220    0    0    0    0  174  71  4 27 69  0
 0  0      0 805860  19824 131220    0    0    0    0  155  71  6 29 65  0
 0  0      0 806480  19828 133940    0    0    0    0  175  69  5 28 67  0

procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 0  0      0 825024  19828 133940    0    0    0    0  314  104  0  9 91  0
 0  0      0 825024  19828 133940    0    0    0    0  505  27  0  0 100  0
 0  0      0 824776  19976 133940    0    0    0  2366  514  49  0  0 88 12
 0  0      0 824776  19976 133940    0    0    0    0  506  28  0  0 100  0
 0  0      0 824776  19976 133940    0    0    0    0  506  34  0  0 100  0
 0  0      0 824776  19976 133940    0    0    0    0  505  28  0  0 100  0
 0  0      0 824776  19976 133940    0    0    0    0  505  32  0  0 100  0
 0  0      0 824776  19976 133940    0    0    0    0  505  30  0  0 100  0
 0  0      0 824776  19976 133940    0    0    0    0  505  28  0  0 100  0
 0  0      0 824776  19976 133940    0    0    0    0  505  34  0  0 100  0
 0  0      0 824776  19976 133940    0    0    0    0  505  28  0  0 100  0
 0  0      0 824776  19976 133940    0    0    0    0  505  34  0  0 100  0
 0  0      0 824776  19976 133940    0    0    0    58  518  30  0  0 100  0
^C

Vmstat report before, during, and after ripping Audio CD with cdparanoia-III10pre0:
Code:

$ vmstat 2

procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 0  0      0 838380  19680 121036    0    0  810    87  322  363  3  4 70 23
 0  0      0 838372  19680 121052    0    0    0    0  505  32  0  0 100  0
 0  0      0 838304  19708 121024    0    0    0    38  512  37  0  0 100  0
 0  0      0 838248  19708 121052    0    0    0    0  505  31  0  0 100  0
 0  0      0 838248  19708 121052    0    0    0    0  505  29  0  0 100  0
 0  0      0 838248  19720 121052    0    0    0    8  506  38  0  0 100  0
 0  0      0 838248  19720 121052    0    0    0    0  505  28  0  0 100  0
 0  0      0 838248  19720 121052    0    0    0    0  505  33  0  0 100  0
 0  0      0 838248  19720 121052    0    0    0    0  506  30  0  0 100  0
 0  0      0 838248  19720 121052    0    0    0    0  506  28  0  0 100  0
 0  0      0 838248  19720 121052    0    0    0    0  506  34  0  0 100  0
 0  0      0 838248  19720 121052    0    0    0    0  506  28  0  0 100  0
 0  0      0 838248  19720 121052    0    0    0  1040  632  42  0  0 99  0

procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 0  0      0 838116  19724 121172    0    0    62    0  547  97  0  2 96  2
 0  0      0 835272  19732 121676    0    0    4    0  405  102  0 18 80  2
 0  0      0 834052  19732 122688    0    0    0    0  355  104  0 22 77  0
 0  0      0 832848  19804 123696    0    0    0  952  373  130  1 22 74  3
 0  0      0 831968  19804 124704    0    0    0    0  373  103  1 24 76  0
 0  0      0 830676  19812 125720    0    0    2    0  363  112  0 23 75  2
 0  0      0 829740  19812 126648    0    0    0    0  391  88  1 16 83  0
 0  0      0 828992  19852 127308    0    0    0  1910  418  100  0 12 85  3
 0  0      0 827356  19852 128072    0    0    0    0  367  112  1 23 76  0
 0  0      0 826268  19860 129000    0    0    2    0  363  102  1 23 74  2
 0  0      0 825476  19860 129980    0    0    0    0  355  112  1 22 76  0
 0  0      0 824016  19860 130640    0    0    0    0  366  105  1 24 75  0
 0  0      0 823528  19908 131652    0    0    2  2168  374  124  1 23 75  1

procs -----------memory---------- ---swap-- -----io---- -system-- ----cpu----
 r  b  swpd  free  buff  cache  si  so    bi    bo  in  cs us sy id wa
 0  0      0 826152  19908 132344    0    0    0    0  427  116  0 11 89  0
 0  0      0 826220  19908 132344    0    0    0    0  507  30  0  0 100  0
 0  0      0 826096  20048 132344    0    0    0  730  510  42  0  0 99  1
 0  0      0 826096  20048 132344    0    0    0    0  506  35  0  0 100  0
 0  0      0 826096  20048 132344    0    0    0    0  506  33  0  0 100  0
 0  0      0 826096  20048 132344    0    0    0    0  506  29  0  0 100  0
 0  0      0 826096  20048 132344    0    0    0    0  506  29  0  0 100  0
 0  0      0 826096  20048 132344    0    0    0    0  506  34  0  0 100  0
 0  0      0 826096  20048 132344    0    0    0    0  506  29  0  0 100  0
 0  0      0 826096  20048 132344    0    0    0    26  512  33  0  0 100  0
 0  0      0 826096  20048 132344    0    0    0    0  506  29  0  0 100  0
 0  0      0 826096  20048 132344    0    0    0    0  505  28  0  0 100  0
 0  0      0 826096  20048 132344    0    0    0    0  520  63  0  0 100  0
^C

Conclusions:

1. 10.2 uses slightly more memory than 10pre0.
2. 10.2 has system in about 150-185 while 10pre0 about 355-420.
3. 10.2 has system cs about 65-90 while 10pre0 about 90-130.
4. 10.2 has cpu us about 0-6 while 10pre0 about 0-1.
5. 10.2 has cpu sy about 25-35 while 10pre0 about 10-25.
6. 10.2 has cpu id about 65-70 while 10pre0 about 75-85.
7. 10.2 has cpu wa about 0-2 while 10pre0 about 0-3.

I don't know how I can interpret these values.


All times are GMT -5. The time now is 01:28 AM.