Re: bttv : overlay mode and big disk io hang and could corrupt thefs

From: matthieu castet
Date: Sat Feb 12 2005 - 06:31:53 EST


Hi,

matthieu castet wrote:
Hi,

if I run "xawtv" [1] and then do a "grep -r toto /usr", my system quickly freeze. If there isn't any xawv running nothing happen. I don't try to use xawtv with grab mode (port 54) because I don't want to loose data by crashing again my / fs.

I retry it and I arrived to get some log (see the attach file).

But after rebooting, I had a big surprise, the / ext3 fs was corrupted and I need to run fsck by hand ....

Matthieu
I have done other tests.

If I use grabdisplay mode the problem doesn't occur.
If using overlay mode with port 54 the problem is present.

I have also try the cvs bttv driver and the problem isn't fixed.

Can someone look at it?
It seem that something is corrupting kernel memory because usb failed & ext3 failed...

I attached a new log when the problem appear.
Note that there are similitude with http://www.ussg.iu.edu/hypermail/linux/kernel/0412.3/0881.html and http://marc.theaimsgroup.com/?t=110531543900002&r=1&w=2

Matthieu


[1]
$xawtv -hwscan
This is xawtv-3.94, running on Linux/i686 (2.6.10)
looking for available devices
port 53-53 [ -xvport 53 ]
type : Xvideo, video overlay
name : video4linux

port 54-54
type : Xvideo, image scaler
name : NV Video Overlay

port 55-86
type : Xvideo, image scaler
name : NV Video Blitter

