From owner-freebsd-scsi@FreeBSD.ORG Mon Mar 12 11:08:36 2007 Return-Path: X-Original-To: freebsd-scsi@FreeBSD.org Delivered-To: freebsd-scsi@FreeBSD.org Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id 1FEFD16A401 for ; Mon, 12 Mar 2007 11:08:36 +0000 (UTC) (envelope-from owner-bugmaster@FreeBSD.org) Received: from freefall.freebsd.org (freefall.freebsd.org [69.147.83.40]) by mx1.freebsd.org (Postfix) with ESMTP id 0EE4713C480 for ; Mon, 12 Mar 2007 11:08:36 +0000 (UTC) (envelope-from owner-bugmaster@FreeBSD.org) Received: from freefall.freebsd.org (linimon@localhost [127.0.0.1]) by freefall.freebsd.org (8.13.4/8.13.4) with ESMTP id l2CB8ZYB065197 for ; Mon, 12 Mar 2007 11:08:35 GMT (envelope-from owner-bugmaster@FreeBSD.org) Received: (from linimon@localhost) by freefall.freebsd.org (8.13.4/8.13.4/Submit) id l2CB8YdM065193 for freebsd-scsi@FreeBSD.org; Mon, 12 Mar 2007 11:08:34 GMT (envelope-from owner-bugmaster@FreeBSD.org) Date: Mon, 12 Mar 2007 11:08:34 GMT Message-Id: <200703121108.l2CB8YdM065193@freefall.freebsd.org> X-Authentication-Warning: freefall.freebsd.org: linimon set sender to owner-bugmaster@FreeBSD.org using -f From: FreeBSD bugmaster To: freebsd-scsi@FreeBSD.org Cc: Subject: Current problem reports assigned to you X-BeenThere: freebsd-scsi@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: SCSI subsystem List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Mon, 12 Mar 2007 11:08:36 -0000 Current FreeBSD problem reports Critical problems Serious problems S Tracker Resp. Description -------------------------------------------------------------------------------- o kern/27059 scsi [sym] SCSI subsystem hangs under heavy load on (Server o kern/39388 scsi ncr/sym drivers fail with 53c810 and more than 256MB m o kern/40895 scsi wierd kernel / device driver bug o kern/52638 scsi [panic] SCSI U320 on SMP server won't run faster than s kern/57398 scsi [mly] Current fails to install on mly(4) based RAID di o kern/60598 scsi wire down of scsi devices conflicts with config o kern/60641 scsi [sym] Sporadic SCSI bus resets with 53C810 under load s kern/61165 scsi [panic] kernel page fault after calling cam_send_ccb o kern/74627 scsi [ahc] [hang] Adaptec 2940U2W Can't boot 5.3 o kern/81887 scsi [aac] Adaptec SCSI 2130S aac0: GetDeviceProbeInfo comm o kern/90282 scsi [sym] SCSI bus resets cause loss of ch device o kern/92798 scsi [ahc] SCSI problem with timeouts o kern/93128 scsi [sym] FreeBSD 6.1 BETA 1 has problems with Symbios/LSI o kern/94838 scsi Kernel panic while mounting SD card with lock switch o o kern/99954 scsi [ahc] reading from DVD failes on 6.x (regression) 15 problems total. Non-critical problems S Tracker Resp. Description -------------------------------------------------------------------------------- o kern/23314 scsi aic driver fails to detect Adaptec 1520B unless PnP is o kern/35234 scsi World access to /dev/pass? (for scanner) requires acce o kern/38828 scsi [feature request] DPT PM2012B/90 doesn't work o kern/44587 scsi dev/dpt/dpt.h is missing defines required for DPT_HAND o kern/76178 scsi [ahd] Problem with ahd and large SCSI Raid system o kern/96133 scsi [scsi] [patch] add scsi quirk for joyfly 128mb flash u o kern/103702 scsi [cam] [patch] ChipsBnk: Unsupported USB memory stick 7 problems total. From owner-freebsd-scsi@FreeBSD.ORG Tue Mar 13 20:57:34 2007 Return-Path: X-Original-To: freebsd-scsi@freebsd.org Delivered-To: freebsd-scsi@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id 3ACB516A406; Tue, 13 Mar 2007 20:57:34 +0000 (UTC) (envelope-from thomas@FreeBSD.ORG) Received: from melamine.cuivre.fr.eu.org (melusine.cuivre.fr.eu.org [82.225.155.84]) by mx1.freebsd.org (Postfix) with ESMTP id 9396813C4BA; Tue, 13 Mar 2007 20:57:32 +0000 (UTC) (envelope-from thomas@FreeBSD.ORG) Received: by melamine.cuivre.fr.eu.org (Postfix, from userid 1000) id 993C85C1A7; Tue, 13 Mar 2007 21:57:31 +0100 (CET) Date: Tue, 13 Mar 2007 21:57:31 +0100 From: Thomas Quinot To: freebsd-scsi@freebsd.org Message-ID: <20070313205731.GB3866@melamine.cuivre.fr.eu.org> Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline X-message-flag: WARNING! Using Outlook can damage your computer. User-Agent: Mutt/1.5.11 Cc: josh.carroll@gmail.com, bug-followup@freebsd.org Subject: Re: kern/103602: drive gets wedged on READ CD CAPACITY if no disc is in X-BeenThere: freebsd-scsi@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: SCSI subsystem List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Tue, 13 Mar 2007 20:57:34 -0000 SCSI guys, kern/103602 is a long-standing issue with a Sony DVD drive that has a strange behaviour (failing to attach and causing an interrupt storm). One aspect of this PR was that an ATAPI command timing out was not correctly reported by the ATAPI/CAM SIM to the XPT layer. This has been fixed in atapi-cam.c rev. 1.49. Now we have a second issue: if there is no disc in the drive, it times out a READ CD CAPACITY (and any subsequent retry, even if a disc is then inserted in the drive), instead of returning an appropriate error (the expected behaviour would be a NOT READY sense key and a MEDIUM NOT PRESENT ASC). A TEST UNIT READY command seems to behave properly (reporting the appropriate error if no disc is present, reporting no error and resetting the drive to a proper state if one is). The timeout causes the unit to fail to attach at boot time if no disc is present. How should we deal with this situation? One possible option is to make sure that we first issue a TEST UNIT READY at attach time prior to issuing READ CD CAPACITY, and not issue the second command if the TUR returned a NOT READY status. The traces below show how the drive behaves in response to TEST UNIT READY and READ CD CAPACITY in various cases. Complete boot messages are available in the PR history. Thomas. ----- Forwarded message from Josh Carroll ----- From: Josh Carroll Reply-To: josh.carroll@psualum.com To: Thomas Quinot Subject: Re: kern/103602: atapi device not working on JMicron 363 Controller Cc: bug-followup@freebsd.org In-Reply-To: <20070309182253.GA3998@melamine.cuivre.fr.eu.org> >OK, we're getting closer. So, to summarize: > * if there's a CD in the drive at boot time, things work OK > * if not the cd0 entry appears transiently and then disappears Exactly, it appears to work with a cd in the drive, and I get a transient cd0 dev entry without the disc. Below is the output from each command you requested. I got a timeout in the last read capacity command, even after the prior steps. I sent a TEST UNIT READY command, then it worked, though. > * run this command (TEST UNIT READY): > camcontrol cmd cd0 -v -t 20 -c "0 0 0 0 0 0" (pass0:ata2:0:1:0): entering cdgetccb (pass0:ata2:0:1:0): xpt_schedule (pass0:ata2:0:1:0): added periph to queue cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue (pass0:ata2:0:1:0): calling xpt_run_devq cam_debug: xpt_run_dev_allocq cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 cam_debug: running device 0xc53bbc00 (pass0:ata2:0:1:0): xpt_setup_ccb cam_debug: calling periph start (pass0:ata2:0:1:0): xpt_setup_ccb (pass0:ata2:0:1:0): xpt_action (pass0:ata2:0:1:0): xpt_action cam_debug: xpt_release_ccb (pass0:ata2:0:1:0): xpt_setup_ccb (pass0:ata2:0:1:0): xpt_action (pass0:ata2:0:1:0): xpt_done (pass0:ata2:0:1:0): xpt_setup_ccb (pass0:ata2:0:1:0): xpt_action (pass0:ata2:0:1:0): xpt_setup_ccb (pass0:ata2:0:1:0): xpt_action (pass0:ata2:0:1:0): GET_TRAN_SETTINGS (pass0:ata2:0:1:0): xpt_done (pass0:ata2:0:1:0): entering cdgetccb (pass0:ata2:0:1:0): xpt_schedule (pass0:ata2:0:1:0): added periph to queue cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue (pass0:ata2:0:1:0): calling xpt_run_devq cam_debug: xpt_run_dev_allocq cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 cam_debug: running device 0xc53bbc00 (pass0:ata2:0:1:0): xpt_setup_ccb cam_debug: calling periph start (pass0:ata2:0:1:0): xpt_action (pass0:ata2:0:1:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_sendq cam_debug: running device 0xc53bbc00 (pass0:ata2:0:1:0): XPT_SCSI_IO atapi_action: hcb@0xc55b8440: 0 0 0 0 0 0 acd0: req=0xc5582540 TEST_UNIT_READY queued acd0: req=0xc5582540 TEST_UNIT_READY starting acd0: req=0xc5582540 TEST_UNIT_READY begin transaction acd0: req=0xc5582540 TEST_UNIT_READY interrupt acd0: req=0xc5582540 TEST_UNIT_READY end transaction acd0: req=0xc5582540 TEST_UNIT_READY finish taskqueue_swi acd0: req=0xc5582540 TEST_UNIT_READY completed entered acd0: req=0xc5582540 TEST_UNIT_READY autoissue request sense acd0: req=0xc5582540 REQUEST_SENSE queued acd0: req=0xc5582540 REQUEST_SENSE starting acd0: req=0xc5582540 REQUEST_SENSE begin transaction acd0: req=0xc5582540 REQUEST_SENSE interrupt acd0: req=0xc5582540 REQUEST_SENSE end transaction acd0: req=0xc5582540 TEST_UNIT_READY interrupt acd0: req=0xc5582540 TEST_UNIT_READY end transaction acd0: req=0xc5582540 TEST_UNIT_READY finish taskqueue_swi acd0: req=0xc5582540 TEST_UNIT_READY completed entered acd0: req=0xc5582540 TEST_UNIT_READY completed callback/wakeup atapi_cb: hcb@0xc55b8440 sense = 02: sk = 2) acd0: cmd TEST_UNIT_READY status 50 result 05 error 00 (pass0:ata2:0:1:0): xpt_done (pass0:ata2:0:1:0): camisr cam_debug: xpt_release_ccb > * run this command (READ CD RECORDED CAPACITY): > camcontrol cmd cd0 -v -t 20 -c "25 0 0 0 0 0 0 0 0 0" -i 8 "i4 i4" This times out with 20, 40 and 60 seconds. Here's the output from the 20 second timeout period: 2617:cam_debug: xpt_release_ccb 2618:(pass0:ata2:0:1:0): entering cdgetccb 2619:(pass0:ata2:0:1:0): xpt_schedule 2620:(pass0:ata2:0:1:0): added periph to queue 2621:cam_debug: xpt_schedule_dev 2622:cam_debug: Inserting onto queue 2623:(pass0:ata2:0:1:0): calling xpt_run_devq 2624:cam_debug: xpt_run_dev_allocq 2625:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2626:cam_debug: running device 0xc53bbc00 2627:(pass0:ata2:0:1:0): xpt_setup_ccb 2628:cam_debug: calling periph start 2629:(pass0:ata2:0:1:0): xpt_setup_ccb 2630:(pass0:ata2:0:1:0): xpt_action 2631:(pass0:ata2:0:1:0): xpt_action 2632:cam_debug: xpt_release_ccb 2633:(pass0:ata2:0:1:0): xpt_setup_ccb 2634:(pass0:ata2:0:1:0): xpt_action 2635:(pass0:ata2:0:1:0): xpt_done 2636:(pass0:ata2:0:1:0): xpt_setup_ccb 2637:(pass0:ata2:0:1:0): xpt_action 2638:(pass0:ata2:0:1:0): xpt_setup_ccb 2639:(pass0:ata2:0:1:0): xpt_action 2640:(pass0:ata2:0:1:0): GET_TRAN_SETTINGS 2641:(pass0:ata2:0:1:0): xpt_done 2642:(pass0:ata2:0:1:0): entering cdgetccb 2643:(pass0:ata2:0:1:0): xpt_schedule 2644:(pass0:ata2:0:1:0): added periph to queue 2645:cam_debug: xpt_schedule_dev 2646:cam_debug: Inserting onto queue 2647:(pass0:ata2:0:1:0): calling xpt_run_devq 2648:cam_debug: xpt_run_dev_allocq 2649:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2650:cam_debug: running device 0xc53bbc00 2651:(pass0:ata2:0:1:0): xpt_setup_ccb 2652:cam_debug: calling periph start 2653:(pass0:ata2:0:1:0): xpt_action 2654:(pass0:ata2:0:1:0): READ CD RECORDED CAPACITY. CDB: 25 0 0 0 0 0 0 0 0 0 2655:cam_debug: xpt_schedule_dev 2656:cam_debug: Inserting onto queue 2657:cam_debug: xpt_run_dev_sendq 2658:cam_debug: running device 0xc53bbc00 2659:(pass0:ata2:0:1:0): XPT_SCSI_IO 2660:atapi_action: hcb@0xc55b8700: 25 0 0 0 0 0 0 0 0 0 2661:acd0: req=0xc5556cc0 READ_CAPACITY queued 2662:acd0: req=0xc5556cc0 READ_CAPACITY starting 2663:acd0: req=0xc5556cc0 READ_CAPACITY begin transaction 2664:acd0: req=0xc5556cc0 READ_CAPACITY timeout 2665:acd0: req=0xc5556cc0 READ_CAPACITY finish taskqueue_swi 2666:acd0: req=0xc5556cc0 READ_CAPACITY completed entered 2667:(noperiph:ata2:0:-1:-1): xpt_async 2668:(noperiph:ata2:0:1:0): xpt_compile_path 2669:(noperiph:ata2:0:1:0): xpt_release_path 2670:(cd0:ata2:0:1:0): xpt_setup_ccb 2671:(cd0:ata2:0:1:0): xpt_action 2672:(cd0:ata2:0:1:0): xpt_setup_ccb 2673:(cd0:ata2:0:1:0): xpt_action 2674:(cd0:ata2:0:1:0): xpt_setup_ccb 2675:(cd0:ata2:0:1:0): xpt_action 2676:acd0: FAILURE - READ_CAPACITY timed out 2677:acd0: req=0xc5556cc0 READ_CAPACITY completed callback/wakeup 2678:atapi_cb: hcb@0xc55b8700 sense = 00: sk = 0) 2679:acd0: cmd READ_CAPACITY status 00 result 05 error 00 2680:(pass0:ata2:0:1:0): xpt_done 2681:(pass0:ata2:0:1:0): camisr 2682:cam_debug: xpt_release_ccb > * Assuming it times out, run it again without -v: > camcontrol cmd cd0 -t 20 -c "25 0 0 0 0 0 0 0 0 0" -i 8 "i4 i4" > and then this one (REQUEST SENSE): > camcontrol cmd cd0 -t 20 -c "03 0 0 0 12 0" -i 18 \ > "{Valid} b1 {RespCode} b7 > {Obsolete} i1 > {Filemark} b1 {EOM} b1 {ILI} b1 {Reserved} b1 {SenseKey} b4 > {Info} i4 {AddLen} i1 {CommSpec} i4 > {ASC} i1 {ASCQ} i1 {FRU} i1 {SKSV} b1 {SKS} b23" 2812:cam_debug: xpt_release_ccb 2813:(pass0:ata2:0:1:0): entering cdgetccb 2814:(pass0:ata2:0:1:0): xpt_schedule 2815:(pass0:ata2:0:1:0): added periph to queue 2816:cam_debug: xpt_schedule_dev 2817:cam_debug: Inserting onto queue 2818:(pass0:ata2:0:1:0): calling xpt_run_devq 2819:cam_debug: xpt_run_dev_allocq 2820:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2821:cam_debug: running device 0xc53bbc00 2822:(pass0:ata2:0:1:0): xpt_setup_ccb 2823:cam_debug: calling periph start 2824:(pass0:ata2:0:1:0): xpt_setup_ccb 2825:(pass0:ata2:0:1:0): xpt_action 2826:(pass0:ata2:0:1:0): xpt_action 2827:cam_debug: xpt_release_ccb 2828:(pass0:ata2:0:1:0): xpt_setup_ccb 2829:(pass0:ata2:0:1:0): xpt_action 2830:(pass0:ata2:0:1:0): xpt_done 2831:(pass0:ata2:0:1:0): xpt_setup_ccb 2832:(pass0:ata2:0:1:0): xpt_action 2833:(pass0:ata2:0:1:0): xpt_setup_ccb 2834:(pass0:ata2:0:1:0): xpt_action 2835:(pass0:ata2:0:1:0): GET_TRAN_SETTINGS 2836:(pass0:ata2:0:1:0): xpt_done 2837:(pass0:ata2:0:1:0): entering cdgetccb 2838:(pass0:ata2:0:1:0): xpt_schedule 2839:(pass0:ata2:0:1:0): added periph to queue 2840:cam_debug: xpt_schedule_dev 2841:cam_debug: Inserting onto queue 2842:(pass0:ata2:0:1:0): calling xpt_run_devq 2843:cam_debug: xpt_run_dev_allocq 2844:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2845:cam_debug: running device 0xc53bbc00 2846:(pass0:ata2:0:1:0): xpt_setup_ccb 2847:cam_debug: calling periph start 2848:(pass0:ata2:0:1:0): xpt_action 2849:(pass0:ata2:0:1:0): READ CD RECORDED CAPACITY. CDB: 25 0 0 0 0 0 0 0 0 0 2850:cam_debug: xpt_schedule_dev 2851:cam_debug: Inserting onto queue 2852:cam_debug: xpt_run_dev_sendq 2853:cam_debug: running device 0xc53bbc00 2854:(pass0:ata2:0:1:0): XPT_SCSI_IO 2855:atapi_action: hcb@0xc55885c0: 25 0 0 0 0 0 0 0 0 0 2856:acd0: req=0xc5582b40 READ_CAPACITY queued 2857:acd0: req=0xc5582b40 READ_CAPACITY starting 2858:acd0: req=0xc5582b40 READ_CAPACITY begin transaction 2859:acd0: req=0xc5582b40 READ_CAPACITY timeout 2860:acd0: req=0xc5582b40 READ_CAPACITY finish taskqueue_swi 2861:acd0: req=0xc5582b40 READ_CAPACITY completed entered 2862:(noperiph:ata2:0:-1:-1): xpt_async 2863:(noperiph:ata2:0:1:0): xpt_compile_path 2864:(noperiph:ata2:0:1:0): xpt_release_path 2865:(cd0:ata2:0:1:0): xpt_setup_ccb 2866:(cd0:ata2:0:1:0): xpt_action 2867:(cd0:ata2:0:1:0): xpt_setup_ccb 2868:(cd0:ata2:0:1:0): xpt_action 2869:(cd0:ata2:0:1:0): xpt_setup_ccb 2870:(cd0:ata2:0:1:0): xpt_action 2871:acd0: FAILURE - READ_CAPACITY timed out 2872:acd0: req=0xc5582b40 READ_CAPACITY completed callback/wakeup 2873:atapi_cb: hcb@0xc55885c0 sense = 00: sk = 0) 2874:acd0: cmd READ_CAPACITY status 00 result 05 error 00 2875:(pass0:ata2:0:1:0): xpt_done 2876:(pass0:ata2:0:1:0): camisr 2877:cam_debug: xpt_release_ccb 2878:(pass0:ata2:0:1:0): entering cdgetccb 2879:(pass0:ata2:0:1:0): xpt_schedule 2880:(pass0:ata2:0:1:0): added periph to queue 2881:cam_debug: xpt_schedule_dev 2882:cam_debug: Inserting onto queue 2883:(pass0:ata2:0:1:0): calling xpt_run_devq 2884:cam_debug: xpt_run_dev_allocq 2885:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2886:cam_debug: running device 0xc53bbc00 2887:(pass0:ata2:0:1:0): xpt_setup_ccb 2888:cam_debug: calling periph start 2889:(pass0:ata2:0:1:0): xpt_setup_ccb 2890:(pass0:ata2:0:1:0): xpt_action 2891:(pass0:ata2:0:1:0): xpt_action 2892:cam_debug: xpt_release_ccb 2893:(pass0:ata2:0:1:0): xpt_setup_ccb 2894:(pass0:ata2:0:1:0): xpt_action 2895:(pass0:ata2:0:1:0): xpt_done 2896:(pass0:ata2:0:1:0): xpt_setup_ccb 2897:(pass0:ata2:0:1:0): xpt_action 2898:(pass0:ata2:0:1:0): xpt_setup_ccb 2899:(pass0:ata2:0:1:0): xpt_action 2900:(pass0:ata2:0:1:0): GET_TRAN_SETTINGS 2901:(pass0:ata2:0:1:0): xpt_done 2902:(pass0:ata2:0:1:0): entering cdgetccb 2903:(pass0:ata2:0:1:0): xpt_schedule 2904:(pass0:ata2:0:1:0): added periph to queue 2905:cam_debug: xpt_schedule_dev 2906:cam_debug: Inserting onto queue 2907:(pass0:ata2:0:1:0): calling xpt_run_devq 2908:cam_debug: xpt_run_dev_allocq 2909:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2910:cam_debug: running device 0xc53bbc00 2911:(pass0:ata2:0:1:0): xpt_setup_ccb 2912:cam_debug: calling periph start 2913:(pass0:ata2:0:1:0): xpt_action 2914:(pass0:ata2:0:1:0): REQUEST SENSE. CDB: 3 0 0 0 12 0 2915:cam_debug: xpt_schedule_dev 2916:cam_debug: Inserting onto queue 2917:cam_debug: xpt_run_dev_sendq 2918:cam_debug: running device 0xc53bbc00 2919:(pass0:ata2:0:1:0): XPT_SCSI_IO 2920:atapi_action: hcb@0xc5588780: 3 0 0 0 12 0 2921:acd0: req=0xc5582d80 REQUEST_SENSE queued 2922:acd0: req=0xc5582d80 REQUEST_SENSE starting 2923:acd0: req=0xc5582d80 REQUEST_SENSE begin transaction 2924:acd0: req=0xc5582d80 REQUEST_SENSE interrupt 2925:acd0: req=0xc5582d80 REQUEST_SENSE end transaction 2926:acd0: req=0xc5582d80 REQUEST_SENSE finish taskqueue_swi 2927:acd0: req=0xc5582d80 REQUEST_SENSE completed entered 2928:acd0: req=0xc5582d80 REQUEST_SENSE completed callback/wakeup 2929:atapi_cb: hcb@0xc5588780 sense = 00: sk = 0) 2930:acd0: cmd REQUEST_SENSE status 50 result 00 error 00 2931:(pass0:ata2:0:1:0): xpt_done 2932:(pass0:ata2:0:1:0): camisr 2933:cam_debug: xpt_release_ccb > * put back the CD in the drive > * run the READ CD RECORDED CAPACITY command again: > camcontrol cmd cd0 -v -t 20 -c "25 0 0 0 0 0 0 0 0 0" -i 8 "i4 i4" > (I assume it should work this time) Output prior to sending the TEST UNIT READY command: 2812:cam_debug: xpt_release_ccb 2813:(pass0:ata2:0:1:0): entering cdgetccb 2814:(pass0:ata2:0:1:0): xpt_schedule 2815:(pass0:ata2:0:1:0): added periph to queue 2816:cam_debug: xpt_schedule_dev 2817:cam_debug: Inserting onto queue 2818:(pass0:ata2:0:1:0): calling xpt_run_devq 2819:cam_debug: xpt_run_dev_allocq 2820:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2821:cam_debug: running device 0xc53bbc00 2822:(pass0:ata2:0:1:0): xpt_setup_ccb 2823:cam_debug: calling periph start 2824:(pass0:ata2:0:1:0): xpt_setup_ccb 2825:(pass0:ata2:0:1:0): xpt_action 2826:(pass0:ata2:0:1:0): xpt_action 2827:cam_debug: xpt_release_ccb 2828:(pass0:ata2:0:1:0): xpt_setup_ccb 2829:(pass0:ata2:0:1:0): xpt_action 2830:(pass0:ata2:0:1:0): xpt_done 2831:(pass0:ata2:0:1:0): xpt_setup_ccb 2832:(pass0:ata2:0:1:0): xpt_action 2833:(pass0:ata2:0:1:0): xpt_setup_ccb 2834:(pass0:ata2:0:1:0): xpt_action 2835:(pass0:ata2:0:1:0): GET_TRAN_SETTINGS 2836:(pass0:ata2:0:1:0): xpt_done 2837:(pass0:ata2:0:1:0): entering cdgetccb 2838:(pass0:ata2:0:1:0): xpt_schedule 2839:(pass0:ata2:0:1:0): added periph to queue 2840:cam_debug: xpt_schedule_dev 2841:cam_debug: Inserting onto queue 2842:(pass0:ata2:0:1:0): calling xpt_run_devq 2843:cam_debug: xpt_run_dev_allocq 2844:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2845:cam_debug: running device 0xc53bbc00 2846:(pass0:ata2:0:1:0): xpt_setup_ccb 2847:cam_debug: calling periph start 2848:(pass0:ata2:0:1:0): xpt_action 2849:(pass0:ata2:0:1:0): READ CD RECORDED CAPACITY. CDB: 25 0 0 0 0 0 0 0 0 0 2850:cam_debug: xpt_schedule_dev 2851:cam_debug: Inserting onto queue 2852:cam_debug: xpt_run_dev_sendq 2853:cam_debug: running device 0xc53bbc00 2854:(pass0:ata2:0:1:0): XPT_SCSI_IO 2855:atapi_action: hcb@0xc55885c0: 25 0 0 0 0 0 0 0 0 0 2856:acd0: req=0xc5582b40 READ_CAPACITY queued 2857:acd0: req=0xc5582b40 READ_CAPACITY starting 2858:acd0: req=0xc5582b40 READ_CAPACITY begin transaction 2859:acd0: req=0xc5582b40 READ_CAPACITY timeout 2860:acd0: req=0xc5582b40 READ_CAPACITY finish taskqueue_swi 2861:acd0: req=0xc5582b40 READ_CAPACITY completed entered 2862:(noperiph:ata2:0:-1:-1): xpt_async 2863:(noperiph:ata2:0:1:0): xpt_compile_path 2864:(noperiph:ata2:0:1:0): xpt_release_path 2865:(cd0:ata2:0:1:0): xpt_setup_ccb 2866:(cd0:ata2:0:1:0): xpt_action 2867:(cd0:ata2:0:1:0): xpt_setup_ccb 2868:(cd0:ata2:0:1:0): xpt_action 2869:(cd0:ata2:0:1:0): xpt_setup_ccb 2870:(cd0:ata2:0:1:0): xpt_action 2871:acd0: FAILURE - READ_CAPACITY timed out 2872:acd0: req=0xc5582b40 READ_CAPACITY completed callback/wakeup 2873:atapi_cb: hcb@0xc55885c0 sense = 00: sk = 0) 2874:acd0: cmd READ_CAPACITY status 00 result 05 error 00 2875:(pass0:ata2:0:1:0): xpt_done 2876:(pass0:ata2:0:1:0): camisr 2877:cam_debug: xpt_release_ccb 2878:(pass0:ata2:0:1:0): entering cdgetccb 2879:(pass0:ata2:0:1:0): xpt_schedule 2880:(pass0:ata2:0:1:0): added periph to queue 2881:cam_debug: xpt_schedule_dev 2882:cam_debug: Inserting onto queue 2883:(pass0:ata2:0:1:0): calling xpt_run_devq 2884:cam_debug: xpt_run_dev_allocq 2885:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2886:cam_debug: running device 0xc53bbc00 2887:(pass0:ata2:0:1:0): xpt_setup_ccb 2888:cam_debug: calling periph start 2889:(pass0:ata2:0:1:0): xpt_setup_ccb 2890:(pass0:ata2:0:1:0): xpt_action 2891:(pass0:ata2:0:1:0): xpt_action 2892:cam_debug: xpt_release_ccb 2893:(pass0:ata2:0:1:0): xpt_setup_ccb 2894:(pass0:ata2:0:1:0): xpt_action 2895:(pass0:ata2:0:1:0): xpt_done 2896:(pass0:ata2:0:1:0): xpt_setup_ccb 2897:(pass0:ata2:0:1:0): xpt_action 2898:(pass0:ata2:0:1:0): xpt_setup_ccb 2899:(pass0:ata2:0:1:0): xpt_action 2900:(pass0:ata2:0:1:0): GET_TRAN_SETTINGS 2901:(pass0:ata2:0:1:0): xpt_done 2902:(pass0:ata2:0:1:0): entering cdgetccb 2903:(pass0:ata2:0:1:0): xpt_schedule 2904:(pass0:ata2:0:1:0): added periph to queue 2905:cam_debug: xpt_schedule_dev 2906:cam_debug: Inserting onto queue 2907:(pass0:ata2:0:1:0): calling xpt_run_devq 2908:cam_debug: xpt_run_dev_allocq 2909:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2910:cam_debug: running device 0xc53bbc00 2911:(pass0:ata2:0:1:0): xpt_setup_ccb 2912:cam_debug: calling periph start 2913:(pass0:ata2:0:1:0): xpt_action 2914:(pass0:ata2:0:1:0): REQUEST SENSE. CDB: 3 0 0 0 12 0 2915:cam_debug: xpt_schedule_dev 2916:cam_debug: Inserting onto queue 2917:cam_debug: xpt_run_dev_sendq 2918:cam_debug: running device 0xc53bbc00 2919:(pass0:ata2:0:1:0): XPT_SCSI_IO 2920:atapi_action: hcb@0xc5588780: 3 0 0 0 12 0 2921:acd0: req=0xc5582d80 REQUEST_SENSE queued 2922:acd0: req=0xc5582d80 REQUEST_SENSE starting 2923:acd0: req=0xc5582d80 REQUEST_SENSE begin transaction 2924:acd0: req=0xc5582d80 REQUEST_SENSE interrupt 2925:acd0: req=0xc5582d80 REQUEST_SENSE end transaction 2926:acd0: req=0xc5582d80 REQUEST_SENSE finish taskqueue_swi 2927:acd0: req=0xc5582d80 REQUEST_SENSE completed entered 2928:acd0: req=0xc5582d80 REQUEST_SENSE completed callback/wakeup 2929:atapi_cb: hcb@0xc5588780 sense = 00: sk = 0) 2930:acd0: cmd REQUEST_SENSE status 50 result 00 error 00 2931:(pass0:ata2:0:1:0): xpt_done 2932:(pass0:ata2:0:1:0): camisr 2933:cam_debug: xpt_release_ccb 2934:(pass0:ata2:0:1:0): entering cdgetccb 2935:(pass0:ata2:0:1:0): xpt_schedule 2936:(pass0:ata2:0:1:0): added periph to queue 2937:cam_debug: xpt_schedule_dev 2938:cam_debug: Inserting onto queue 2939:(pass0:ata2:0:1:0): calling xpt_run_devq 2940:cam_debug: xpt_run_dev_allocq 2941:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2942:cam_debug: running device 0xc53bbc00 2943:(pass0:ata2:0:1:0): xpt_setup_ccb 2944:cam_debug: calling periph start 2945:(pass0:ata2:0:1:0): xpt_setup_ccb 2946:(pass0:ata2:0:1:0): xpt_action 2947:(pass0:ata2:0:1:0): xpt_action 2948:cam_debug: xpt_release_ccb 2949:(pass0:ata2:0:1:0): xpt_setup_ccb 2950:(pass0:ata2:0:1:0): xpt_action 2951:(pass0:ata2:0:1:0): xpt_done 2952:(pass0:ata2:0:1:0): xpt_setup_ccb 2953:(pass0:ata2:0:1:0): xpt_action 2954:(pass0:ata2:0:1:0): xpt_setup_ccb 2955:(pass0:ata2:0:1:0): xpt_action 2956:(pass0:ata2:0:1:0): GET_TRAN_SETTINGS 2957:(pass0:ata2:0:1:0): xpt_done 2958:(pass0:ata2:0:1:0): entering cdgetccb 2959:(pass0:ata2:0:1:0): xpt_schedule 2960:(pass0:ata2:0:1:0): added periph to queue 2961:cam_debug: xpt_schedule_dev 2962:cam_debug: Inserting onto queue 2963:(pass0:ata2:0:1:0): calling xpt_run_devq 2964:cam_debug: xpt_run_dev_allocq 2965:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2966:cam_debug: running device 0xc53bbc00 2967:(pass0:ata2:0:1:0): xpt_setup_ccb 2968:cam_debug: calling periph start 2969:(pass0:ata2:0:1:0): xpt_action 2970:(pass0:ata2:0:1:0): READ CD RECORDED CAPACITY. CDB: 25 0 0 0 0 0 0 0 0 0 2971:cam_debug: xpt_schedule_dev 2972:cam_debug: Inserting onto queue 2973:cam_debug: xpt_run_dev_sendq 2974:cam_debug: running device 0xc53bbc00 2975:(pass0:ata2:0:1:0): XPT_SCSI_IO 2976:atapi_action: hcb@0xc55897c0: 25 0 0 0 0 0 0 0 0 0 2977:acd0: req=0xc55823c0 READ_CAPACITY queued 2978:acd0: req=0xc55823c0 READ_CAPACITY starting 2979:acd0: req=0xc55823c0 READ_CAPACITY begin transaction 2980:acd0: req=0xc55823c0 READ_CAPACITY timeout 2981:acd0: req=0xc55823c0 READ_CAPACITY finish taskqueue_swi 2982:acd0: req=0xc55823c0 READ_CAPACITY completed entered 2983:(noperiph:ata2:0:-1:-1): xpt_async 2984:(noperiph:ata2:0:1:0): xpt_compile_path 2985:(noperiph:ata2:0:1:0): xpt_release_path 2986:(cd0:ata2:0:1:0): xpt_setup_ccb 2987:(cd0:ata2:0:1:0): xpt_action 2988:(cd0:ata2:0:1:0): xpt_setup_ccb 2989:(cd0:ata2:0:1:0): xpt_action 2990:(cd0:ata2:0:1:0): xpt_setup_ccb 2991:(cd0:ata2:0:1:0): xpt_action 2992:acd0: FAILURE - READ_CAPACITY timed out 2993:acd0: req=0xc55823c0 READ_CAPACITY completed callback/wakeup 2994:atapi_cb: hcb@0xc55897c0 sense = 00: sk = 0) 2995:acd0: cmd READ_CAPACITY status 00 result 05 error 00 2996:(pass0:ata2:0:1:0): xpt_done 2997:(pass0:ata2:0:1:0): camisr 2998:cam_debug: xpt_release_ccb And here's the output after a TEST UNIT READY command, then the read capacity command (it worked): 2812:cam_debug: xpt_release_ccb 2813:(pass0:ata2:0:1:0): entering cdgetccb 2814:(pass0:ata2:0:1:0): xpt_schedule 2815:(pass0:ata2:0:1:0): added periph to queue 2816:cam_debug: xpt_schedule_dev 2817:cam_debug: Inserting onto queue 2818:(pass0:ata2:0:1:0): calling xpt_run_devq 2819:cam_debug: xpt_run_dev_allocq 2820:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2821:cam_debug: running device 0xc53bbc00 2822:(pass0:ata2:0:1:0): xpt_setup_ccb 2823:cam_debug: calling periph start 2824:(pass0:ata2:0:1:0): xpt_setup_ccb 2825:(pass0:ata2:0:1:0): xpt_action 2826:(pass0:ata2:0:1:0): xpt_action 2827:cam_debug: xpt_release_ccb 2828:(pass0:ata2:0:1:0): xpt_setup_ccb 2829:(pass0:ata2:0:1:0): xpt_action 2830:(pass0:ata2:0:1:0): xpt_done 2831:(pass0:ata2:0:1:0): xpt_setup_ccb 2832:(pass0:ata2:0:1:0): xpt_action 2833:(pass0:ata2:0:1:0): xpt_setup_ccb 2834:(pass0:ata2:0:1:0): xpt_action 2835:(pass0:ata2:0:1:0): GET_TRAN_SETTINGS 2836:(pass0:ata2:0:1:0): xpt_done 2837:(pass0:ata2:0:1:0): entering cdgetccb 2838:(pass0:ata2:0:1:0): xpt_schedule 2839:(pass0:ata2:0:1:0): added periph to queue 2840:cam_debug: xpt_schedule_dev 2841:cam_debug: Inserting onto queue 2842:(pass0:ata2:0:1:0): calling xpt_run_devq 2843:cam_debug: xpt_run_dev_allocq 2844:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2845:cam_debug: running device 0xc53bbc00 2846:(pass0:ata2:0:1:0): xpt_setup_ccb 2847:cam_debug: calling periph start 2848:(pass0:ata2:0:1:0): xpt_action 2849:(pass0:ata2:0:1:0): READ CD RECORDED CAPACITY. CDB: 25 0 0 0 0 0 0 0 0 0 2850:cam_debug: xpt_schedule_dev 2851:cam_debug: Inserting onto queue 2852:cam_debug: xpt_run_dev_sendq 2853:cam_debug: running device 0xc53bbc00 2854:(pass0:ata2:0:1:0): XPT_SCSI_IO 2855:atapi_action: hcb@0xc55885c0: 25 0 0 0 0 0 0 0 0 0 2856:acd0: req=0xc5582b40 READ_CAPACITY queued 2857:acd0: req=0xc5582b40 READ_CAPACITY starting 2858:acd0: req=0xc5582b40 READ_CAPACITY begin transaction 2859:acd0: req=0xc5582b40 READ_CAPACITY timeout 2860:acd0: req=0xc5582b40 READ_CAPACITY finish taskqueue_swi 2861:acd0: req=0xc5582b40 READ_CAPACITY completed entered 2862:(noperiph:ata2:0:-1:-1): xpt_async 2863:(noperiph:ata2:0:1:0): xpt_compile_path 2864:(noperiph:ata2:0:1:0): xpt_release_path 2865:(cd0:ata2:0:1:0): xpt_setup_ccb 2866:(cd0:ata2:0:1:0): xpt_action 2867:(cd0:ata2:0:1:0): xpt_setup_ccb 2868:(cd0:ata2:0:1:0): xpt_action 2869:(cd0:ata2:0:1:0): xpt_setup_ccb 2870:(cd0:ata2:0:1:0): xpt_action 2871:acd0: FAILURE - READ_CAPACITY timed out 2872:acd0: req=0xc5582b40 READ_CAPACITY completed callback/wakeup 2873:atapi_cb: hcb@0xc55885c0 sense = 00: sk = 0) 2874:acd0: cmd READ_CAPACITY status 00 result 05 error 00 2875:(pass0:ata2:0:1:0): xpt_done 2876:(pass0:ata2:0:1:0): camisr 2877:cam_debug: xpt_release_ccb 2878:(pass0:ata2:0:1:0): entering cdgetccb 2879:(pass0:ata2:0:1:0): xpt_schedule 2880:(pass0:ata2:0:1:0): added periph to queue 2881:cam_debug: xpt_schedule_dev 2882:cam_debug: Inserting onto queue 2883:(pass0:ata2:0:1:0): calling xpt_run_devq 2884:cam_debug: xpt_run_dev_allocq 2885:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2886:cam_debug: running device 0xc53bbc00 2887:(pass0:ata2:0:1:0): xpt_setup_ccb 2888:cam_debug: calling periph start 2889:(pass0:ata2:0:1:0): xpt_setup_ccb 2890:(pass0:ata2:0:1:0): xpt_action 2891:(pass0:ata2:0:1:0): xpt_action 2892:cam_debug: xpt_release_ccb 2893:(pass0:ata2:0:1:0): xpt_setup_ccb 2894:(pass0:ata2:0:1:0): xpt_action 2895:(pass0:ata2:0:1:0): xpt_done 2896:(pass0:ata2:0:1:0): xpt_setup_ccb 2897:(pass0:ata2:0:1:0): xpt_action 2898:(pass0:ata2:0:1:0): xpt_setup_ccb 2899:(pass0:ata2:0:1:0): xpt_action 2900:(pass0:ata2:0:1:0): GET_TRAN_SETTINGS 2901:(pass0:ata2:0:1:0): xpt_done 2902:(pass0:ata2:0:1:0): entering cdgetccb 2903:(pass0:ata2:0:1:0): xpt_schedule 2904:(pass0:ata2:0:1:0): added periph to queue 2905:cam_debug: xpt_schedule_dev 2906:cam_debug: Inserting onto queue 2907:(pass0:ata2:0:1:0): calling xpt_run_devq 2908:cam_debug: xpt_run_dev_allocq 2909:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2910:cam_debug: running device 0xc53bbc00 2911:(pass0:ata2:0:1:0): xpt_setup_ccb 2912:cam_debug: calling periph start 2913:(pass0:ata2:0:1:0): xpt_action 2914:(pass0:ata2:0:1:0): REQUEST SENSE. CDB: 3 0 0 0 12 0 2915:cam_debug: xpt_schedule_dev 2916:cam_debug: Inserting onto queue 2917:cam_debug: xpt_run_dev_sendq 2918:cam_debug: running device 0xc53bbc00 2919:(pass0:ata2:0:1:0): XPT_SCSI_IO 2920:atapi_action: hcb@0xc5588780: 3 0 0 0 12 0 2921:acd0: req=0xc5582d80 REQUEST_SENSE queued 2922:acd0: req=0xc5582d80 REQUEST_SENSE starting 2923:acd0: req=0xc5582d80 REQUEST_SENSE begin transaction 2924:acd0: req=0xc5582d80 REQUEST_SENSE interrupt 2925:acd0: req=0xc5582d80 REQUEST_SENSE end transaction 2926:acd0: req=0xc5582d80 REQUEST_SENSE finish taskqueue_swi 2927:acd0: req=0xc5582d80 REQUEST_SENSE completed entered 2928:acd0: req=0xc5582d80 REQUEST_SENSE completed callback/wakeup 2929:atapi_cb: hcb@0xc5588780 sense = 00: sk = 0) 2930:acd0: cmd REQUEST_SENSE status 50 result 00 error 00 2931:(pass0:ata2:0:1:0): xpt_done 2932:(pass0:ata2:0:1:0): camisr 2933:cam_debug: xpt_release_ccb 2934:(pass0:ata2:0:1:0): entering cdgetccb 2935:(pass0:ata2:0:1:0): xpt_schedule 2936:(pass0:ata2:0:1:0): added periph to queue 2937:cam_debug: xpt_schedule_dev 2938:cam_debug: Inserting onto queue 2939:(pass0:ata2:0:1:0): calling xpt_run_devq 2940:cam_debug: xpt_run_dev_allocq 2941:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2942:cam_debug: running device 0xc53bbc00 2943:(pass0:ata2:0:1:0): xpt_setup_ccb 2944:cam_debug: calling periph start 2945:(pass0:ata2:0:1:0): xpt_setup_ccb 2946:(pass0:ata2:0:1:0): xpt_action 2947:(pass0:ata2:0:1:0): xpt_action 2948:cam_debug: xpt_release_ccb 2949:(pass0:ata2:0:1:0): xpt_setup_ccb 2950:(pass0:ata2:0:1:0): xpt_action 2951:(pass0:ata2:0:1:0): xpt_done 2952:(pass0:ata2:0:1:0): xpt_setup_ccb 2953:(pass0:ata2:0:1:0): xpt_action 2954:(pass0:ata2:0:1:0): xpt_setup_ccb 2955:(pass0:ata2:0:1:0): xpt_action 2956:(pass0:ata2:0:1:0): GET_TRAN_SETTINGS 2957:(pass0:ata2:0:1:0): xpt_done 2958:(pass0:ata2:0:1:0): entering cdgetccb 2959:(pass0:ata2:0:1:0): xpt_schedule 2960:(pass0:ata2:0:1:0): added periph to queue 2961:cam_debug: xpt_schedule_dev 2962:cam_debug: Inserting onto queue 2963:(pass0:ata2:0:1:0): calling xpt_run_devq 2964:cam_debug: xpt_run_dev_allocq 2965:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 2966:cam_debug: running device 0xc53bbc00 2967:(pass0:ata2:0:1:0): xpt_setup_ccb 2968:cam_debug: calling periph start 2969:(pass0:ata2:0:1:0): xpt_action 2970:(pass0:ata2:0:1:0): READ CD RECORDED CAPACITY. CDB: 25 0 0 0 0 0 0 0 0 0 2971:cam_debug: xpt_schedule_dev 2972:cam_debug: Inserting onto queue 2973:cam_debug: xpt_run_dev_sendq 2974:cam_debug: running device 0xc53bbc00 2975:(pass0:ata2:0:1:0): XPT_SCSI_IO 2976:atapi_action: hcb@0xc55897c0: 25 0 0 0 0 0 0 0 0 0 2977:acd0: req=0xc55823c0 READ_CAPACITY queued 2978:acd0: req=0xc55823c0 READ_CAPACITY starting 2979:acd0: req=0xc55823c0 READ_CAPACITY begin transaction 2980:acd0: req=0xc55823c0 READ_CAPACITY timeout 2981:acd0: req=0xc55823c0 READ_CAPACITY finish taskqueue_swi 2982:acd0: req=0xc55823c0 READ_CAPACITY completed entered 2983:(noperiph:ata2:0:-1:-1): xpt_async 2984:(noperiph:ata2:0:1:0): xpt_compile_path 2985:(noperiph:ata2:0:1:0): xpt_release_path 2986:(cd0:ata2:0:1:0): xpt_setup_ccb 2987:(cd0:ata2:0:1:0): xpt_action 2988:(cd0:ata2:0:1:0): xpt_setup_ccb 2989:(cd0:ata2:0:1:0): xpt_action 2990:(cd0:ata2:0:1:0): xpt_setup_ccb 2991:(cd0:ata2:0:1:0): xpt_action 2992:acd0: FAILURE - READ_CAPACITY timed out 2993:acd0: req=0xc55823c0 READ_CAPACITY completed callback/wakeup 2994:atapi_cb: hcb@0xc55897c0 sense = 00: sk = 0) 2995:acd0: cmd READ_CAPACITY status 00 result 05 error 00 2996:(pass0:ata2:0:1:0): xpt_done 2997:(pass0:ata2:0:1:0): camisr 2998:cam_debug: xpt_release_ccb 2999:(pass0:ata2:0:1:0): entering cdgetccb 3000:(pass0:ata2:0:1:0): xpt_schedule 3001:(pass0:ata2:0:1:0): added periph to queue 3002:cam_debug: xpt_schedule_dev 3003:cam_debug: Inserting onto queue 3004:(pass0:ata2:0:1:0): calling xpt_run_devq 3005:cam_debug: xpt_run_dev_allocq 3006:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 3007:cam_debug: running device 0xc53bbc00 3008:(pass0:ata2:0:1:0): xpt_setup_ccb 3009:cam_debug: calling periph start 3010:(pass0:ata2:0:1:0): xpt_setup_ccb 3011:(pass0:ata2:0:1:0): xpt_action 3012:(pass0:ata2:0:1:0): xpt_action 3013:cam_debug: xpt_release_ccb 3014:(pass0:ata2:0:1:0): xpt_setup_ccb 3015:(pass0:ata2:0:1:0): xpt_action 3016:(pass0:ata2:0:1:0): xpt_done 3017:(pass0:ata2:0:1:0): xpt_setup_ccb 3018:(pass0:ata2:0:1:0): xpt_action 3019:(pass0:ata2:0:1:0): xpt_setup_ccb 3020:(pass0:ata2:0:1:0): xpt_action 3021:(pass0:ata2:0:1:0): GET_TRAN_SETTINGS 3022:(pass0:ata2:0:1:0): xpt_done 3023:(pass0:ata2:0:1:0): entering cdgetccb 3024:(pass0:ata2:0:1:0): xpt_schedule 3025:(pass0:ata2:0:1:0): added periph to queue 3026:cam_debug: xpt_schedule_dev 3027:cam_debug: Inserting onto queue 3028:(pass0:ata2:0:1:0): calling xpt_run_devq 3029:cam_debug: xpt_run_dev_allocq 3030:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 3031:cam_debug: running device 0xc53bbc00 3032:(pass0:ata2:0:1:0): xpt_setup_ccb 3033:cam_debug: calling periph start 3034:(pass0:ata2:0:1:0): xpt_action 3035:(pass0:ata2:0:1:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 3036:cam_debug: xpt_schedule_dev 3037:cam_debug: Inserting onto queue 3038:cam_debug: xpt_run_dev_sendq 3039:cam_debug: running device 0xc53bbc00 3040:(pass0:ata2:0:1:0): XPT_SCSI_IO 3041:atapi_action: hcb@0xc55b8900: 0 0 0 0 0 0 3042:acd0: req=0xc5556780 TEST_UNIT_READY queued 3043:acd0: req=0xc5556780 TEST_UNIT_READY starting 3044:acd0: req=0xc5556780 TEST_UNIT_READY begin transaction 3045:acd0: req=0xc5556780 TEST_UNIT_READY interrupt 3046:acd0: req=0xc5556780 TEST_UNIT_READY end transaction 3047:acd0: req=0xc5556780 TEST_UNIT_READY finish taskqueue_swi 3048:acd0: req=0xc5556780 TEST_UNIT_READY completed entered 3049:acd0: req=0xc5556780 TEST_UNIT_READY completed callback/wakeup 3050:atapi_cb: hcb@0xc55b8900 sense = 00: sk = 0) 3051:acd0: cmd TEST_UNIT_READY status 50 result 00 error 00 3052:(pass0:ata2:0:1:0): xpt_done 3053:(pass0:ata2:0:1:0): camisr 3054:cam_debug: xpt_release_ccb 3055:(pass0:ata2:0:1:0): entering cdgetccb 3056:(pass0:ata2:0:1:0): xpt_schedule 3057:(pass0:ata2:0:1:0): added periph to queue 3058:cam_debug: xpt_schedule_dev 3059:cam_debug: Inserting onto queue 3060:(pass0:ata2:0:1:0): calling xpt_run_devq 3061:cam_debug: xpt_run_dev_allocq 3062:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 3063:cam_debug: running device 0xc53bbc00 3064:(pass0:ata2:0:1:0): xpt_setup_ccb 3065:cam_debug: calling periph start 3066:(pass0:ata2:0:1:0): xpt_setup_ccb 3067:(pass0:ata2:0:1:0): xpt_action 3068:(pass0:ata2:0:1:0): xpt_action 3069:cam_debug: xpt_release_ccb 3070:(pass0:ata2:0:1:0): xpt_setup_ccb 3071:(pass0:ata2:0:1:0): xpt_action 3072:(pass0:ata2:0:1:0): xpt_done 3073:(pass0:ata2:0:1:0): xpt_setup_ccb 3074:(pass0:ata2:0:1:0): xpt_action 3075:(pass0:ata2:0:1:0): xpt_setup_ccb 3076:(pass0:ata2:0:1:0): xpt_action 3077:(pass0:ata2:0:1:0): GET_TRAN_SETTINGS 3078:(pass0:ata2:0:1:0): xpt_done 3079:(pass0:ata2:0:1:0): entering cdgetccb 3080:(pass0:ata2:0:1:0): xpt_schedule 3081:(pass0:ata2:0:1:0): added periph to queue 3082:cam_debug: xpt_schedule_dev 3083:cam_debug: Inserting onto queue 3084:(pass0:ata2:0:1:0): calling xpt_run_devq 3085:cam_debug: xpt_run_dev_allocq 3086:cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 3087:cam_debug: running device 0xc53bbc00 3088:(pass0:ata2:0:1:0): xpt_setup_ccb 3089:cam_debug: calling periph start 3090:(pass0:ata2:0:1:0): xpt_action 3091:(pass0:ata2:0:1:0): READ CD RECORDED CAPACITY. CDB: 25 0 0 0 0 0 0 0 0 0 3092:cam_debug: xpt_schedule_dev 3093:cam_debug: Inserting onto queue 3094:cam_debug: xpt_run_dev_sendq 3095:cam_debug: running device 0xc53bbc00 3096:(pass0:ata2:0:1:0): XPT_SCSI_IO 3097:atapi_action: hcb@0xc55b8700: 25 0 0 0 0 0 0 0 0 0 3098:acd0: req=0xc5556600 READ_CAPACITY queued 3099:acd0: req=0xc5556600 READ_CAPACITY starting 3100:acd0: req=0xc5556600 READ_CAPACITY begin transaction 3101:acd0: req=0xc5556600 READ_CAPACITY interrupt 3102:acd0: req=0xc5556600 READ_CAPACITY end transaction 3103:acd0: req=0xc5556600 READ_CAPACITY finish taskqueue_swi 3104:acd0: req=0xc5556600 READ_CAPACITY completed entered 3105:acd0: req=0xc5556600 READ_CAPACITY completed callback/wakeup 3106:atapi_cb: hcb@0xc55b8700 sense = 00: sk = 0) 3107:acd0: cmd READ_CAPACITY status 50 result 00 error 00 3108:(pass0:ata2:0:1:0): xpt_done 3109:(pass0:ata2:0:1:0): camisr 3110:cam_debug: xpt_release_ccb Thanks! Josh ----- End forwarded message ----- Thomas. From owner-freebsd-scsi@FreeBSD.ORG Tue Mar 13 21:39:39 2007 Return-Path: X-Original-To: freebsd-scsi@freebsd.org Delivered-To: freebsd-scsi@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id A11F816A403; Tue, 13 Mar 2007 21:39:39 +0000 (UTC) (envelope-from scottl@samsco.org) Received: from pooker.samsco.org (pooker.samsco.org [168.103.85.57]) by mx1.freebsd.org (Postfix) with ESMTP id 34C2F13C44C; Tue, 13 Mar 2007 21:39:38 +0000 (UTC) (envelope-from scottl@samsco.org) Received: from phobos.samsco.home (phobos.samsco.home [192.168.254.11]) (authenticated bits=0) by pooker.samsco.org (8.13.8/8.13.8) with ESMTP id l2DLdUs2094035; Tue, 13 Mar 2007 14:39:37 -0700 (MST) (envelope-from scottl@samsco.org) Message-ID: <45F71A0F.4080601@samsco.org> Date: Tue, 13 Mar 2007 15:39:27 -0600 From: Scott Long User-Agent: Mozilla/5.0 (Macintosh; U; Intel Mac OS X; en-US; rv:1.8.1.2pre) Gecko/20070111 SeaMonkey/1.1 MIME-Version: 1.0 To: Thomas Quinot References: <20070313205731.GB3866@melamine.cuivre.fr.eu.org> In-Reply-To: <20070313205731.GB3866@melamine.cuivre.fr.eu.org> Content-Type: text/plain; charset=ISO-8859-1; format=flowed Content-Transfer-Encoding: 7bit X-Greylist: Sender succeeded SMTP AUTH authentication, not delayed by milter-greylist-2.0.2 (pooker.samsco.org [168.103.85.57]); Tue, 13 Mar 2007 14:39:37 -0700 (MST) X-Spam-Status: No, score=-1.4 required=3.8 tests=ALL_TRUSTED autolearn=failed version=3.1.1 X-Spam-Checker-Version: SpamAssassin 3.1.1 (2006-03-10) on pooker.samsco.org Cc: josh.carroll@gmail.com, freebsd-scsi@freebsd.org, bug-followup@freebsd.org Subject: Re: kern/103602: drive gets wedged on READ CD CAPACITY if no disc is in X-BeenThere: freebsd-scsi@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: SCSI subsystem List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Tue, 13 Mar 2007 21:39:39 -0000 Thomas Quinot wrote: > SCSI guys, > > kern/103602 is a long-standing issue with a Sony DVD drive that has a > strange behaviour (failing to attach and causing an interrupt storm). > > One aspect of this PR was that an ATAPI command timing out was not > correctly reported by the ATAPI/CAM SIM to the XPT layer. This has been > fixed in atapi-cam.c rev. 1.49. > > Now we have a second issue: if there is no disc in the drive, it > times out a READ CD CAPACITY (and any subsequent retry, even if a disc > is then inserted in the drive), instead of returning an appropriate > error (the expected behaviour would be a NOT READY sense key and a > MEDIUM NOT PRESENT ASC). A TEST UNIT READY command seems to behave > properly (reporting the appropriate error if no disc is present, > reporting no error and resetting the drive to a proper state if > one is). > > The timeout causes the unit to fail to attach at boot time if no disc is > present. How should we deal with this situation? One possible option is > to make sure that we first issue a TEST UNIT READY at attach time prior > to issuing READ CD CAPACITY, and not issue the second command if the TUR > returned a NOT READY status. > > The traces below show how the drive behaves in response to TEST UNIT > READY and READ CD CAPACITY in various cases. Complete boot messages are > available in the PR history. > > Thomas. > I'm confused. CAM actually sends a READ_CAPACITY command to the drive, not a READ_CD_CAPACITY command. Are you saying that issuing both a READ_CAPACITY and a READ_CD_CAPACITY to an empty Sony drive results in the same bad behavior? Re-arranging the probe process to issue a TUR first is tricky. I'd rather not do it if possible. Scott From owner-freebsd-scsi@FreeBSD.ORG Tue Mar 13 21:45:43 2007 Return-Path: X-Original-To: freebsd-scsi@freebsd.org Delivered-To: freebsd-scsi@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id 8B15216A401; Tue, 13 Mar 2007 21:45:43 +0000 (UTC) (envelope-from scottl@samsco.org) Received: from pooker.samsco.org (pooker.samsco.org [168.103.85.57]) by mx1.freebsd.org (Postfix) with ESMTP id 1E48913C44C; Tue, 13 Mar 2007 21:45:42 +0000 (UTC) (envelope-from scottl@samsco.org) Received: from phobos.samsco.home (phobos.samsco.home [192.168.254.11]) (authenticated bits=0) by pooker.samsco.org (8.13.8/8.13.8) with ESMTP id l2DLjatw094081; Tue, 13 Mar 2007 14:45:41 -0700 (MST) (envelope-from scottl@samsco.org) Message-ID: <45F71B7D.3090305@samsco.org> Date: Tue, 13 Mar 2007 15:45:33 -0600 From: Scott Long User-Agent: Mozilla/5.0 (Macintosh; U; Intel Mac OS X; en-US; rv:1.8.1.2pre) Gecko/20070111 SeaMonkey/1.1 MIME-Version: 1.0 To: Thomas Quinot References: <20070313205731.GB3866@melamine.cuivre.fr.eu.org> <45F71A0F.4080601@samsco.org> In-Reply-To: <45F71A0F.4080601@samsco.org> Content-Type: text/plain; charset=ISO-8859-1; format=flowed Content-Transfer-Encoding: 7bit X-Greylist: Sender succeeded SMTP AUTH authentication, not delayed by milter-greylist-2.0.2 (pooker.samsco.org [168.103.85.57]); Tue, 13 Mar 2007 14:45:42 -0700 (MST) X-Spam-Status: No, score=-1.4 required=3.8 tests=ALL_TRUSTED autolearn=failed version=3.1.1 X-Spam-Checker-Version: SpamAssassin 3.1.1 (2006-03-10) on pooker.samsco.org Cc: josh.carroll@gmail.com, freebsd-scsi@freebsd.org, bug-followup@freebsd.org Subject: Re: kern/103602: drive gets wedged on READ CD CAPACITY if no disc is in X-BeenThere: freebsd-scsi@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: SCSI subsystem List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Tue, 13 Mar 2007 21:45:43 -0000 Scott Long wrote: > Thomas Quinot wrote: >> SCSI guys, >> >> kern/103602 is a long-standing issue with a Sony DVD drive that has a >> strange behaviour (failing to attach and causing an interrupt storm). >> >> One aspect of this PR was that an ATAPI command timing out was not >> correctly reported by the ATAPI/CAM SIM to the XPT layer. This has been >> fixed in atapi-cam.c rev. 1.49. >> >> Now we have a second issue: if there is no disc in the drive, it >> times out a READ CD CAPACITY (and any subsequent retry, even if a disc >> is then inserted in the drive), instead of returning an appropriate >> error (the expected behaviour would be a NOT READY sense key and a >> MEDIUM NOT PRESENT ASC). A TEST UNIT READY command seems to behave >> properly (reporting the appropriate error if no disc is present, >> reporting no error and resetting the drive to a proper state if >> one is). >> >> The timeout causes the unit to fail to attach at boot time if no disc is >> present. How should we deal with this situation? One possible option is >> to make sure that we first issue a TEST UNIT READY at attach time prior >> to issuing READ CD CAPACITY, and not issue the second command if the TUR >> returned a NOT READY status. >> >> The traces below show how the drive behaves in response to TEST UNIT >> READY and READ CD CAPACITY in various cases. Complete boot messages are >> available in the PR history. >> >> Thomas. >> > > I'm confused. CAM actually sends a READ_CAPACITY command to the drive, > not a READ_CD_CAPACITY command. Are you saying that issuing both a > READ_CAPACITY and a READ_CD_CAPACITY to an empty Sony drive results in > the same bad behavior? > > Re-arranging the probe process to issue a TUR first is tricky. I'd > rather not do it if possible. > Actually, I take that back, adding a TUR phase isn't as hard as I thought. I'd like to know if doing a READ_CD_CAPACITY makes a difference, though. Scott From owner-freebsd-scsi@FreeBSD.ORG Tue Mar 13 22:01:30 2007 Return-Path: X-Original-To: freebsd-scsi@freebsd.org Delivered-To: freebsd-scsi@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id 5503916A400; Tue, 13 Mar 2007 22:01:30 +0000 (UTC) (envelope-from thomas@FreeBSD.ORG) Received: from melamine.cuivre.fr.eu.org (melusine.cuivre.fr.eu.org [82.225.155.84]) by mx1.freebsd.org (Postfix) with ESMTP id 0F5A713C45A; Tue, 13 Mar 2007 22:01:30 +0000 (UTC) (envelope-from thomas@FreeBSD.ORG) Received: by melamine.cuivre.fr.eu.org (Postfix, from userid 1000) id 56CC65C1C7; Tue, 13 Mar 2007 23:01:29 +0100 (CET) Date: Tue, 13 Mar 2007 23:01:29 +0100 From: Thomas Quinot To: Scott Long Message-ID: <20070313220129.GA6579@melamine.cuivre.fr.eu.org> References: <20070313205731.GB3866@melamine.cuivre.fr.eu.org> <45F71A0F.4080601@samsco.org> <45F71B7D.3090305@samsco.org> Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline In-Reply-To: <45F71B7D.3090305@samsco.org> X-message-flag: WARNING! Using Outlook can damage your computer. User-Agent: Mutt/1.5.11 Cc: josh.carroll@gmail.com, freebsd-scsi@freebsd.org, bug-followup@freebsd.org Subject: Re: kern/103602: drive gets wedged on READ CD CAPACITY if no disc is in X-BeenThere: freebsd-scsi@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: SCSI subsystem List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Tue, 13 Mar 2007 22:01:30 -0000 * Scott Long, 2007-03-13 : > >I'm confused. CAM actually sends a READ_CAPACITY command to the drive, > >not a READ_CD_CAPACITY command. Are you saying that issuing both a > >READ_CAPACITY and a READ_CD_CAPACITY to an empty Sony drive results in > >the same bad behavior? Er, now *I* am confused. As I understand it, READ CAPACITY and READ CD RECORDED CAPACITY both refer to the same opcode (0x25), READ CAPACITY being the SBC terminology, while READ CD RECORDED CAPACITY is the MMC terminology. > Actually, I take that back, adding a TUR phase isn't as hard as I > thought. I'd like to know if doing a READ_CD_CAPACITY makes a > difference, though. Josh can probably test some commands and report the results, if you send him some camcontrol cmd's to experiment with. Thomas. From owner-freebsd-scsi@FreeBSD.ORG Wed Mar 14 04:33:07 2007 Return-Path: X-Original-To: freebsd-scsi@freebsd.org Delivered-To: freebsd-scsi@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id 1A0BF16A406 for ; Wed, 14 Mar 2007 04:33:07 +0000 (UTC) (envelope-from josh.carroll@gmail.com) Received: from nf-out-0910.google.com (nf-out-0910.google.com [64.233.182.186]) by mx1.freebsd.org (Postfix) with ESMTP id 9D3A813C487 for ; Wed, 14 Mar 2007 04:33:06 +0000 (UTC) (envelope-from josh.carroll@gmail.com) Received: by nf-out-0910.google.com with SMTP id k27so49524nfc for ; Tue, 13 Mar 2007 21:33:05 -0700 (PDT) DKIM-Signature: a=rsa-sha1; c=relaxed/relaxed; d=gmail.com; s=beta; h=domainkey-signature:received:received:message-id:date:from:reply-to:to:subject:cc:in-reply-to:mime-version:content-type:content-transfer-encoding:content-disposition:references; b=g+q1Ldl+Hh2bH9Kue98RhIHouRJg5ZM2De0S0GNvwT7bwHduf+AC0wBATE7rmFmNYSYQvAX4TQPZLZplHB2575MxkTIbM6P/HsuvVBki0RqSlfLhbYVlhvyX7KenUIQ8hNIeq78L8FbESEbXlQ9bJ4fMZXBWj0DF4lRNF7o7SWI= DomainKey-Signature: a=rsa-sha1; c=nofws; d=gmail.com; s=beta; h=received:message-id:date:from:reply-to:to:subject:cc:in-reply-to:mime-version:content-type:content-transfer-encoding:content-disposition:references; b=HOimPjlR1gHSY+IBgB9u4Yw7EYY4UfLftoYqin+MmAzaLXYxidOAq0Z0duo4Wf6Xt2wxMChkR8AX2k4M/s1xc1OeEg20f43bIX2Jmhezq8TeGAtqjcMSVy2l1s0kT47sHoVxZWAIQxZJsTqekSOTt1X/b88ojobukNOGojPjskU= Received: by 10.78.200.3 with SMTP id x3mr843692huf.1173845206116; Tue, 13 Mar 2007 21:06:46 -0700 (PDT) Received: by 10.82.163.14 with HTTP; Tue, 13 Mar 2007 21:06:45 -0700 (PDT) Message-ID: <8cb6106e0703132106j475f50ceuad0e01e51634cce3@mail.gmail.com> Date: Tue, 13 Mar 2007 21:06:45 -0700 From: "Josh Carroll" To: "Thomas Quinot" In-Reply-To: <20070313220129.GA6579@melamine.cuivre.fr.eu.org> MIME-Version: 1.0 Content-Type: text/plain; charset=ISO-8859-1; format=flowed Content-Transfer-Encoding: 7bit Content-Disposition: inline References: <20070313205731.GB3866@melamine.cuivre.fr.eu.org> <45F71A0F.4080601@samsco.org> <45F71B7D.3090305@samsco.org> <20070313220129.GA6579@melamine.cuivre.fr.eu.org> Cc: freebsd-scsi@freebsd.org, bug-followup@freebsd.org Subject: Re: kern/103602: drive gets wedged on READ CD CAPACITY if no disc is in X-BeenThere: freebsd-scsi@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list Reply-To: josh.carroll@psualum.com List-Id: SCSI subsystem List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Wed, 14 Mar 2007 04:33:07 -0000 Hi Scott/Thomas, I bought a Pioneer DVR-112D IDE DVD-RW drive to see if the timeouts were specific to my Sony DRU-800A, but I had no luck with it, either. In fact, it wouldn't even work with a disc in the drive as the Sony does. It times out on INQUIRY (dmesg attached below). I'm hoping this is a valid data point for this issue - it definitely seems to point towards something that's not drive dependent. Although I was hoping it'd act the same way as the Sony. Anyway, below is the dmesg from a boot without a disc in the drive (the same INQUIRY failures happen with a disc in the drive). Thanks, Josh Copyright (c) 1992-2007 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 7.0-CURRENT #19: Fri Mar 9 07:57:24 PST 2007 root@pflog.net:/usr/obj/usr/src/sys/DEBLIN WARNING: WITNESS option enabled, expect reduced performance. Timecounter "i8254" frequency 1193182 Hz quality 0 CPU: Intel(R) Core(TM)2 CPU X6900 @ 3.20GHz (3200.01-MHz 686-class CPU) Origin = "GenuineIntel" Id = 0x6f6 Stepping = 6 Features=0xbfebfbff Features2=0xe3bd> AMD Features=0x20100000 AMD Features2=0x1 Cores per package: 2 real memory = 2147024896 (2047 MB) avail memory = 2090582016 (1993 MB) ACPI APIC Table: FreeBSD/SMP: Multiprocessor System Detected: 2 CPUs cpu0 (BSP): APIC ID: 0 cpu1 (AP): APIC ID: 1 ioapic0 irqs 0-23 on motherboard kbd1 at kbdmux0 ath_hal: 0.9.20.3 (AR5210, AR5211, AR5212, RF5111, RF5112, RF2413, RF5413) acpi0: on motherboard acpi0: [ITHREAD] acpi_bus_number: can't get _ADR acpi_bus_number: can't get _ADR acpi_bus_number: can't get _ADR acpi_bus_number: can't get _ADR acpi0: Power Button (fixed) Timecounter "ACPI-fast" frequency 3579545 Hz quality 1000 acpi_timer0: <24-bit timer at 3.579545MHz> port 0x808-0x80b on acpi0 cpu0: on acpi0 cpu1: on acpi0 pcib0: port 0xcf8-0xcff on acpi0 pci0: on pcib0 pcib1: irq 16 at device 1.0 on pci0 pci1: on pcib1 vgapci0: mem 0xfd000000-0xfdffffff,0xc0000000-0xcfffffff,0xfc000000-0xfcffffff irq 16 at device 0.0 on pci1 uhci0: port 0xe000-0xe01f irq 16 at device 26.0 on pci0 uhci0: [GIANT-LOCKED] uhci0: [ITHREAD] usb0: on uhci0 usb0: USB revision 1.0 uhub0: on usb0 uhub0: 2 ports with 2 removable, self powered uhci1: port 0xe080-0xe09f irq 17 at device 26.1 on pci0 uhci1: [GIANT-LOCKED] uhci1: [ITHREAD] usb1: on uhci1 usb1: USB revision 1.0 uhub1: on usb1 uhub1: 2 ports with 2 removable, self powered ehci0: mem 0xfebff400-0xfebff7ff irq 18 at device 26.7 on pci0 ehci0: [GIANT-LOCKED] ehci0: [ITHREAD] usb2: EHCI version 1.0 usb2: companion controllers, 2 ports each: usb0 usb1 usb2: on ehci0 usb2: USB revision 2.0 uhub2: on usb2 uhub2: 4 ports with 4 removable, self powered pcib2: irq 16 at device 28.0 on pci0 pci3: on pcib2 pcib3: irq 16 at device 28.4 on pci0 pci2: on pcib3 atapci0: port 0xbc00-0xbc07,0xb880-0xb883,0xb800-0xb807,0xb480-0xb483,0xb400-0xb40f mem 0xfe8fe000-0xfe8fffff irq 16 at device 0.0 on pci2 atapci0: [ITHREAD] atapci0: AHCI Version 01.00 controller with 2 ports detected ata2: on atapci0 ata2: [ITHREAD] ata3: on atapci0 ata3: [ITHREAD] ata4: on atapci0 ata4: [ITHREAD] uhci2: port 0xd800-0xd81f irq 23 at device 29.0 on pci0 uhci2: [GIANT-LOCKED] uhci2: [ITHREAD] usb3: on uhci2 usb3: USB revision 1.0 uhub3: on usb3 uhub3: 2 ports with 2 removable, self powered uhci3: port 0xd880-0xd89f irq 19 at device 29.1 on pci0 uhci3: [GIANT-LOCKED] uhci3: [ITHREAD] usb4: on uhci3 usb4: USB revision 1.0 uhub4: on usb4 uhub4: 2 ports with 2 removable, self powered uhci4: port 0xdc00-0xdc1f irq 18 at device 29.2 on pci0 uhci4: [GIANT-LOCKED] uhci4: [ITHREAD] usb5: on uhci4 usb5: USB revision 1.0 uhub5: on usb5 uhub5: 2 ports with 2 removable, self powered ehci1: mem 0xfebff000-0xfebff3ff irq 23 at device 29.7 on pci0 ehci1: [GIANT-LOCKED] ehci1: [ITHREAD] usb6: EHCI version 1.0 usb6: companion controllers, 2 ports each: usb3 usb4 usb5 usb6: on ehci1 usb6: USB revision 2.0 uhub6: on usb6 uhub6: 6 ports with 6 removable, self powered pcib4: at device 30.0 on pci0 pci4: on pcib4 em0: port 0xcc00-0xcc3f mem 0xfeae0000-0xfeafffff,0xfeac0000-0xfeadffff irq 22 at device 1.0 on pci4 em0: Ethernet address: 00:0e:0c:6c:b9:16 em0: [FILTER] em1: port 0xc880-0xc8bf mem 0xfea80000-0xfea9ffff,0xfea60000-0xfea7ffff irq 23 at device 2.0 on pci4 em1: Ethernet address: 00:0e:0c:6c:b9:0a em1: [FILTER] isab0: at device 31.0 on pci0 isa0: on isab0 atapci1: port 0xec00-0xec07,0xe880-0xe883,0xe800-0xe807,0xe480-0xe483,0xe400-0xe41f mem 0xfebff800-0xfebfffff irq 19 at device 31.2 on pci0 atapci1: [ITHREAD] atapci1: AHCI Version 01.10 controller with 4 ports detected ata5: on atapci1 ata5: [ITHREAD] ata6: on atapci1 ata6: [ITHREAD] ata7: on atapci1 ata7: port not implemented ata7: [ITHREAD] ata8: on atapci1 ata8: port not implemented ata8: [ITHREAD] ata9: on atapci1 ata9: [ITHREAD] ata10: on atapci1 ata10: [ITHREAD] pci0: at device 31.3 (no driver attached) acpi_button0: on acpi0 sio0: <16550A-compatible COM port> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0 sio0: type 16550A sio0: [FILTER] fdc0: port 0x3f0-0x3f5,0x3f7 irq 6 drq 2 on acpi0 fdc0: [FILTER] fd0: <1440-KB 3.5" drive> on fdc0 drive 0 acpi_hpet0: iomem 0xfed00000-0xfed003ff on acpi0 Timecounter "HPET" frequency 14318180 Hz quality 2000 atkbdc0: port 0x60,0x64 irq 1 on acpi0 atkbd0: irq 1 on atkbdc0 kbd0 at atkbd0 atkbd0: [GIANT-LOCKED] atkbd0: [ITHREAD] psm0: irq 12 on atkbdc0 psm0: [GIANT-LOCKED] psm0: [ITHREAD] psm0: model IntelliMouse, device ID 3 pmtimer0 on isa0 orm0: at iomem 0xd2000-0xd2fff,0xd3000-0xd3fff pnpid ORM0000 on isa0 ppc0: at port 0x378-0x37f irq 7 on isa0 ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode ppc0: FIFO with 16/16/9 bytes threshold ppbus0: on ppc0 plip0: on ppbus0 lpt0: on ppbus0 lpt0: Interrupt-driven port ppi0: on ppbus0 ppc0: [GIANT-LOCKED] ppc0: [ITHREAD] sc0: at flags 0x100 on isa0 sc0: VGA <16 virtual consoles, flags=0x300> sio1: configured irq 3 not in bitmap of probed irqs 0 sio1: port may not be enabled vga0: at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0 ugen0: on uhub3 Timecounters tick every 1.000 msec (noperiph:xpt0:0:-1:-1): xpt_compile_path (noperiph:xpt0:0:-1:-1): xpt_setup_ccb (noperiph:xpt0:0:-1:-1): xpt_action (noperiph:xpt0:0:-1:-1): xpt_free_path (noperiph:xpt0:0:-1:-1): xpt_release_path (noperiph:xpt0:0:-1:-1): xpt_compile_path (noperiph:xpt0:0:-1:-1): xpt_setup_ccb (noperiph:xpt0:0:-1:-1): xpt_action (noperiph:xpt0:0:-1:-1): xpt_free_path (noperiph:xpt0:0:-1:-1): xpt_release_path (noperiph:xpt0:0:-1:-1): xpt_compile_path (noperiph:xpt0:0:-1:-1): xpt_setup_ccb (noperiph:xpt0:0:-1:-1): xpt_action (noperiph:xpt0:0:-1:-1): xpt_free_path (noperiph:xpt0:0:-1:-1): xpt_release_path (noperiph:xpt0:0:-1:-1): xpt_compile_path (noperiph:xpt0:0:-1:-1): xpt_setup_ccb (noperiph:xpt0:0:-1:-1): xpt_action (noperiph:xpt0:0:-1:-1): xpt_free_path (noperiph:xpt0:0:-1:-1): xpt_release_path (noperiph:xpt0:0:-1:-1): xpt_compile_path (noperiph:xpt0:0:-1:-1): xpt_setup_ccb (noperiph:xpt0:0:-1:-1): xpt_action (noperiph:xpt0:0:-1:-1): xpt_free_path (noperiph:xpt0:0:-1:-1): xpt_release_path (noperiph:xpt0:0:-1:-1): xpt_compile_path (noperiph:xpt0:0:-1:-1): xpt_setup_ccb (noperiph:xpt0:0:-1:-1): xpt_action (noperiph:xpt0:0:-1:-1): xpt_free_path (noperiph:xpt0:0:-1:-1): xpt_release_path (noperiph:ata0:0:-1:-1): xpt_compile_path (noperiph:ata0:0:-1:-1): xpt_setup_ccb (noperiph:ata0:0:-1:-1): xpt_action (noperiph:ata0:0:-1:-1): xpt_done (noperiph:ata0:0:-1:-1): xpt_async (noperiph:ata0:0:-1:-1): xpt_release_path (noperiph:ata0:0:-1:-1): xpt_compile_path (noperiph:ata0:0:-1:-1): Registered SIM for ata0 (noperiph:ata0:0:-1:-1): xpt_setup_ccb (noperiph:ata0:0:-1:-1): xpt_action (xpt0:ata0:0:-1:-1): xpt_compile_path (xpt0:ata0:0:-1:-1): Rescanning ATAPI bus. (xpt0:ata0:0:-1:-1): xpt_setup_ccb (xpt0:ata0:0:-1:-1): xpt_action (xpt0:ata0:0:-1:-1): xpt_scan_bus (xpt0:ata0:0:-1:-1): xpt_setup_ccb (xpt0:ata0:0:-1:-1): xpt_action (xpt0:ata0:0:-1:-1): xpt_done (noperiph:ata0:0:0:0): xpt_compile_path (noperiph:ata0:0:0:0): xpt_setup_ccb (noperiph:ata0:0:0:0): xpt_action (noperiph:ata0:0:0:0): xpt_done (noperiph:ata0:0:0:0): xpt_setup_ccb (noperiph:ata0:0:0:0): xpt_action (noperiph:ata0:0:0:0): xpt_setup_ccb (noperiph:ata0:0:0:0): xpt_action (noperiph:ata0:0:0:0): xpt_done (noperiph:ata0:0:0:0): xpt_setup_ccb (noperiph:ata0:0:0:0): xpt_action (noperiph:ata0:0:0:0): GET_TRAN_SETTINGS (noperiph:ata0:0:0:0): xpt_done (noperiph:ata0:0:0:0): SET_TRAN_SETTINGS not supported (noperiph:ata0:0:0:0): xpt_done (noperiph:ata0:0:0:0): xpt_release_path (xpt0:ata0:0:0:0): xpt_compile_path (xpt0:ata0:0:0:0): xpt_setup_ccb (xpt0:ata0:0:0:0): xpt_action (xpt0:ata0:0:0:0): xpt_scan_lun (xpt0:ata0:0:0:0): xpt_setup_ccb (xpt0:ata0:0:0:0): xpt_action (xpt0:ata0:0:0:0): xpt_done (xpt0:ata0:0:0:0): xpt_done (noperiph:ata0:0:1:0): xpt_compile_path (noperiph:ata0:0:1:0): xpt_setup_ccb (noperiph:ata0:0:1:0): xpt_action (noperiph:ata0:0:1:0): xpt_done (noperiph:ata0:0:1:0): xpt_setup_ccb (noperiph:ata0:0:1:0): xpt_action (noperiph:ata0:0:1:0): xpt_setup_ccb (noperiph:ata0:0:1:0): xpt_action (noperiph:ata0:0:1:0): xpt_done (noperiph:ata0:0:1:0): xpt_setup_ccb (noperiph:ata0:0:1:0): xpt_action (noperiph:ata0:0:1:0): GET_TRAN_SETTINGS (noperiph:ata0:0:1:0): xpt_done (noperiph:ata0:0:1:0): SET_TRAN_SETTINGS not supported (noperiph:ata0:0:1:0): xpt_done (noperiph:ata0:0:1:0): xpt_release_path (xpt0:ata0:0:1:0): xpt_compile_path (xpt0:ata0:0:1:0): xpt_setup_ccb (xpt0:ata0:0:1:0): xpt_action (xpt0:ata0:0:1:0): xpt_scan_lun (xpt0:ata0:0:1:0): xpt_setup_ccb (xpt0:ata0:0:1:0): xpt_action (xpt0:ata0:0:1:0): xpt_done (xpt0:ata0:0:1:0): xpt_done (noperiph:ata1:0:-1:-1): xpt_compile_path (noperiph:ata1:0:-1:-1): xpt_setup_ccb (noperiph:ata1:0:-1:-1): xpt_action (noperiph:ata1:0:-1:-1): xpt_done (noperiph:ata1:0:-1:-1): xpt_async (noperiph:ata1:0:-1:-1): xpt_release_path (noperiph:ata1:0:-1:-1): xpt_compile_path (noperiph:ata1:0:-1:-1): Registered SIM for ata1 (noperiph:ata1:0:-1:-1): xpt_setup_ccb (noperiph:ata1:0:-1:-1): xpt_action (xpt0:ata1:0:-1:-1): xpt_compile_path (xpt0:ata1:0:-1:-1): Rescanning ATAPI bus. (xpt0:ata1:0:-1:-1): xpt_setup_ccb (xpt0:ata1:0:-1:-1): xpt_action (xpt0:ata1:0:-1:-1): xpt_scan_bus (xpt0:ata1:0:-1:-1): xpt_setup_ccb (xpt0:ata1:0:-1:-1): xpt_action (xpt0:ata1:0:-1:-1): xpt_done (noperiph:ata1:0:0:0): xpt_compile_path (noperiph:ata1:0:0:0): xpt_setup_ccb (noperiph:ata1:0:0:0): xpt_action (noperiph:ata1:0:0:0): xpt_done (noperiph:ata1:0:0:0): xpt_setup_ccb (noperiph:ata1:0:0:0): xpt_action (noperiph:ata1:0:0:0): xpt_setup_ccb (noperiph:ata1:0:0:0): xpt_action (noperiph:ata1:0:0:0): xpt_done (noperiph:ata1:0:0:0): xpt_setup_ccb (noperiph:ata1:0:0:0): xpt_action (noperiph:ata1:0:0:0): GET_TRAN_SETTINGS (noperiph:ata1:0:0:0): xpt_done (noperiph:ata1:0:0:0): SET_TRAN_SETTINGS not supported (noperiph:ata1:0:0:0): xpt_done (noperiph:ata1:0:0:0): xpt_release_path (xpt0:ata1:0:0:0): xpt_compile_path (xpt0:ata1:0:0:0): xpt_setup_ccb (xpt0:ata1:0:0:0): xpt_action (xpt0:ata1:0:0:0): xpt_scan_lun (xpt0:ata1:0:0:0): xpt_setup_ccb (xpt0:ata1:0:0:0): xpt_action (xpt0:ata1:0:0:0): xpt_done (xpt0:ata1:0:0:0): xpt_done (noperiph:ata1:0:1:0): xpt_compile_path (noperiph:ata1:0:1:0): xpt_setup_ccb (noperiph:ata1:0:1:0): xpt_action (noperiph:ata1:0:1:0): xpt_done (noperiph:ata1:0:1:0): xpt_setup_ccb (noperiph:ata1:0:1:0): xpt_action (noperiph:ata1:0:1:0): xpt_setup_ccb (noperiph:ata1:0:1:0): xpt_action (noperiph:ata1:0:1:0): xpt_done (noperiph:ata1:0:1:0): xpt_setup_ccb (noperiph:ata1:0:1:0): xpt_action (noperiph:ata1:0:1:0): GET_TRAN_SETTINGS (noperiph:ata1:0:1:0): xpt_done (noperiph:ata1:0:1:0): SET_TRAN_SETTINGS not supported (noperiph:ata1:0:1:0): xpt_done (noperiph:ata1:0:1:0): xpt_release_path (xpt0:ata1:0:1:0): xpt_compile_path (xpt0:ata1:0:1:0): xpt_setup_ccb (xpt0:ata1:0:1:0): xpt_action (xpt0:ata1:0:1:0): xpt_scan_lun (xpt0:ata1:0:1:0): xpt_setup_ccb (xpt0:ata1:0:1:0): xpt_action (xpt0:ata1:0:1:0): xpt_done (xpt0:ata1:0:1:0): xpt_done (xpt0:ata0:0:0:0): camisr (xpt0:ata0:0:0:0): xpt_scan_bus (xpt0:ata0:0:0:0): xpt_setup_ccb (xpt0:ata0:0:0:0): xpt_action (xpt0:ata0:0:0:0): xpt_free_path (xpt0:ata0:0:0:0): xpt_release_path (xpt0:ata0:0:1:0): camisr (xpt0:ata0:0:1:0): xpt_scan_bus (xpt0:ata0:0:1:0): xpt_setup_ccb (xpt0:ata0:0:1:0): xpt_action (xpt0:ata0:0:1:0): xpt_free_path (xpt0:ata0:0:1:0): xpt_release_path (xpt0:ata0:0:-1:-1): xpt_done (xpt0:ata1:0:0:0): camisr (xpt0:ata1:0:0:0): xpt_scan_bus (xpt0:ata1:0:0:0): xpt_setup_ccb (xpt0:ata1:0:0:0): xpt_action (xpt0:ata1:0:0:0): xpt_free_path (xpt0:ata1:0:0:0): xpt_release_path (xpt0:ata1:0:1:0): camisr (xpt0:ata1:0:1:0): xpt_scan_bus (xpt0:ata1:0:1:0): xpt_setup_ccb (xpt0:ata1:0:1:0): xpt_action (xpt0:ata1:0:1:0): xpt_free_path (xpt0:ata1:0:1:0): xpt_release_path (xpt0:ata1:0:-1:-1): xpt_done (xpt0:ata0:0:-1:-1): camisr (xpt0:ata0:0:-1:-1): Rescan succeeded (xpt0:ata0:0:-1:-1): xpt_free_path (xpt0:ata0:0:-1:-1): xpt_release_path (xpt0:ata1:0:-1:-1): camisr (xpt0:ata1:0:-1:-1): Rescan succeeded (xpt0:ata1:0:-1:-1): xpt_free_path (xpt0:ata1:0:-1:-1): xpt_release_path ad8: 152627MB at ata4-master UDMA100 acd0: DVDR at ata4-slave UDMA66 (noperiph:ata2:0:-1:-1): xpt_compile_path (noperiph:ata2:0:-1:-1): xpt_setup_ccb (noperiph:ata2:0:-1:-1): xpt_action (noperiph:ata2:0:-1:-1): xpt_done (noperiph:ata2:0:-1:-1): xpt_async (noperiph:ata2:0:-1:-1): xpt_release_path (noperiph:ata2:0:-1:-1): xpt_compile_path (noperiph:ata2:0:-1:-1): Registered SIM for ata2 (noperiph:ata2:0:-1:-1): xpt_setup_ccb (noperiph:ata2:0:-1:-1): xpt_action (xpt0:ata2:0:-1:-1): xpt_compile_path (xpt0:ata2:0:-1:-1): Rescanning ATAPI bus. (xpt0:ata2:0:-1:-1): xpt_setup_ccb (xpt0:ata2:0:-1:-1): xpt_action (xpt0:ata2:0:-1:-1): xpt_scan_bus (xpt0:ata2:0:-1:-1): xpt_setup_ccb (xpt0:ata2:0:-1:-1): xpt_action (xpt0:ata2:0:-1:-1): xpt_done (noperiph:ata2:0:0:0): xpt_compile_path (noperiph:ata2:0:0:0): xpt_setup_ccb (noperiph:ata2:0:0:0): xpt_action (noperiph:ata2:0:0:0): xpt_done (noperiph:ata2:0:0:0): xpt_setup_ccb (noperiph:ata2:0:0:0): xpt_action (noperiph:ata2:0:0:0): xpt_setup_ccb (noperiph:ata2:0:0:0): xpt_action (noperiph:ata2:0:0:0): xpt_done (noperiph:ata2:0:0:0): xpt_setup_ccb (noperiph:ata2:0:0:0): xpt_action (noperiph:ata2:0:0:0): GET_TRAN_SETTINGS (noperiph:ata2:0:0:0): xpt_done (noperiph:ata2:0:0:0): SET_TRAN_SETTINGS not supported (noperiph:ata2:0:0:0): xpt_done (noperiph:ata2:0:0:0): xpt_release_path (xpt0:ata2:0:0:0): xpt_compile_path (xpt0:ata2:0:0:0): xpt_setup_ccb (xpt0:ata2:0:0:0): xpt_action (xpt0:ata2:0:0:0): xpt_scan_lun (xpt0:ata2:0:0:0): xpt_setup_ccb (xpt0:ata2:0:0:0): xpt_action (xpt0:ata2:0:0:0): xpt_done (xpt0:ata2:0:0:0): xpt_done (noperiph:ata2:0:1:0): xpt_compile_path (noperiph:ata2:0:1:0): xpt_setup_ccb (noperiph:ata2:0:1:0): xpt_action (noperiph:ata2:0:1:0): xpt_done (noperiph:ata2:0:1:0): xpt_setup_ccb (noperiph:ata2:0:1:0): xpt_action (noperiph:ata2:0:1:0): xpt_setup_ccb (noperiph:ata2:0:1:0): xpt_action (noperiph:ata2:0:1:0): xpt_done (noperiph:ata2:0:1:0): xpt_setup_ccb (noperiph:ata2:0:1:0): xpt_action (noperiph:ata2:0:1:0): GET_TRAN_SETTINGS (noperiph:ata2:0:1:0): xpt_done (noperiph:ata2:0:1:0): SET_TRAN_SETTINGS not supported (noperiph:ata2:0:1:0): xpt_done (noperiph:ata2:0:1:0): xpt_release_path (xpt0:ata2:0:1:0): xpt_compile_path (xpt0:ata2:0:1:0): xpt_setup_ccb (xpt0:ata2:0:1:0): xpt_action (xpt0:ata2:0:1:0): xpt_scan_lun (xpt0:ata2:0:1:0): xpt_setup_ccb (xpt0:ata2:0:1:0): xpt_action (xpt0:ata2:0:1:0): xpt_done (probe0:ata2:0:1:0): xpt_compile_path (probe0:ata2:0:1:0): xpt_setup_ccb (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): xpt_setup_ccb (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): xpt_setup_ccb (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): xpt_done (probe0:ata2:0:1:0): xpt_schedule (probe0:ata2:0:1:0): added periph to queue cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue (probe0:ata2:0:1:0): calling xpt_run_devq cam_debug: xpt_run_dev_allocq cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 cam_debug: running device 0xc53b6c00 (probe0:ata2:0:1:0): xpt_setup_ccb cam_debug: calling periph start (probe0:ata2:0:1:0): probestart (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): INQUIRY. CDB: 12 0 0 0 24 0 (xpt0:ata2:0:0:0): camisr (xpt0:ata2:0:0:0): xpt_scan_bus (xpt0:ata2:0:0:0): xpt_setup_ccb (xpt0:ata2:0:0:0): xpt_action (xpt0:ata2:0:0:0): xpt_free_path (xpt0:ata2:0:0:0): xpt_release_path ad10: 381553MB at ata5-master SATA150 (noperiph:ata3:0:-1:-1): xpt_compile_path (noperiph:ata3:0:-1:-1): xpt_setup_ccb (noperiph:ata3:0:-1:-1): xpt_action (noperiph:ata3:0:-1:-1): xpt_done (noperiph:ata3:0:-1:-1): xpt_async (noperiph:ata3:0:-1:-1): xpt_release_path (noperiph:ata3:0:-1:-1): xpt_compile_path (noperiph:ata3:0:-1:-1): Registered SIM for ata3 (noperiph:ata3:0:-1:-1): xpt_setup_ccb (noperiph:ata3:0:-1:-1): xpt_action (xpt0:ata3:0:-1:-1): xpt_compile_path (xpt0:ata3:0:-1:-1): Rescanning ATAPI bus. (xpt0:ata3:0:-1:-1): xpt_setup_ccb (xpt0:ata3:0:-1:-1): xpt_action (xpt0:ata3:0:-1:-1): xpt_scan_bus (xpt0:ata3:0:-1:-1): xpt_setup_ccb (xpt0:ata3:0:-1:-1): xpt_action (xpt0:ata3:0:-1:-1): xpt_done (noperiph:ata3:0:0:0): xpt_compile_path (noperiph:ata3:0:0:0): xpt_setup_ccb (noperiph:ata3:0:0:0): xpt_action (noperiph:ata3:0:0:0): xpt_done (noperiph:ata3:0:0:0): xpt_setup_ccb (noperiph:ata3:0:0:0): xpt_action (noperiph:ata3:0:0:0): xpt_setup_ccb (noperiph:ata3:0:0:0): xpt_action (noperiph:ata3:0:0:0): xpt_done (noperiph:ata3:0:0:0): xpt_setup_ccb (noperiph:ata3:0:0:0): xpt_action (noperiph:ata3:0:0:0): GET_TRAN_SETTINGS (noperiph:ata3:0:0:0): xpt_done (noperiph:ata3:0:0:0): SET_TRAN_SETTINGS not supported (noperiph:ata3:0:0:0): xpt_done (noperiph:ata3:0:0:0): xpt_release_path (xpt0:ata3:0:0:0): xpt_compile_path (xpt0:ata3:0:0:0): xpt_setup_ccb (xpt0:ata3:0:0:0): xpt_action (xpt0:ata3:0:0:0): xpt_scan_lun (xpt0:ata3:0:0:0): xpt_setup_ccb (xpt0:ata3:0:0:0): xpt_action (xpt0:ata3:0:0:0): xpt_done (xpt0:ata3:0:0:0): xpt_done (noperiph:ata3:0:1:0): xpt_compile_path (noperiph:ata3:0:1:0): xpt_setup_ccb (noperiph:ata3:0:1:0): xpt_action (noperiph:ata3:0:1:0): xpt_done (noperiph:ata3:0:1:0): xpt_setup_ccb (noperiph:ata3:0:1:0): xpt_action (noperiph:ata3:0:1:0): xpt_setup_ccb (noperiph:ata3:0:1:0): xpt_action (noperiph:ata3:0:1:0): xpt_done (noperiph:ata3:0:1:0): xpt_setup_ccb (noperiph:ata3:0:1:0): xpt_action (noperiph:ata3:0:1:0): GET_TRAN_SETTINGS (noperiph:ata3:0:1:0): xpt_done (noperiph:ata3:0:1:0): SET_TRAN_SETTINGS not supported (noperiph:ata3:0:1:0): xpt_done (noperiph:ata3:0:1:0): xpt_release_path (xpt0:ata3:0:1:0): xpt_compile_path (xpt0:ata3:0:1:0): xpt_setup_ccb (xpt0:ata3:0:1:0): xpt_action (xpt0:ata3:0:1:0): xpt_scan_lun (xpt0:ata3:0:1:0): xpt_setup_ccb (xpt0:ata3:0:1:0): xpt_action (xpt0:ata3:0:1:0): xpt_done (xpt0:ata3:0:1:0): xpt_done (xpt0:ata3:0:0:0): camisr (xpt0:ata3:0:0:0): xpt_scan_bus (xpt0:ata3:0:0:0): xpt_setup_ccb (xpt0:ata3:0:0:0): xpt_action (xpt0:ata3:0:0:0): xpt_free_path (xpt0:ata3:0:0:0): xpt_release_path (xpt0:ata3:0:1:0): camisr (xpt0:ata3:0:1:0): xpt_scan_bus (xpt0:ata3:0:1:0): xpt_setup_ccb (xpt0:ata3:0:1:0): xpt_action (xpt0:ata3:0:1:0): xpt_free_path (xpt0:ata3:0:1:0): xpt_release_path (xpt0:ata3:0:-1:-1): xpt_done (xpt0:ata3:0:-1:-1): camisr (xpt0:ata3:0:-1:-1): Rescan succeeded (xpt0:ata3:0:-1:-1): xpt_free_path (xpt0:ata3:0:-1:-1): xpt_release_path ad12: 114440MB at ata6-master SATA150 (noperiph:ata4:0:-1:-1): xpt_compile_path (noperiph:ata4:0:-1:-1): xpt_setup_ccb (noperiph:ata4:0:-1:-1): xpt_action (noperiph:ata4:0:-1:-1): xpt_done (noperiph:ata4:0:-1:-1): xpt_async (noperiph:ata4:0:-1:-1): xpt_release_path (noperiph:ata4:0:-1:-1): xpt_compile_path (noperiph:ata4:0:-1:-1): Registered SIM for ata4 (noperiph:ata4:0:-1:-1): xpt_setup_ccb (noperiph:ata4:0:-1:-1): xpt_action (xpt0:ata4:0:-1:-1): xpt_compile_path (xpt0:ata4:0:-1:-1): Rescanning ATAPI bus. (xpt0:ata4:0:-1:-1): xpt_setup_ccb (xpt0:ata4:0:-1:-1): xpt_action (xpt0:ata4:0:-1:-1): xpt_scan_bus (xpt0:ata4:0:-1:-1): xpt_setup_ccb (xpt0:ata4:0:-1:-1): xpt_action (xpt0:ata4:0:-1:-1): xpt_done (noperiph:ata4:0:0:0): xpt_compile_path (noperiph:ata4:0:0:0): xpt_setup_ccb (noperiph:ata4:0:0:0): xpt_action (noperiph:ata4:0:0:0): xpt_done (noperiph:ata4:0:0:0): xpt_setup_ccb (noperiph:ata4:0:0:0): xpt_action (noperiph:ata4:0:0:0): xpt_setup_ccb (noperiph:ata4:0:0:0): xpt_action (noperiph:ata4:0:0:0): xpt_done (noperiph:ata4:0:0:0): xpt_setup_ccb (noperiph:ata4:0:0:0): xpt_action (noperiph:ata4:0:0:0): GET_TRAN_SETTINGS (noperiph:ata4:0:0:0): xpt_done (noperiph:ata4:0:0:0): SET_TRAN_SETTINGS not supported (noperiph:ata4:0:0:0): xpt_done (noperiph:ata4:0:0:0): xpt_release_path (xpt0:ata4:0:0:0): xpt_compile_path (xpt0:ata4:0:0:0): xpt_setup_ccb (xpt0:ata4:0:0:0): xpt_action (xpt0:ata4:0:0:0): xpt_scan_lun (xpt0:ata4:0:0:0): xpt_setup_ccb (xpt0:ata4:0:0:0): xpt_action (xpt0:ata4:0:0:0): xpt_done (xpt0:ata4:0:0:0): xpt_done (noperiph:ata4:0:1:0): xpt_compile_path (noperiph:ata4:0:1:0): xpt_setup_ccb (noperiph:ata4:0:1:0): xpt_action (noperiph:ata4:0:1:0): xpt_done (noperiph:ata4:0:1:0): xpt_setup_ccb (noperiph:ata4:0:1:0): xpt_action (noperiph:ata4:0:1:0): xpt_setup_ccb (noperiph:ata4:0:1:0): xpt_action (noperiph:ata4:0:1:0): xpt_done (noperiph:ata4:0:1:0): xpt_setup_ccb (noperiph:ata4:0:1:0): xpt_action (noperiph:ata4:0:1:0): GET_TRAN_SETTINGS (noperiph:ata4:0:1:0): xpt_done (noperiph:ata4:0:1:0): SET_TRAN_SETTINGS not supported (noperiph:ata4:0:1:0): xpt_done (noperiph:ata4:0:1:0): xpt_release_path (xpt0:ata4:0:1:0): xpt_compile_path (xpt0:ata4:0:1:0): xpt_setup_ccb (xpt0:ata4:0:1:0): xpt_action (xpt0:ata4:0:1:0): xpt_scan_lun (xpt0:ata4:0:1:0): xpt_setup_ccb (xpt0:ata4:0:1:0): xpt_action (xpt0:ata4:0:1:0): xpt_done (xpt0:ata4:0:1:0): xpt_done (noperiph:ata5:0:-1:-1): xpt_compile_path (noperiph:ata5:0:-1:-1): xpt_setup_ccb (noperiph:ata5:0:-1:-1): xpt_action (noperiph:ata5:0:-1:-1): xpt_done (noperiph:ata5:0:-1:-1): xpt_async (noperiph:ata5:0:-1:-1): xpt_release_path (noperiph:ata5:0:-1:-1): xpt_compile_path (noperiph:ata5:0:-1:-1): Registered SIM for ata5 (noperiph:ata5:0:-1:-1): xpt_setup_ccb (noperiph:ata5:0:-1:-1): xpt_action (xpt0:ata5:0:-1:-1): xpt_compile_path (xpt0:ata5:0:-1:-1): Rescanning ATAPI bus. (xpt0:ata5:0:-1:-1): xpt_setup_ccb (xpt0:ata5:0:-1:-1): xpt_action (xpt0:ata5:0:-1:-1): xpt_scan_bus (xpt0:ata5:0:-1:-1): xpt_setup_ccb (xpt0:ata5:0:-1:-1): xpt_action (xpt0:ata5:0:-1:-1): xpt_done (noperiph:ata5:0:0:0): xpt_compile_path (noperiph:ata5:0:0:0): xpt_setup_ccb (noperiph:ata5:0:0:0): xpt_action (noperiph:ata5:0:0:0): xpt_done (noperiph:ata5:0:0:0): xpt_setup_ccb (noperiph:ata5:0:0:0): xpt_action (noperiph:ata5:0:0:0): xpt_setup_ccb (noperiph:ata5:0:0:0): xpt_action (noperiph:ata5:0:0:0): xpt_done (noperiph:ata5:0:0:0): xpt_setup_ccb (noperiph:ata5:0:0:0): xpt_action (noperiph:ata5:0:0:0): GET_TRAN_SETTINGS (noperiph:ata5:0:0:0): xpt_done (noperiph:ata5:0:0:0): SET_TRAN_SETTINGS not supported (noperiph:ata5:0:0:0): xpt_done (noperiph:ata5:0:0:0): xpt_release_path (xpt0:ata5:0:0:0): xpt_compile_path (xpt0:ata5:0:0:0): xpt_setup_ccb (xpt0:ata5:0:0:0): xpt_action (xpt0:ata5:0:0:0): xpt_scan_lun (xpt0:ata5:0:0:0): xpt_setup_ccb (xpt0:ata5:0:0:0): xpt_action (xpt0:ata5:0:0:0): xpt_done (xpt0:ata5:0:0:0): xpt_done (noperiph:ata5:0:1:0): xpt_compile_path (noperiph:ata5:0:1:0): xpt_setup_ccb (noperiph:ata5:0:1:0): xpt_action (noperiph:ata5:0:1:0): xpt_done (noperiph:ata5:0:1:0): xpt_setup_ccb (noperiph:ata5:0:1:0): xpt_action (noperiph:ata5:0:1:0): xpt_setup_ccb (noperiph:ata5:0:1:0): xpt_action (noperiph:ata5:0:1:0): xpt_done (noperiph:ata5:0:1:0): xpt_setup_ccb (noperiph:ata5:0:1:0): xpt_action (noperiph:ata5:0:1:0): GET_TRAN_SETTINGS (noperiph:ata5:0:1:0): xpt_done (noperiph:ata5:0:1:0): SET_TRAN_SETTINGS not supported (noperiph:ata5:0:1:0): xpt_done (noperiph:ata5:0:1:0): xpt_release_path (xpt0:ata5:0:1:0): xpt_compile_path (xpt0:ata5:0:1:0): xpt_setup_ccb (xpt0:ata5:0:1:0): xpt_action (xpt0:ata5:0:1:0): xpt_scan_lun (xpt0:ata5:0:1:0): xpt_setup_ccb (xpt0:ata5:0:1:0): xpt_action (xpt0:ata5:0:1:0): xpt_done (xpt0:ata5:0:1:0): xpt_done (noperiph:ata6:0:-1:-1): xpt_compile_path (noperiph:ata6:0:-1:-1): xpt_setup_ccb (noperiph:ata6:0:-1:-1): xpt_action (noperiph:ata6:0:-1:-1): xpt_done (noperiph:ata6:0:-1:-1): xpt_async (noperiph:ata6:0:-1:-1): xpt_release_path (noperiph:ata6:0:-1:-1): xpt_compile_path (noperiph:ata6:0:-1:-1): Registered SIM for ata6 (noperiph:ata6:0:-1:-1): xpt_setup_ccb (noperiph:ata6:0:-1:-1): xpt_action (xpt0:ata6:0:-1:-1): xpt_compile_path (xpt0:ata6:0:-1:-1): Rescanning ATAPI bus. (xpt0:ata6:0:-1:-1): xpt_setup_ccb (xpt0:ata6:0:-1:-1): xpt_action (xpt0:ata6:0:-1:-1): xpt_scan_bus (xpt0:ata6:0:-1:-1): xpt_setup_ccb (xpt0:ata6:0:-1:-1): xpt_action (xpt0:ata6:0:-1:-1): xpt_done (noperiph:ata6:0:0:0): xpt_compile_path (noperiph:ata6:0:0:0): xpt_setup_ccb (noperiph:ata6:0:0:0): xpt_action (noperiph:ata6:0:0:0): xpt_done (noperiph:ata6:0:0:0): xpt_setup_ccb (noperiph:ata6:0:0:0): xpt_action (noperiph:ata6:0:0:0): xpt_setup_ccb (noperiph:ata6:0:0:0): xpt_action (noperiph:ata6:0:0:0): xpt_done (noperiph:ata6:0:0:0): xpt_setup_ccb (noperiph:ata6:0:0:0): xpt_action (noperiph:ata6:0:0:0): GET_TRAN_SETTINGS (noperiph:ata6:0:0:0): xpt_done (noperiph:ata6:0:0:0): SET_TRAN_SETTINGS not supported (noperiph:ata6:0:0:0): xpt_done (noperiph:ata6:0:0:0): xpt_release_path (xpt0:ata6:0:0:0): xpt_compile_path (xpt0:ata6:0:0:0): xpt_setup_ccb (xpt0:ata6:0:0:0): xpt_action (xpt0:ata6:0:0:0): xpt_scan_lun (xpt0:ata6:0:0:0): xpt_setup_ccb (xpt0:ata6:0:0:0): xpt_action (xpt0:ata6:0:0:0): xpt_done (xpt0:ata6:0:0:0): xpt_done (noperiph:ata6:0:1:0): xpt_compile_path (noperiph:ata6:0:1:0): xpt_setup_ccb (noperiph:ata6:0:1:0): xpt_action (noperiph:ata6:0:1:0): xpt_done (noperiph:ata6:0:1:0): xpt_setup_ccb (noperiph:ata6:0:1:0): xpt_action (noperiph:ata6:0:1:0): xpt_setup_ccb (noperiph:ata6:0:1:0): xpt_action (noperiph:ata6:0:1:0): xpt_done (noperiph:ata6:0:1:0): xpt_setup_ccb (noperiph:ata6:0:1:0): xpt_action (noperiph:ata6:0:1:0): GET_TRAN_SETTINGS (noperiph:ata6:0:1:0): xpt_done (noperiph:ata6:0:1:0): SET_TRAN_SETTINGS not supported (noperiph:ata6:0:1:0): xpt_done (noperiph:ata6:0:1:0): xpt_release_path (xpt0:ata6:0:1:0): xpt_compile_path (xpt0:ata6:0:1:0): xpt_setup_ccb (xpt0:ata6:0:1:0): xpt_action (xpt0:ata6:0:1:0): xpt_scan_lun (xpt0:ata6:0:1:0): xpt_setup_ccb (xpt0:ata6:0:1:0): xpt_action (xpt0:ata6:0:1:0): xpt_done (xpt0:ata6:0:1:0): xpt_done (noperiph:ata7:0:-1:-1): xpt_compile_path (noperiph:ata7:0:-1:-1): xpt_setup_ccb (noperiph:ata7:0:-1:-1): xpt_action (noperiph:ata7:0:-1:-1): xpt_done (noperiph:ata7:0:-1:-1): xpt_async (noperiph:ata7:0:-1:-1): xpt_release_path (noperiph:ata7:0:-1:-1): xpt_compile_path (noperiph:ata7:0:-1:-1): Registered SIM for ata7 (noperiph:ata7:0:-1:-1): xpt_setup_ccb (noperiph:ata7:0:-1:-1): xpt_action (xpt0:ata7:0:-1:-1): xpt_compile_path (xpt0:ata7:0:-1:-1): Rescanning ATAPI bus. (xpt0:ata7:0:-1:-1): xpt_setup_ccb (xpt0:ata7:0:-1:-1): xpt_action (xpt0:ata7:0:-1:-1): xpt_scan_bus (xpt0:ata7:0:-1:-1): xpt_setup_ccb (xpt0:ata7:0:-1:-1): xpt_action (xpt0:ata7:0:-1:-1): xpt_done (noperiph:ata7:0:0:0): xpt_compile_path (noperiph:ata7:0:0:0): xpt_setup_ccb (noperiph:ata7:0:0:0): xpt_action (noperiph:ata7:0:0:0): xpt_done (noperiph:ata7:0:0:0): xpt_setup_ccb (noperiph:ata7:0:0:0): xpt_action (noperiph:ata7:0:0:0): xpt_setup_ccb (noperiph:ata7:0:0:0): xpt_action (noperiph:ata7:0:0:0): xpt_done (noperiph:ata7:0:0:0): xpt_setup_ccb (noperiph:ata7:0:0:0): xpt_action (noperiph:ata7:0:0:0): GET_TRAN_SETTINGS (noperiph:ata7:0:0:0): xpt_done (noperiph:ata7:0:0:0): SET_TRAN_SETTINGS not supported (noperiph:ata7:0:0:0): xpt_done (noperiph:ata7:0:0:0): xpt_release_path (xpt0:ata7:0:0:0): xpt_compile_path (xpt0:ata7:0:0:0): xpt_setup_ccb (xpt0:ata7:0:0:0): xpt_action (xpt0:ata7:0:0:0): xpt_scan_lun (xpt0:ata7:0:0:0): xpt_setup_ccb (xpt0:ata7:0:0:0): xpt_action (xpt0:ata7:0:0:0): xpt_done (xpt0:ata7:0:0:0): xpt_done (noperiph:ata7:0:1:0): xpt_compile_path (noperiph:ata7:0:1:0): xpt_setup_ccb (noperiph:ata7:0:1:0): xpt_action (noperiph:ata7:0:1:0): xpt_done (noperiph:ata7:0:1:0): xpt_setup_ccb (noperiph:ata7:0:1:0): xpt_action (noperiph:ata7:0:1:0): xpt_setup_ccb (noperiph:ata7:0:1:0): xpt_action (noperiph:ata7:0:1:0): xpt_done (noperiph:ata7:0:1:0): xpt_setup_ccb (noperiph:ata7:0:1:0): xpt_action (noperiph:ata7:0:1:0): GET_TRAN_SETTINGS (noperiph:ata7:0:1:0): xpt_done (noperiph:ata7:0:1:0): SET_TRAN_SETTINGS not supported (noperiph:ata7:0:1:0): xpt_done (noperiph:ata7:0:1:0): xpt_release_path (xpt0:ata7:0:1:0): xpt_compile_path (xpt0:ata7:0:1:0): xpt_setup_ccb (xpt0:ata7:0:1:0): xpt_action (xpt0:ata7:0:1:0): xpt_scan_lun (xpt0:ata7:0:1:0): xpt_setup_ccb (xpt0:ata7:0:1:0): xpt_action (xpt0:ata7:0:1:0): xpt_done (xpt0:ata7:0:1:0): xpt_done (noperiph:ata8:0:-1:-1): xpt_compile_path (noperiph:ata8:0:-1:-1): xpt_setup_ccb (noperiph:ata8:0:-1:-1): xpt_action (noperiph:ata8:0:-1:-1): xpt_done (noperiph:ata8:0:-1:-1): xpt_async (noperiph:ata8:0:-1:-1): xpt_release_path (noperiph:ata8:0:-1:-1): xpt_compile_path (noperiph:ata8:0:-1:-1): Registered SIM for ata8 (noperiph:ata8:0:-1:-1): xpt_setup_ccb (noperiph:ata8:0:-1:-1): xpt_action (xpt0:ata8:0:-1:-1): xpt_compile_path (xpt0:ata8:0:-1:-1): Rescanning ATAPI bus. (xpt0:ata8:0:-1:-1): xpt_setup_ccb (xpt0:ata8:0:-1:-1): xpt_action (xpt0:ata8:0:-1:-1): xpt_scan_bus (xpt0:ata8:0:-1:-1): xpt_setup_ccb (xpt0:ata8:0:-1:-1): xpt_action (xpt0:ata8:0:-1:-1): xpt_done (noperiph:ata8:0:0:0): xpt_compile_path (noperiph:ata8:0:0:0): xpt_setup_ccb (noperiph:ata8:0:0:0): xpt_action (noperiph:ata8:0:0:0): xpt_done (noperiph:ata8:0:0:0): xpt_setup_ccb (noperiph:ata8:0:0:0): xpt_action (noperiph:ata8:0:0:0): xpt_setup_ccb (noperiph:ata8:0:0:0): xpt_action (noperiph:ata8:0:0:0): xpt_done (noperiph:ata8:0:0:0): xpt_setup_ccb (noperiph:ata8:0:0:0): xpt_action (noperiph:ata8:0:0:0): GET_TRAN_SETTINGS (noperiph:ata8:0:0:0): xpt_done (noperiph:ata8:0:0:0): SET_TRAN_SETTINGS not supported (noperiph:ata8:0:0:0): xpt_done (noperiph:ata8:0:0:0): xpt_release_path (xpt0:ata8:0:0:0): xpt_compile_path (xpt0:ata8:0:0:0): xpt_setup_ccb (xpt0:ata8:0:0:0): xpt_action (xpt0:ata8:0:0:0): xpt_scan_lun (xpt0:ata8:0:0:0): xpt_setup_ccb (xpt0:ata8:0:0:0): xpt_action (xpt0:ata8:0:0:0): xpt_done (xpt0:ata8:0:0:0): xpt_done (noperiph:ata8:0:1:0): xpt_compile_path (noperiph:ata8:0:1:0): xpt_setup_ccb (noperiph:ata8:0:1:0): xpt_action (noperiph:ata8:0:1:0): xpt_done (noperiph:ata8:0:1:0): xpt_setup_ccb (noperiph:ata8:0:1:0): xpt_action (noperiph:ata8:0:1:0): xpt_setup_ccb (noperiph:ata8:0:1:0): xpt_action (noperiph:ata8:0:1:0): xpt_done (noperiph:ata8:0:1:0): xpt_setup_ccb (noperiph:ata8:0:1:0): xpt_action (noperiph:ata8:0:1:0): GET_TRAN_SETTINGS (noperiph:ata8:0:1:0): xpt_done (noperiph:ata8:0:1:0): SET_TRAN_SETTINGS not supported (noperiph:ata8:0:1:0): xpt_done (noperiph:ata8:0:1:0): xpt_release_path (xpt0:ata8:0:1:0): xpt_compile_path (xpt0:ata8:0:1:0): xpt_setup_ccb (xpt0:ata8:0:1:0): xpt_action (xpt0:ata8:0:1:0): xpt_scan_lun (xpt0:ata8:0:1:0): xpt_setup_ccb (xpt0:ata8:0:1:0): xpt_action (xpt0:ata8:0:1:0): xpt_done (xpt0:ata8:0:1:0): xpt_done SMP: AP CPU #1 Launched! (xpt0:ata4:0:0:0): camisr (xpt0:ata4:0:0:0): xpt_scan_bus (xpt0:ata4:0:0:0): xpt_setup_ccb (xpt0:ata4:0:0:0): xpt_action (xpt0:ata4:0:0:0): xpt_free_path (xpt0:ata4:0:0:0): xpt_release_path (xpt0:ata4:0:1:0): camisr (xpt0:ata4:0:1:0): xpt_scan_bus (xpt0:ata4:0:1:0): xpt_setup_ccb (xpt0:ata4:0:1:0): xpt_action (xpt0:ata4:0:1:0): xpt_free_path (xpt0:ata4:0:1:0): xpt_release_path (xpt0:ata4:0:-1:-1): xpt_done (xpt0:ata5:0:0:0): camisr (xpt0:ata5:0:0:0): xpt_scan_bus (xpt0:ata5:0:0:0): xpt_setup_ccb (xpt0:ata5:0:0:0): xpt_action (xpt0:ata5:0:0:0): xpt_free_path (xpt0:ata5:0:0:0): xpt_release_path (xpt0:ata5:0:1:0): camisr (xpt0:ata5:0:1:0): xpt_scan_bus (xpt0:ata5:0:1:0): xpt_setup_ccb (xpt0:ata5:0:1:0): xpt_action (xpt0:ata5:0:1:0): xpt_free_path (xpt0:ata5:0:1:0): xpt_release_path (xpt0:ata5:0:-1:-1): xpt_done (xpt0:ata6:0:0:0): camisr (xpt0:ata6:0:0:0): xpt_scan_bus (xpt0:ata6:0:0:0): xpt_setup_ccb (xpt0:ata6:0:0:0): xpt_action (xpt0:ata6:0:0:0): xpt_free_path (xpt0:ata6:0:0:0): xpt_release_path (xpt0:ata6:0:1:0): camisr (xpt0:ata6:0:1:0): xpt_scan_bus (xpt0:ata6:0:1:0): xpt_setup_ccb (xpt0:ata6:0:1:0): xpt_action (xpt0:ata6:0:1:0): xpt_free_path (xpt0:ata6:0:1:0): xpt_release_path (xpt0:ata6:0:-1:-1): xpt_done (xpt0:ata7:0:0:0): camisr (xpt0:ata7:0:0:0): xpt_scan_bus (xpt0:ata7:0:0:0): xpt_setup_ccb (xpt0:ata7:0:0:0): xpt_action (xpt0:ata7:0:0:0): xpt_free_path (xpt0:ata7:0:0:0): xpt_release_path (xpt0:ata7:0:1:0): camisr (xpt0:ata7:0:1:0): xpt_scan_bus (xpt0:ata7:0:1:0): xpt_setup_ccb (xpt0:ata7:0:1:0): xpt_action (xpt0:ata7:0:1:0): xpt_free_path (xpt0:ata7:0:1:0): xpt_release_path (xpt0:ata7:0:-1:-1): xpt_done (xpt0:ata8:0:0:0): camisr (xpt0:ata8:0:0:0): xpt_scan_bus (xpt0:ata8:0:0:0): xpt_setup_ccb (xpt0:ata8:0:0:0): xpt_action (xpt0:ata8:0:0:0): xpt_free_path (xpt0:ata8:0:0:0): xpt_release_path (xpt0:ata8:0:1:0): camisr (xpt0:ata8:0:1:0): xpt_scan_bus (xpt0:ata8:0:1:0): xpt_setup_ccb (xpt0:ata8:0:1:0): xpt_action (xpt0:ata8:0:1:0): xpt_free_path (xpt0:ata8:0:1:0): xpt_release_path (xpt0:ata8:0:-1:-1): xpt_done (xpt0:ata4:0:-1:-1): camisr (xpt0:ata4:0:-1:-1): Rescan succeeded (xpt0:ata4:0:-1:-1): xpt_free_path (xpt0:ata4:0:-1:-1): xpt_release_path (xpt0:ata5:0:-1:-1): camisr (xpt0:ata5:0:-1:-1): Rescan succeeded (xpt0:ata5:0:-1:-1): xpt_free_path (xpt0:ata5:0:-1:-1): xpt_release_path (xpt0:ata6:0:-1:-1): camisr (xpt0:ata6:0:-1:-1): Rescan succeeded (xpt0:ata6:0:-1:-1): xpt_free_path (xpt0:ata6:0:-1:-1): xpt_release_path (xpt0:ata7:0:-1:-1): camisr (xpt0:ata7:0:-1:-1): Rescan succeeded (xpt0:ata7:0:-1:-1): xpt_free_path (xpt0:ata7:0:-1:-1): xpt_release_path (xpt0:ata8:0:-1:-1): camisr (xpt0:ata8:0:-1:-1): Rescan succeeded (xpt0:ata8:0:-1:-1): xpt_free_path (xpt0:ata8:0:-1:-1): xpt_release_path Trying to mount root from ufs:/dev/ad10s1a cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_sendq cam_debug: running device 0xc53b6c00 (probe0:ata2:0:1:0): XPT_SCSI_IO atapi_action: hcb@0xc554e680: 12 0 0 0 24 0 acd0: req=0xc55889c0 INQUIRY queued acd0: req=0xc55889c0 INQUIRY starting acd0: req=0xc55889c0 INQUIRY begin transaction acd0: req=0xc55889c0 INQUIRY interrupt acd0: req=0xc55889c0 INQUIRY end transaction acd0: req=0xc55889c0 INQUIRY finish taskqueue_swi acd0: req=0xc55889c0 INQUIRY completed entered acd0: req=0xc55889c0 INQUIRY completed callback/wakeup atapi_cb: hcb@0xc554e680 sense = 00: sk = 0) acd0: cmd INQUIRY status 50 result 00 error 00 (probe0:ata2:0:1:0): xpt_done (probe0:ata2:0:1:0): camisr (probe0:ata2:0:1:0): probedone cam_debug: xpt_release_ccb (probe0:ata2:0:1:0): xpt_schedule (probe0:ata2:0:1:0): added periph to queue cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue (probe0:ata2:0:1:0): calling xpt_run_devq cam_debug: xpt_run_dev_allocq cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 cam_debug: running device 0xc53b6c00 (probe0:ata2:0:1:0): xpt_setup_ccb cam_debug: calling periph start (probe0:ata2:0:1:0): probestart (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): INQUIRY. CDB: 12 0 0 0 60 0 cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_sendq cam_debug: running device 0xc53b6c00 (probe0:ata2:0:1:0): XPT_SCSI_IO atapi_action: hcb@0xc554e580: 12 0 0 0 60 0 acd0: req=0xc55883c0 INQUIRY queued acd0: req=0xc55883c0 INQUIRY starting acd0: req=0xc55883c0 INQUIRY begin transaction acd0: req=0xc55883c0 INQUIRY interrupt acd0: req=0xc55883c0 INQUIRY end transaction acd0: req=0xc55883c0 INQUIRY finish taskqueue_swi acd0: req=0xc55883c0 INQUIRY completed entered acd0: req=0xc55883c0 INQUIRY completed callback/wakeup atapi_cb: hcb@0xc554e580 sense = 00: sk = 0) acd0: cmd INQUIRY status 50 result 00 error 00 (probe0:ata2:0:1:0): xpt_done (probe0:ata2:0:1:0): camisr (probe0:ata2:0:1:0): probedone (probe0:ata2:0:1:0): xpt_setup_ccb (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): xpt_done (probe0:ata2:0:1:0): xpt_setup_ccb (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): xpt_setup_ccb (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): xpt_done (probe0:ata2:0:1:0): xpt_setup_ccb (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): GET_TRAN_SETTINGS (probe0:ata2:0:1:0): xpt_done (probe0:ata2:0:1:0): SET_TRAN_SETTINGS not supported (probe0:ata2:0:1:0): xpt_done cam_debug: xpt_release_ccb (probe0:ata2:0:1:0): xpt_schedule (probe0:ata2:0:1:0): added periph to queue cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue (probe0:ata2:0:1:0): calling xpt_run_devq cam_debug: xpt_run_dev_allocq cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 cam_debug: running device 0xc53b6c00 (probe0:ata2:0:1:0): xpt_setup_ccb cam_debug: calling periph start (probe0:ata2:0:1:0): probestart (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): INQUIRY. CDB: 12 1 80 0 ff 0 cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_sendq cam_debug: running device 0xc53b6c00 (probe0:ata2:0:1:0): XPT_SCSI_IO atapi_action: hcb@0xc554e4c0: 12 1 80 0 ff 0 acd0: req=0xc5556180 INQUIRY queued acd0: req=0xc5556180 INQUIRY starting acd0: req=0xc5556180 INQUIRY begin transaction (xpt0:xpt0:0:-1:-1): xpt_action acd0: req=0xc5556180 INQUIRY timeout acd0: req=0xc5556180 INQUIRY finish taskqueue_swi acd0: req=0xc5556180 INQUIRY completed entered (noperiph:ata2:0:-1:-1): xpt_async (noperiph:ata2:0:1:0): xpt_compile_path (noperiph:ata2:0:1:0): xpt_release_path acd0: FAILURE - INQUIRY timed out acd0: req=0xc5556180 INQUIRY completed callback/wakeup atapi_cb: hcb@0xc554e4c0 sense = 00: sk = 0) acd0: cmd INQUIRY status 00 result 05 error 00 (probe0:ata2:0:1:0): xpt_done (probe0:ata2:0:1:0): camisr (probe0:ata2:0:1:0): probedone (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): INQUIRY. CDB: 12 1 80 0 ff 0 cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_sendq cam_debug: running device 0xc53b6c00 (probe0:ata2:0:1:0): XPT_SCSI_IO atapi_action: hcb@0xc555d500: 12 1 80 0 ff 0 acd0: req=0xc55889c0 INQUIRY queued acd0: req=0xc55889c0 INQUIRY starting acd0: req=0xc55889c0 INQUIRY begin transaction (xpt0:xpt0:0:-1:-1): xpt_action acd0: req=0xc55889c0 INQUIRY timeout acd0: req=0xc55889c0 INQUIRY finish taskqueue_swi acd0: req=0xc55889c0 INQUIRY completed entered (noperiph:ata2:0:-1:-1): xpt_async (noperiph:ata2:0:1:0): xpt_compile_path (noperiph:ata2:0:1:0): xpt_release_path acd0: FAILURE - INQUIRY timed out acd0: req=0xc55889c0 INQUIRY completed callback/wakeup atapi_cb: hcb@0xc555d500 sense = 00: sk = 0) acd0: cmd INQUIRY status 00 result 05 error 00 (probe0:ata2:0:1:0): xpt_done (probe0:ata2:0:1:0): camisr (probe0:ata2:0:1:0): probedone (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): INQUIRY. CDB: 12 1 80 0 ff 0 cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_sendq cam_debug: running device 0xc53b6c00 (probe0:ata2:0:1:0): XPT_SCSI_IO atapi_action: hcb@0xc555d540: 12 1 80 0 ff 0 acd0: req=0xc5588840 INQUIRY queued acd0: req=0xc5588840 INQUIRY starting acd0: req=0xc5588840 INQUIRY begin transaction acd0: req=0xc5588840 INQUIRY timeout acd0: req=0xc5588840 INQUIRY finish taskqueue_swi acd0: req=0xc5588840 INQUIRY completed entered (noperiph:ata2:0:-1:-1): xpt_async (noperiph:ata2:0:1:0): xpt_compile_path (noperiph:ata2:0:1:0): xpt_release_path acd0: FAILURE - INQUIRY timed out acd0: req=0xc5588840 INQUIRY completed callback/wakeup atapi_cb: hcb@0xc555d540 sense = 00: sk = 0) acd0: cmd INQUIRY status 00 result 05 error 00 (probe0:ata2:0:1:0): xpt_done (probe0:ata2:0:1:0): camisr (probe0:ata2:0:1:0): probedone (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): INQUIRY. CDB: 12 1 80 0 ff 0 cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_sendq cam_debug: running device 0xc53b6c00 (probe0:ata2:0:1:0): XPT_SCSI_IO atapi_action: hcb@0xc5595a00: 12 1 80 0 ff 0 acd0: req=0xc5555780 INQUIRY queued acd0: req=0xc5555780 INQUIRY starting acd0: req=0xc5555780 INQUIRY begin transaction acd0: req=0xc5555780 INQUIRY timeout acd0: req=0xc5555780 INQUIRY finish taskqueue_swi acd0: req=0xc5555780 INQUIRY completed entered (noperiph:ata2:0:-1:-1): xpt_async (noperiph:ata2:0:1:0): xpt_compile_path (noperiph:ata2:0:1:0): xpt_release_path acd0: FAILURE - INQUIRY timed out acd0: req=0xc5555780 INQUIRY completed callback/wakeup atapi_cb: hcb@0xc5595a00 sense = 00: sk = 0) acd0: cmd INQUIRY status 00 result 05 error 00 (probe0:ata2:0:1:0): xpt_done (probe0:ata2:0:1:0): camisr (probe0:ata2:0:1:0): probedone (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): INQUIRY. CDB: 12 1 80 0 ff 0 cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_sendq cam_debug: running device 0xc53b6c00 (probe0:ata2:0:1:0): XPT_SCSI_IO atapi_action: hcb@0xc554e100: 12 1 80 0 ff 0 acd0: req=0xc5588300 INQUIRY queued acd0: req=0xc5588300 INQUIRY starting acd0: req=0xc5588300 INQUIRY begin transaction acd0: req=0xc5588300 INQUIRY timeout acd0: req=0xc5588300 INQUIRY finish taskqueue_swi acd0: req=0xc5588300 INQUIRY completed entered (noperiph:ata2:0:-1:-1): xpt_async (noperiph:ata2:0:1:0): xpt_compile_path (noperiph:ata2:0:1:0): xpt_release_path acd0: FAILURE - INQUIRY timed out acd0: req=0xc5588300 INQUIRY completed callback/wakeup atapi_cb: hcb@0xc554e100 sense = 00: sk = 0) acd0: cmd INQUIRY status 00 result 05 error 00 (probe0:ata2:0:1:0): xpt_done (probe0:ata2:0:1:0): camisr (probe0:ata2:0:1:0): probedone (probe0:ata2:0:1:0): xpt_setup_ccb (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): GET_TRAN_SETTINGS (probe0:ata2:0:1:0): xpt_done (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): xpt_setup_ccb (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): xpt_done (probe0:ata2:0:1:0): xpt_setup_ccb (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): GET_TRAN_SETTINGS (probe0:ata2:0:1:0): xpt_done (probe0:ata2:0:1:0): SET_TRAN_SETTINGS not supported (probe0:ata2:0:1:0): xpt_done cam_debug: xpt_release_ccb (probe0:ata2:0:1:0): xpt_schedule (probe0:ata2:0:1:0): added periph to queue cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue (probe0:ata2:0:1:0): calling xpt_run_devq cam_debug: xpt_run_dev_allocq cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 cam_debug: running device 0xc53b6c00 (probe0:ata2:0:1:0): xpt_setup_ccb cam_debug: calling periph start (probe0:ata2:0:1:0): probestart (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_sendq cam_debug: running device 0xc53b6c00 (probe0:ata2:0:1:0): XPT_SCSI_IO atapi_action: hcb@0xc555d800: 0 0 0 0 0 0 acd0: req=0xc5588240 TEST_UNIT_READY queued acd0: req=0xc5588240 TEST_UNIT_READY starting acd0: req=0xc5588240 TEST_UNIT_READY begin transaction acd0: req=0xc5588240 TEST_UNIT_READY interrupt acd0: req=0xc5588240 TEST_UNIT_READY end transaction acd0: req=0xc5588240 TEST_UNIT_READY finish taskqueue_swi acd0: req=0xc5588240 TEST_UNIT_READY completed entered acd0: req=0xc5588240 TEST_UNIT_READY autoissue request sense acd0: req=0xc5588240 REQUEST_SENSE queued acd0: req=0xc5588240 REQUEST_SENSE starting acd0: req=0xc5588240 REQUEST_SENSE begin transaction acd0: req=0xc5588240 REQUEST_SENSE interrupt acd0: req=0xc5588240 REQUEST_SENSE end transaction acd0: req=0xc5588240 TEST_UNIT_READY interrupt acd0: req=0xc5588240 TEST_UNIT_READY end transaction acd0: req=0xc5588240 TEST_UNIT_READY finish taskqueue_swi acd0: req=0xc5588240 TEST_UNIT_READY completed entered acd0: req=0xc5588240 TEST_UNIT_READY completed callback/wakeup atapi_cb: hcb@0xc555d800 sense = 06: sk = 6) acd0: cmd TEST_UNIT_READY status 50 result 05 error 00 (probe0:ata2:0:1:0): xpt_done (probe0:ata2:0:1:0): camisr (probe0:ata2:0:1:0): probedone (probe0:ata2:0:1:0): xpt_action (probe0:ata2:0:1:0): xpt_async (pass0:ata2:0:1:0): xpt_compile_path (pass0:ata2:0:1:0): xpt_setup_ccb (pass0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): xpt_compile_path (cd0:ata2:0:1:0): xpt_setup_ccb (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): xpt_done (cd0:ata2:0:1:0): xpt_setup_ccb (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): xpt_schedule (cd0:ata2:0:1:0): added periph to queue cam_debug: xpt_release_ccb cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_allocq cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 cam_debug: running device 0xc53b6c00 (cd0:ata2:0:1:0): xpt_setup_ccb cam_debug: calling periph start (cd0:ata2:0:1:0): entering cdstart (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): READ CD RECORDED CAPACITY. CDB: 25 0 0 0 0 0 0 0 0 0 cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_sendq cam_debug: running device 0xc53b6c00 (cd0:ata2:0:1:0): XPT_SCSI_IO atapi_action: hcb@0xc5595cc0: 25 0 0 0 0 0 0 0 0 0 acd0: req=0xc55556c0 READ_CAPACITY queued acd0: req=0xc55556c0 READ_CAPACITY starting acd0: req=0xc55556c0 READ_CAPACITY begin transaction (xpt0:ata2:0:1:0): xpt_done (probe0:ata2:0:1:0): xpt_free_path (probe0:ata2:0:1:0): xpt_release_path (xpt0:ata2:0:1:0): camisr (xpt0:ata2:0:1:0): xpt_scan_bus (xpt0:ata2:0:1:0): xpt_setup_ccb (xpt0:ata2:0:1:0): xpt_action (xpt0:ata2:0:1:0): xpt_free_path (xpt0:ata2:0:1:0): xpt_release_path (xpt0:ata2:0:-1:-1): xpt_done (xpt0:ata2:0:-1:-1): camisr (xpt0:ata2:0:-1:-1): Rescan succeeded (xpt0:ata2:0:-1:-1): xpt_free_path (xpt0:ata2:0:-1:-1): xpt_release_path acd0: req=0xc55556c0 READ_CAPACITY timeout acd0: req=0xc55556c0 READ_CAPACITY finish taskqueue_swi acd0: req=0xc55556c0 READ_CAPACITY completed entered (noperiph:ata2:0:-1:-1): xpt_async (noperiph:ata2:0:1:0): xpt_compile_path (noperiph:ata2:0:1:0): xpt_release_path (cd0:ata2:0:1:0): xpt_setup_ccb (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): xpt_setup_ccb (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): xpt_setup_ccb (cd0:ata2:0:1:0): xpt_action acd0: FAILURE - READ_CAPACITY timed out acd0: req=0xc55556c0 READ_CAPACITY completed callback/wakeup atapi_cb: hcb@0xc5595cc0 sense = 00: sk = 0) acd0: cmd READ_CAPACITY status 00 result 05 error 00 (cd0:ata2:0:1:0): xpt_done (cd0:ata2:0:1:0): camisr (cd0:ata2:0:1:0): entering cddone (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): READ CD RECORDED CAPACITY. CDB: 25 0 0 0 0 0 0 0 0 0 cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_sendq cam_debug: running device 0xc53b6c00 (cd0:ata2:0:1:0): XPT_SCSI_IO atapi_action: hcb@0xc555d800: 25 0 0 0 0 0 0 0 0 0 acd0: req=0xc5556240 READ_CAPACITY queued acd0: req=0xc5556240 READ_CAPACITY starting acd0: req=0xc5556240 READ_CAPACITY begin transaction acd0: req=0xc5556240 READ_CAPACITY timeout acd0: req=0xc5556240 READ_CAPACITY finish taskqueue_swi acd0: req=0xc5556240 READ_CAPACITY completed entered (noperiph:ata2:0:-1:-1): xpt_async (noperiph:ata2:0:1:0): xpt_compile_path (noperiph:ata2:0:1:0): xpt_release_path (cd0:ata2:0:1:0): xpt_setup_ccb (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): xpt_setup_ccb (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): xpt_setup_ccb (cd0:ata2:0:1:0): xpt_action acd0: FAILURE - READ_CAPACITY timed out acd0: req=0xc5556240 READ_CAPACITY completed callback/wakeup atapi_cb: hcb@0xc555d800 sense = 00: sk = 0) acd0: cmd READ_CAPACITY status 00 result 05 error 00 (cd0:ata2:0:1:0): xpt_done (cd0:ata2:0:1:0): camisr (cd0:ata2:0:1:0): entering cddone (cd0:ata2:0:1:0): xpt_setup_ccb (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): xpt_setup_ccb (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): got CAM status 0xb (cd0:ata2:0:1:0): fatal error, failed to attach to device (cd0:ata2:0:1:0): xpt_setup_ccb (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): lost device cam_debug: xpt_release_ccb (cd0:ata2:0:1:0): entering cdprevent (cd0:ata2:0:1:0): entering cdgetccb (cd0:ata2:0:1:0): xpt_schedule (cd0:ata2:0:1:0): added periph to queue cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue (cd0:ata2:0:1:0): calling xpt_run_devq cam_debug: xpt_run_dev_allocq cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 cam_debug: running device 0xc53b6c00 (cd0:ata2:0:1:0): xpt_setup_ccb cam_debug: calling periph start (cd0:ata2:0:1:0): entering cdstart (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): PREVENT ALLOW MEDIUM REMOVAL. CDB: 1e 0 0 0 1 0 cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_sendq cam_debug: running device 0xc53b6c00 (cd0:ata2:0:1:0): XPT_SCSI_IO atapi_action: hcb@0xc554ed00: 1e 0 0 0 1 0 acd0: req=0xc5588900 PREVENT_ALLOW queued acd0: req=0xc5588900 PREVENT_ALLOW starting acd0: req=0xc5588900 PREVENT_ALLOW begin transaction acd0: req=0xc5588900 PREVENT_ALLOW interrupt acd0: req=0xc5588900 PREVENT_ALLOW end transaction acd0: req=0xc5588900 PREVENT_ALLOW finish taskqueue_swi acd0: req=0xc5588900 PREVENT_ALLOW completed entered acd0: req=0xc5588900 PREVENT_ALLOW autoissue request sense acd0: req=0xc5588900 REQUEST_SENSE queued acd0: req=0xc5588900 REQUEST_SENSE starting acd0: req=0xc5588900 REQUEST_SENSE begin transaction acd0: req=0xc5588900 REQUEST_SENSE interrupt acd0: req=0xc5588900 REQUEST_SENSE end transaction acd0: req=0xc5588900 PREVENT_ALLOW interrupt acd0: req=0xc5588900 PREVENT_ALLOW end transaction acd0: req=0xc5588900 PREVENT_ALLOW finish taskqueue_swi acd0: req=0xc5588900 PREVENT_ALLOW completed entered acd0: req=0xc5588900 PREVENT_ALLOW completed callback/wakeup atapi_cb: hcb@0xc554ed00 sense = 06: sk = 6) acd0: cmd PREVENT_ALLOW status 50 result 05 error 00 (cd0:ata2:0:1:0): xpt_done (cd0:ata2:0:1:0): camisr (cd0:ata2:0:1:0): entering cddone (cd0:ata2:0:1:0): trying to wakeup ccbwait (cd0:ata2:0:1:0): xpt_setup_ccb (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): PREVENT ALLOW MEDIUM REMOVAL. CDB: 1e 0 0 0 1 0 cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_sendq cam_debug: running device 0xc53b6c00 (cd0:ata2:0:1:0): XPT_SCSI_IO atapi_action: hcb@0xc5595900: 1e 0 0 0 1 0 acd0: req=0xc5588240 PREVENT_ALLOW queued acd0: req=0xc5588240 PREVENT_ALLOW starting acd0: req=0xc5588240 PREVENT_ALLOW begin transaction acd0: req=0xc5588240 PREVENT_ALLOW interrupt acd0: req=0xc5588240 PREVENT_ALLOW end transaction acd0: req=0xc5588240 PREVENT_ALLOW finish taskqueue_swi acd0: req=0xc5588240 PREVENT_ALLOW completed entered acd0: req=0xc5588240 PREVENT_ALLOW completed callback/wakeup atapi_cb: hcb@0xc5595900 sense = 00: sk = 0) acd0: cmd PREVENT_ALLOW status 50 result 00 error 00 (cd0:ata2:0:1:0): xpt_done (cd0:ata2:0:1:0): camisr (cd0:ata2:0:1:0): entering cddone (cd0:ata2:0:1:0): trying to wakeup ccbwait cam_debug: xpt_release_ccb (cd0:ata2:0:1:0): entering cdsize (cd0:ata2:0:1:0): entering cdgetccb (cd0:ata2:0:1:0): xpt_schedule (cd0:ata2:0:1:0): added periph to queue cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue (cd0:ata2:0:1:0): calling xpt_run_devq cam_debug: xpt_run_dev_allocq cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 cam_debug: running device 0xc53b6c00 (cd0:ata2:0:1:0): xpt_setup_ccb cam_debug: calling periph start (cd0:ata2:0:1:0): entering cdstart (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): READ CD RECORDED CAPACITY. CDB: 25 0 0 0 0 0 0 0 0 0 cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_sendq cam_debug: running device 0xc53b6c00 (cd0:ata2:0:1:0): XPT_SCSI_IO atapi_action: hcb@0xc555c3c0: 25 0 0 0 0 0 0 0 0 0 acd0: req=0xc5588240 READ_CAPACITY queued acd0: req=0xc5588240 READ_CAPACITY starting acd0: req=0xc5588240 READ_CAPACITY begin transaction acd0: req=0xc5588240 READ_CAPACITY timeout acd0: req=0xc5588240 READ_CAPACITY finish taskqueue_swi acd0: req=0xc5588240 READ_CAPACITY completed entered (noperiph:ata2:0:-1:-1): xpt_async (noperiph:ata2:0:1:0): xpt_compile_path (noperiph:ata2:0:1:0): xpt_release_path acd0: FAILURE - READ_CAPACITY timed out acd0: req=0xc5588240 READ_CAPACITY completed callback/wakeup atapi_cb: hcb@0xc555c3c0 sense = 00: sk = 0) acd0: cmd READ_CAPACITY status 00 result 05 error 00 (cd0:ata2:0:1:0): xpt_done (cd0:ata2:0:1:0): camisr (cd0:ata2:0:1:0): entering cddone (cd0:ata2:0:1:0): trying to wakeup ccbwait (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): READ CD RECORDED CAPACITY. CDB: 25 0 0 0 0 0 0 0 0 0 cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_sendq cam_debug: running device 0xc53b6c00 (cd0:ata2:0:1:0): XPT_SCSI_IO atapi_action: hcb@0xc554e700: 25 0 0 0 0 0 0 0 0 0 acd0: req=0xc5588780 READ_CAPACITY queued acd0: req=0xc5588780 READ_CAPACITY starting acd0: req=0xc5588780 READ_CAPACITY begin transaction acd0: req=0xc5588780 READ_CAPACITY timeout acd0: req=0xc5588780 READ_CAPACITY finish taskqueue_swi acd0: req=0xc5588780 READ_CAPACITY completed entered (noperiph:ata2:0:-1:-1): xpt_async (noperiph:ata2:0:1:0): xpt_compile_path (noperiph:ata2:0:1:0): xpt_release_path acd0: FAILURE - READ_CAPACITY timed out acd0: req=0xc5588780 READ_CAPACITY completed callback/wakeup atapi_cb: hcb@0xc554e700 sense = 00: sk = 0) acd0: cmd READ_CAPACITY status 00 result 05 error 00 (cd0:ata2:0:1:0): xpt_done (cd0:ata2:0:1:0): camisr (cd0:ata2:0:1:0): entering cddone (cd0:ata2:0:1:0): trying to wakeup ccbwait cam_debug: xpt_release_ccb (cd0:ata2:0:1:0): entering cdprevent (cd0:ata2:0:1:0): entering cdgetccb (cd0:ata2:0:1:0): xpt_schedule (cd0:ata2:0:1:0): added periph to queue cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue (cd0:ata2:0:1:0): calling xpt_run_devq cam_debug: xpt_run_dev_allocq cam_debug: qfrozen_cnt == 0x0, entries == 1, openings == 16, active == 0 cam_debug: running device 0xc53b6c00 (cd0:ata2:0:1:0): xpt_setup_ccb cam_debug: calling periph start (cd0:ata2:0:1:0): entering cdstart (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): PREVENT ALLOW MEDIUM REMOVAL. CDB: 1e 0 0 0 0 0 cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_sendq cam_debug: running device 0xc53b6c00 (cd0:ata2:0:1:0): XPT_SCSI_IO atapi_action: hcb@0xc5595840: 1e 0 0 0 0 0 acd0: req=0xc5588900 PREVENT_ALLOW queued acd0: req=0xc5588900 PREVENT_ALLOW starting acd0: req=0xc5588900 PREVENT_ALLOW begin transaction acd0: req=0xc5588900 PREVENT_ALLOW interrupt acd0: req=0xc5588900 PREVENT_ALLOW end transaction acd0: req=0xc5588900 PREVENT_ALLOW finish taskqueue_swi acd0: req=0xc5588900 PREVENT_ALLOW completed entered acd0: req=0xc5588900 PREVENT_ALLOW autoissue request sense acd0: req=0xc5588900 REQUEST_SENSE queued acd0: req=0xc5588900 REQUEST_SENSE starting acd0: req=0xc5588900 REQUEST_SENSE begin transaction acd0: req=0xc5588900 REQUEST_SENSE interrupt acd0: req=0xc5588900 REQUEST_SENSE end transaction acd0: req=0xc5588900 PREVENT_ALLOW interrupt acd0: req=0xc5588900 PREVENT_ALLOW end transaction acd0: req=0xc5588900 PREVENT_ALLOW finish taskqueue_swi acd0: req=0xc5588900 PREVENT_ALLOW completed entered acd0: req=0xc5588900 PREVENT_ALLOW completed callback/wakeup atapi_cb: hcb@0xc5595840 sense = 06: sk = 6) acd0: cmd PREVENT_ALLOW status 50 result 05 error 00 (cd0:ata2:0:1:0): xpt_done (cd0:ata2:0:1:0): camisr (cd0:ata2:0:1:0): entering cddone (cd0:ata2:0:1:0): trying to wakeup ccbwait (cd0:ata2:0:1:0): xpt_setup_ccb (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): xpt_action (cd0:ata2:0:1:0): PREVENT ALLOW MEDIUM REMOVAL. CDB: 1e 0 0 0 0 0 cam_debug: xpt_schedule_dev cam_debug: Inserting onto queue cam_debug: xpt_run_dev_sendq cam_debug: running device 0xc53b6c00 (cd0:ata2:0:1:0): XPT_SCSI_IO atapi_action: hcb@0xc554e580: 1e 0 0 0 0 0 acd0: req=0xc55883c0 PREVENT_ALLOW queued acd0: req=0xc55883c0 PREVENT_ALLOW starting acd0: req=0xc55883c0 PREVENT_ALLOW begin transaction acd0: req=0xc55883c0 PREVENT_ALLOW interrupt acd0: req=0xc55883c0 PREVENT_ALLOW end transaction acd0: req=0xc55883c0 PREVENT_ALLOW finish taskqueue_swi acd0: req=0xc55883c0 PREVENT_ALLOW completed entered acd0: req=0xc55883c0 PREVENT_ALLOW completed callback/wakeup atapi_cb: hcb@0xc554e580 sense = 00: sk = 0) acd0: cmd PREVENT_ALLOW status 50 result 00 error 00 (cd0:ata2:0:1:0): xpt_done (cd0:ata2:0:1:0): camisr (cd0:ata2:0:1:0): entering cddone (cd0:ata2:0:1:0): trying to wakeup ccbwait cam_debug: xpt_release_ccb (cd0:ata2:0:1:0): leaving cdopen (cd0:ata2:0:1:0): entering cdprevent (cd0:ata2:0:1:0): removing device entry (cd0:ata2:0:1:0): xpt_free_path (cd0:ata2:0:1:0): xpt_release_path From owner-freebsd-scsi@FreeBSD.ORG Wed Mar 14 06:55:34 2007 Return-Path: X-Original-To: freebsd-scsi@freebsd.org Delivered-To: freebsd-scsi@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id EB0C916A402; Wed, 14 Mar 2007 06:55:34 +0000 (UTC) (envelope-from thomas@FreeBSD.ORG) Received: from melamine.cuivre.fr.eu.org (melusine.cuivre.fr.eu.org [82.225.155.84]) by mx1.freebsd.org (Postfix) with ESMTP id AFBCA13C448; Wed, 14 Mar 2007 06:55:34 +0000 (UTC) (envelope-from thomas@FreeBSD.ORG) Received: by melamine.cuivre.fr.eu.org (Postfix, from userid 1000) id F29B35C1C9; Wed, 14 Mar 2007 07:55:33 +0100 (CET) Date: Wed, 14 Mar 2007 07:55:33 +0100 From: Thomas Quinot To: josh.carroll@psualum.com Message-ID: <20070314065533.GB22882@melamine.cuivre.fr.eu.org> References: <20070313205731.GB3866@melamine.cuivre.fr.eu.org> <45F71A0F.4080601@samsco.org> <45F71B7D.3090305@samsco.org> <20070313220129.GA6579@melamine.cuivre.fr.eu.org> <8cb6106e0703132106j475f50ceuad0e01e51634cce3@mail.gmail.com> Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline In-Reply-To: <8cb6106e0703132106j475f50ceuad0e01e51634cce3@mail.gmail.com> X-message-flag: WARNING! Using Outlook can damage your computer. User-Agent: Mutt/1.5.11 Cc: freebsd-scsi@freebsd.org, bug-followup@freebsd.org Subject: Re: kern/103602: drive gets wedged on READ CD CAPACITY if no disc is in X-BeenThere: freebsd-scsi@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: SCSI subsystem List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Wed, 14 Mar 2007 06:55:35 -0000 * Josh Carroll, 2007-03-14 : > Hi Scott/Thomas, > > I bought a Pioneer DVR-112D IDE DVD-RW drive to see if the timeouts > were specific to my Sony DRU-800A, but I had no luck with it, either. > In fact, it wouldn't even work with a disc in the drive as the Sony > does. It times out on INQUIRY (dmesg attached below). I'm hoping this > is a valid data point for this issue - it definitely seems to point > towards something that's not drive dependent. Although I was hoping > it'd act the same way as the Sony. > > Anyway, below is the dmesg from a boot without a disc in the drive > (the same INQUIRY failures happen with a disc in the drive). Interesting, the drive times out on the particular INQUIRY to obtain the drive serial number... and Josh has been running a kernel with a quirk entry that disabled this lookup for the Sony drive. Thomas. From owner-freebsd-scsi@FreeBSD.ORG Fri Mar 16 12:06:29 2007 Return-Path: X-Original-To: freebsd-scsi@freebsd.org Delivered-To: freebsd-scsi@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id 822FE16A400 for ; Fri, 16 Mar 2007 12:06:29 +0000 (UTC) (envelope-from danny@cs.huji.ac.il) Received: from cs1.cs.huji.ac.il (cs1.cs.huji.ac.il [132.65.16.10]) by mx1.freebsd.org (Postfix) with ESMTP id 3E22413C4AE for ; Fri, 16 Mar 2007 12:06:29 +0000 (UTC) (envelope-from danny@cs.huji.ac.il) Received: from pampa.cs.huji.ac.il ([132.65.80.32]) by cs1.cs.huji.ac.il with esmtp id 1HSAhj-000KwL-By; Fri, 16 Mar 2007 13:34:39 +0200 X-Mailer: exmh version 2.7.2 01/07/2005 with nmh-1.2 To: freebsd-scsi@freebsd.org Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Date: Fri, 16 Mar 2007 13:34:39 +0200 From: Danny Braniss Message-ID: Cc: freebsd-hackers@freebsd.org Subject: iSCSI boot mussings X-BeenThere: freebsd-scsi@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: SCSI subsystem List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Fri, 16 Mar 2007 12:06:29 -0000 Hi, Now that I have my hands on a server that can boot iSCSI, I started to look into it. After figuring out what magic is needed in the dhcpd.conf (just add option root-path "iscsi:target-ip::::target-name") I can boot FreeBSD to the point that it can't find a root device, and assuming that some more magic can be applied (ala NFS), I'm just wondering aloud, if it's realy worth the efford. For a PXE based diskless solution, you need 1 - a working dhcpd 2 - a working tftpd 3 - a working NFS server with the exported root fs. appplying some minor magic, you can have only one read-only fs. For an iSCSI based diskless solution, you need 1- a working dhcpd 2- a working iscsi-initiator, unless the BIOS can be used. 3- a working target with a root fs (one for each client, unless applying 3 from the above). Hybrid solution: boot via PXE, but mount root via iSCSI So, what say you all? danny From owner-freebsd-scsi@FreeBSD.ORG Fri Mar 16 16:16:29 2007 Return-Path: X-Original-To: freebsd-scsi@freebsd.org Delivered-To: freebsd-scsi@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id 1025116A400 for ; Fri, 16 Mar 2007 16:16:29 +0000 (UTC) (envelope-from lists@jnielsen.net) Received: from ns1.jnielsen.net (ns1.jnielsen.net [69.55.238.237]) by mx1.freebsd.org (Postfix) with ESMTP id D942613C46A for ; Fri, 16 Mar 2007 16:16:28 +0000 (UTC) (envelope-from lists@jnielsen.net) Received: from localhost (jn@ns1 [69.55.238.237]) (authenticated bits=0) by ns1.jnielsen.net (8.12.9p2/8.12.9) with ESMTP id l2GFxVXl071526; Fri, 16 Mar 2007 11:59:32 -0400 (EDT) (envelope-from lists@jnielsen.net) From: John Nielsen To: freebsd-hackers@freebsd.org Date: Fri, 16 Mar 2007 11:59:37 -0400 User-Agent: KMail/1.9.5 References: In-Reply-To: X-Face: #X5#Y*q>F:]zT!DegL3z5Xo'^MN[$8k\[4^3rN~wm=s=Uw(sW}R?3b^*f1Wu*.<=?utf-8?q?of=5F4NrS=0A=09P*M/9CpxDo!D6?=)IY1w<9B1jB; tBQf[RU-R<,I)e"$q7N7 MIME-Version: 1.0 Content-Type: text/plain; charset="iso-8859-1" Content-Transfer-Encoding: quoted-printable Content-Disposition: inline Message-Id: <200703161159.37735.lists@jnielsen.net> X-Virus-Scanned: ClamAV version 0.88.4, clamav-milter version 0.88.4 on ns1.jnielsen.net X-Virus-Status: Clean Cc: freebsd-scsi@freebsd.org Subject: Re: iSCSI boot mussings X-BeenThere: freebsd-scsi@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: SCSI subsystem List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Fri, 16 Mar 2007 16:16:29 -0000 On Friday 16 March 2007 07:34, Danny Braniss wrote: > Hi, > Now that I have my hands on a server that can boot iSCSI, > I started to look into it. After figuring out what magic is needed > in the dhcpd.conf (just add option root-path > "iscsi:target-ip::::target-name") I can boot FreeBSD to the point that it > can't find a root device, and assuming that some more magic can be applied > (ala NFS), I'm just > wondering aloud, if it's realy worth the efford. > For a PXE based diskless solution, you need > 1 - a working dhcpd > 2 - a working tftpd > 3 - a working NFS server with the exported root fs. > appplying some minor magic, you can have only one read-only fs. > For an iSCSI based diskless solution, you need > 1- a working dhcpd > 2- a working iscsi-initiator, unless the BIOS can be used. > 3- a working target with a root fs > (one for each client, unless applying 3 from the above). > Hybrid solution: > boot via PXE, but mount root via iSCSI > > So, what say you all? =46rom the kernel's perspective (at the moment just prior to mounting root)= , is=20 there a difference between the last two approaches? The situation as I see = it=20 (in both cases) is that the kernel is loaded into memory (by some magical=20 means which is at this point irrelevant), and now has to locate a root devi= ce=20 using only what it already has to bootstrap the process. If what it already= =20 has includes BOOTP code then it's possible to get some additional informati= on=20 externally. Whether the initial magic was PXE or BIOS-based iSCSI, the kern= el=20 has to have its own storage drivers and do its own network setup, right? I think there are some benefits to being able to do this, but perhaps 90% o= f=20 them could be realized with what we already have (iscontrol and the=20 iscsi_initiator kernel module) plus some rc and fstab glue. Any kind of=20 diskless server farm needs at least one "master" server to run dhcpd, and i= f=20 you have it doing that you might as well have it do NFS and tftpd as well.= =20 Pretty much any client you'd want to use as an iSCSI initiator will have a= =20 decent NIC which nowadays implies PXE support. So it shouldn't be hard to g= et=20 root mounted using currently available means (local disk or PXE+NFS), and=20 from there have the option of using iSCSI for other partitions=20 (including /usr). A truly standalone iSCSI client will most likely want to use a TOE card, wh= ich=20 to the OS looks like any other SCSI adapter. (I'm unsure which if any such= =20 cards are currently supported in FreeBSD, but that's a tangential question.) Machines with iSCSI-capable BIOS'es are an inbetween case. Allowing such=20 machines to be standalone clients would require things like the initiator=20 name, the initiator's IP address and netmask, the target's IP address, and= =20 the target's (volume) name to be hard-coded in the kernel. It would be nice= =20 to support this scenario, but IMO it's the one with the lowest benefit/cost= =20 ratio. Making it easy to integrate iSCSI into existing environments (diskless or n= ot)=20 is IMO the biggest hole in the current implementation (the missing rc and=20 fstab bits I mentioned before). JN From owner-freebsd-scsi@FreeBSD.ORG Fri Mar 16 17:51:21 2007 Return-Path: X-Original-To: freebsd-scsi@freebsd.org Delivered-To: freebsd-scsi@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id 83B9C16A404; Fri, 16 Mar 2007 17:51:21 +0000 (UTC) (envelope-from danny@cs.huji.ac.il) Received: from cs1.cs.huji.ac.il (cs1.cs.huji.ac.il [132.65.16.10]) by mx1.freebsd.org (Postfix) with ESMTP id 3A14713C468; Fri, 16 Mar 2007 17:51:21 +0000 (UTC) (envelope-from danny@cs.huji.ac.il) Received: from pampa.cs.huji.ac.il ([132.65.80.32]) by cs1.cs.huji.ac.il with esmtp id 1HSGaF-0009MW-EB; Fri, 16 Mar 2007 19:51:19 +0200 X-Mailer: exmh version 2.7.2 01/07/2005 with nmh-1.2 To: John Nielsen In-reply-to: <200703161159.37735.lists@jnielsen.net> References: <200703161159.37735.lists@jnielsen.net> Comments: In-reply-to John Nielsen message dated "Fri, 16 Mar 2007 11:59:37 -0400." Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Date: Fri, 16 Mar 2007 19:51:19 +0200 From: Danny Braniss Message-ID: Cc: freebsd-hackers@freebsd.org, freebsd-scsi@freebsd.org Subject: Re: iSCSI boot mussings X-BeenThere: freebsd-scsi@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: SCSI subsystem List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Fri, 16 Mar 2007 17:51:21 -0000 >> Hi, >> Now that I have my hands on a server that can boot iSCSI, >> I started to look into it. After figuring out what magic is needed >> in the dhcpd.conf (just add option root-path >> "iscsi:target-ip::::target-name") I can boot FreeBSD to the point that it >> can't find a root device, and assuming that some more magic can be applied >> (ala NFS), I'm just >> wondering aloud, if it's realy worth the efford. >> For a PXE based diskless solution, you need >> 1 - a working dhcpd >> 2 - a working tftpd >> 3 - a working NFS server with the exported root fs. >> appplying some minor magic, you can have only one read-only fs. >> For an iSCSI based diskless solution, you need >> 1- a working dhcpd >> 2- a working iscsi-initiator, unless the BIOS can be used. >> 3- a working target with a root fs >> (one for each client, unless applying 3 from the above). >> Hybrid solution: >> boot via PXE, but mount root via iSCSI >> >> So, what say you all? > >From the kernel's perspective (at the moment just prior to mounting root), is >there a difference between the last two approaches? with PXE, all the information, including the nfs-file-handle used to access the root partition is passed to the kernel (pxeboot), and nfsdiskless.c does the rest. With iSCSI zero, will need to be done. > The situation as I see it >(in both cases) is that the kernel is loaded into memory (by some magical >means which is at this point irrelevant), and now has to locate a root device >using only what it already has to bootstrap the process. If what it already >has includes BOOTP code then it's possible to get some additional information >externally. with PXE this is done via pxeboot (and my modified libstand/bootp.c which copies the dhcp obtained tags to the kernel environment). > Whether the initial magic was PXE or BIOS-based iSCSI, the kernel >has to have its own storage drivers and do its own network setup, right? > true, since FreeBSD does not like to use BIOS provided 'drivers'. the problem is 'discovering' the device used to boot, and here there is an added complication, the iSCSI is on top of the network card, so initializing the NIC will probably disconnect the iSCSI connection. It would be nice to use the BIOS supplied 'glue' to mount root, so that it can read /boot/loader.conf etc, then once the kernel is ready, remount root, >I think there are some benefits to being able to do this, but perhaps 90% of >them could be realized with what we already have (iscontrol and the >iscsi_initiator kernel module) plus some rc and fstab glue. but this can happen only after the kernel is running. horse/cart ... > Any kind of >diskless server farm needs at least one "master" server to run dhcpd, and if >you have it doing that you might as well have it do NFS and tftpd as well. >Pretty much any client you'd want to use as an iSCSI initiator will have a >decent NIC which nowadays implies PXE support. So it shouldn't be hard to get >root mounted using currently available means (local disk or PXE+NFS), and >from there have the option of using iSCSI for other partitions >(including /usr). I think that PXE is already concidered 'legacy' :-) > >A truly standalone iSCSI client will most likely want to use a TOE card, which >to the OS looks like any other SCSI adapter. (I'm unsure which if any such >cards are currently supported in FreeBSD, but that's a tangential question.) > This was my thinking too, but the few TOEs I've seen do not emulate a scsi device! and they are not cheap (last i checked they were over 1000$). >Machines with iSCSI-capable BIOS'es are an inbetween case. Allowing such >machines to be standalone clients would require things like the initiator >name, the initiator's IP address and netmask, the target's IP address, and >the target's (volume) name to be hard-coded in the kernel. It would be nice >to support this scenario, but IMO it's the one with the lowest benefit/cost >ratio. and some more, if you want password/CHAP ... > >Making it easy to integrate iSCSI into existing environments (diskless or not) >is IMO the biggest hole in the current implementation (the missing rc and >fstab bits I mentioned before). yes, but this is not a real problem, I have an almost working script. > >JN > From owner-freebsd-scsi@FreeBSD.ORG Sat Mar 17 01:01:29 2007 Return-Path: X-Original-To: freebsd-scsi@freebsd.org Delivered-To: freebsd-scsi@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id DB5D416A400; Sat, 17 Mar 2007 01:01:29 +0000 (UTC) (envelope-from ap@bnc.net) Received: from mailomat.net (mailomat.net [217.110.117.101]) by mx1.freebsd.org (Postfix) with ESMTP id 4C57313C457; Sat, 17 Mar 2007 01:01:28 +0000 (UTC) (envelope-from ap@bnc.net) X-Spam-Checker-Version: SpamAssassin 3.1.7 (2006-10-05) on f-1.mailomat.net X-Spam-Level: X-Spam-Status: No, hits=-3.9 required=4.0 tests=ALL_TRUSTED=-1.8, AWL=0.450, BAYES_00=-2.599 autolearn=ham version=3.1.7 X-TFF-CGPSA-Version: 1.5 X-Mailomat-CGPSA-Filter: Scanned X-Mailomat-SpamCatcher-Score: 50 [XX] X-Mailomat-Cloudmark-Score: 0.000000 [] Received: from [87.234.79.113] (account ap HELO [194.39.192.148]) by mailomat.net (CommuniGate Pro SMTP 5.1.5) with ESMTPSA id 16914197; Sat, 17 Mar 2007 01:01:23 +0100 In-Reply-To: <200703161159.37735.lists@jnielsen.net> References: <200703161159.37735.lists@jnielsen.net> Mime-Version: 1.0 (Apple Message framework v752.3) Content-Type: text/plain; charset=US-ASCII; delsp=yes; format=flowed Message-Id: <1D8F7F7D-E49D-4DD7-927C-36CC195D44CC@bnc.net> Content-Transfer-Encoding: 7bit From: Achim Patzner Date: Sat, 17 Mar 2007 01:01:15 +0100 To: John Nielsen X-Mailer: Apple Mail (2.752.3) Cc: freebsd-hackers@freebsd.org, freebsd-scsi@freebsd.org Subject: Re: iSCSI boot mussings X-BeenThere: freebsd-scsi@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: SCSI subsystem List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Sat, 17 Mar 2007 01:01:30 -0000 On 16.03.2007, at 16:59, John Nielsen wrote: > A truly standalone iSCSI client will most likely want to use a TOE > card, which > to the OS looks like any other SCSI adapter. (I'm unsure which if > any such > cards are currently supported in FreeBSD, but that's a tangential > question.) Maybe someone feels like playing with these: http://www.myri.com/ Myri-10G/10gbe_solutions.html Achim From owner-freebsd-scsi@FreeBSD.ORG Sat Mar 17 01:35:04 2007 Return-Path: X-Original-To: freebsd-scsi@freebsd.org Delivered-To: freebsd-scsi@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id 93BD916A401 for ; Sat, 17 Mar 2007 01:35:04 +0000 (UTC) (envelope-from kip.macy@gmail.com) Received: from wx-out-0506.google.com (wx-out-0506.google.com [66.249.82.225]) by mx1.freebsd.org (Postfix) with ESMTP id 5285613C45B for ; Sat, 17 Mar 2007 01:35:04 +0000 (UTC) (envelope-from kip.macy@gmail.com) Received: by wx-out-0506.google.com with SMTP id s18so698857wxc for ; Fri, 16 Mar 2007 18:35:03 -0700 (PDT) DKIM-Signature: a=rsa-sha1; c=relaxed/relaxed; d=gmail.com; s=beta; h=domainkey-signature:received:received:message-id:date:from:to:subject:cc:in-reply-to:mime-version:content-type:content-transfer-encoding:content-disposition:references; b=Kf4GPcvbnRYQ+3RkDp2ees+nPtQCxLACETVkjJTnZnNkixXI6jE4fezA2iMoUGsFotIgxR3dWtMr3leaXgGd82Jw3mCqbi0K6+YtgnCwkzGksGzA28y+DsCiJBdTbonEb3g2WDFdUgACLySmgMxq7mG8iNSn9BJZnpGfL8/QVPU= DomainKey-Signature: a=rsa-sha1; c=nofws; d=gmail.com; s=beta; h=received:message-id:date:from:to:subject:cc:in-reply-to:mime-version:content-type:content-transfer-encoding:content-disposition:references; b=OLFosNStSeWIZYERlSTl3Ab1qLXgsTONKTnp8wUw068Mr7lF4pUnLUDFQFg8OOxfQg9Ol3rMFxtIoLG29uypxAk7sZXDYfy3dJ1GChvYtFXHxoKqc92CuvRFChwI5gysOKP6ueJOjCAZezryDVDgrvLHUuz+1bGmHrNV/H+l1Vg= Received: by 10.90.79.6 with SMTP id c6mr2536273agb.1174093628412; Fri, 16 Mar 2007 18:07:08 -0700 (PDT) Received: by 10.90.25.1 with HTTP; Fri, 16 Mar 2007 18:07:08 -0700 (PDT) Message-ID: Date: Fri, 16 Mar 2007 18:07:08 -0700 From: "Kip Macy" To: "Achim Patzner" In-Reply-To: <1D8F7F7D-E49D-4DD7-927C-36CC195D44CC@bnc.net> MIME-Version: 1.0 Content-Type: text/plain; charset=ISO-8859-1; format=flowed Content-Transfer-Encoding: 7bit Content-Disposition: inline References: <200703161159.37735.lists@jnielsen.net> <1D8F7F7D-E49D-4DD7-927C-36CC195D44CC@bnc.net> Cc: freebsd-hackers@freebsd.org, freebsd-scsi@freebsd.org Subject: Re: iSCSI boot mussings X-BeenThere: freebsd-scsi@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: SCSI subsystem List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Sat, 17 Mar 2007 01:35:04 -0000 On 3/16/07, Achim Patzner wrote: > On 16.03.2007, at 16:59, John Nielsen wrote: > > A truly standalone iSCSI client will most likely want to use a TOE > > card, which > > to the OS looks like any other SCSI adapter. (I'm unsure which if > > any such > > cards are currently supported in FreeBSD, but that's a tangential > > question.) > > Maybe someone feels like playing with these: http://www.myri.com/ > Myri-10G/10gbe_solutions.html There are at least a half dozen if not a dozen HBA iSCSI initiators. The only gating factor is price. A pxebooted ramdisk image with an iSCSI software initiator in it is probably the best way to go for most people. I don't think that there is a FreeBSD driver for any of the current hardware iSCSI HBAs. -Kip From owner-freebsd-scsi@FreeBSD.ORG Sat Mar 17 09:46:48 2007 Return-Path: X-Original-To: freebsd-scsi@freebsd.org Delivered-To: freebsd-scsi@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id A0A6C16A404; Sat, 17 Mar 2007 09:46:48 +0000 (UTC) (envelope-from danny@cs.huji.ac.il) Received: from cs1.cs.huji.ac.il (cs1.cs.huji.ac.il [132.65.16.10]) by mx1.freebsd.org (Postfix) with ESMTP id 2343813C448; Sat, 17 Mar 2007 09:46:47 +0000 (UTC) (envelope-from danny@cs.huji.ac.il) Received: from pampa.cs.huji.ac.il ([132.65.80.32]) by cs1.cs.huji.ac.il with esmtp id 1HSVUq-00091l-GY; Sat, 17 Mar 2007 11:46:44 +0200 X-Mailer: exmh version 2.7.2 01/07/2005 with nmh-1.2 To: Achim Patzner In-reply-to: <1D8F7F7D-E49D-4DD7-927C-36CC195D44CC@bnc.net> References: <200703161159.37735.lists@jnielsen.net> <1D8F7F7D-E49D-4DD7-927C-36CC195D44CC@bnc.net> Comments: In-reply-to Achim Patzner message dated "Sat, 17 Mar 2007 01:01:15 +0100." Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii Date: Sat, 17 Mar 2007 11:46:44 +0200 From: Danny Braniss Message-ID: Cc: freebsd-hackers@freebsd.org, freebsd-scsi@freebsd.org Subject: Re: iSCSI boot mussings X-BeenThere: freebsd-scsi@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: SCSI subsystem List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Sat, 17 Mar 2007 09:46:48 -0000 > On 16.03.2007, at 16:59, John Nielsen wrote: > > A truly standalone iSCSI client will most likely want to use a TOE > > card, which > > to the OS looks like any other SCSI adapter. (I'm unsure which if > > any such > > cards are currently supported in FreeBSD, but that's a tangential > > question.) > > Maybe someone feels like playing with these: http://www.myri.com/ > Myri-10G/10gbe_solutions.html > > > Achim as far as I can tell, this is a 10G card, without any iSCSI support. danny From owner-freebsd-scsi@FreeBSD.ORG Sat Mar 17 21:50:57 2007 Return-Path: X-Original-To: freebsd-scsi@freebsd.org Delivered-To: freebsd-scsi@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [69.147.83.52]) by hub.freebsd.org (Postfix) with ESMTP id 06D3416A406; Sat, 17 Mar 2007 21:50:57 +0000 (UTC) (envelope-from tom@samplonius.org) Received: from ly.sdf.com (ly.sdf.com [216.113.193.83]) by mx1.freebsd.org (Postfix) with ESMTP id 8FEF913C45D; Sat, 17 Mar 2007 21:50:56 +0000 (UTC) (envelope-from tom@samplonius.org) Received: from localhost (localhost [127.0.0.1]) by ly.sdf.com (Postfix) with ESMTP id BCCFA114447; Sat, 17 Mar 2007 14:29:44 -0700 (PDT) X-Virus-Scanned: amavisd-new at X-Spam-Score: -3.958 X-Spam-Level: X-Spam-Status: No, score=-3.958 tagged_above=-10 required=6.6 tests=[ALL_TRUSTED=-1.8, AWL=0.441, BAYES_00=-2.599] Received: from ly.sdf.com ([127.0.0.1]) by localhost (ly.sdf.com [127.0.0.1]) (amavisd-new, port 10024) with ESMTP id N+TvOrHdLDxv; Sat, 17 Mar 2007 14:29:42 -0700 (PDT) Received: from ly.sdf.com (ly.sdf.com [216.113.193.83]) by ly.sdf.com (Postfix) with ESMTP id 9104D114446; Sat, 17 Mar 2007 14:29:42 -0700 (PDT) Message-ID: <8169444.541174166982500.JavaMail.root@ly.sdf.com> Date: Sat, 17 Mar 2007 14:29:42 -0700 (PDT) From: Tom Samplonius To: John Nielsen In-Reply-To: <200703161159.37735.lists@jnielsen.net> MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 7bit X-Originating-IP: [216.113.193.85] Cc: freebsd-scsi@freebsd.org, freebsd-hackers@freebsd.org Subject: Re: iSCSI boot mussings X-BeenThere: freebsd-scsi@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: SCSI subsystem List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Sat, 17 Mar 2007 21:50:57 -0000 ... > A truly standalone iSCSI client will most likely want to use a TOE > card, which > to the OS looks like any other SCSI adapter. (I'm unsure which if any > such > cards are currently supported in FreeBSD, but that's a tangential > question.) I really doubt this. TOE iSCSI cards are quite rare. Qlogic has one, and it could work under FreeBSD, as it looks similar the other Qlogic HBA cards. But they are still pretty rare. And TOE doesn't necessarily imply iSCSI. Various cards existing ethernet cards have various levels of offload support now. I think the general case is going to succeed: ethernet cards with acceleration, not iSCSI HBAs. > Machines with iSCSI-capable BIOS'es are an inbetween case. Allowing > such > machines to be standalone clients would require things like the > initiator > name, the initiator's IP address and netmask, the target's IP address, > and > the target's (volume) name to be hard-coded in the kernel. It would be > nice > to support this scenario, but IMO it's the one with the lowest > benefit/cost > ratio. Maybe, maybe not. Probably more people have iSCSI BIOSes in their servers now, than iSCSI dedicated HBAs. IBM introduced iSCSI boot code into many of their server BIOSes last year. I don't think it is that hard either. I don't think you will have to hard code into the kernel, but at least make sure the targets match what the BIOS has. It could just be a boot option, as the boot loader just uses BIOS calls, which would hit read from iSCSI. If you don't have an iSCSI BIOS in your server (which probably every Dell, IBM, and HP server will have standard in every server this year), and you don't have one of the very rare iSCSI dedicated HBAs, then PXE booting an iSCSI enabled kernel is probably the way to go. > Making it easy to integrate iSCSI into existing environments (diskless > or not) > is IMO the biggest hole in the current implementation (the missing rc > and > fstab bits I mentioned before). > > JN Tom