From owner-freebsd-arm@FreeBSD.ORG Sat May 9 05:11:22 2009 Return-Path: Delivered-To: freebsd-arm@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id D81EC106566C; Sat, 9 May 2009 05:11:22 +0000 (UTC) (envelope-from yohanes@gmail.com) Received: from yx-out-2324.google.com (yx-out-2324.google.com [74.125.44.28]) by mx1.freebsd.org (Postfix) with ESMTP id 664948FC15; Sat, 9 May 2009 05:11:22 +0000 (UTC) (envelope-from yohanes@gmail.com) Received: by yx-out-2324.google.com with SMTP id 8so1085356yxb.13 for ; Fri, 08 May 2009 22:11:21 -0700 (PDT) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=gamma; h=domainkey-signature:mime-version:received:from:date:message-id :subject:to:content-type:content-transfer-encoding; bh=tyg0+9H/MFgQIhVTRqOKQMpQKpagZA4bnWNIIZ5bIUo=; b=E7DS/aKxlbKfrpoH643KD8kMzmLMc6MCbXZGq7FkO0LSw8tH9YkIPXLnKgiDldWivL AoBcKr4ZLgEuw23zOjxuvRUmuCt2b282fZ7BCxCubb7a+v3Tl8qTslJZ4YeRL4u8aWdi gY5Jc3qwAsnt8mATC1tO8faLnb/k3NorhqHow= DomainKey-Signature: a=rsa-sha1; c=nofws; d=gmail.com; s=gamma; h=mime-version:from:date:message-id:subject:to:content-type :content-transfer-encoding; b=Ns1Oen7eyrK5V7QwbTywg/wMlEe2qLu1A7Q958Uqhe4oPEeQvKGU8sfHrSq8MvakjJ 8f8MU9+4/CIUqW2N++uqcBZnOpKLdbKqcrPvQt0GP8ya6I3f5D2JMXgz5B3ibCPUu2vh f+x5OGFJaHE+ZqOyw/+90HRURQKC9HRTKa5e4= MIME-Version: 1.0 Received: by 10.90.75.13 with SMTP id x13mr2457406aga.3.1241842530155; Fri, 08 May 2009 21:15:30 -0700 (PDT) From: Yohanes Nugroho Date: Sat, 9 May 2009 11:15:10 +0700 Message-ID: <260bb65e0905082115l4f017fb8g85ec88b81c191dc6@mail.gmail.com> To: freebsd-arm@freebsd.org, freebsd-usb@freebsd.org Content-Type: text/plain; charset=UTF-8 Content-Transfer-Encoding: 7bit Cc: Subject: EHCI Problem on FreeBSD arm port X-BeenThere: freebsd-arm@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: Porting FreeBSD to the StrongARM Processor List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Sat, 09 May 2009 05:11:23 -0000 Hi all, Bruce M Simpson has donated me an Emprex NSD 100 for porting FreeBSD to it, and currently I am working to port FreeBSD to this machine, which is ARM SoC Cavium Econa CNS11XX (formerly Star 91XX). But now I am stuck in implementing the USB EHCI controller. Before getting into the question, I would like to give some background. This SoC uses Faraday FA526 Arm CPU (arm4), and I have worked on Linux port of this SoC. The current progress is as follows: - FA526 CPU support is working (taken from NetBSD with minor adjustment) - Interrupt controller, timer, and serial console is working There are several more things that i need to implement: EHCI controller driver, OHCI controller driver, and network driver. I am starting with EHCI because then I would be able to use USB disk for booting, and I will be able to use my USB-Ethernet adapter (supported by FreeBSD) to test the networking stuff. This is where I got stuck. The EHCI controller is detected, most of the time the hub is detected (2 ports), and some of the time the USB mass controller is loaded. From my observation, it seems that even though the USB transaction is completed successfully, it sometimes doesn't return correct data. By "completing successfully", The result is (error=0): ehci_device_done:2149: xfer=0xc19360b0, pipe=0xc192b878, error=0 It is obvious that the data is sometimes invalid, because the display name of the device is sometimes corrupted. For example, this is when it is ok (see: "Kingston DataTraveler"): pass0: Removable Direct Access SCSI-2 device and when it is error ("Kingston DataTravele2F921"): umass0: on usbus0 (and sometimes even weirder string is shown) This invalid data happens randomly, and causes the error to move around (USB_ERR_NO_PIPE, CAM_REQ_CMP_ERR, etc). I have checked these parts: - I have verified that the device itself is working fine in Linux (always fine) - the Linux implementation doesn't have any quirks for this SoC's EHCI controller - Timer: the timer is working OK, so this should not be a timing problem - Interrupt controller: interrupt controller is also working OK, EHCI interrupt handler is called as it should - Caching/sync problem. I don't have deep understanding about ARM cache mechanism, but I have tried adding a code to flush the all of Dcache at the end of ehci_device_done, and it doesn't help. My current code is quite messy (many debugging printf). I am currently cleaning the source code so that everyone can have a look. But while I am cleaning, maybe anyone can point out things that I need to check. Here is the boot log of the most successful boot. KDB: debugger backends: ddb KDB: current backend: ddb kernel stack c12eb000 Copyright (c) 1992-2009 The FreeBSD Project. Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994 The Regents of the University of California. All rights reserved. FreeBSD is a registered trademark of The FreeBSD Foundation. FreeBSD 8.0-CURRENT #153: Sat May 9 10:19:30 WIT 2009 yohanes@cameron:/usr/home/yohanes/freebsd/freebsd/src/sys/arm/compile/CNS11XXNAS WARNING: DIAGNOSTIC option enabled, expect reduced performance. Preloaded elf kernel "elf kernel" at 0xc12d6f4c. unknown CPU (ID = 0x66015261) WB enabled LABT 16KB/16B 2-way Instruction cache 16KB/16B 2-way write-back-locking-B Data cache real memory = 67108864 (64 MB) Physical memory chunk(s): 00000000 - 0xffffff, 16777216 bytes (4096 pages) 0x133b000 - 0x3eaefff, 45563904 bytes (11124 pages) avail memory = 61702144 (58 MB) ULE: setup cpu 0 nfslock: pseudo-device null: random: mem: econa identify econa probe econaarm0: on motherboard econa attach econa add children econa add child econa_ic addr 7d000000 econa add child uart addr 78000000 econa add child timer addr 79000000 econa add child ehci addr f8000000 timer0: mem 0x79000000-0x79ffffff irq 0,1 on econaarm0 econa_alloc_resource start 00000000 end ffffffff, count = 00000001 econa_alloc_resource start 79000000 end 79ffffff, count = 01000000 sys_res_memory Alloc OK econa_alloc_resource start 00000000 end ffffffff, count = 00000001 econa_alloc_resource start 00000000 end 00000000, count = 00000001 sys_res_irq Alloc OK STR9100 CPU Clock: 200 MHz HZ = 100 reload value = 500000 IRQ Timer1 at int #0x0 clock 100000000(Hz) econa_setup_intr done econa_setup_intr 0 timer0: [FILTER] DONE timer done00000000 ehci probe ehci probe ehci0: mem 0xf8000000-0xffffffff irq 24 on econaarm0 econa_alloc_resource start 00000000 end ffffffff, count = 00000001 econa_alloc_resource start f8000000 end ffffffff, count = 08000000 sys_res_memory Alloc OK econa_alloc_resource start 00000000 end ffffffff, count = 00000001 econa_alloc_resource start 00000018 end 00000018, count = 00000001 sys_res_irq Alloc OK add USB device setup intr econa_setup_intr done econa_setup_intr 24 ehci0: [MPSAFE] ehci0: [ITHREAD] init ehci ehci_init:222: start cmd=0x01000020 EHCI_CMD_ASE sts=0x00101202 EHCI_STS_HCH EHCI_STS_ERRINT ien=0x00007070 frindex=0x00000000 ctrdsegm=0x00000000 periodic=0x00000000 async=0x00000000 usbus0: EHCI version 1.0 ehci_init:240: sparams=0x101202 ehci_init:244: cparams=0x7070 ehci_init:255: usbus0: resetting QH(0xcd001800) at 0x03e3b800: link=0x03e3b802 endp=0x0000a000 addr=0x00 inact=0 endpt=0 eps=2 dtc=0 hrecl=1 mpl=0x0 ctl=0 nrl=0 endphub=0x40000000 smask=0x00 cmask=0x00 huba=0x00 port=0 mult=1 curqtd=0x00000000<> Overlay qTD: next=0x00000001 altnext=0x00000001 status=0x00000040: toggle=0 bytes=0x0 ioc=0 c_page=0x0 cerr=0 pid=0 stat=NOT_ACTIVE-HALTED buffer[0]=0x00000000 buffer[1]=0x00000000 buffer[2]=0x00000000 buffer[3]=0x00000000 buffer[4]=0x00000000 buffer_hi[0]=0x00000000 buffer_hi[1]=0x00000000 buffer_hi[2]=0x00000000 buffer_hi[3]=0x00000000 buffer_hi[4]=0x00000000 probe and attach usbus0: on ehci0 done initcloks enabling timer Timecounter "CPU Timer" frequency 50000000 Hz quality 1000 Timecounters tick every 10.000 msec ehci_root_intr:2001: port 2 changed usbus0: 480Mbps High Speed USB v2.0 ehci_set_hw_power:3793: ehci_set_hw_power:3805: Async is active ehci_set_hw_power:3810: Periodic is active ehci_pipe_init:3663: pipe=0xc1928078, addr=0, endpt=0, mode=0 (0) ehci_roothub_exec:3032: type=0x00 request=0x05 wLen=0x0000 wValue=0x0001 wIndex=0x0000 ehci_roothub_exec:3032: type=0x80 request=0x06 wLen=0x0008 wValue=0x0100 wIndex=0x0000 ehci_roothub_exec:3032: type=0x80 request=0x06 wLen=0x0012 wValue=0x0100 wIndex=0x0000 ehci_roothub_exec:3032: type=0x80 request=0x06 wLen=0x0002 wValue=0x0300 wIndex=0x0080 ehci_roothub_exec:3032: type=0x80 request=0x06 wLen=0x0004 wValue=0x0300 wIndex=0x0080 ehci_roothub_exec:3032: type=0x80 request=0x06 wLen=0x0002 wValue=0x0301 wIndex=0x0001 ehci_roothub_exec:3032: type=0x80 request=0x06 wLen=0x000e wValue=0x0301 wIndex=0x0001 ehci_roothub_exec:3032: type=0x80 request=0x06 wLen=0x0002 wValue=0x0302 wIndex=0x0001 ehci_roothub_exec:3032: type=0x80 request=0x06 wLen=0x001c wValue=0x0302 wIndex=0x0001 ehci_roothub_exec:3032: type=0x80 request=0x06 wLen=0x0009 wValue=0x0200 wIndex=0x0000 ehci_roothub_exec:3032: type=0x80 request=0x06 wLen=0x0019 wValue=0x0200 wIndex=0x0000 ehci_roothub_exec:3032: type=0x00 request=0x09 wLen=0x0000 wValue=0x0001 wIndex=0x0000 ehci_pipe_init:3663: pipe=0xc18a1900, addr=1, endpt=129, mode=0 (1) ugen0.1: at usbus0 uhub0: on usbus0 ehci_roothub_exec:3032: type=0xa0 request=0x06 wLen=0x0009 wValue=0x2900 wIndex=0x0000 WARNING: DIAGNOSTIC option enabled, expect reduced performance. ehci_roothub_exec:3032: type=0x23 request=0x03 wLen=0x0000 wValue=0x0008 wIndex=0x0001 ehci_roothub_exec:3364: set port power 1 ehci_root_intr:2001: port 2 changed ehci_roothub_exec:3032: type=0x23 request=0x03 wLen=0x0000 wValue=0x0008 wIndex=0x0002 ehci_roothub_exec:3364: set port power 2 uhub0: 2 ports with 2 removable, self powered ehci_set_hw_power:3793: ehci_roothub_exec:3032: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001 port 68 (idx=1) status=0x1000 ehci_roothub_exec:3032: type=0x23 request=0x01 wLen=0x0000 wValue=0x0010 wIndex=0x0001 ehci_roothub_exec:3032: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001 port 68 (idx=1) status=0x1000 ehci_roothub_exec:3032: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002 port 72 (idx=2) status=0x1803 ehci_roothub_exec:3032: type=0x23 request=0x01 wLen=0x0000 wValue=0x0010 wIndex=0x0002 ehci_roothub_exec:3032: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002 port 72 (idx=2) status=0x1801 ehci_roothub_exec:3032: type=0x23 request=0x03 wLen=0x0000 wValue=0x0004 wIndex=0x0002 ehci_roothub_exec:3345: ehci after reset, status=0x00001005 ehci_roothub_exec:3360: ehci port 2 reset, status = 0x00001005 ehci_roothub_exec:3032: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002 port 72 (idx=2) status=0x1005 ehci_roothub_exec:3032: type=0x23 request=0x01 wLen=0x0000 wValue=0x0014 wIndex=0x0002 ehci_roothub_exec:3032: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002 port 72 (idx=2) status=0x1005 ehci_pipe_init:3663: pipe=0xc1927878, addr=0, endpt=0, mode=0 (1) usb2_transfer_setup ../../../dev/usb/usb_transfer.c:743 ehci_set_hw_power:3793: ehci_set_hw_power:3805: Async is active ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19340b0, pipe=0xc1927878, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19340b0, pipe=0xc1927878, error=0 ehci_device_ctrl_close ../../../dev/usb/controller/ehci.c:2236 ehci_device_done:2134: xfer=0xc19340b0, pipe=0xc1927878, error=5 usb2_transfer_setup ../../../dev/usb/usb_transfer.c:743 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19330b0, pipe=0xc1927878, error=0 ehci_device_ctrl_close ../../../dev/usb/controller/ehci.c:2236 ehci_device_done:2134: xfer=0xc19330b0, pipe=0xc1927878, error=5 usb2_transfer_setup ../../../dev/usb/usb_transfer.c:743 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19320b0, pipe=0xc1927878, error=0 ehci_device_ctrl_close ../../../dev/usb/controller/ehci.c:2236 ehci_device_done:2134: xfer=0xc19320b0, pipe=0xc1927878, error=5 usb2_transfer_setup ../../../dev/usb/usb_transfer.c:743 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19310b0, pipe=0xc1927878, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19310b0, pipe=0xc1927878, error=0 ehci_roothub_exec:3032: type=0x23 request=0x03 wLen=0x0000 wValue=0x0004 wIndex=0x0002 ehci_roothub_exec:3345: ehci after reset, status=0x00001005 ehci_roothub_exec:3360: ehci port 2 reset, status = 0x00001005 ehci_roothub_exec:3032: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002 port 72 (idx=2) status=0x1005 ehci_roothub_exec:3032: type=0x23 request=0x01 wLen=0x0000 wValue=0x0014 wIndex=0x0002 ehci_device_ctrl_close ../../../dev/usb/controller/ehci.c:2236 ehci_device_done:2134: xfer=0xc19310b0, pipe=0xc1927878, error=5 usb2_transfer_setup ../../../dev/usb/usb_transfer.c:743 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19300b0, pipe=0xc1927878, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19300b0, pipe=0xc1927878, error=0 ehci_device_ctrl_close ../../../dev/usb/controller/ehci.c:2236 ehci_device_done:2134: xfer=0xc19300b0, pipe=0xc1927878, error=5 usb2_transfer_setup ../../../dev/usb/usb_transfer.c:743 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19300b0, pipe=0xc1927878, error=0 ehci_device_ctrl_close ../../../dev/usb/controller/ehci.c:2236 ehci_device_done:2134: xfer=0xc19300b0, pipe=0xc1927878, error=5 usb2_transfer_setup ../../../dev/usb/usb_transfer.c:743 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19310b0, pipe=0xc1927878, error=0 ehci_device_ctrl_close ../../../dev/usb/controller/ehci.c:2236 ehci_device_done:2134: xfer=0xc19310b0, pipe=0xc1927878, error=5 usb2_transfer_setup ../../../dev/usb/usb_transfer.c:743 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19320b0, pipe=0xc1927878, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19320b0, pipe=0xc1927878, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19320b0, pipe=0xc1927878, error=0 ehci_pipe_init:3663: pipe=0xc1871a80, addr=2, endpt=129, mode=0 (1) ehci_pipe_init:3663: pipe=0xc1871aa4, addr=2, endpt=2, mode=0 (1) usb2_transfer_setup ../../../dev/usb/usb_transfer.c:743 ehci_set_hw_power:3793: ehci_set_hw_power:3805: Async is active ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19390b0, pipe=0xc1871aa4, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc1939180, pipe=0xc1871a80, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc1939850, pipe=0xc1871a80, error=0 ehci_device_bulk_close ../../../dev/usb/controller/ehci.c:2185 ehci_device_done:2134: xfer=0xc1939850, pipe=0xc1871a80, error=5 ehci_device_bulk_close ../../../dev/usb/controller/ehci.c:2185 ehci_device_done:2134: xfer=0xc1939180, pipe=0xc1871a80, error=5 ehci_device_bulk_close ../../../dev/usb/controller/ehci.c:2185 ehci_device_done:2134: xfer=0xc19390b0, pipe=0xc1871aa4, error=5 ugen0.2: at usbus0 umass_probe_proto ../../../dev/usb/storage/umass.c:1297 retrieving interface descriptors done ../../../dev/usb/storage/umass.c:1405 umass_probe_proto ../../../dev/usb/storage/umass.c:1297 retrieving interface descriptors done ../../../dev/usb/storage/umass.c:1405 umass0: on usbus0 umass0: SCSI over Bulk-Only; quirks = 0x0000 umass_attach ../../../dev/usb/storage/umass.c:1526 usb2_transfer_setup ../../../dev/usb/usb_transfer.c:743 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19320b0, pipe=0xc1927878, error=0 register sim 0 scanning the sim umass0:0:0:-1: Attached to scbus0 probestart ../../../cam/cam_xpt.c:5768 scsi_inquiry ../../../cam/scsi/scsi_all.c:3608 probestart ../../../cam/cam_xpt.c:5779 probestart ../../../cam/cam_xpt.c:5887 ehci_set_hw_power:3793: ehci_roothub_exec:3032: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001 port 68 (idx=1) status=0x1000 ehci_roothub_exec:3032: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002 port 72 (idx=2) status=0x1005 ehci_set_hw_power:3793: ehci_set_hw_power:3805: Async is active ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19412d8, pipe=0xc1871aa4, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19413a8, pipe=0xc1871a80, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc1941678, pipe=0xc1871a80, error=0 probestart ../../../cam/cam_xpt.c:5823 scsi_inquiry ../../../cam/scsi/scsi_all.c:3608 probestart ../../../cam/cam_xpt.c:5887 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19412d8, pipe=0xc1871aa4, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19413a8, pipe=0xc1871a80, error=22 ehci_set_hw_power:3793: ehci_set_hw_power:3805: Async is active ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc1941458, pipe=0xc1927878, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc1941678, pipe=0xc1871a80, error=0 cam_periph_error ../../../cam/cam_periph.c:1563 probestart ../../../cam/cam_xpt.c:5696 probestart ../../../cam/cam_xpt.c:5887 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19412d8, pipe=0xc1871aa4, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc1941678, pipe=0xc1871a80, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19412d8, pipe=0xc1871aa4, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19413a8, pipe=0xc1871a80, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc1941678, pipe=0xc1871a80, error=0 cam_periph_error ../../../cam/cam_periph.c:1563 (probe0:umass-sim0:0:0:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 (probe0:umass-sim0:0:0:0): CAM Status: SCSI Status Error (probe0:umass-sim0:0:0:0): SCSI Status: Check Condition (probe0:umass-sim0:0:0:0): UNIT ATTENTION asc:28,0 (probe0:umass-sim0:0:0:0): Not ready to ready change, medium may have changed (probe0:umass-sim0:0:0:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 (probe0:umass-sim0:0:0:0): UNIT ATTENTION asc:28,0 (probe0:umass-sim0:0:0:0): Not ready to ready change, medium may have changed Retrying Command (per Sense Data) (probe0:umass-sim0:0:0:0): Retrying Command probedone ../../../cam/cam_xpt.c:6326 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19412d8, pipe=0xc1871aa4, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc1941678, pipe=0xc1871a80, error=0 pass0 at umass-sim0 bus 0 target 0 lun 0 pass0: Removable Direct Access SCSI-2 device pass0: 40.000MB/s transfers ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19412d8, pipe=0xc1871aa4, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19413a8, pipe=0xc1871a80, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc1941678, pipe=0xc1871a80, error=0 da0 at umass-sim0 bus 0 target 0 lun 0 da0: Removable Direct Access SCSI-2 device da0: 40.000MB/s transfers da0: 1906MB (3903578 512 byte sectors: 255H 63S/T 242C) GEOM: new disk da0 dagetcapacity ../../../cam/scsi/scsi_da.c:1894 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19412d8, pipe=0xc1871aa4, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19413a8, pipe=0xc1871a80, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc1941678, pipe=0xc1871a80, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19412d8, pipe=0xc1871aa4, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc1941678, pipe=0xc1871a80, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19412d8, pipe=0xc1871aa4, error=0 Expensive timeout(9) function: 0xc10ee6bc(0xc1885000) -1.990236639 s ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc19413a8, pipe=0xc1871a80, error=0 ehci_non_isoc_done ../../../dev/usb/controller/ehci.c:1276 ehci_device_done:2134: xfer=0xc1941678, pipe=0xc1871a80, error=0 name = da0 geom da0 sector size = 1886417008 name = da0 geom da0 sector size = 1886417008 Offset = 0 length = 1886417008 panic: g_read_data(): invalid length 1886417008 KDB: enter: panic [thread pid 2 tid 100006 ] Stopped at kdb_enter+0x44: ldrb r15, [r15, r15, ror r15]! -- Regards Yohanes http://yohan.es -- Regards Yohanes http://yohan.es/