Re: 2.6.8: IDE cdrecord problems

Re: 2.6.8: IDE cdrecord problems

[Emilio Jesús Gallego Arias]

In-Reply-To: <20040814192211.312de2a2 () jack ! colino ! net>

Jack Colino wrote:

> Something quickly took all the memory and swap available, OOM started
> to kill things and the laptop got bogged down so much that I had to 
> reboot.
> Everything is fine with 2.6.8-rc2
> Excerpts of the syslog are attached. I started to burn at about 14:52,
> tried to login remotely at 15:27:47 (but ssh timed out).

Umm, interesting. I've had the same problem and I blamed the drive, but 
I've the same logs:

Aug  8 00:59:00 localhost kernel: oom-killer: gfp_mask=0xd2
Aug  8 00:59:01 localhost kernel: DMA per-cpu:
Aug  8 00:59:01 localhost kernel: cpu 0 hot: low 2, high 6, batch 1
Aug  8 00:59:01 localhost kernel: cpu 0 cold: low 0, high 2, batch 1
Aug  8 00:59:01 localhost kernel: Normal per-cpu:
Aug  8 00:59:01 localhost kernel: cpu 0 hot: low 32, high 96, batch 16
Aug  8 00:59:01 localhost kernel: cpu 0 cold: low 0, high 32, batch 16
Aug  8 00:59:01 localhost kernel: HighMem per-cpu: empty
Aug  8 00:59:01 localhost kernel:
Aug  8 00:59:01 localhost kernel: Free pages:        2396kB (0kB HighMem)
Aug  8 00:59:01 localhost kernel: Active:16513 inactive:407 dirty:0 writeback:0 unstable:0 free:599 slab:2859 mapped:16891 pagetables:686
Aug  8 00:59:01 localhost kernel: DMA free:1420kB min:20kB low:40kB high:60kB active:11880kB inactive:104kB present:16384kB
Aug  8 00:59:01 localhost kernel: protections[]: 10 360 360
Aug  8 00:59:03 localhost kernel: Normal free:976kB min:700kB low:1400kB high:2100kB active:54172kB inactive:1524kB present:507840kB
Aug  8 00:59:03 localhost kernel: protections[]: 0 350 350
Aug  8 00:59:03 localhost kernel: HighMem free:0kB min:128kB low:256kB high:384kB active:0kB inactive:0kB present:0kB
Aug  8 00:59:03 localhost kernel: protections[]: 0 0 0
Aug  8 00:59:03 localhost kernel: DMA: 1*4kB 1*8kB 0*16kB 0*32kB 0*64kB 1*128kB 1*256kB 0*512kB 1*1024kB 0*2048kB 0*4096kB = 1420kB
Aug  8 00:59:03 localhost kernel: Normal: 72*4kB 4*8kB 1*16kB 0*32kB 0*64kB 1*128kB 0*256kB 1*512kB 0*1024kB 0*2048kB 0*4096kB = 976kB
Aug  8 00:59:03 localhost kernel: HighMem: empty
Aug  8 00:59:03 localhost kernel: Swap cache: add 50405, delete 50305, find 1397/2028, race 0+0
Aug  8 00:59:03 localhost kernel: Out of Memory: Killed process 3004 (mysqld).

This log repeats for every cd i tried to burn.

Unfortunately I've no blank media until Monday I can go to the shop :) so 
will test a stock kernel soon.

Running:

ellugar:/var/log# uname -a
Linux ellugar 2.6.8-rc4 #48 Fri Aug 13 19:25:22 CEST 2004 i686 GNU/Linux

Debian unstable.

More info and testing on request.
Any changeset to revert?

Please cc me as I'm not subscribed to lkml.

Regards, Emilio

Re: 2.6.8: IDE cdrecord problems

[Colin Leroy]

On 14 Aug 2004 at 21h08, Emilio Jesús Gallego Arias wrote:

Hi, 

> Unfortunately I've no blank media until Monday I can go to the shop :)
> so will test a stock kernel soon.

In my case it is a stock kernel. Also, I never saw any of these
messages before...

-- 
Colin
  http://ayttm.sf.net/ : Chat with your friends
  via msn, aim, yahoo, and more

Re: 2.6.8: IDE cdrecord problems

[Simon Kirby]

On Sat, Aug 14, 2004 at 11:19:09PM +0200, Colin Leroy wrote:

> In my case it is a stock kernel. Also, I never saw any of these
> messages before...

I can confirm that this also happens here, on 2.6.8rc4 and possibly
earlier rcs (should probably have reported it earlier). :)

I can burn DVDs with growisofs without problem, but burning an audio
CD with cdrecord OOMs everything and the box chokes.  slabinfo did
not appear to report anything large if I remember correctly, but I'm
not certain.

Simon-

Re: 2.6.8: IDE cdrecord problems

[Emilio Jesús Gallego Arias]

Hi, sorry I wasn't clear, I'm using too stock 2.6.8.1. I meant will test
with stock 2.6.7.

Also I think it may be related to some of these changelogs:

very likely:
[PATCH] BIO page refcounting fix:

http://linux.bkbits.net:8080/linux-2.5/cset@1.1807.25.6

I'll try to patch -r this and test on Monday.

these changesets are also cdrom or bio related, althought I don't think 
are causing any trouble:

bio_copy_user() cleanups and fixes:
http://linux.bkbits.net:8080/linux-2.5/cset@1.1807.38.2
[PATCH] fix cdrom cdda rip single frame dma fall back
http://linux.bkbits.net:8080/linux-2.5/cset@1.1807.1.68

