2005-09-23 19:22:13

by Arnaud Boulan

[permalink] [raw]
Subject: reiserfs problem after usb hard drive disconnected

Sep 12 19:52:56 t42p usb 4-3: default language 0x0409
Sep 12 19:52:56 t42p usb 4-3: new device strings: Mfr=56, Product=78, SerialNumber=100
Sep 12 19:52:56 t42p usb 4-3: Product: USB2.0 Storage Device
Sep 12 19:52:56 t42p usb 4-3: Manufacturer: Cypress Semiconductor
Sep 12 19:52:56 t42p usb 4-3: SerialNumber: DEF107679C83
Sep 12 19:52:56 t42p usb 4-3: hotplug
Sep 12 19:52:56 t42p usb 4-3: adding 4-3:1.0 (config #1, interface 0)
Sep 12 19:52:56 t42p usb 4-3:1.0: hotplug
Sep 12 19:52:56 t42p Initializing USB Mass Storage driver...
Sep 12 19:52:56 t42p usb-storage 4-3:1.0: usb_probe_interface
Sep 12 19:52:56 t42p usb-storage 4-3:1.0: usb_probe_interface - got id
Sep 12 19:52:56 t42p scsi0 : SCSI emulation for USB Mass Storage devices
Sep 12 19:52:56 t42p usb-storage: device found at 6
Sep 12 19:52:56 t42p usb-storage: waiting for device to settle before scanning
Sep 12 19:52:56 t42p usbcore: registered new driver usb-storage
Sep 12 19:52:56 t42p USB Mass Storage support registered.
Sep 12 19:53:01 t42p Vendor: SAMSUNG Model: MP0804H Rev: 0 0
Sep 12 19:53:01 t42p Type: Direct-Access ANSI SCSI revision: 00
Sep 12 19:53:01 t42p usb-storage: device scan complete
Sep 12 19:53:01 t42p SCSI device sda: 156368016 512-byte hdwr sectors (80060 MB)
Sep 12 19:53:01 t42p sda: assuming drive cache: write through
Sep 12 19:53:01 t42p SCSI device sda: 156368016 512-byte hdwr sectors (80060 MB)
Sep 12 19:53:01 t42p sda: assuming drive cache: write through
Sep 12 19:53:01 t42p sda: sda1
Sep 12 19:53:01 t42p Attached scsi disk sda at scsi0, channel 0, id 0, lun 0
Sep 12 19:53:11 t42p ReiserFS: sda1: found reiserfs format "3.6" with standard journal
Sep 12 19:53:18 t42p ReiserFS: sda1: using ordered data mode
Sep 12 19:53:18 t42p ReiserFS: sda1: journal params: device sda1, size 8192, journal first block 18, max trans len 1024, max batch 900, max commit age 30, max trans age 30
Sep 12 19:53:18 t42p ReiserFS: sda1: checking transaction log (sda1)
Sep 12 19:53:18 t42p ReiserFS: sda1: Using r5 hash to sort names

[...]

Sep 13 20:53:47 t42p ehci_hcd 0000:00:1d.7: port 3 high speed
Sep 13 20:53:47 t42p ehci_hcd 0000:00:1d.7: GetStatus port 3 status 001005 POWER sig=se0 PE CONNECT
Sep 13 20:53:47 t42p usb 4-3: reset high speed USB device using ehci_hcd and address 6
Sep 13 20:53:47 t42p ehci_hcd 0000:00:1d.7: port 3 high speed
Sep 13 20:53:47 t42p ehci_hcd 0000:00:1d.7: GetStatus port 3 status 001005 POWER sig=se0 PE CONNECT
Sep 13 20:54:13 t42p ehci_hcd 0000:00:1d.7: port 3 high speed
Sep 13 20:54:13 t42p ehci_hcd 0000:00:1d.7: GetStatus port 3 status 001005 POWER sig=se0 PE CONNECT
Sep 13 20:54:13 t42p usb 4-3: reset high speed USB device using ehci_hcd and address 6
Sep 13 20:54:13 t42p ehci_hcd 0000:00:1d.7: port 3 high speed
Sep 13 20:54:13 t42p ehci_hcd 0000:00:1d.7: GetStatus port 3 status 001005 POWER sig=se0 PE CONNECT
Sep 13 20:54:13 t42p scsi: Device offlined - not ready after error recovery: host 0 channel 0 id 0 lun 0
Sep 13 20:54:13 t42p SCSI error : <0 0 0 0> return code = 0x50000
Sep 13 20:54:13 t42p end_request: I/O error, dev sda, sector 61921479
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p Buffer I/O error on device sda1, logical block 7740177
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p Buffer I/O error on device sda1, logical block 618
Sep 13 20:54:13 t42p lost page write due to I/O error on sda1
Sep 13 20:54:13 t42p Buffer I/O error on device sda1, logical block 619
Sep 13 20:54:13 t42p lost page write due to I/O error on sda1
Sep 13 20:54:13 t42p Buffer I/O error on device sda1, logical block 620
Sep 13 20:54:13 t42p lost page write due to I/O error on sda1
Sep 13 20:54:13 t42p Buffer I/O error on device sda1, logical block 621
Sep 13 20:54:13 t42p lost page write due to I/O error on sda1
Sep 13 20:54:13 t42p Buffer I/O error on device sda1, logical block 622
Sep 13 20:54:13 t42p lost page write due to I/O error on sda1
Sep 13 20:54:13 t42p Buffer I/O error on device sda1, logical block 623
Sep 13 20:54:13 t42p lost page write due to I/O error on sda1
Sep 13 20:54:13 t42p Buffer I/O error on device sda1, logical block 624
Sep 13 20:54:13 t42p lost page write due to I/O error on sda1
Sep 13 20:54:13 t42p Buffer I/O error on device sda1, logical block 625
Sep 13 20:54:13 t42p lost page write due to I/O error on sda1
Sep 13 20:54:13 t42p Buffer I/O error on device sda1, logical block 626
Sep 13 20:54:13 t42p lost page write due to I/O error on sda1
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p REISERFS: abort (device sda1): Journal write error in flush_commit_list
Sep 13 20:54:13 t42p REISERFS: Aborting journal for filesystem on sda1
Sep 13 20:54:13 t42p ------------[ cut here ]------------
Sep 13 20:54:13 t42p kernel BUG at /usr/src/linux-2.6.13/fs/reiserfs/journal.c:666!
Sep 13 20:54:13 t42p invalid operand: 0000 [#1]
Sep 13 20:54:13 t42p PREEMPT
Sep 13 20:54:13 t42p CPU: 0
Sep 13 20:54:13 t42p EIP: 0060:[<b01c3aab>] Tainted: P VLI
Sep 13 20:54:13 t42p EFLAGS: 00010246 (2.6.13)
Sep 13 20:54:13 t42p EIP is at submit_ordered_buffer+0x1b/0x40
Sep 13 20:54:13 t42p eax: 0000002c ebx: 00000001 ecx: eb37eb28 edx: b3c0ebdc
Sep 13 20:54:13 t42p esi: efd63e44 edi: f0cc20d4 ebp: dc382c58 esp: efd63dfc
Sep 13 20:54:13 t42p ds: 007b es: 007b ss: 0068
Sep 13 20:54:13 t42p Process reiserfs/0 (pid: 238, threadinfo=efd62000 task=efcea020)
Sep 13 20:54:13 t42p Stack: 010758ec 7a9313dc b01c3bec b3c0ebdc efd63e44 00000001 b01c3c79 efd63e44
Sep 13 20:54:13 t42p b3b10d48 efd63e3c fffffffb b01c4025 efd63e44 b3b10d48 f0cc20d4 b01c3bb0
Sep 13 20:54:13 t42p eb37eb28 b489d568 b3c0ebdc b3fb921c b3fb92ec b3fb9320 b3fb9354 b3fb9388
Sep 13 20:54:13 t42p Call Trace:
Sep 13 20:54:13 t42p [<b01c3bec>] write_ordered_chunk+0x3c/0x60
Sep 13 20:54:13 t42p [<b01c3c79>] add_to_chunk+0x69/0xa0
Sep 13 20:54:13 t42p [<b01c4025>] write_ordered_buffers+0xd5/0x270
Sep 13 20:54:13 t42p [<b01c3bb0>] write_ordered_chunk+0x0/0x60
Sep 13 20:54:13 t42p [<b01c4748>] flush_commit_list+0x448/0x4d0
Sep 13 20:54:13 t42p [<b01c852e>] flush_async_commits+0x8e/0x90
Sep 13 20:54:13 t42p [<b012e7e3>] worker_thread+0x233/0x300
Sep 13 20:54:13 t42p [<b01c84a0>] flush_async_commits+0x0/0x90
Sep 13 20:54:13 t42p [<b0119800>] default_wake_function+0x0/0x20
Sep 13 20:54:13 t42p [<b0119800>] default_wake_function+0x0/0x20
Sep 13 20:54:13 t42p [<b012e5b0>] worker_thread+0x0/0x300
Sep 13 20:54:13 t42p [<b0133199>] kthread+0xa9/0xf0
Sep 13 20:54:13 t42p [<b01330f0>] kthread+0x0/0xf0
Sep 13 20:54:13 t42p [<b0101395>] kernel_thread_helper+0x5/0x10
Sep 13 20:54:13 t42p Code: 01 00 00 00 e8 97 29 fa ff 83 c4 08 c3 8d 76 00 83 ec 08 8b 54 24 0c ff 42 0c c7 42 24 10 3a 1c b0 0f ba 32 01 8b 02 a8 01 75 08 <0f> 0b 9a 02 18 7e 37 b0 89 54 24 04 c7 04 24 01 00 00 00 e8 5d
Sep 13 20:54:13 t42p Badness in do_exit at /usr/src/linux-2.6.13/kernel/exit.c:787
Sep 13 20:54:13 t42p [<b01200d9>] do_exit+0x419/0x460
Sep 13 20:54:13 t42p [<b0104940>] do_invalid_op+0x0/0xb0
Sep 13 20:54:13 t42p [<b01045bb>] die+0x18b/0x190
Sep 13 20:54:13 t42p [<b01049e6>] do_invalid_op+0xa6/0xb0
Sep 13 20:54:13 t42p [<b0166de0>] bio_alloc+0x20/0x30
Sep 13 20:54:13 t42p [<b01c3aab>] submit_ordered_buffer+0x1b/0x40
Sep 13 20:54:13 t42p [<b021ad43>] radix_tree_gang_lookup_tag+0x63/0x80
Sep 13 20:54:13 t42p [<b0101a87>] __switch_to+0x27/0x210
Sep 13 20:54:13 t42p [<b035c47f>] schedule+0x34f/0x6c0
Sep 13 20:54:13 t42p [<b035c4f1>] schedule+0x3c1/0x6c0
Sep 13 20:54:13 t42p [<b0103d8b>] error_code+0x4f/0x54
Sep 13 20:54:13 t42p [<b011007b>] get_cur_freq+0x5b/0x60
Sep 13 20:54:13 t42p [<b01c3aab>] submit_ordered_buffer+0x1b/0x40
Sep 13 20:54:13 t42p [<b01c3bec>] write_ordered_chunk+0x3c/0x60
Sep 13 20:54:13 t42p [<b01c3c79>] add_to_chunk+0x69/0xa0
Sep 13 20:54:13 t42p [<b01c4025>] write_ordered_buffers+0xd5/0x270
Sep 13 20:54:13 t42p [<b01c3bb0>] write_ordered_chunk+0x0/0x60
Sep 13 20:54:13 t42p [<b01c4748>] flush_commit_list+0x448/0x4d0
Sep 13 20:54:13 t42p [<b01c852e>] flush_async_commits+0x8e/0x90
Sep 13 20:54:13 t42p [<b012e7e3>] worker_thread+0x233/0x300
Sep 13 20:54:13 t42p [<b01c84a0>] flush_async_commits+0x0/0x90
Sep 13 20:54:13 t42p [<b0119800>] default_wake_function+0x0/0x20
Sep 13 20:54:13 t42p [<b0119800>] default_wake_function+0x0/0x20
Sep 13 20:54:13 t42p [<b012e5b0>] worker_thread+0x0/0x300
Sep 13 20:54:13 t42p [<b0133199>] kthread+0xa9/0xf0
Sep 13 20:54:13 t42p [<b01330f0>] kthread+0x0/0xf0
Sep 13 20:54:13 t42p [<b0101395>] kernel_thread_helper+0x5/0x10
Sep 13 20:54:13 t42p ReiserFS: sda1: warning: clm-6006: writing inode 5602431 on readonly FS
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:13 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p printk: 13 messages suppressed.
Sep 13 20:54:18 t42p Buffer I/O error on device sda1, logical block 7776265
Sep 13 20:54:18 t42p lost page write due to I/O error on sda1
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 20:54:18 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 21:03:49 t42p scsi0 (0:0): rejecting I/O to offline device
Sep 13 21:03:49 t42p ReiserFS: sda1: warning: zam-7001: io error in reiserfs_find_entry
Sep 13 21:08:02 t42p usb 4-4.4: unlink qh8-0601/ef58b200 start 7 [8/16 us]
Sep 13 21:09:21 t42p SysRq : Keyboard mode set to XLATE
Sep 13 21:09:25 t42p SysRq : Emergency Sync
Sep 13 21:09:29 t42p SysRq : HELP : loglevel0-8 reBoot tErm Full kIll saK showMem Nice powerOff showPc unRaw Sync showTasks Unmount
Sep 13 21:09:36 t42p SysRq : Emergency Remount R/O


Attachments:
(No filename) (970.00 B)
kern.log-09-13 (13.83 kB)
Download all attachments

2005-09-23 21:41:03

by Mateusz Berezecki

[permalink] [raw]
Subject: Re: reiserfs problem after usb hard drive disconnected

Arnaud Boulan <[email protected]> wrote:
| Hello,
|
| I am using an external USB hard drive, and i hit a bug in the reiserfs code
| after the drive got disconnected by a power problem. You can see this in the
| attached logs.
| The disconnection of the hard drive was not done manually, it has been
| offlined "spontaneously" by what seems to be a power loss. In fact, i suspect
| i have bad power at home since i have had other similar problems with another
| device (an usb tv tuner)
|
| However, i already have disconnected my drive manually a few times by mistake,
| and i always recovered from that by unmouting, running reiserfsck and
| remounting the device.
|
| This time, although i can consider it is a hardware problem, it would have
| been nice if the kernel had also recovered from the USB problem. Instead, i
| couldn't unmount the filesystem and the usb stack was stucked, leaving no
| choice but to reboot...
|

Same problem here. Power went down and I had no other choice than to
reboot. FS(ext3) got recovered almost completely but some page
writes were lost. I were unable to unmount the disk too. I guess this
is more a usb storage problem than a fs one (?)


Sep 22 09:57:46 pc2 kernel: scsi0 (0:0): rejecting I/O to dead device
Sep 22 09:57:46 pc2 kernel: Buffer I/O error on device sda1, logical
block 24884044
Sep 22 09:57:46 pc2 kernel: lost page write due to I/O error on sda1
Sep 22 09:57:46 pc2 kernel: scsi0 (0:0): rejecting I/O to dead device
Sep 22 09:57:46 pc2 kernel: journal_bmap: journal block not found at
offset 3144 on sda1
Sep 22 09:57:46 pc2 kernel: Aborting journal on device sda1.
Sep 22 09:57:46 pc2 kernel: scsi0 (0:0): rejecting I/O to dead device
Sep 22 09:57:46 pc2 kernel: Buffer I/O error on device sda1, logical
block 527
Sep 22 09:57:46 pc2 kernel: lost page write due to I/O error on sda1
Sep 22 09:58:13 pc2 kernel: ext3_abort called.
Sep 22 09:58:13 pc2 kernel: EXT3-fs error (device sda1):
ext3_journal_start_sb: Detected aborted journal
Sep 22 09:58:13 pc2 kernel: Remounting filesystem read-only


Mateusz

--
@..@ Mateusz Berezecki
(----) [email protected] http://mateusz.agrest.org
( >__< ) PGP: 5F1C 86DF 89DB BFE9 899E 8CBE EB60 B7A7 43F9 5808
^^ ~~ ^^


Attachments:
(No filename) (2.21 kB)
signature.asc (189.00 B)
Digital signature
Download all attachments