RE: usb-strage not working in the latest kernel 2.4

RE: usb-strage not working in the latest kernel 2.4

[Tomita, Haruo]

Hi Alan,

Thank you for your help and useful comment.

Alan wrote:

Haruo> I tested using kernel 2.4.22-bk6. It failed by the 
Haruo> following two patterns.
Haruo> 1. Bulk command transfer result=-104
Haruo> 2.  usb_stor_bulk_msg() returned -32
 
Alan> This was two different failures on two separate runs?

Yes. I tested separately. 
It is because it is hard to generate an error in testing
two simultaneously. When testing simultaneously,
much time is required by the time it generates an error.
The reason is not known.

Haruo> 1. Bulk command transfer result=-104

[skip]

Alan> This was the same sort of pattern we saw before.  No data was 
Alan> transferred from the drive, and the command timed out
Ａｌａｎ> and was aborted.

I am also looking at this pattern repeatedly.
It seems that a device does not answer in the case of kernel 2.4.
However, when operating by kernel 2.6 and two or more devices
are connected simultaneously, it operates. 
I cannot think the error of a device.

[skip]

Haruo> hub.c: port 1, portstatus 503, change 10, 480 Mb/s
Haruo> usb.c: ignoring set_interface for dev 3, iface 0, alt 0
Haruo> usb-storage: Examinging driver usb-storage...skipping ourselves.
Haruo> usb-storage: bus_reset() complete
 
Alan> This time the bus_reset() routine worked, which is an 
Alan> improvement over 
Alan> what you were seeing before.  But the device still didn't respond:

If a device becomes an error, it seems that reset is not effective.
Using ioctl (fd, USBDEVFS_RESET, 0) and this device,  
Is checking whether a device can reset correctly by 
performing effective?

[skip]
 
Haruo> scsi: device set offline - not ready or command retry 
Haruo> failed after bus reset: host 0 channel 0 id 0 lun 0
Haruo> SCSI cdrom error : host 0 channel 0 id 0 lun 0 return code = 50000
Haruo>  I/O error: dev 0b:00, sector 473224
Haruo>  I/O error: dev 0b:00, sector 473228
Haruo>  I/O error: dev 0b:00, sector 473476
Haruo>  I/O error: dev 0b:00, sector 473224

Alan> Once that happened there were no error recovery procedures 
Alan> left to try, so 
Alan> the driver gave up and marked the device offline.

Doesn't a device receive any commands?

Alan> There's something a little strange I just noticed in the log.  It's
Alan> probably not related to your problem, but we should check it 
Alan> out anyway.  
Alan> Please send a copy of /proc/bus/usb/devices, with the drive 
Alan> plugged in.

OK. 
 
> > 2.  usb_stor_bulk_msg() returned -32
 
Alan> Under what circumstances did this error occur?  It must have 
Alan> been quite different from the other error.

The used device(Logitec LCW-WIN40U2) is different. 
It is the same circumstances except a device.


Haruo> usb-storage: usb_stor_transfer_partial(): xfer 2048 bytes
Haruo> usb-storage: usb_stor_bulk_msg() returned -32 xferred 0/2048
Haruo> usb-storage: -- code: 0x70, key: 0x2, ASC: 0x4, ASCQ: 0x1
Haruo> usb-storage: Not Ready: LUN in process of becoming ready

Alan> That's normal.  It means that the drive was just turned on or 
Alan> reset, and it's not yet ready to execute commands.

I see.

Haruo> usb-storage: Unit Attention: not ready to ready transition
 
Haruo> More of the same.  This means that the drive just finished its 
Haruo> initialization and now it's ready to operate.
Haruo> usb-storage: scsi cmd done, result=0x2
Haruo> usb-storage: *** thread sleeping.
Haruo>  I/O error: dev 0b:00, sector 804236
Haruo>  I/O error: dev 0b:00, sector 804240
Haruo>  I/O error: dev 0b:00, sector 804492
Haruo>  I/O error: dev 0b:00, sector 804236
 
Alan> But apparently at this point the SCSI driver got tired of 
Alan> waiting and set 
Alan> the drive offline.  This should be fixed in the SCSI driver.  

If a SCSI driver performs retry, 
is it that usb-storage can also restart processing?

Alan> That's why 
Alan> it's important to know what you were doing when the error occurred.

Ok, echo is put into a script, and as processing can be distinguished, test it.

Thanks again,
Haruo 
-
To unsubscribe from this list: send the line "unsubscribe linux-scsi" in
the body of a message to majordomo@vger.kernel.org
More majordomo info at  http://vger.kernel.org/majordomo-info.html

RE: usb-strage not working in the latest kernel 2.4

[Alan Stern]

On Sun, 7 Sep 2003, Tomita, Haruo wrote:

> I am also looking at this pattern repeatedly.
> It seems that a device does not answer in the case of kernel 2.4.
> However, when operating by kernel 2.6 and two or more devices
> are connected simultaneously, it operates. 
> I cannot think the error of a device.

Don't be too sure.  It could be that 2.4 and 2.6 are sending different 
data to the device, but for both systems the data is correct and the 
device _should_ work correctly.  Maybe it's a device error that causes it 
to work okay with the data from 2.6 but not the data from 2.4.

> Alan> This time the bus_reset() routine worked, which is an 
> Alan> improvement over 
> Alan> what you were seeing before.  But the device still didn't respond:
> 
> If a device becomes an error, it seems that reset is not effective.
> Using ioctl (fd, USBDEVFS_RESET, 0) and this device,  
> Is checking whether a device can reset correctly by 
> performing effective?

Go ahead and try it.  I expect it will reset correctly when you test it 
like this, but it won't reset correctly after one of those errors occurs 
and the device stops responding.  That's just a guess.

> Alan> Once that happened there were no error recovery procedures 
> Alan> left to try, so 
> Alan> the driver gave up and marked the device offline.
> 
> Doesn't a device receive any commands?

The system sent commands, but the device didn't respond to them.  However, 
it's possible that this patch will help.  Try it out and see what happens.

--- 2.4.22/drivers/usb/storage/protocol.c.orig	Mon Aug 25 07:44:42 2003
+++ 2.4.22/drivers/usb/storage/protocol.c	Fri Sep  5 16:20:26 2003
@@ -237,6 +237,10 @@
 	 * a unsigned char cmnd[12], so we know we have storage available
 	 */
 
+	/* Pad the ATAPI command with zeros */
+	for (; srb->cmd_len<12; srb->cmd_len++)
+		srb->cmnd[srb->cmd_len] = 0;
+
 	/* set command length to 12 bytes (this affects the transport layer) */
 	srb->cmd_len = 12;
 
> > > 2.  usb_stor_bulk_msg() returned -32
>  
> Alan> Under what circumstances did this error occur?  It must have 
> Alan> been quite different from the other error.
> 
> The used device(Logitec LCW-WIN40U2) is different. 
> It is the same circumstances except a device.

But when the error occurred, had you just plugged in the drive?  Or had it 
been running already for some time?  What were you doing when the error 
occurred?  Was there some reason that the drive returned an error code 
meaning it had just been reset?

> If a SCSI driver performs retry, 
> is it that usb-storage can also restart processing?

usb-storage doesn't do its own retries.  Only the SCSI driver does.  But
when the SCSI driver retries a command, it sends a new copy of the command
to usb-storage, so usb-storage sends the new copy of the command to the 
device.

Alan Stern

Re: usb-strage not working in the latest kernel 2.4

[David Brownell]

Tomita, Haruo wrote:
> It is because it is hard to generate an error in testing
> two simultaneously. When testing simultaneously,
> much time is required by the time it generates an error.

This is basically a good thing:  the root causes are
getting fixed one by one, so it needs more time before
one of the remaining problems appears.

Of course, that means it's harder to find/fix whatever
problem you're hitting ... :(



> The reason is not known.
> 
> Haruo> 1. Bulk command transfer result=-104
> 
> [skip]
> 
> Alan> This was the same sort of pattern we saw before.  No data was 
> Alan> transferred from the drive, and the command timed out
> > and was aborted.
> 
> I am also looking at this pattern repeatedly.

Some sort of USB-level trace would be very useful here,
such as from a CATC capture.


> It seems that a device does not answer in the case of kernel 2.4.
> However, when operating by kernel 2.6 and two or more devices
> are connected simultaneously, it operates. 
> I cannot think the error of a device.

I'd almost agree, except that the device stops responding;
and seems to stop responding to anything except (cases I've
seen) that it'll accept more OUT packets ... maybe treating
them as data to write to the disk (one hypothesis).