/dev/video0: OK [ -device /dev/video0 ]
type : v4l2
name : BT878 video (Pinnacle PCTV Stud
flags: overlay capture tuner

Feb 12 12:08:33 localhost kernel: Linux video capture interface: v1.00
Feb 12 12:08:33 localhost kernel: bttv: driver version 0.9.15 loaded
Feb 12 12:08:33 localhost kernel: bttv: snapshot date 2005-02-10
Feb 12 12:08:33 localhost kernel: bttv: using 8 buffers with 2080k (520 pages) each for capture
Feb 12 12:08:33 localhost kernel: bttv: Bt8xx card found (0).
Feb 12 12:08:33 localhost kernel: ACPI: PCI interrupt 0000:00:0a.0[A] -> GSI 17 (level, low) -> IRQ 17
Feb 12 12:08:33 localhost kernel: bttv0: Bt878 (rev 17) at 0000:00:0a.0, irq: 17, latency: 32, mmio: 0xddcfe000
Feb 12 12:08:33 localhost kernel: bttv0: detected: Pinnacle PCTV [card=39], PCI subsystem ID is 11bd:0012
Feb 12 12:08:33 localhost kernel: bttv0: using: Pinnacle PCTV Studio/Rave [card=39,insmod option]
Feb 12 12:08:33 localhost kernel: bttv0: gpio: en=00000000, out=00000000 in=00ff27ff [init]
Feb 12 12:08:33 localhost kernel: bttv0: i2c: checking for MSP34xx @ 0x80... not found
Feb 12 12:08:33 localhost kernel: bttv0: miro: id=9 tuner=3 radio=no stereo=no
Feb 12 12:08:33 localhost kernel: bttv0: using tuner=3
Feb 12 12:08:33 localhost kernel: bttv0: i2c: checking for MSP34xx @ 0x80... not found
Feb 12 12:08:33 localhost kernel: bttv0: i2c: checking for TDA9875 @ 0xb0... not found
Feb 12 12:08:33 localhost kernel: bttv0: i2c: checking for TDA7432 @ 0x8a... not found
Feb 12 12:08:33 localhost kernel: bttv0: i2c: checking for TDA9887 @ 0x86... not found
Feb 12 12:08:34 localhost kernel: tuner: chip found at addr 0xc0 i2c-bus bt878 #0 [sw]
Feb 12 12:08:34 localhost kernel: tuner: type set to 3 (Philips (SECAM+PAL_BG) (FI1216MF, FM1216MF, FR1216MF))
Feb 12 12:08:34 localhost kernel: bttv0: registered device video0
Feb 12 12:08:34 localhost kernel: bttv0: registered device vbi0
Feb 12 12:08:34 localhost kernel: bttv0: PLL: 28636363 => 35468950 . ok
Feb 12 12:09:14 localhost kernel: tuner: tv freq set to 208.00
Feb 12 12:09:14 localhost kernel: tv: VHF high range
Feb 12 12:09:14 localhost kernel: tuner: tv 0x0f 0x6f 0x8e 0x97
Feb 12 12:09:14 localhost kernel: tv: VHF high range
Feb 12 12:09:14 localhost kernel: tuner: tv 0x0f 0x6f 0x8e 0x97
Feb 12 12:09:49 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW RISCI* OCERR*
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: non-integral number of cells in incoming data.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: incoming data length = 1.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: ATM_CELL_SIZE = 35.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: non-integral number of cells in incoming data.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: incoming data length = 1.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: ATM_CELL_SIZE = 35.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: non-integral number of cells in incoming data.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: incoming data length = 1.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: ATM_CELL_SIZE = 35.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: non-integral number of cells in incoming data.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: incoming data length = 1.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: ATM_CELL_SIZE = 35.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: non-integral number of cells in incoming data.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: incoming data length = 1.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: ATM_CELL_SIZE = 35.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: non-integral number of cells in incoming data.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: incoming data length = 1.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: ATM_CELL_SIZE = 35.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: non-integral number of cells in incoming data.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: incoming data length = 1.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: ATM_CELL_SIZE = 35.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: non-integral number of cells in incoming data.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: incoming data length = 1.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: ATM_CELL_SIZE = 35.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: non-integral number of cells in incoming data.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: incoming data length = 1.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: ATM_CELL_SIZE = 35.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: non-integral number of cells in incoming data.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: incoming data length = 1.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: ATM_CELL_SIZE = 35.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: non-integral number of cells in incoming data.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: incoming data length = 1.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: ATM_CELL_SIZE = 35.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: non-integral number of cells in incoming data.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: incoming data length = 1.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: ATM_CELL_SIZE = 35.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: non-integral number of cells in incoming data.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: incoming data length = 1.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: ATM_CELL_SIZE = 35.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: non-integral number of cells in incoming data.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: incoming data length = 1.
Feb 12 12:09:49 localhost kernel: [EAGLE-USB] eu_uni_process_in_data: ATM_CELL_SIZE = 35.
Feb 12 12:09:49 localhost kernel: uhci_hcd 0000:00:10.0: host system error, PCI problems?
Feb 12 12:09:49 localhost kernel: uhci_hcd 0000:00:10.0: host controller halted, very bad!
Feb 12 12:09:49 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:49 localhost last message repeated 6 times
Feb 12 12:09:49 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:49 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:49 localhost kernel: EXT3-fs error (device hdb2): ext3_readdir: bad entry in directory #258280: rec_len is smaller than minimal - offset=0, inode=0, rec_len=0, name_len=0
Feb 12 12:09:49 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:49 localhost kernel: EXT3-fs error (device hdb2): ext3_readdir: bad entry in directory #629067: rec_len %% 4 != 0 - offset=0, inode=1919250533, rec_len=25389, name_len=111
Feb 12 12:09:49 localhost kernel: EXT3-fs error (device hdb2): ext3_readdir: bad entry in directory #629078: rec_len is smaller than minimal - offset=0, inode=0, rec_len=0, name_len=0
Feb 12 12:09:49 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:49 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:49 localhost kernel: EXT3-fs error (device hdb2): ext3_readdir: bad entry in directory #145262: rec_len is smaller than minimal - offset=0, inode=0, rec_len=0, name_len=0
Feb 12 12:09:49 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:49 localhost kernel: init_special_inode: bogus i_mode (170757)
Feb 12 12:09:49 localhost kernel: init_special_inode: bogus i_mode (51046)
Feb 12 12:09:49 localhost kernel: EXT3-fs error (device hdb2): ext3_readdir: bad entry in directory #822876: rec_len is smaller than minimal - offset=0, inode=0, rec_len=0, name_len=0
Feb 12 12:09:49 localhost kernel: EXT3-fs error (device hdb2): ext3_readdir: bad entry in directory #96986: rec_len is smaller than minimal - offset=0, inode=0, rec_len=0, name_len=0
Feb 12 12:09:49 localhost kernel: init_special_inode: bogus i_mode (35425)
Feb 12 12:09:49 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:49 localhost kernel: init_special_inode: bogus i_mode (71670)
Feb 12 12:09:49 localhost kernel: init_special_inode: bogus i_mode (116206)
Feb 12 12:09:49 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:49 localhost kernel: EXT3-fs error (device hdb2): ext3_readdir: bad entry in directory #729605: rec_len %% 4 != 0 - offset=0, inode=37585332, rec_len=7786, name_len=0
Feb 12 12:09:49 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:49 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:49 localhost kernel: init_special_inode: bogus i_mode (116006)
Feb 12 12:09:49 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:49 localhost last message repeated 2 times
Feb 12 12:09:49 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:49 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:49 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:49 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:50 localhost last message repeated 8 times
Feb 12 12:09:50 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:50 localhost last message repeated 2 times
Feb 12 12:09:50 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:50 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:50 localhost kernel: EXT3-fs error (device hdb2): ext3_readdir: bad entry in directory #194919: rec_len is smaller than minimal - offset=0, inode=0, rec_len=0, name_len=0
Feb 12 12:09:50 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:50 localhost last message repeated 4 times
Feb 12 12:09:50 localhost kernel: init_special_inode: bogus i_mode (56712)
Feb 12 12:09:50 localhost kernel: init_special_inode: bogus i_mode (166203)
Feb 12 12:09:50 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:50 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:50 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:50 localhost last message repeated 2 times
Feb 12 12:09:50 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:50 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:50 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:50 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:50 localhost kernel: init_special_inode: bogus i_mode (113250)
Feb 12 12:09:50 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:50 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:50 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:50 localhost kernel: init_special_inode: bogus i_mode (32763)
Feb 12 12:09:50 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:50 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:50 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:50 localhost last message repeated 3 times
Feb 12 12:09:50 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:50 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:50 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:50 localhost kernel: EXT3-fs error (device hdb2): ext3_readdir: bad entry in directory #532951: rec_len is smaller than minimal - offset=0, inode=0, rec_len=0, name_len=0
Feb 12 12:09:50 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:50 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:50 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:50 localhost last message repeated 3 times
Feb 12 12:09:50 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:50 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:50 localhost last message repeated 2 times
Feb 12 12:09:50 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:51 localhost kernel: init_special_inode: bogus i_mode (115532)
Feb 12 12:09:51 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:51 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:51 localhost kernel: attempt to access beyond end of device
Feb 12 12:09:51 localhost kernel: hdb2: rw=0, want=1871353184, limit=13671504
Feb 12 12:09:51 localhost kernel: attempt to access beyond end of device
Feb 12 12:09:51 localhost kernel: hdb2: rw=0, want=8619069784, limit=13671504
Feb 12 12:09:51 localhost kernel: attempt to access beyond end of device
Feb 12 12:09:51 localhost kernel: hdb2: rw=0, want=8529944672, limit=13671504
Feb 12 12:09:51 localhost kernel: attempt to access beyond end of device
Feb 12 12:09:51 localhost kernel: hdb2: rw=0, want=8486519816, limit=13671504
Feb 12 12:09:51 localhost kernel: attempt to access beyond end of device
Feb 12 12:09:51 localhost kernel: hdb2: rw=0, want=8486519816, limit=13671504
Feb 12 12:09:51 localhost kernel: attempt to access beyond end of device
Feb 12 12:09:51 localhost kernel: hdb2: rw=0, want=8486519816, limit=13671504
Feb 12 12:09:51 localhost kernel: attempt to access beyond end of device
Feb 12 12:09:51 localhost kernel: hdb2: rw=0, want=8486519816, limit=13671504
Feb 12 12:09:51 localhost kernel: attempt to access beyond end of device
Feb 12 12:09:51 localhost kernel: hdb2: rw=0, want=8486519816, limit=13671504
Feb 12 12:09:51 localhost kernel: attempt to access beyond end of device
Feb 12 12:09:51 localhost kernel: hdb2: rw=0, want=8486519816, limit=13671504
Feb 12 12:09:51 localhost kernel: attempt to access beyond end of device
Feb 12 12:09:51 localhost kernel: hdb2: rw=0, want=8486519816, limit=13671504
Feb 12 12:09:51 localhost kernel: attempt to access beyond end of device
Feb 12 12:09:51 localhost kernel: hdb2: rw=0, want=8486519816, limit=13671504
Feb 12 12:09:51 localhost kernel: attempt to access beyond end of device
Feb 12 12:09:51 localhost kernel: hdb2: rw=0, want=1871353184, limit=13671504
Feb 12 12:09:51 localhost kernel: attempt to access beyond end of device
Feb 12 12:09:51 localhost kernel: hdb2: rw=0, want=1871353184, limit=13671504
Feb 12 12:09:51 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:51 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW FDSR OCERR*
Feb 12 12:09:51 localhost kernel: init_special_inode: bogus i_mode (0)
Feb 12 12:09:51 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:51 localhost kernel: init_special_inode: bogus i_mode (72150)
Feb 12 12:09:51 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:09:51 localhost last message repeated 2 times
Feb 12 12:09:51 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW FDSR OCERR*
Feb 12 12:09:51 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:10:07 localhost last message repeated 406 times
Feb 12 12:10:07 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW FDSR OCERR*
Feb 12 12:10:07 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:10:17 localhost last message repeated 243 times
Feb 12 12:10:17 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW FDSR OCERR*
Feb 12 12:10:17 localhost kernel: bttv0: OCERR @ 3328b01c,bits: HSYNC OFLOW OCERR*
Feb 12 12:10:17 localhost last message repeated 6 times
Feb 12 12:10:17 localhost kernel: bttv0: timeout: drop=0 irq=3900/3900, risc=3328b01c, bits: HSYNC OFLOW
Feb 12 12:10:17 localhost kernel: bttv0: reset, reinitialize
Feb 12 12:10:17 localhost kernel: bttv0: PLL: 28636363 => 35468950 . ok
Feb 12 12:10:17 localhost kernel: tv: VHF high range
Feb 12 12:10:17 localhost kernel: tuner: tv 0x0f 0x6f 0x8e 0x97
Feb 12 12:10:18 localhost kernel: bttv0: OCERR @ 3328b01c,bits: VSYNC HSYNC OFLOW RISCI* OCERR*
Feb 12 12:10:18 localhost kernel: bttv0: OCERR @ 3328b01c,bits: VSYNC HSYNC OFLOW OCERR*
Feb 12 12:10:19 localhost last message repeated 2 times
Feb 12 12:10:19 localhost kernel: bttv0: OCERR @ 3328b01c,bits: VSYNC HSYNC OFLOW RISCI* OCERR*
Feb 12 12:10:19 localhost kernel: bttv0: OCERR @ 3328b01c,bits: VSYNC HSYNC OFLOW OCERR*
Feb 12 12:10:20 localhost last message repeated 4 times
Feb 12 12:10:22 localhost kernel: bttv0: unloading