El sáb, 14-08-2004 a las 23:19 +0200, Colin Leroy escribió:
> On 14 Aug 2004 at 21h08, Emilio Jesús Gallego Arias wrote:
> 
> Hi, 
> 
> > Unfortunately I've no blank media until Monday I can go to the shop :)
> > so will test a stock kernel soon.
> 
> In my case it is a stock kernel. Also, I never saw any of these
> messages before...
> 

2.6.8: IDE cdrecord problems

[Colin Leroy]

[-- Attachment #1: Type: text/plain, Size: 588 bytes --]

Hi,

I upgraded today from 2.6.8-rc2 to 2.6.8. Tried to burn a CD, using
cdrecord on /dev/hdc, which is an IDE CD burner (Matshita something in
the iBook G4).

Something quickly took all the memory and swap available, OOM started to
kill things and the laptop got bogged down so much that I had to reboot.
Everything is fine with 2.6.8-rc2
Excerpts of the syslog are attached. I started to burn at about 14:52,
tried to login remotely at 15:27:47 (but ssh timed out).


-- 
Colin
  Like any talented dog, it can do flips. 
  Like any talented cow, it can do precision bitmap alignment.  

[-- Attachment #2: syslog.txt --]
[-- Type: text/plain, Size: 3118 bytes --]

Aug 14 14:54:08 [kernel] hdc: packet command error: status=0x51 { DriveReady SeekComplete Error }
Aug 14 14:54:08 [kernel] cdrom: This disc doesn't have any tracks I recognize!
Aug 14 14:54:08 [kernel] hdc: packet command error: status=0x51 { DriveReady SeekComplete Error }
Aug 14 15:02:56 [kernel] oom-killer: gfp_mask=0xd2
Aug 14 15:03:15 [kernel] DMA per-cpu:
Aug 14 15:27:47 [sshd] Did not receive identification string from 192.168.0.3
Aug 14 15:38:17 [kernel] cpu 0 cold: low 0, high 32, batch 16
Aug 14 15:38:21 [kernel] Normal per-cpu: empty
Aug 14 15:38:55 [kernel] oom-killer: gfp_mask=0x1d2
Aug 14 15:39:30 [kernel] DMA per-cpu:
Aug 14 15:41:13 [kernel] oom-killer: gfp_mask=0x1d2
                - Last output repeated 2 times -
Aug 14 15:42:56 [kernel] DMA per-cpu:
Aug 14 15:43:34 [kernel] oom-killer: gfp_mask=0x1d2
Aug 14 15:44:49 [kernel] adt746x: Stopping CPU fan.
Aug 14 15:45:54 [kernel] oom-killer: gfp_mask=0x1d2
Aug 14 15:48:57 [kernel] oom-killer: gfp_mask=0x1d2
                - Last output repeated 2 times -
Aug 14 15:51:31 [kernel] DMA per-cpu:
Aug 14 15:52:57 [kernel] DMA: 38*4kB 5*8kB 0*16kB 1*32kB 0*64kB 0*128kB 0*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 1760kB
Aug 14 15:53:00 [kernel] Normal: empty
Aug 14 15:53:26 [kernel] oom-killer: gfp_mask=0x1d2
Aug 14 15:56:28 [kernel] DMA per-cpu:
Aug 14 15:57:17 [kernel] HighMem free:0kB min:128kB low:256kB high:384kB active:0kB inactive:0kB present:0kB
Aug 14 15:58:41 [kernel] Normal free:0kB min:0kB low:0kB high:0kB active:0kB inactive:0kB present:0kB
Aug 14 15:58:44 [kernel] protections[]: 0 0 0
Aug 14 15:59:36 [kernel] Active:124 inactive:5770 dirty:0 writeback:5283 unstable:0 free:414 slab:2098 mapped:758 pagetables:470
Aug 14 16:03:05 [kernel] DMA: 14*4kB 25*8kB 15*16kB 11*32kB 1*64kB 0*128kB 1*256kB 1*512kB 0*1024kB 0*2048kB 0*4096kB = 1680kB
Aug 14 16:04:19 [kernel] Normal: empty
Aug 14 16:11:01 [kernel] cpu 0 cold: low 0, high 32, batch 16
Aug 14 16:18:45 [kernel] protections[]: 404 404 404
Aug 14 16:20:45 [kernel] protections[]: 404 404 404
Aug 14 16:22:05 [kernel] Normal free:0kB min:0kB low:0kB high:0kB active:0kB inactive:0kB present:0kB
Aug 14 16:23:48 [kernel] Active:152 inactive:5621 dirty:0 writeback:5543 unstable:0 free:654 slab:2077 mapped:198 pagetables:360
Aug 14 16:24:41 [kernel] DMA free:1648kB min:808kB low:1616kB high:2424kB active:968kB inactive:23972kB present:655360kB
Aug 14 16:26:05 [kernel] protections[]: 404 404 404
Aug 14 16:30:40 [kernel] oom-killer: gfp_mask=0x1d2
Aug 14 16:36:20 [kernel] DMA per-cpu:
Aug 14 16:37:54 [kernel] Active:105 inactive:6157 dirty:0 writeback:5828 unstable:0 free:392 slab:2063 mapped:419 pagetables:311
Aug 14 16:40:08 [kernel] DMA free:1568kB min:808kB low:1616kB high:2424kB active:420kB inactive:24628kB present:655360kB
Aug 14 16:43:20 [kernel] protections[]: 0 0 0
Aug 14 16:46:34 [kernel] HighMem free:0kB min:128kB low:256kB high:384kB active:0kB inactive:0kB present:0kB
Aug 14 16:49:41 [kernel] protections[]: 0 0 0
Aug 14 16:51:04 [kernel] DMA: 18*4kB 10*8kB 22*16kB 11*32kB 1*64kB 0*128kB 1*256kB 1*512kB 0*1024kB 0*2048kB 0*4096kB = 1688kB