When a device stops responding, it's hard to think the
problem is anything other than with the device.  (Though
I still don't see where the storage class-specific reset
gets sent by usb-storage, maybe that'd clean up better.)


What's curious is that "two or more devices connected"
behavior.  Are they both doing I/O at the same time?
Having a second active device would change a few things:

  - The EHCI controller will switch between endpoints,
    doing some transfers from one device then the next.

    A second active device means longer gaps between
    packets going to the device ... so if one of those
    devices can't handle continuous streaming of high
    speed packets, it may lock up except when that
    second device increases those gaps.

    Yes, we DO seem to have started seeing such storage
    adapters.  Ones from GeneSys appear to have that
    problem with fast EHCI controllers, like the one in
    NForce2 (which can issue two or more back-to-back
    transfers using the "park" mechanism).

  - Since usb-storage on 2.4 doesn't stream usb-storage
    transfers (64 writes of one page, vs one big write
    of 64 pages), the EHCI controller spends lots of
    time unlinking bulk endpoints (QHs) from its i/o
    queue and linking them right back again.

    The VIA chips have been very sensitive, even buggy,
    in how they handle this logic.  I've been unable to
    convince myself the current EHCI driver has a bug in
    this area -- though maybe it's possible someone else
    would have better luck finding one.

    If this is a factor, then you should be able to
    change the behavior by increasing EHCI_SHRINK_JIFFIES
    (top of ehci-hcd.c), maybe to HZ/100 or so.  I know
    that increasing that from zero was a big win in some
    VT6202 (stability) and VT8235 (performance on 2.4)
    testing, but the other EHCI silicon didn't much care.

That's about all the insight I have to share on this
at the moment.

- Dave

Re: usb-strage not working in the latest kernel 2.4

[Alan Stern]

On Mon, 8 Sep 2003, David Brownell wrote:

> > It seems that a device does not answer in the case of kernel 2.4.
> > However, when operating by kernel 2.6 and two or more devices
> > are connected simultaneously, it operates. 
> > I cannot think the error of a device.
> 
> I'd almost agree, except that the device stops responding;
> and seems to stop responding to anything except (cases I've
> seen) that it'll accept more OUT packets ... maybe treating
> them as data to write to the disk (one hypothesis).
> 
> When a device stops responding, it's hard to think the
> problem is anything other than with the device.  (Though
> I still don't see where the storage class-specific reset
> gets sent by usb-storage, maybe that'd clean up better.)

The reset was sent three times.  Twice in response to command aborts and
once at the express request of the SCSI layer.  You can see it in the
logs:

usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 01 ce 22 00 00 3f 00 00 00
usb-storage: Bulk command S 0x43425355 T 0x93534f Trg 0 LUN 0 L 129024 F 128 CL 12
usb-storage: command_abort() called
usb-storage: Bulk command transfer result=-104
usb-storage: -- transport indicates command was aborted

Here's one:
usb-storage: Bulk reset requested
usb_control/bulk_msg: timeout
usb-storage: Bulk soft reset failed -110
usb-storage: scsi command aborted
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command TEST_UNIT_READY (6 bytes)
usb-storage: 00 00 00 00 00 00 00 00 3f 00 00 00
usb-storage: Bulk command S 0x43425355 T 0x935350 Trg 0 LUN 0 L 0 F 0 CL 12
usb-storage: Bulk command transfer result=0
usb-storage: Attempting to get CSW...
usb-storage: command_abort() called
usb-storage: -- transport indicates command was aborted

Here's another:
usb-storage: Bulk reset requested
usb_control/bulk_msg: timeout
usb-storage: Bulk soft reset failed -110
usb-storage: scsi command aborted
usb-storage: *** thread sleeping.

Here's the third:
usb-storage: device_reset() called
usb-storage: Bulk reset requested
usb_control/bulk_msg: timeout
usb-storage: Bulk soft reset failed -110

Those "Bulk reset requested" messages refer to the class-specific device 
reset command, not a clear-halt for a bulk pipe.

Alan Stern

Re: usb-strage not working in the latest kernel 2.4

[David Brownell]

Alan Stern wrote:
> On Mon, 8 Sep 2003, David Brownell wrote:
> 

>>When a device stops responding, it's hard to think the
>>problem is anything other than with the device.  (Though
>>I still don't see where the storage class-specific reset
>>gets sent by usb-storage, maybe that'd clean up better.)
> 
> 
> The reset was sent three times.  Twice in response to command aborts and
> once at the express request of the SCSI layer.  You can see it in the
> logs:

OK, I must have missed it when I was scanning for that
control request ... likely because this one's using the
usbcore method, not the usb-storage one I grepped for.

So I'm back to "hard to think the problem is anything
other than with the device".

- Dave

RE: usb-strage not working in the latest kernel 2.4

[Tomita, Haruo]

Hi Alan,

Thank you for your help.

Alan> This time the bus_reset() routine worked, which is an 
Alan> improvement over 
Alan> what you were seeing before.  But the device still didn't respond:
 
Haruo> If a device becomes an error, it seems that reset is not effective.
Haruo> Using ioctl (fd, USBDEVFS_RESET, 0) and this device,  
Haruo> Is checking whether a device can reset correctly by 
Haruo> performing effective?
 
Alan> Go ahead and try it.  I expect it will reset correctly when 
Alan> you test it like this, but it won't reset correctly after one of
Alan>  those errors occurs and the device stops responding.  
Alan>  That's just a guess.

Using ioctl (fd, USBDEVFS_RESET, 0), I tested reset. 
usb-storage of USB2.0 was successful.
When FDD was used in the case of USB1.1, 
mount became impossible after reset.

I adapt in your patch and continue a test.
--
Haruo


-------------------------------------------------------
This sf.net email is sponsored by:ThinkGeek
Welcome to geek heaven.
http://thinkgeek.com/sf
_______________________________________________
linux-usb-devel@lists.sourceforge.net
To unsubscribe, use the last form field at:
https://lists.sourceforge.net/lists/listinfo/linux-usb-devel

RE: usb-strage not working in the latest kernel 2.4

[Alan Stern]

On Wed, 10 Sep 2003, Tomita, Haruo wrote:

> Using ioctl (fd, USBDEVFS_RESET, 0), I tested reset. 
> usb-storage of USB2.0 was successful.
> When FDD was used in the case of USB1.1, 
> mount became impossible after reset.

What do you mean?  What does the log show?

Alan Stern

RE: usb-strage not working in the latest kernel 2.4

[Tomita, Haruo]

Hi Alan,

Haruo> Using ioctl (fd, USBDEVFS_RESET, 0), I tested reset. 
Haruo> usb-storage of USB2.0 was successful.
Haruo> When FDD was used in the case of USB1.1, 
Haruo> mount became impossible after reset.
 
Alan> What do you mean?  What does the log show?

I'm sorry. I wrote the following programs.
The execution result is as follows.

[root@Andromeda root]# cd ~tomita/usbreset
[root@Andromeda usbreset]# ./usbreset 
bus = /proc/bus/usb/001/002
合計 864
-rwxr-xr-x    1 root     root        46481  8月 28 12:05 1.18j1.wks
-rwxr-xr-x    1 root     root        46481  8月 28 12:05 1.18j2.wks
-rwxr-xr-x    1 root     root        46481  8月 28 12:05 1.18j3.wks
-rwxr-xr-x    1 root     root        11829  9月 10 17:30 15.50s.tar.gz
-rwxr-xr-x    1 root     root           16  3月  6  2003 S99zzz
-rwxr-xr-x    1 root     root          914  4月 10 08:58 abort-test.patch
-rwxr-xr-x    1 root     root          938  4月 23  2002 autoftp.bat
-rwxr-xr-x    1 root     root         1412  9月 11 13:28 fdd.txt
-rwxr-xr-x    1 root     root         3703  3月  6  2003 halt
-rwxr-xr-x    1 root     root          378  8月 27 17:57 iozone_test.sh
-rwxr-xr-x    1 root     root        46481  9月  9 08:51 mr518_128MB_1.wks
-rwxr-xr-x    1 root     root        46481  9月  9 08:51 mr518_128MB_2.wks
-rwxr-xr-x    1 root     root        46481  9月  9 08:51 mr518_128MB_3.wks
-rwxr-xr-x    1 root     root        46481  9月  9 14:32 mr518_256MB_1.wks
-rwxr-xr-x    1 root     root        46481  9月  9 14:32 mr518_256MB_2.wks
-rwxr-xr-x    1 root     root        46481  9月  9 14:32 mr518_256MB_3.wks
-rwxr-xr-x    1 root     root        46481  9月  8 16:38 mr518_64MB_1.wks
-rwxr-xr-x    1 root     root        46481  9月  8 16:38 mr518_64MB_2.wks
-rwxr-xr-x    1 root     root        46481  9月  8 16:38 mr518_64MB_3.wks
-rwxr-xr-x    1 root     root       104118  8月 26 02:10 patch-0825
-rwxr-xr-x    1 root     root          759  5月 30 11:38 profile
-rwxr-xr-x    1 root     root        17081  9月  8 20:12 rhn_err1.jpg
-rwxr-xr-x    1 root     root        37151  9月  8 20:12 rhn_err2.jpg
-rwxr-xr-x    1 root     root         1369  9月  8 20:16 rpm.txt
-rwxr-xr-x    1 root     root          345  4月  3 20:15 script_cp.sh
-rwxr-xr-x    1 root     root          859  4月  3 20:16 script_cp_hdd.sh
-rwxr-xr-x    1 root     root          539  4月  5 14:41 script_cp_scsi.sh
-rwxr-xr-x    1 root     root        46481  8月 28 12:06 v2.00.71.wks
-rwxr-xr-x    1 root     root        46481  8月 28 12:06 v2.00.72.wks
-rwxr-xr-x    1 root     root        46481  8月 28 12:06 v2.00.73.wks
usb reset success 1
error: usbdevfs not opend
[root@Andromeda usbreset]# 

[root@Andromeda root]# mount /dev/sda /mnt/floppy/
/dev/sda: Input/output error
mount: you must specify the filesystem type

---- sample program 

int main(int argc, char **argv);
static int open_usbdevfs(char *bus);

int main(int argc, char **argv)
{
    char bus[22];
    int fd, ret=1, count=0;

    /* make bus name */ 
    snprintf(bus,22,"/proc/bus/usb/001/002");
#ifdef DEBUG
    printf("bus = %s\n", bus);	
#endif /* DEBUG */

    while (1) {
        /* device open */
        if ((fd=open_usbdevfs(bus)) < 0) {
    	    printf("error: usbdevfs not opend\n");
            ret=0;
	    break; 
        }
	
        system("mount /dev/sda /mnt/floppy");
        system("ls -l /mnt/floppy");
        system("umount /dev/sda");
	
        /* device reset */
        ret=ioctl(fd, USBDEVFS_RESET, 0);
        if (ret == -1) {
            printf("error: usbdevfs_reset error %d\n", ret);
            ret = 0;	    
            break;
	}
	/* device close */
	close (fd);
        　　sleep(10);
        　　count++;
	printf("usb reset success %d\n", count);		
    }

    if (fd)
        close(fd);
    
    return 1;
}

static int open_usbdevfs(char *bus) {
    int fd;

    fd = open(bus, O_RDWR);
    if (fd < 0)
        return -1;

    return fd;
}

--
Haruo
-
To unsubscribe from this list: send the line "unsubscribe linux-scsi" in
the body of a message to majordomo@vger.kernel.org
More majordomo info at  http://vger.kernel.org/majordomo-info.html

RE: usb-strage not working in the latest kernel 2.4

[Alan Stern]

On Thu, 11 Sep 2003, Tomita, Haruo wrote:

> Hi Alan,
> 
> Haruo> Using ioctl (fd, USBDEVFS_RESET, 0), I tested reset. 
> Haruo> usb-storage of USB2.0 was successful.
> Haruo> When FDD was used in the case of USB1.1, 
> Haruo> mount became impossible after reset.
>  
> Alan> What do you mean?  What does the log show?
> 
> I'm sorry. I wrote the following programs.
> The execution result is as follows.

What did the system log show?  Try turning on verbose usb debugging and 
run your test again.  This time post a copy of the system log.

Alan Stern

RE: usb-strage not working in the latest kernel 2.4

[Tomita, Haruo]

Alan,

Thank you for your help!!

Alan wrote;

Alan> What did the system log show?  Try turning on verbose usb 
Alan> debugging and run your test again.
Alan> This time post a copy of the system log.
 
USB DEBUG was not confirmed. The log is as follows.

</var/log/messages>
Sep 12 12:53:44 Andromeda syslogd 1.4.1: restart.
Sep 12 12:53:44 Andromeda syslog: syslogd startup succeeded
Sep 12 12:53:44 Andromeda kernel: klogd 1.4.1, log source = /proc/kmsg started.
Sep 12 12:53:44 Andromeda kernel: Linux version 2.4.22-10smp (root@Andromeda) (gcc version 2.96 20000731 (Red Hat Linux 7.3 2.96-110)) #1 SMP Fri Sep 12 11:36:55 JST 2003
Sep 12 12:53:44 Andromeda kernel: BIOS-provided physical RAM map:
Sep 12 12:53:44 Andromeda kernel:  BIOS-e820: 0000000000000000 - 0000000000099000 (usable)
Sep 12 12:53:44 Andromeda kernel:  BIOS-e820: 0000000000099000 - 000000000009c000 (reserved)
Sep 12 12:53:44 Andromeda kernel:  BIOS-e820: 00000000000e4400 - 0000000000100000 (reserved)
Sep 12 12:53:44 Andromeda kernel:  BIOS-e820: 0000000000100000 - 000000008fef0000 (usable)
Sep 12 12:53:44 Andromeda kernel:  BIOS-e820: 000000008fef0000 - 000000008feff000 (ACPI data)
Sep 12 12:53:44 Andromeda kernel:  BIOS-e820: 000000008feff000 - 000000008ff00000 (ACPI NVS)
Sep 12 12:53:44 Andromeda kernel:  BIOS-e820: 000000008ff00000 - 0000000090000000 (usable)
Sep 12 12:53:44 Andromeda kernel:  BIOS-e820: 00000000fec00000 - 00000000fec10000 (reserved)
Sep 12 12:53:44 Andromeda kernel:  BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved)
Sep 12 12:53:44 Andromeda kernel:  BIOS-e820: 00000000fffe0000 - 0000000100000000 (reserved)
Sep 12 12:53:44 Andromeda syslog: klogd startup succeeded
Sep 12 12:53:44 Andromeda kernel: 1408MB HIGHMEM available.
Sep 12 12:53:44 Andromeda kernel: 896MB LOWMEM available.
Sep 12 12:53:44 Andromeda kernel: found SMP MP-table at 000f6cb0
Sep 12 12:53:44 Andromeda kernel: hm, page 000f6000 reserved twice.
Sep 12 12:53:44 Andromeda kernel: hm, page 000f7000 reserved twice.
Sep 12 12:53:44 Andromeda kernel: hm, page 0009a000 reserved twice.
Sep 12 12:53:44 Andromeda kernel: hm, page 0009b000 reserved twice.
Sep 12 12:53:44 Andromeda kernel: On node 0 totalpages: 589824
Sep 12 12:53:44 Andromeda kernel: zone(0): 4096 pages.
Sep 12 12:53:44 Andromeda kernel: zone(1): 225280 pages.
Sep 12 12:53:44 Andromeda kernel: zone(2): 360448 pages.
Sep 12 12:53:44 Andromeda kernel: Intel MultiProcessor Specification v1.4
Sep 12 12:53:44 Andromeda kernel:     Virtual Wire compatibility mode.
Sep 12 12:53:44 Andromeda kernel: OEM ID: VIA    Product ID: VIA_694T APIC at: 0xFEE00000
Sep 12 12:53:44 Andromeda kernel: Processor #3 Pentium(tm) Pro APIC version 17
Sep 12 12:53:44 Andromeda kernel: Processor #0 Pentium(tm) Pro APIC version 17
Sep 12 12:53:44 Andromeda kernel: I/O APIC #1 Version 17 at 0xFEC00000.
Sep 12 12:53:44 Andromeda kernel: Enabling APIC mode: Flat.^IUsing 1 I/O APICs
Sep 12 12:53:44 Andromeda kernel: Processors: 2
Sep 12 12:53:44 Andromeda kernel: Kernel command line: ro root=/dev/hdc5
Sep 12 12:53:44 Andromeda kernel: Initializing CPU#0
Sep 12 12:53:44 Andromeda kernel: Detected 1263.480 MHz processor.
Sep 12 12:53:44 Andromeda kernel: Console: colour VGA+ 80x25
Sep 12 12:53:44 Andromeda kernel: Calibrating delay loop... 2523.13 BogoMIPS
Sep 12 12:53:44 Andromeda kernel: Memory: 2328484k/2359296k available (1272k kernel code, 30336k reserved, 512k data, 140k init, 1441728k highmem)
Sep 12 12:53:44 Andromeda kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes)
Sep 12 12:53:44 Andromeda kernel: Inode cache hash table entries: 262144 (order: 9, 2097152 bytes)
Sep 12 12:53:44 Andromeda kernel: Mount cache hash table entries: 512 (order: 0, 4096 bytes)
Sep 12 12:53:44 Andromeda kernel: Buffer cache hash table entries: 262144 (order: 8, 1048576 bytes)
Sep 12 12:53:44 Andromeda kernel: Page-cache hash table entries: 524288 (order: 9, 2097152 bytes)
Sep 12 12:53:44 Andromeda kernel: CPU: L1 I cache: 16K, L1 D cache: 16K
Sep 12 12:53:44 Andromeda kernel: CPU: L2 cache: 512K
Sep 12 12:53:44 Andromeda kernel: Intel machine check architecture supported.
Sep 12 12:53:44 Andromeda kernel: Intel machine check reporting enabled on CPU#0.
Sep 12 12:53:44 Andromeda kernel: Enabling fast FPU save and restore... done.
Sep 12 12:53:44 Andromeda portmap: portmap startup succeeded
Sep 12 12:53:44 Andromeda kernel: Enabling unmasked SIMD FPU exception support... done.
Sep 12 12:53:44 Andromeda kernel: Checking 'hlt' instruction... OK.
Sep 12 12:53:44 Andromeda kernel: POSIX conformance testing by UNIFIX
Sep 12 12:53:44 Andromeda kernel: mtrr: v1.40 (20010327) Richard Gooch (rgooch@atnf.csiro.au)
Sep 12 12:53:44 Andromeda kernel: mtrr: detected mtrr type: Intel
Sep 12 12:53:44 Andromeda kernel: CPU: L1 I cache: 16K, L1 D cache: 16K
Sep 12 12:53:44 Andromeda kernel: CPU: L2 cache: 512K
Sep 12 12:53:44 Andromeda kernel: Intel machine check reporting enabled on CPU#0.
Sep 12 12:53:44 Andromeda kernel: CPU0: Intel(R) Pentium(R) III CPU family      1266MHz stepping 01
Sep 12 12:53:44 Andromeda kernel: per-CPU timeslice cutoff: 1462.38 usecs.
Sep 12 12:53:44 Andromeda kernel: enabled ExtINT on CPU#0
Sep 12 12:53:44 Andromeda kernel: ESR value before enabling vector: 00000000
Sep 12 12:53:44 Andromeda kernel: ESR value after enabling vector: 00000000
Sep 12 12:53:44 Andromeda kernel: Booting processor 1/0 eip 2000
Sep 12 12:53:44 Andromeda kernel: Initializing CPU#1
Sep 12 12:53:44 Andromeda kernel: masked ExtINT on CPU#1
Sep 12 12:53:44 Andromeda kernel: ESR value before enabling vector: 00000000
Sep 12 12:53:44 Andromeda kernel: ESR value after enabling vector: 00000000
Sep 12 12:53:44 Andromeda kernel: Calibrating delay loop... 2523.13 BogoMIPS
Sep 12 12:53:44 Andromeda kernel: CPU: L1 I cache: 16K, L1 D cache: 16K
Sep 12 12:53:44 Andromeda kernel: CPU: L2 cache: 512K
Sep 12 12:53:44 Andromeda kernel: Intel machine check reporting enabled on CPU#1.
Sep 12 12:53:44 Andromeda kernel: CPU1: Intel(R) Pentium(R) III CPU family      1266MHz stepping 01
Sep 12 12:53:44 Andromeda kernel: Total of 2 processors activated (5046.27 BogoMIPS).
Sep 12 12:53:44 Andromeda kernel: ENABLING IO-APIC IRQs
Sep 12 12:53:44 Andromeda kernel: Setting 1 in the phys_id_present_map
Sep 12 12:53:44 Andromeda kernel: ...changing IO-APIC physical APIC ID to 1 ... ok.
Sep 12 12:53:44 Andromeda kernel: ..TIMER: vector=0x31 pin1=2 pin2=0
Sep 12 12:53:44 Andromeda kernel: testing the IO APIC.......................
Sep 12 12:53:44 Andromeda kernel: 
Sep 12 12:53:44 Andromeda kernel: .................................... done.
Sep 12 12:53:44 Andromeda kernel: Using local APIC timer interrupts.
Sep 12 12:53:44 Andromeda kernel: calibrating APIC timer ...
Sep 12 12:53:44 Andromeda kernel: ..... CPU clock speed is 1263.5086 MHz.
Sep 12 12:53:44 Andromeda kernel: ..... host bus clock speed is 133.0008 MHz.
Sep 12 12:53:44 Andromeda kernel: cpu: 0, clocks: 1330008, slice: 443336
Sep 12 12:53:44 Andromeda kernel: CPU0<T0:1330000,T1:886656,D:8,S:443336,C:1330008>
Sep 12 12:53:44 Andromeda kernel: cpu: 1, clocks: 1330008, slice: 443336
Sep 12 12:53:44 Andromeda kernel: CPU1<T0:1330000,T1:443328,D:0,S:443336,C:1330008>
Sep 12 12:53:44 Andromeda kernel: checking TSC synchronization across CPUs: passed.
Sep 12 12:53:44 Andromeda kernel: Waiting on wait_init_idle (map = 0x2)
Sep 12 12:53:44 Andromeda kernel: All processors have done init_idle
Sep 12 12:53:44 Andromeda kernel: PCI: PCI BIOS revision 2.10 entry at 0xfd7f5, last bus=2
Sep 12 12:53:44 Andromeda kernel: PCI: Using configuration type 1
Sep 12 12:53:44 Andromeda kernel: PCI: Probing PCI hardware
Sep 12 12:53:44 Andromeda kernel: PCI: Probing PCI hardware (bus 00)
Sep 12 12:53:44 Andromeda kernel: PCI: Using IRQ router VIA [1106/0686] at 00:07.0
Sep 12 12:53:44 Andromeda kernel: PCI: Enabling Via external APIC routing
Sep 12 12:53:44 Andromeda kernel: isapnp: Scanning for PnP cards...
Sep 12 12:53:44 Andromeda kernel: isapnp: No Plug & Play device found
Sep 12 12:53:44 Andromeda kernel: Linux NET4.0 for Linux 2.4
Sep 12 12:53:44 Andromeda kernel: Based upon Swansea University Computer Society NET3.039
Sep 12 12:53:44 Andromeda kernel: Initializing RT netlink socket
Sep 12 12:53:44 Andromeda kernel: apm: BIOS version 1.2 Flags 0x03 (Driver version 1.16)
Sep 12 12:53:44 Andromeda kernel: apm: disabled - APM is not SMP safe.
Sep 12 12:53:44 Andromeda kernel: Starting kswapd
Sep 12 12:53:44 Andromeda kernel: allocated 32 pages and 32 bhs reserved for the highmem bounces
Sep 12 12:53:44 Andromeda kernel: VFS: Disk quotas vdquot_6.5.1
Sep 12 12:53:44 Andromeda kernel: pty: 2048 Unix98 ptys configured
Sep 12 12:53:44 Andromeda kernel: Serial driver version 5.05c (2001-07-08) with MANY_PORTS MULTIPORT SHARE_IRQ SERIAL_PCI ISAPNP enabled
Sep 12 12:53:44 Andromeda kernel: ttyS00 at 0x03f8 (irq = 4) is a 16550A
Sep 12 12:53:44 Andromeda kernel: Real Time Clock Driver v1.10e
Sep 12 12:53:44 Andromeda kernel: floppy0: no floppy controllers found
Sep 12 12:53:44 Andromeda kernel: RAMDISK driver initialized: 16 RAM disks of 4096K size 1024 blocksize
Sep 12 12:53:44 Andromeda kernel: Uniform Multi-Platform E-IDE driver Revision: 7.00beta4-2.4
Sep 12 12:53:44 Andromeda kernel: ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
Sep 12 12:53:44 Andromeda kernel: VP_IDE: IDE controller at PCI slot 00:07.1
Sep 12 12:53:44 Andromeda kernel: VP_IDE: chipset revision 6
Sep 12 12:53:44 Andromeda kernel: VP_IDE: not 100%% native mode: will probe irqs later
Sep 12 12:53:44 Andromeda kernel: ide: Assuming 33MHz system bus speed for PIO modes; override with idebus=xx
Sep 12 12:53:44 Andromeda kernel: VP_IDE: VIA vt82c686b (rev 40) IDE UDMA100 controller on pci00:07.1
Sep 12 12:53:44 Andromeda kernel:     ide0: BM-DMA at 0x10a0-0x10a7, BIOS settings: hda:DMA, hdb:pio
Sep 12 12:53:44 Andromeda kernel: CMD649: IDE controller at PCI slot 00:0b.0
Sep 12 12:53:44 Andromeda kernel: CMD649: chipset revision 2
Sep 12 12:53:44 Andromeda kernel: CMD649: not 100%% native mode: will probe irqs later
Sep 12 12:53:44 Andromeda kernel:     ide1: BM-DMA at 0x10b0-0x10b7, BIOS settings: hdc:pio, hdd:pio
Sep 12 12:53:44 Andromeda kernel:     ide2: BM-DMA at 0x10b8-0x10bf, BIOS settings: hde:pio, hdf:pio
Sep 12 12:53:44 Andromeda kernel: hda: CD-224E, ATAPI CD/DVD-ROM drive
Sep 12 12:53:44 Andromeda kernel: hdc: IC35L040AVER07-0, ATA DISK drive
Sep 12 12:53:44 Andromeda kernel: blk: queue c032ba8c, I/O limit 4095Mb (mask 0xffffffff)
Sep 12 12:53:44 Andromeda kernel: ide0 at 0x1f0-0x1f7,0x3f6 on irq 14
Sep 12 12:53:44 Andromeda kernel: ide1 at 0x10d0-0x10d7,0x10c6 on irq 11
Sep 12 12:53:44 Andromeda kernel: hdc: attached ide-disk driver.
Sep 12 12:53:44 Andromeda kernel: hdc: host protected area => 1
Sep 12 12:53:44 Andromeda kernel: hdc: 80418240 sectors (41174 MB) w/1916KiB Cache, CHS=79780/16/63, UDMA(100)
Sep 12 12:53:44 Andromeda kernel: Partition check:
Sep 12 12:53:44 Andromeda kernel:  hdc: hdc1 hdc2 < hdc5 hdc6 hdc7 hdc8 hdc9 >
Sep 12 12:53:44 Andromeda kernel: ide: late registration of driver.
Sep 12 12:53:44 Andromeda kernel: md: md driver 0.90.0 MAX_MD_DEVS=256, MD_SB_DISKS=27
Sep 12 12:53:44 Andromeda kernel: md: Autodetecting RAID arrays.
Sep 12 12:53:44 Andromeda kernel: md: autorun ...
Sep 12 12:53:44 Andromeda kernel: md: ... autorun DONE.
Sep 12 12:53:44 Andromeda kernel: pci_hotplug: PCI Hot Plug PCI Core version: 0.5
Sep 12 12:53:44 Andromeda kernel: Initializing Cryptographic API
Sep 12 12:53:44 Andromeda kernel: NET4: Linux TCP/IP 1.0 for NET4.0
Sep 12 12:53:44 Andromeda kernel: IP Protocols: ICMP, UDP, TCP, IGMP
Sep 12 12:53:44 Andromeda kernel: IP: routing cache hash table of 32768 buckets, 256Kbytes
Sep 12 12:53:44 Andromeda kernel: TCP: Hash tables configured (established 262144 bind 65536)
Sep 12 12:53:44 Andromeda kernel: Linux IP multicast router 0.06 plus PIM-SM
Sep 12 12:53:44 Andromeda kernel: NET4: Unix domain sockets 1.0/SMP for Linux NET4.0.
Sep 12 12:53:44 Andromeda kernel: RAMDISK: Compressed image found at block 0
Sep 12 12:53:44 Andromeda kernel: Freeing initrd memory: 256k freed
Sep 12 12:53:44 Andromeda kernel: VFS: Mounted root (ext2 filesystem).
Sep 12 12:53:44 Andromeda kernel: SCSI subsystem driver Revision: 1.00
Sep 12 12:53:44 Andromeda kernel: scsi0 : Adaptec AIC7XXX EISA/VLB/PCI SCSI HBA DRIVER, Rev 6.2.36
Sep 12 12:53:44 Andromeda kernel:         <Adaptec aic7892 Ultra160 SCSI adapter>
Sep 12 12:53:44 Andromeda kernel:         aic7892: Ultra160 Wide Channel A, SCSI Id=7, 32/253 SCBs
Sep 12 12:53:44 Andromeda kernel: 
Sep 12 12:53:44 Andromeda kernel: blk: queue f7fcb218, I/O limit 4095Mb (mask 0xffffffff)
Sep 12 12:53:44 Andromeda kernel: Journalled Block Device driver loaded
Sep 12 12:53:44 Andromeda kernel: kjournald starting.  Commit interval 5 seconds
Sep 12 12:53:44 Andromeda kernel: EXT3-fs: mounted filesystem with ordered data mode.
Sep 12 12:53:44 Andromeda kernel: Freeing unused kernel memory: 140k freed
Sep 12 12:53:44 Andromeda kernel: Adding Swap: 2097104k swap-space (priority -1)
Sep 12 12:53:44 Andromeda kernel: usb.c: registered new driver usbdevfs
Sep 12 12:53:44 Andromeda kernel: usb.c: registered new driver hub
Sep 12 12:53:44 Andromeda kernel: usb-uhci.c: $Revision: 1.275 $ time 11:55:35 Sep 12 2003
Sep 12 12:53:44 Andromeda kernel: usb-uhci.c: High bandwidth mode enabled
Sep 12 12:53:44 Andromeda keytable: Loading keymap:  succeeded
Sep 12 12:53:44 Andromeda kernel: usb-uhci.c: USB UHCI at I/O 0x1080, IRQ 11
Sep 12 12:53:44 Andromeda kernel: usb-uhci.c: Detected 2 ports
Sep 12 12:53:44 Andromeda kernel: usb.c: new USB bus registered, assigned bus number 1
Sep 12 12:53:44 Andromeda kernel: Product: USB UHCI Root Hub
Sep 12 12:53:44 Andromeda kernel: SerialNumber: 1080
Sep 12 12:53:44 Andromeda kernel: hub.c: USB hub found
Sep 12 12:53:44 Andromeda kernel: hub.c: 2 ports detected
Sep 12 12:53:44 Andromeda keytable: Loading system font:  succeeded
Sep 12 12:53:44 Andromeda kernel: usb-uhci.c: v1.275:USB Universal Host Controller Interface driver
Sep 12 12:53:44 Andromeda kernel: hub.c: new USB device 00:07.2-1, assigned address 2
Sep 12 12:53:44 Andromeda kernel: Manufacturer: TEAC    
Sep 12 12:53:44 Andromeda kernel: Product: TEAC FD-05PUB   
Sep 12 12:53:44 Andromeda kernel: usb.c: USB device 2 (vend/prod 0x644/0x0) is not claimed by any active driver.
Sep 12 12:53:44 Andromeda kernel:   Length              = 18
Sep 12 12:53:44 Andromeda kernel:   DescriptorType      = 01
Sep 12 12:53:44 Andromeda kernel:   USB version         = 1.10
Sep 12 12:53:44 Andromeda kernel:   Vendor:Product      = 0644:0000
Sep 12 12:53:44 Andromeda kernel:   MaxPacketSize0      = 8
Sep 12 12:53:44 Andromeda kernel:   NumConfigurations   = 1
Sep 12 12:53:44 Andromeda kernel:   Device version      = 0.00
Sep 12 12:53:44 Andromeda kernel:   Device Class:SubClass:Protocol = 00:00:00
Sep 12 12:53:44 Andromeda kernel:     Per-interface classes
Sep 12 12:53:44 Andromeda kernel: Configuration:
Sep 12 12:53:44 Andromeda kernel:   bLength             =    9
Sep 12 12:53:44 Andromeda kernel:   bDescriptorType     =   02
Sep 12 12:53:44 Andromeda kernel:   wTotalLength        = 0027
Sep 12 12:53:44 Andromeda kernel:   bNumInterfaces      =   01
Sep 12 12:53:44 Andromeda kernel:   bConfigurationValue =   01
Sep 12 12:53:44 Andromeda kernel:   iConfiguration      =   00
Sep 12 12:53:44 Andromeda kernel:   bmAttributes        =   80
Sep 12 12:53:44 Andromeda kernel:   MaxPower            =  500mA
Sep 12 12:53:44 Andromeda kernel: 
Sep 12 12:53:44 Andromeda kernel:   Interface: 0
Sep 12 12:53:44 Andromeda kernel:   Alternate Setting:  0
Sep 12 12:53:44 Andromeda kernel:     bLength             =    9
Sep 12 12:53:44 Andromeda kernel:     bDescriptorType     =   04
Sep 12 12:53:44 Andromeda kernel:     bInterfaceNumber    =   00
Sep 12 12:53:44 Andromeda kernel:     bAlternateSetting   =   00
Sep 12 12:53:44 Andromeda kernel:     bNumEndpoints       =   03
Sep 12 12:53:44 Andromeda kernel:     bInterface Class:SubClass:Protocol =   08:04:00
Sep 12 12:53:44 Andromeda kernel:     iInterface          =   00
Sep 12 12:53:44 Andromeda kernel:     Endpoint:
Sep 12 12:53:44 Andromeda kernel:       bLength             =    7
Sep 12 12:53:44 Andromeda kernel:       bDescriptorType     =   05
Sep 12 12:53:44 Andromeda kernel:       bEndpointAddress    =   81 (in)
Sep 12 12:53:44 Andromeda kernel:       bmAttributes        =   02 (Bulk)
Sep 12 12:53:44 Andromeda kernel:       wMaxPacketSize      = 0040
Sep 12 12:53:44 Andromeda kernel:       bInterval           =   00
Sep 12 12:53:44 Andromeda kernel:     Endpoint:
Sep 12 12:53:44 Andromeda kernel:       bLength             =    7
Sep 12 12:53:44 Andromeda kernel:       bDescriptorType     =   05
Sep 12 12:53:44 Andromeda kernel:       bEndpointAddress    =   02 (out)
Sep 12 12:53:44 Andromeda kernel:       bmAttributes        =   02 (Bulk)
Sep 12 12:53:44 Andromeda kernel:       wMaxPacketSize      = 0040
Sep 12 12:53:44 Andromeda kernel:       bInterval           =   00
Sep 12 12:53:44 Andromeda kernel:     Endpoint:
Sep 12 12:53:44 Andromeda kernel:       bLength             =    7
Sep 12 12:53:44 Andromeda kernel:       bDescriptorType     =   05
Sep 12 12:53:44 Andromeda kernel:       bEndpointAddress    =   83 (in)
Sep 12 12:53:44 Andromeda kernel:       bmAttributes        =   03 (Interrupt)
Sep 12 12:53:44 Andromeda kernel:       wMaxPacketSize      = 0002
Sep 12 12:53:44 Andromeda kernel:       bInterval           =   ff
Sep 12 12:53:44 Andromeda kernel: Initializing USB Mass Storage driver...
Sep 12 12:53:44 Andromeda kernel: usb.c: registered new driver usb-storage
Sep 12 12:53:44 Andromeda kernel: scsi1 : SCSI emulation for USB Mass Storage devices
Sep 12 12:53:44 Andromeda kernel:   Vendor: TEAC      Model: FD-05PUB          Rev: 1026
Sep 12 12:53:44 Andromeda kernel:   Type:   Direct-Access                      ANSI SCSI revision: 02
Sep 12 12:53:44 Andromeda kernel: Attached scsi removable disk sda at scsi1, channel 0, id 0, lun 0
Sep 12 12:53:44 Andromeda kernel: usb-uhci.c: interrupt, status 3, frame# 1860
Sep 12 12:53:44 Andromeda kernel: sda: Unit Not Ready, sense:
Sep 12 12:53:44 Andromeda kernel: Current 00:00: sense key Not Ready
Sep 12 12:53:44 Andromeda kernel: Additional sense indicates Medium not present
Sep 12 12:53:44 Andromeda kernel: sda : READ CAPACITY failed.
Sep 12 12:53:44 Andromeda kernel: sda : status = 1, message = 00, host = 0, driver = 08 
Sep 12 12:53:44 Andromeda kernel: Current sd00:00: sense key Not Ready
Sep 12 12:53:44 Andromeda kernel: Additional sense indicates Medium not present
Sep 12 12:53:44 Andromeda kernel: sda : block size assumed to be 512 bytes, disk size 1GB.  
Sep 12 12:53:44 Andromeda kernel:  sda: I/O error: dev 08:00, sector 0
Sep 12 12:53:44 Andromeda kernel:  I/O error: dev 08:00, sector 0
Sep 12 12:53:44 Andromeda kernel:  unable to read partition table
Sep 12 12:53:44 Andromeda kernel: USB Mass Storage support registered.
Sep 12 12:53:44 Andromeda kernel: EXT3 FS 2.4-0.9.19, 19 August 2002 on ide1(22,5), internal journal
Sep 12 12:53:44 Andromeda kernel: kjournald starting.  Commit interval 5 seconds
Sep 12 12:53:44 Andromeda kernel: EXT3 FS 2.4-0.9.19, 19 August 2002 on ide1(22,1), internal journal
Sep 12 12:53:44 Andromeda kernel: EXT3-fs: mounted filesystem with ordered data mode.
Sep 12 12:53:44 Andromeda kernel: hda: attached ide-cdrom driver.
Sep 12 12:53:44 Andromeda kernel: hda: ATAPI 24X CD-ROM drive, 128kB Cache, UDMA(33)
Sep 12 12:53:44 Andromeda kernel: Uniform CD-ROM driver Revision: 3.12
Sep 12 12:53:44 Andromeda kernel: hda: DMA disabled
Sep 12 12:53:44 Andromeda kernel: parport_pc: Via 686A parallel port disabled in BIOS
Sep 12 12:53:44 Andromeda kernel: eepro100.c:v1.09j-t 9/29/99 Donald Becker http://www.scyld.com/network/eepro100.html
Sep 12 12:53:44 Andromeda kernel: eepro100.c: $Revision: 1.36 $ 2000/11/17 Modified by Andrey V. Savochkin <saw@saw.sw.com.sg> and others
Sep 12 12:53:44 Andromeda kernel: eth0: OEM i82557/i82558 10/100 Ethernet, 00:00:39:72:30:2F, IRQ 7.
Sep 12 12:53:44 Andromeda kernel:   Board assembly a32076-002, Physical connectors present: RJ45
Sep 12 12:53:44 Andromeda kernel:   Primary interface chip i82555 PHY #1.
Sep 12 12:53:44 Andromeda kernel:   General self-test: passed.
Sep 12 12:53:44 Andromeda kernel:   Serial sub-system self-test: passed.
Sep 12 12:53:44 Andromeda kernel:   Internal registers self-test: passed.
Sep 12 12:53:44 Andromeda kernel:   ROM checksum self-test: passed (0xb874c1d3).
Sep 12 12:53:44 Andromeda kernel: eth1: OEM i82557/i82558 10/100 Ethernet, 00:00:39:73:30:2F, IRQ 5.
Sep 12 12:53:44 Andromeda random: Initializing random number generator:  succeeded
Sep 12 12:53:44 Andromeda kernel:   Board assembly a32076-002, Physical connectors present: RJ45
Sep 12 12:53:44 Andromeda kernel:   Primary interface chip i82555 PHY #1.
Sep 12 12:53:44 Andromeda kernel:   General self-test: passed.
Sep 12 12:53:44 Andromeda kernel:   Serial sub-system self-test: passed.
Sep 12 12:53:44 Andromeda kernel:   Internal registers self-test: passed.
Sep 12 12:53:44 Andromeda kernel:   ROM checksum self-test: passed (0xb874c1d3).
Sep 12 12:53:44 Andromeda pcmcia: Starting PCMCIA services:
Sep 12 12:53:44 Andromeda pcmcia:  modules
Sep 12 12:53:44 Andromeda kernel: Linux Kernel Card Services 3.1.22
Sep 12 12:53:44 Andromeda kernel:   options:  [pci] [cardbus] [pm]
Sep 12 12:53:44 Andromeda kernel: PCI: No IRQ known for interrupt pin A of device 00:0c.0. Probably buggy MP table.
Sep 12 12:53:45 Andromeda kernel: ds: no socket drivers loaded!
Sep 12 12:53:45 Andromeda kernel: Yenta IRQ list 0008, PCI irq0
Sep 12 12:53:45 Andromeda kernel: Socket status: 30000007
Sep 12 12:53:45 Andromeda pcmcia: Hint: insmod errors can be caused by incorrect module parameters, including invalid IO or IRQ parameters
Sep 12 12:53:45 Andromeda pcmcia: /lib/modules/2.4.22-10smp/pcmcia/ds.o: init_module: Operation not permitted
Sep 12 12:53:45 Andromeda pcmcia:  cardmgr.
Sep 12 12:53:45 Andromeda cardmgr[708]: starting, version is 3.1.22
Sep 12 12:53:45 Andromeda rc: Starting pcmcia:  succeeded
Sep 12 12:53:45 Andromeda cardmgr[708]: config error, file './config.opts' line 8: no function bindings
Sep 12 12:53:45 Andromeda cardmgr[708]: no pcmcia driver in /proc/devices
Sep 12 12:53:45 Andromeda cardmgr[708]: exiting
Sep 12 12:53:45 Andromeda xinetd[727]: xinetd Version 2002.03.28 started with libwrap options compiled in.
Sep 12 12:53:45 Andromeda xinetd[727]: Started working: 2 available services
Sep 12 12:53:41 Andromeda sysctl: net.ipv4.conf.default.rp_filter = 1 
Sep 12 12:53:41 Andromeda sysctl: kernel.sysrq = 0 
Sep 12 12:53:41 Andromeda sysctl: kernel.core_uses_pid = 1 
Sep 12 12:53:41 Andromeda sysctl: vm.bdflush = 40^I0 0 0 3600 3000 60 0 0 
Sep 12 12:53:41 Andromeda network: Setting network parameters:  succeeded 
Sep 12 12:53:41 Andromeda network: Bringing up loopback interface:  succeeded 
Sep 12 12:53:48 Andromeda xinetd: xinetd startup succeeded
Sep 12 12:53:48 Andromeda gpm: gpm startup succeeded
Sep 12 12:53:48 Andromeda FreeWnn: Starting FreeWnn: 
Sep 12 12:53:48 Andromeda FreeWnn: ^[[60G[  
Sep 12 12:53:48 Andromeda FreeWnn: 
Sep 12 12:53:48 Andromeda rc: Starting FreeWnn:  succeeded
Sep 12 12:53:49 Andromeda xfs: xfs startup succeeded
Sep 12 12:53:49 Andromeda xfs: listening on port 7100 
Sep 12 12:53:49 Andromeda wine: Registering binary handler for Windows applications
Sep 12 12:53:49 Andromeda rc: Starting wine:  succeeded
Sep 12 12:53:50 Andromeda xfs: ignoring font path element /usr/X11R6/lib/X11/fonts/cyrillic (unreadable) 
Sep 12 12:53:50 Andromeda xfs: ignoring font path element /usr/X11R6/lib/X11/fonts/CID (unreadable) 
Sep 12 12:53:50 Andromeda xfs: ignoring font path element /usr/X11R6/lib/X11/fonts/local (unreadable) 
Sep 12 12:53:50 Andromeda xfs: ignoring font path element /usr/X11R6/lib/X11/fonts/latin2/Type1 (unreadable) 
Sep 12 12:53:54 Andromeda login(pam_unix)[846]: session opened for user root by LOGIN(uid=0)
Sep 12 12:53:54 Andromeda  -- root[846]: ROOT LOGIN ON tty1
Sep 12 12:54:11 Andromeda kernel: usb-uhci.c: interrupt, status 3, frame# 1994
Sep 12 12:54:11 Andromeda kernel: SCSI device sda: 2880 512-byte hdwr sectors (1 MB)
Sep 12 12:54:11 Andromeda kernel: sda: Write Protect is off
Sep 12 12:54:11 Andromeda kernel:  sda:<7>usb-storage: queuecommand() called
Sep 12 12:54:12 Andromeda kernel:  sda1 sda2 sda4
Sep 12 12:54:16 Andromeda kernel: usb.c: USB disconnect on device 00:07.2-1 address 2
Sep 12 12:54:16 Andromeda /sbin/hotplug: arguments (usb) env (PWD=/etc/hotplug DEVICE=/proc/bus/usb/001/002 INTERFACE=8/4/0 ACTION=remove DEBUG=kernel OLDPWD=/ DEVFS=/proc/bus/usb TYPE=0/0/0 SHLVL=1 HOME=/ PATH=/bin:/sbin:/usr/sbin:/usr/bin PRODUCT=644/0/0 _=/usr/bin/env)
Sep 12 12:54:16 Andromeda /sbin/hotplug: invoke /etc/hotplug/usb.agent ()

<dmesg>
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: -- Result from auto-sense is 0
usb-storage: -- code: 0x70, key: 0x5, ASC: 0x24, ASCQ: 0x0
usb-storage: Illegal Request: invalid field in CDB
usb-storage: scsi cmd done, result=0x2
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 00 00 00 00 08 00 82 f8
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: CBI data stage result is 0x0
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command TEST_UNIT_READY (6 bytes)
usb-storage: 00 00 00 00 00 00 00 00 00 00 00 00
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command TEST_UNIT_READY (6 bytes)
usb-storage: 00 00 00 00 00 00 00 00 00 00 00 00
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 00 00 00 00 01 00 00 00
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: usb_stor_transfer_partial(): xfer 512 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 512/512
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: CBI data stage result is 0x0
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 00 13 00 00 01 00 00 00
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: usb_stor_transfer_partial(): xfer 512 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 512/512
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: CBI data stage result is 0x0
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 00 14 00 00 01 00 00 00
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: usb_stor_transfer_partial(): xfer 512 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 512/512
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: CBI data stage result is 0x0
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 00 15 00 00 01 00 00 00
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: usb_stor_transfer_partial(): xfer 512 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 512/512
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: CBI data stage result is 0x0
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 00 16 00 00 01 00 00 00
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: usb_stor_transfer_partial(): xfer 512 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 512/512
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: CBI data stage result is 0x0
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 00 17 00 00 01 00 00 00
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: usb_stor_transfer_partial(): xfer 512 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 512/512
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: CBI data stage result is 0x0
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 00 18 00 00 01 00 00 00
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: usb_stor_transfer_partial(): xfer 512 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 512/512
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: CBI data stage result is 0x0
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 00 19 00 00 01 00 00 00
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: usb_stor_transfer_partial(): xfer 512 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 512/512
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: CBI data stage result is 0x0
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 00 1a 00 00 01 00 00 00
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: usb_stor_transfer_partial(): xfer 512 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 512/512
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: CBI data stage result is 0x0
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 00 1b 00 00 01 00 00 00
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: usb_stor_transfer_partial(): xfer 512 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 512/512
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: CBI data stage result is 0x0
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 00 1c 00 00 01 00 00 00
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: usb_stor_transfer_partial(): xfer 512 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 512/512
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: CBI data stage result is 0x0
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 00 1d 00 00 01 00 00 00
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: usb_stor_transfer_partial(): xfer 512 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 512/512
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: CBI data stage result is 0x0
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 00 1e 00 00 01 00 00 00
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: usb_stor_transfer_partial(): xfer 512 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 512/512
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: CBI data stage result is 0x0
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 00 1f 00 00 01 00 00 00
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: usb_stor_transfer_partial(): xfer 512 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 512/512
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: CBI data stage result is 0x0
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 00 20 00 00 01 00 00 00
usb-storage: Call to usb_stor_control_msg() returned 12
usb-storage: usb_stor_transfer_partial(): xfer 512 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 512/512
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: CBI data stage result is 0x0
usb-storage: Current value of ip_waitq is: 0
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is 0
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: -- Current value of ip_waitq is: -1
usb-storage: Got interrupt data (0x0, 0x0)
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
hub.c: port 1, portstatus 110, change 3, 12 Mb/s
hub.c: port 1 connection change
hub.c: port 1, portstatus 110, change 3, 12 Mb/s
usb.c: USB disconnect on device 00:07.2-1 address 2
usb-storage: storage_disconnect() called
usb-storage: -- releasing irq URB
usb-storage: USB IRQ received for device on host 1
usb-storage: -- IRQ data length is 2
usb-storage: -- IRQ state is -2
usb-storage: -- Interrupt Status (0x0, 0x0)
usb-storage: ERROR: Unwanted interrupt received!
usb-storage: -- usb_unlink_urb() returned 0
usb-storage: -- releasing main URB
usb-storage: -- usb_unlink_urb() returned -19
usb.c: kusbd: /sbin/hotplug remove 2
hub.c: port 1, portstatus 103, change 0, 12 Mb/s
usb.c: ignoring set_interface for dev 2, iface 0, alt 0
hub.c: port 2, portstatus 100, change 0, 12 Mb/s
hub.c: port 1, portstatus 103, change 0, 12 Mb/s
hub.c: port 2, portstatus 100, change 0, 12 Mb/s

</proc/bus/devices>  It is in the state mounted without testing.
T:  Bus=01 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#=  1 Spd=12  MxCh= 2
B:  Alloc= 12/900 us ( 1%), #Int=  1, #Iso=  0
D:  Ver= 1.00 Cls=09(hub  ) Sub=00 Prot=00 MxPS= 8 #Cfgs=  1
P:  Vendor=0000 ProdID=0000 Rev= 0.00
S:  Product=USB UHCI Root Hub
S:  SerialNumber=1080
C:* #Ifs= 1 Cfg#= 1 Atr=40 MxPwr=  0mA
I:  If#= 0 Alt= 0 #EPs= 1 Cls=09(hub  ) Sub=00 Prot=00 Driver=hub
E:  Ad=81(I) Atr=03(Int.) MxPS=   8 Ivl=255ms
T:  Bus=01 Lev=01 Prnt=01 Port=00 Cnt=01 Dev#=  2 Spd=12  MxCh= 0
D:  Ver= 1.10 Cls=00(>ifc ) Sub=00 Prot=00 MxPS= 8 #Cfgs=  1
P:  Vendor=0644 ProdID=0000 Rev= 0.00
S:  Manufacturer=TEAC    
S:  Product=TEAC FD-05PUB   
C:* #Ifs= 1 Cfg#= 1 Atr=80 MxPwr=500mA
I:  If#= 0 Alt= 0 #EPs= 3 Cls=08(stor.) Sub=04 Prot=00 Driver=usb-storage
E:  Ad=81(I) Atr=02(Bulk) MxPS=  64 Ivl=0ms
E:  Ad=02(O) Atr=02(Bulk) MxPS=  64 Ivl=0ms
E:  Ad=83(I) Atr=03(Int.) MxPS=   2 Ivl=255ms

Thanks again,
Haruo

RE: usb-strage not working in the latest kernel 2.4

[Alan Stern]

On Fri, 12 Sep 2003, Tomita, Haruo wrote:

> hub.c: port 1, portstatus 110, change 3, 12 Mb/s
> hub.c: port 1 connection change
> hub.c: port 1, portstatus 110, change 3, 12 Mb/s
> usb.c: USB disconnect on device 00:07.2-1 address 2
> usb-storage: storage_disconnect() called
> usb-storage: -- releasing irq URB
> usb-storage: USB IRQ received for device on host 1
> usb-storage: -- IRQ data length is 2
> usb-storage: -- IRQ state is -2
> usb-storage: -- Interrupt Status (0x0, 0x0)
> usb-storage: ERROR: Unwanted interrupt received!
> usb-storage: -- usb_unlink_urb() returned 0
> usb-storage: -- releasing main URB
> usb-storage: -- usb_unlink_urb() returned -19
> usb.c: kusbd: /sbin/hotplug remove 2
> hub.c: port 1, portstatus 103, change 0, 12 Mb/s
> usb.c: ignoring set_interface for dev 2, iface 0, alt 0
> hub.c: port 2, portstatus 100, change 0, 12 Mb/s
> hub.c: port 1, portstatus 103, change 0, 12 Mb/s
> hub.c: port 2, portstatus 100, change 0, 12 Mb/s

This makes it look like the device isn't probed again after usbdevfs does 
the port reset.  Yet another thing to worry about.  But since it's in 2.4, 
people probably won't worry about it very hard.

Alan Stern

RE: usb-strage not working in the latest kernel 2.4

[Tomita, Haruo]

Hi Alan

Alan  wrote:

Haruo> hub.c: port 1, portstatus 110, change 3, 12 Mb/s
Haruo> hub.c: port 1 connection change
Haruo> hub.c: port 1, portstatus 110, change 3, 12 Mb/s
Haruo> usb.c: USB disconnect on device 00:07.2-1 address 2
Haruo> usb-storage: storage_disconnect() called
Haruo> usb-storage: -- releasing irq URB
Haruo> usb-storage: USB IRQ received for device on host 1
Haruo> usb-storage: -- IRQ data length is 2
Haruo> usb-storage: -- IRQ state is -2
Haruo> usb-storage: -- Interrupt Status (0x0, 0x0)
Haruo> usb-storage: ERROR: Unwanted interrupt received!
Haruo> usb-storage: -- usb_unlink_urb() returned 0
Haruo> usb-storage: -- releasing main URB
Haruo> usb-storage: -- usb_unlink_urb() returned -19
Haruo> usb.c: kusbd: /sbin/hotplug remove 2
Haruo> hub.c: port 1, portstatus 103, change 0, 12 Mb/s
Haruo> usb.c: ignoring set_interface for dev 2, iface 0, alt 0
Haruo> hub.c: port 2, portstatus 100, change 0, 12 Mb/s
Haruo> hub.c: port 1, portstatus 103, change 0, 12 Mb/s
Haruo> hub.c: port 2, portstatus 100, change 0, 12 Mb/s
 
Alan> This makes it look like the device isn't probed again after 
Alan> usbdevfs does the port reset. 

Is it that a problem is in a device?

Alan> Yet another thing to worry about.  But since it's in 2.4, 
Alan> people probably won't worry about it very hard.

I think so.

Thanks!!
Haruo


-------------------------------------------------------
This sf.net email is sponsored by:ThinkGeek
Welcome to geek heaven.
http://thinkgeek.com/sf
_______________________________________________
linux-usb-devel@lists.sourceforge.net
To unsubscribe, use the last form field at:
https://lists.sourceforge.net/lists/listinfo/linux-usb-devel

RE: usb-strage not working in the latest kernel 2.4

[Alan Stern]

On Sat, 13 Sep 2003, Tomita, Haruo wrote:

> Alan> This makes it look like the device isn't probed again after 
> Alan> usbdevfs does the port reset. 
> 
> Is it that a problem is in a device?

No, it's a problem in Linux.  The port-reset code is not fully developed.

> Alan> Yet another thing to worry about.  But since it's in 2.4, 
> Alan> people probably won't worry about it very hard.
> 
> I think so.
> 
> Thanks!!
> Haruo

Alan Stern

RE: usb-strage not working in the latest kernel 2.4

[Tomita, Haruo]

Hi Alan,

Alan> The system sent commands, but the device didn't respond to them. 
Alan> However, it's possible that this patch will help.
Alan> Try it out and see what happens.

Using 2.4.22-bk15, 
It was adapted in the following patche and I tested.

===== your patch =====

--- 2.4.22/drivers/usb/storage/protocol.c.orig	Mon Aug 25 07:44:42 2003
+++ 2.4.22/drivers/usb/storage/protocol.c	Fri Sep  5 16:20:26 2003
@@ -237,6 +237,10 @@
 	 * a unsigned char cmnd[12], so we know we have storage available
 	 */
 
+	/* Pad the ATAPI command with zeros */
+	for (; srb->cmd_len<12; srb->cmd_len++)
+		srb->cmnd[srb->cmd_len] = 0;
+
 	/* set command length to 12 bytes (this affects the transport layer) */
 	srb->cmd_len = 12;

===== end of your patch =====

The test was successful the 3rd day or more with this patch.
However, it became the following errors.
I think that no data was transferred from the drive, 
and the command timed out and was aborted.
Ejection will also be impossible if it will be in this state. 
I recovered the error by the power supply OFF.

</proc/bus/usb/devices>

:  Bus=05 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#=  1 Spd=12  MxCh= 2
B:  Alloc=  0/900 us ( 0%), #Int=  0, #Iso=  0
D:  Ver= 1.00 Cls=09(hub  ) Sub=00 Prot=00 MxPS= 8 #Cfgs=  1
P:  Vendor=0000 ProdID=0000 Rev= 0.00
S:  Product=USB UHCI Root Hub
S:  SerialNumber=d400
C:* #Ifs= 1 Cfg#= 1 Atr=40 MxPwr=  0mA
I:  If#= 0 Alt= 0 #EPs= 1 Cls=09(hub  ) Sub=00 Prot=00 Driver=hub
E:  Ad=81(I) Atr=03(Int.) MxPS=   8 Ivl=255ms
T:  Bus=04 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#=  1 Spd=12  MxCh= 2
B:  Alloc=  0/900 us ( 0%), #Int=  0, #Iso=  0
D:  Ver= 1.00 Cls=09(hub  ) Sub=00 Prot=00 MxPS= 8 #Cfgs=  1
P:  Vendor=0000 ProdID=0000 Rev= 0.00
S:  Product=USB UHCI Root Hub
S:  SerialNumber=d800
C:* #Ifs= 1 Cfg#= 1 Atr=40 MxPwr=  0mA
I:  If#= 0 Alt= 0 #EPs= 1 Cls=09(hub  ) Sub=00 Prot=00 Driver=hub
E:  Ad=81(I) Atr=03(Int.) MxPS=   8 Ivl=255ms
T:  Bus=03 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#=  1 Spd=12  MxCh= 2
B:  Alloc=  0/900 us ( 0%), #Int=  0, #Iso=  0
D:  Ver= 1.00 Cls=09(hub  ) Sub=00 Prot=00 MxPS= 8 #Cfgs=  1
P:  Vendor=0000 ProdID=0000 Rev= 0.00
:  Product=USB UHCI Root Hub
S:  SerialNumber=e400
C:* #Ifs= 1 Cfg#= 1 Atr=40 MxPwr=  0mA
I:  If#= 0 Alt= 0 #EPs= 1 Cls=09(hub  ) Sub=00 Prot=00 Driver=hub
E:  Ad=81(I) Atr=03(Int.) MxPS=   8 Ivl=255ms
T:  Bus=02 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#=  1 Spd=12  MxCh= 2
B:  Alloc=  0/900 us ( 0%), #Int=  0, #Iso=  0
D:  Ver= 1.00 Cls=09(hub  ) Sub=00 Prot=00 MxPS= 8 #Cfgs=  1
P:  Vendor=0000 ProdID=0000 Rev= 0.00
S:  Product=USB UHCI Root Hub
S:  SerialNumber=ec00
C:* #Ifs= 1 Cfg#= 1 Atr=40 MxPwr=  0mA
I:  If#= 0 Alt= 0 #EPs= 1 Cls=09(hub  ) Sub=00 Prot=00 Driver=hub
E:  Ad=81(I) Atr=03(Int.) MxPS=   8 Ivl=255ms
T:  Bus=01 Lev=00 Prnt=00 Port=00 Cnt=00 Dev#=  1 Spd=480 MxCh= 4
B:  Alloc=  0/800 us ( 0%), #Int=  0, #Iso=  0
D:  Ver= 2.00 Cls=09(hub  ) Sub=00 Prot=01 MxPS= 8 #Cfgs=  1
P:  Vendor=0000 ProdID=0000 Rev= 2.04
S:  Manufacturer=Linux 2.4.22-bk15 ehci_hcd
S:  Product=VIA Technologies, Inc. USB 2.0
S:  SerialNumber=00:0c.2
C:* #Ifs= 1 Cfg#= 1 Atr=40 MxPwr=  0mA
I:  If#= 0 Alt= 0 #EPs= 1 Cls=09(hub  ) Sub=00 Prot=00 Driver=hub
E:  Ad=81(I) Atr=03(Int.) MxPS=   2 Ivl=256ms
T:  Bus=01 Lev=01 Prnt=01 Port=00 Cnt=01 Dev#=  2 Spd=480 MxCh= 0
D:  Ver= 2.00 Cls=00(>ifc ) Sub=00 Prot=00 MxPS=64 #Cfgs=  1
P:  Vendor=093b ProdID=0012 Rev=11.01
S:  Manufacturer=Plextor Corp.
S:  Product=USB Storage Adapter
S:  SerialNumber=11100E000052487C
C:* #Ifs= 1 Cfg#= 2 Atr=c0 MxPwr=  0mA
I:  If#= 0 Alt= 0 #EPs= 3 Cls=08(stor.) Sub=02 Prot=50 Driver=usb-storage
E:  Ad=01(O) Atr=02(Bulk) MxPS= 512 Ivl=125us
E:  Ad=82(I) Atr=02(Bulk) MxPS= 512 Ivl=0ms
E:  Ad=83(I) Atr=03(Int.) MxPS=   2 Ivl=32ms

<dmesg>
rage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 2048 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 2048/2048
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: Bulk data transfer result 0x0
usb-storage: Attempting to get CSW...
usb-storage: Bulk status result = 0
usb-storage: Bulk status Sig 0x53425355 T 0x14a4c2d R 0 Stat 0x0
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 2d e1 00 00 01 00 00 00
usb-storage: Bulk command S 0x43425355 T 0x14a4c2e Trg 0 LUN 0 L 2048 F 128 CL 12
usb-storage: Bulk command transfer result=0
usb-storage: usb_stor_transfer_partial(): xfer 2048 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 2048/2048
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: Bulk data transfer result 0x0
usb-storage: Attempting to get CSW...
usb-storage: Bulk status result = 0
usb-storage: Bulk status Sig 0x53425355 T 0x14a4c2e R 0 Stat 0x0
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 2d e2 00 00 3f 00 00 00
usb-storage: Bulk command S 0x43425355 T 0x14a4c2f Trg 0 LUN 0 L 129024 F 128 CL 12
usb-storage: Bulk command transfer result=0
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 4096 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 4096/4096
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: usb_stor_transfer_partial(): xfer 2048 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 2048/2048
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: Bulk data transfer result 0x0
usb-storage: Attempting to get CSW...
usb-storage: Bulk status result = 0
usb-storage: Bulk status Sig 0x53425355 T 0x14a4c2f R 0 Stat 0x0
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 2e 21 00 00 01 00 00 00
usb-storage: Bulk command S 0x43425355 T 0x14a4c30 Trg 0 LUN 0 L 2048 F 128 CL 12
usb-storage: Bulk command transfer result=0
usb-storage: usb_stor_transfer_partial(): xfer 2048 bytes
usb-storage: usb_stor_bulk_msg() returned 0 xferred 2048/2048
usb-storage: usb_stor_transfer_partial(): transfer complete
usb-storage: Bulk data transfer result 0x0
usb-storage: Attempting to get CSW...
usb-storage: Bulk status result = 0
usb-storage: Bulk status Sig 0x53425355 T 0x14a4c30 R 0 Stat 0x0
usb-storage: scsi cmd done, result=0x0
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command READ_10 (10 bytes)
usb-storage: 28 00 00 00 2e 22 00 00 3f 00 00 00
usb-storage: Bulk command S 0x43425355 T 0x14a4c31 Trg 0 LUN 0 L 129024 F 128 CL 12
usb-storage: command_abort() called
usb-storage: Bulk command transfer result=-104
usb-storage: -- transport indicates command was aborted
usb-storage: Bulk reset requested
usb_control/bulk_msg: timeout
usb-storage: Bulk soft reset failed -110
usb-storage: scsi command aborted
usb-storage: *** thread sleeping.
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command TEST_UNIT_READY (6 bytes)
usb-storage: 00 00 00 00 00 00 00 00 3f 00 00 00
usb-storage: Bulk command S 0x43425355 T 0x14a4c32 Trg 0 LUN 0 L 0 F 0 CL 12
usb-storage: Bulk command transfer result=0
usb-storage: Attempting to get CSW...
usb-storage: command_abort() called
usb-storage: -- transport indicates command was aborted
usb-storage: Bulk reset requested
usb_control/bulk_msg: timeout
usb-storage: Bulk soft reset failed -110
usb-storage: scsi command aborted
usb-storage: *** thread sleeping.
usb-storage: device_reset() called
usb-storage: Bulk reset requested
usb_control/bulk_msg: timeout
usb-storage: Bulk soft reset failed -110
usb-storage: bus_reset() called
hub.c: port 1, portstatus 511, change 0, 480 Mb/s
hub.c: port 1 of hub 1 not reset yet, waiting 10ms
hub.c: port 1, portstatus 511, change 0, 480 Mb/s
hub.c: port 1 of hub 1 not reset yet, waiting 10ms
ehci_hcd 00:0c.2: port 1 high speed
ehci_hcd 00:0c.2: GetStatus port 1 status 001005 POWER sig=se0  PE CONNECT
hub.c: port 1, portstatus 503, change 10, 480 Mb/s
usb.c: ignoring set_interface for dev 2, iface 0, alt 0
usb-storage: Examinging driver usb-storage...skipping ourselves.
usb-storage: bus_reset() complete
usb-storage: queuecommand() called
usb-storage: *** thread awakened.
usb-storage: Command TEST_UNIT_READY (6 bytes)
usb-storage: 00 00 00 00 00 00 00 00 3f 00 00 00
usb-storage: Bulk command S 0x43425355 T 0x14a4c33 Trg 0 LUN 0 L 0 F 0 CL 12
usb-storage: Bulk command transfer result=0
usb-storage: Attempting to get CSW...
usb-storage: command_abort() called
usb-storage: -- transport indicates command was aborted
usb-storage: Bulk reset requested
usb_control/bulk_msg: timeout
usb-storage: Bulk soft reset failed -110
usb-storage: scsi command aborted
usb-storage: *** thread sleeping.
scsi: device set offline - not ready or command retry failed after bus reset: host 0 channel 0 id 0 lun 0
SCSI cdrom error : host 0 channel 0 id 0 lun 0 return code = 50000
 I/O error: dev 0b:00, sector 47240
 I/O error: dev 0b:00, sector 47244
 I/O error: dev 0b:00, sector 47492
 I/O error: dev 0b:00, sector 47240

Thanks!!
Haruo 


-------------------------------------------------------
This sf.net email is sponsored by:ThinkGeek
Welcome to geek heaven.
http://thinkgeek.com/sf
_______________________________________________
linux-usb-devel@lists.sourceforge.net
To unsubscribe, use the last form field at:
https://lists.sourceforge.net/lists/listinfo/linux-usb-devel

RE: usb-strage not working in the latest kernel 2.4

[Alan Stern]

On Wed, 17 Sep 2003, Tomita, Haruo wrote:

> Hi Alan,
> 
> Alan> The system sent commands, but the device didn't respond to them. 
> Alan> However, it's possible that this patch will help.
> Alan> Try it out and see what happens.
> 
> Using 2.4.22-bk15, 
> It was adapted in the following patche and I tested.

> The test was successful the 3rd day or more with this patch.
> However, it became the following errors.
> I think that no data was transferred from the drive, 
> and the command timed out and was aborted.
> Ejection will also be impossible if it will be in this state. 
> I recovered the error by the power supply OFF.

Looks like that patch didn't help.

I still think it's something wrong with your drive.  Even though it works 
okay under 2.6 and not under 2.4.

Sorry I can't help any more than that.

Alan Stern