powerpc64 head -r314687 (PowerMac G5 so-called "Quad Core", clang based): CAM status: Command timeout (always?)

Mark Millard markmi at dsl-only.net
Mon Mar 6 18:36:18 UTC 2017


On 2017-Mar-6, at 8:43 AM, Mark Johnston <markj at FreeBSD.org> wrote:

> On Mon, Mar 06, 2017 at 02:05:39AM -0800, Mark Millard wrote:
>> On 2017-Mar-6, at 1:37 AM, Mark Millard <markmi at dsl-only.net> wrote:
>> 
>>> When I tried to jump from head -r314479 to -r314687 the -r314687 kernel
>>> the result failed by always(?) getting:
>>> 
>>> CAM status: Command timeout
>>> 
>>> for:
>>> 
>>> ATAPI_IDENTIFY
>>> INQUIRY
>>> DSM TRIM
>>> READ_DMA48
>>> SETFEATURES ENABLE RCACHE
>>> WRITE_DMA48
>>> etc.
>>> 
>>> at:
>>> 
>>> ada0:ata2:0:0:0
>>> aprobe0:ata0:0:0:0
>>> 
>>> Booting with the older -r314479 works fine (same -r314687 world).
>>> 
>>> [FYI: It is a ufs context.]
>>> 
>>> 
>>> The only thing that looks likely to me for
>>> the change in behavior is. . .
>>> 
>>> Author: markj
>>> Date: Fri Mar  3 20:51:57 2017
>>> New Revision: 314624
>>> URL: 
>>> https://svnweb.freebsd.org/changeset/base/314624
>>> 
>>> 
>>> Log:
>>> Reject userland CCBs that have CAM_UNLOCKED set.
>>> 
>>> CAM_UNLOCKED is internal flag and cannot correctly be set by userland.
>>> Return EINVAL from CAMIOCOMMAND and CAMIOQUEUE if it is set.
>>> 
>>> Also fix leaks in some of the error paths for CAMIOQUEUE.
>>> 
>>> PR:		215356
>>> Reviewed by:	ken, mav
>>> MFC after:	1 week
>>> Differential Revision:	
>>> https://reviews.freebsd.org/D9869
>>> 
>>> 
>>> Modified:
>>> head/sys/cam/cam_xpt.c
>>> head/sys/cam/scsi/scsi_pass.c
>>> 
>>> 
>>> 
>>> [This may just mean that it exposes other problems.]
>> 
>> Yep: reverting the two files allowed the PowerMac G5 so-called
>> "Quad Core" to boot fully and I could log in.
> 
> Do you have a full dmesg of the failed boot? Am I correct in thinking
> that the boot failed before making it to user mode?

/var/log/messages has the boot. I'll put the text at the end of
this reply.

It ends abruptly because I had to use the power switch: It never
did take input to login with. Being ufs with softupdates the
recorded end is possibly before the actual end.

> If so I'm rather
> puzzled, as the change should only affect userland applications.
> Specifically, it modified a couple of ioctl handlers.
> 
>> 
>> It appears that if such powerpc64 machines are to stay bootable
>> then other things need to be cleaned up before the two updated
>> files from -r314624 should be used.
>> 
>> Should the 2 files be reverted until other things are cleaned up?
> 
> I don't mind reverting the change, but my suspicion is that it uncovered
> a problem rather than introducing it. If you're willing to narrow things
> down a bit, could you try booting with one of the file modifications and
> not the other? They are independent.

In a while I'll try each of the files individually, one old, one modern
each time.

Here is the /var/log/messages content:
(I've removed various serial numbers.)


Mar  6 03:54:36 FBSDG5L syslogd: kernel boot file is /boot/kernel/kernel
Mar  6 03:54:36 FBSDG5L kernel: Copyright (c) 1992-2017 The FreeBSD Project.
Mar  6 03:54:36 FBSDG5L kernel: Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
Mar  6 03:54:36 FBSDG5L kernel: The Regents of the University of California. All rights reserved.
Mar  6 03:54:36 FBSDG5L kernel: FreeBSD is a registered trademark of The FreeBSD Foundation.
Mar  6 03:54:36 FBSDG5L kernel: FreeBSD 12.0-CURRENT  r314687M powerpc
Mar  6 03:54:36 FBSDG5L kernel: FreeBSD clang version 4.0.0 (branches/release_40 296509) (based on LLVM 4.0.0)
Mar  6 03:54:36 FBSDG5L kernel: VT(ofwfb): resolution 1280x1024
Mar  6 03:54:36 FBSDG5L kernel: cpu0: IBM PowerPC 970MP revision 1.1, 2500.41 MHz
Mar  6 03:54:36 FBSDG5L kernel: cpu0: Features dc000000<PPC32,PPC64,ALTIVEC,FPU,MMU>
Mar  6 03:54:36 FBSDG5L kernel: cpu0: HID0 1511081<DEEPNAP,NAP,DPM,NHR,TBEN,ENATTN>
Mar  6 03:54:36 FBSDG5L kernel: real memory  = 17148002304 (16353 MB)
Mar  6 03:54:36 FBSDG5L kernel: avail memory = 16428167168 (15667 MB)
Mar  6 03:54:36 FBSDG5L kernel: FreeBSD/SMP: Multiprocessor System Detected: 4 CPUs
Mar  6 03:54:36 FBSDG5L kernel: cpu0: dev=ff89d680 (BSP)
Mar  6 03:54:36 FBSDG5L kernel: cpu1: dev=ff89eb70
Mar  6 03:54:36 FBSDG5L kernel: cpu2: dev=ff89f248
Mar  6 03:54:36 FBSDG5L kernel: cpu3: dev=ff89f920
Mar  6 03:54:36 FBSDG5L kernel: random: unblocking device.
Mar  6 03:54:36 FBSDG5L kernel: random: entropy device external interface
Mar  6 03:54:36 FBSDG5L kernel: kbd0 at kbdmux0
Mar  6 03:54:36 FBSDG5L kernel: ofwbus0: <Open Firmware Device Tree> on nexus0
Mar  6 03:54:36 FBSDG5L kernel: cpulist0: <Open Firmware CPU Group> on ofwbus0
Mar  6 03:54:36 FBSDG5L kernel: cpu0: <Open Firmware CPU> on cpulist0
Mar  6 03:54:36 FBSDG5L kernel: pcr0: <PPC 970 Power Control Register> on cpu0
Mar  6 03:54:36 FBSDG5L kernel: cpu1: <Open Firmware CPU> on cpulist0
Mar  6 03:54:36 FBSDG5L kernel: pcr1: <PPC 970 Power Control Register> on cpu1
Mar  6 03:54:36 FBSDG5L kernel: cpu2: <Open Firmware CPU> on cpulist0
Mar  6 03:54:36 FBSDG5L kernel: pcr2: <PPC 970 Power Control Register> on cpu2
Mar  6 03:54:36 FBSDG5L kernel: cpu3: <Open Firmware CPU> on cpulist0
Mar  6 03:54:36 FBSDG5L kernel: pcr3: <PPC 970 Power Control Register> on cpu3
Mar  6 03:54:36 FBSDG5L kernel: powermac_nvram0: <Apple NVRAM> mem 0xfff04000-0xfff07fff on ofwbus0
Mar  6 03:54:36 FBSDG5L kernel: powermac_nvram0: bank0 generation 1650, bank1 generation 1649
Mar  6 03:54:36 FBSDG5L kernel: unin0: <Apple UniNorth System Controller> mem 0xf8000000-0xf8ffffff on ofwbus0
Mar  6 03:54:36 FBSDG5L kernel: unin0: Version 66
Mar  6 03:54:36 FBSDG5L kernel: iichb0: <Keywest I2C controller> mem 0xf8001000-0xf8001fff irq 0 on unin0
Mar  6 03:54:36 FBSDG5L kernel: iicbus0: <OFW I2C bus> on iichb0
Mar  6 03:54:36 FBSDG5L kernel: ds17750: <Temp-Monitor DS1775> at addr 0x94 on iicbus0
Mar  6 03:54:36 FBSDG5L kernel: ds16310: <Temp-Monitor DS1631> at addr 0x96 on iicbus0
Mar  6 03:54:36 FBSDG5L kernel: max66900: <Temp-Monitor MAX6690> at addr 0x98 on iicbus0
Mar  6 03:54:36 FBSDG5L kernel: max66901: <Temp-Monitor MAX6690> at addr 0x9c on iicbus0
Mar  6 03:54:36 FBSDG5L kernel: htpic0: <OpenPIC Interrupt Controller> mem 0xf8040000-0xf807ffff on unin0
Mar  6 03:54:36 FBSDG5L kernel: pcib0: <IBM CPC945 PCI Express Root> mem 0xf0000000-0xf1ffffff on ofwbus0
Mar  6 03:54:36 FBSDG5L kernel: pci0: <OFW PCI bus> on pcib0
Mar  6 03:54:36 FBSDG5L kernel: vgapci0: <VGA-compatible display> mem 0xa1000000-0xa1ffffff,0x90000000-0x9fffffff,0xa0000000-0xa0ffffff irq 3 at device 0.0 on pci0
Mar  6 03:54:36 FBSDG5L kernel: vgapci0: Boot video device
Mar  6 03:54:36 FBSDG5L kernel: pcib1: <IBM CPC9X5 HyperTransport Tunnel> mem 0xf2000000-0xf47fffff,0xf8070000-0xf8070fff on ofwbus0
Mar  6 03:54:36 FBSDG5L kernel: pcib1: 86 HT IRQs on device 7.0
Mar  6 03:54:36 FBSDG5L kernel: pci1: <OFW PCI bus> on pcib1
Mar  6 03:54:36 FBSDG5L kernel: pcib1: Enabling MSI window for HyperTransport slave at pci1:0:1:0
Mar  6 03:54:36 FBSDG5L kernel: pcib2: <OFW PCI-PCI bridge> at device 1.0 on pci1
Mar  6 03:54:36 FBSDG5L kernel: pci2: <OFW PCI bus> on pcib2
Mar  6 03:54:36 FBSDG5L kernel: pcib3: <OFW PCI-PCI bridge> at device 2.0 on pci1
Mar  6 03:54:36 FBSDG5L kernel: pci3: <OFW PCI bus> on pcib3
Mar  6 03:54:36 FBSDG5L kernel: bge0: <Broadcom BCM5714 B3, ASIC rev. 0x008003> mem 0xfa530000-0xfa53ffff,0xfa520000-0xfa52ffff irq 66 at device 4.0 on pci3
Mar  6 03:54:36 FBSDG5L kernel: bge0: CHIP ID 0x00008003; ASIC REV 0x08; CHIP REV 0x80; PCI-X 33 MHz
Mar  6 03:54:36 FBSDG5L kernel: miibus0: <MII bus> on bge0
Mar  6 03:54:36 FBSDG5L kernel: brgphy0: <BCM5780 1000BASE-T media interface> PHY 1 on miibus0
Mar  6 03:54:36 FBSDG5L kernel: brgphy0:  10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT, 1000baseT-master, 1000baseT-FDX, 1000baseT-FDX-master, auto, auto-flow
Mar  6 03:54:36 FBSDG5L kernel: bge0: Ethernet address: 00:14:51:68:5a:44
Mar  6 03:54:36 FBSDG5L kernel: bge1: <Broadcom BCM5714 B3, ASIC rev. 0x008003> mem 0xfa510000-0xfa51ffff,0xfa500000-0xfa50ffff irq 67 at device 4.1 on pci3
Mar  6 03:54:36 FBSDG5L kernel: bge1: CHIP ID 0x00008003; ASIC REV 0x08; CHIP REV 0x80; PCI-X 33 MHz
Mar  6 03:54:36 FBSDG5L kernel: miibus1: <MII bus> on bge1
Mar  6 03:54:36 FBSDG5L kernel: brgphy1: <BCM5780 1000BASE-T media interface> PHY 1 on miibus1
Mar  6 03:54:36 FBSDG5L kernel: brgphy1:  10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, 1000baseT, 1000baseT-master, 1000baseT-FDX, 1000baseT-FDX-master, auto, auto-flow
Mar  6 03:54:36 FBSDG5L kernel: bge1: Ethernet address: 00:14:51:68:5a:45
Mar  6 03:54:36 FBSDG5L kernel: pcib4: <OFW PCI-PCI bridge> at device 3.0 on pci1
Mar  6 03:54:36 FBSDG5L kernel: pci4: <OFW PCI bus> on pcib4
Mar  6 03:54:36 FBSDG5L kernel: pcib5: <OFW PCI-PCI bridge> at device 4.0 on pci1
Mar  6 03:54:36 FBSDG5L kernel: pci5: <OFW PCI bus> on pcib5
Mar  6 03:54:36 FBSDG5L kernel: pcib6: <OFW PCI-PCI bridge> at device 5.0 on pci1
Mar  6 03:54:36 FBSDG5L kernel: pci6: <OFW PCI bus> on pcib6
Mar  6 03:54:36 FBSDG5L kernel: pcib7: <OFW PCI-PCI bridge> at device 6.0 on pci1
Mar  6 03:54:36 FBSDG5L kernel: pci7: <OFW PCI bus> on pcib7
Mar  6 03:54:36 FBSDG5L kernel: pcib8: <OFW PCI-PCI bridge> at device 7.0 on pci1
Mar  6 03:54:36 FBSDG5L kernel: pci8: <OFW PCI bus> on pcib8
Mar  6 03:54:36 FBSDG5L kernel: gem0: <Apple Shasta GMAC Ethernet> mem 0xfa200000-0xfa3fffff at device 15.0 on pci8
Mar  6 03:54:36 FBSDG5L kernel: gem0: invalid MAC address
Mar  6 03:54:36 FBSDG5L kernel: device_attach: gem0 attach returned 6
Mar  6 03:54:36 FBSDG5L kernel: pcib9: <OFW PCI-PCI bridge> at device 8.0 on pci1
Mar  6 03:54:36 FBSDG5L kernel: pci9: <OFW PCI bus> on pcib9
Mar  6 03:54:36 FBSDG5L kernel: macio0: <Shasta I/O Controller> mem 0x80000000-0x8007ffff at device 7.0 on pci9
Mar  6 03:54:36 FBSDG5L kernel: macgpio0: <MacIO GPIO Controller> mem 0x50-0x8a on macio0
Mar  6 03:54:36 FBSDG5L kernel: scc0: <Zilog Z8530 dual channel SCC> mem 0x13000-0x13fff,0x8400-0x84ff,0x8500-0x85ff,0x8600-0x86ff,0x8700-0x87ff irq 23,17,18,24,19,20 on macio0
Mar  6 03:54:36 FBSDG5L kernel: uart0: <z8530, channel A> on scc0
Mar  6 03:54:36 FBSDG5L kernel: uart1: <z8530, channel B> on scc0
Mar  6 03:54:36 FBSDG5L kernel: iichb1: <Keywest I2C controller> mem 0x18000-0x18fff irq 27 on macio0
Mar  6 03:54:36 FBSDG5L kernel: iicbus1: <OFW I2C bus> on iichb1
Mar  6 03:54:36 FBSDG5L kernel: onyx0: <Texas Instruments PCM3052 Audio Codec> at addr 0x8c on iicbus1
Mar  6 03:54:36 FBSDG5L kernel: iicbus1: <unknown card> at addr 0x24
Mar  6 03:54:36 FBSDG5L kernel: pcm0: <Apple I2S Audio Controller> mem 0x10000-0x10fff,0x8000-0x80ff,0x8100-0x81ff irq 28,11,12,30,15,16 on macio0
Mar  6 03:54:36 FBSDG5L kernel: pci9: <network> at device 1.0 (no driver attached)
Mar  6 03:54:36 FBSDG5L kernel: ohci0: <NEC uPD 9210 USB controller> mem 0x80082000-0x80082fff irq 70 at device 11.0 on pci9
Mar  6 03:54:36 FBSDG5L kernel: usbus0 on ohci0
Mar  6 03:54:36 FBSDG5L kernel: ohci1: <NEC uPD 9210 USB controller> mem 0x80081000-0x80081fff irq 70 at device 11.1 on pci9
Mar  6 03:54:36 FBSDG5L kernel: usbus1 on ohci1
Mar  6 03:54:36 FBSDG5L kernel: ehci0: <NEC uPD 72010x USB 2.0 controller> mem 0x80080000-0x800800ff irq 70 at device 11.2 on pci9
Mar  6 03:54:36 FBSDG5L kernel: usbus2: EHCI version 1.0
Mar  6 03:54:36 FBSDG5L kernel: usbus2 on ehci0
Mar  6 03:54:36 FBSDG5L kernel: pcib10: <OFW PCI-PCI bridge> at device 9.0 on pci1
Mar  6 03:54:36 FBSDG5L kernel: pci10: <OFW PCI bus> on pcib10
Mar  6 03:54:36 FBSDG5L kernel: atapci0: <ServerWorks K2 SATA150 controller> mem 0xfa402000-0xfa403fff irq 10 at device 12.0 on pci10
Mar  6 03:54:36 FBSDG5L kernel: pcib1: failed to reserve resource for pcib10
Mar  6 03:54:36 FBSDG5L kernel: atapci0: 0x10 bytes of rid 0x20 res 4 failed (0, 0xffffffffffffffff).
Mar  6 03:54:36 FBSDG5L kernel: ata2: <ATA channel> at channel 0 on atapci0
Mar  6 03:54:36 FBSDG5L kernel: ata3: <ATA channel> at channel 1 on atapci0
Mar  6 03:54:36 FBSDG5L kernel: ata4: <ATA channel> at channel 2 on atapci0
Mar  6 03:54:36 FBSDG5L kernel: ata5: <ATA channel> at channel 3 on atapci0
Mar  6 03:54:36 FBSDG5L kernel: ata0: <Shasta Kauai ATA Controller> mem 0xfa404000-0xfa407fff irq 38,37 at device 13.0 on pci10
Mar  6 03:54:36 FBSDG5L kernel: fwohci0: <1394 Open Host Controller Interface> mem 0xfa400000-0xfa400fff irq 39 at device 14.0 on pci10
Mar  6 03:54:36 FBSDG5L kernel: fwohci0: OHCI version 1.0 (ROM=0)
Mar  6 03:54:36 FBSDG5L kernel: fwohci0: No. of Isochronous channels is 8.
Mar  6 03:54:36 FBSDG5L kernel: fwohci0: EUI64 00:14:51:ff:fe:35:63:7c
Mar  6 03:54:36 FBSDG5L kernel: fwohci0: invalid speed 7 (fixed to 3).
Mar  6 03:54:36 FBSDG5L kernel: fwohci0: Phy 1394a available S800, 3 ports.
Mar  6 03:54:36 FBSDG5L kernel: fwohci0: Link S800, max_rec 4096 bytes.
Mar  6 03:54:36 FBSDG5L kernel: firewire0: <IEEE1394(FireWire) bus> on fwohci0
Mar  6 03:54:36 FBSDG5L kernel: fwe0: <Ethernet over FireWire> on firewire0
Mar  6 03:54:36 FBSDG5L kernel: if_fwe0: Fake Ethernet address: 02:14:51:35:63:7c
Mar  6 03:54:36 FBSDG5L kernel: fwe0: Ethernet address: 02:14:51:35:63:7c
Mar  6 03:54:36 FBSDG5L kernel: sbp0: <SBP-2/SCSI over FireWire> on firewire0
Mar  6 03:54:36 FBSDG5L kernel: fwohci0: Initiate bus reset
Mar  6 03:54:36 FBSDG5L kernel: fwohci0: fwohci_intr_core: BUS reset
Mar  6 03:54:36 FBSDG5L kernel: fwohci0: PhysicalUpperBound register is not implemented.  Physical memory access is limited to the first 4GB
Mar  6 03:54:36 FBSDG5L kernel: fwohci0: PhysicalUpperBound = 0x00000000
Mar  6 03:54:36 FBSDG5L kernel: fwohci0: fwohci_intr_core: node_id=0x00000001, SelfID Count=1, CYCLEMASTER mode
Mar  6 03:54:36 FBSDG5L kernel: smu0: <Apple System Management Unit> on ofwbus0
Mar  6 03:54:36 FBSDG5L kernel: iichb2: <SMU I2C controller> on smu0
Mar  6 03:54:36 FBSDG5L kernel: iicbus2: <OFW I2C bus> on iichb2
Mar  6 03:54:36 FBSDG5L kernel: smusat0: <SMU Satellite Sensors> at addr 0xb0 on iicbus2
Mar  6 03:54:36 FBSDG5L kernel: smusat1: <SMU Satellite Sensors> at addr 0xb2 on iicbus2
Mar  6 03:54:36 FBSDG5L kernel: iicbus2: <unknown card> at addr 0xd4
Mar  6 03:54:36 FBSDG5L kernel: iichb3: <SMU I2C controller> on smu0
Mar  6 03:54:36 FBSDG5L kernel: iicbus3: <OFW I2C bus> on iichb3
Mar  6 03:54:36 FBSDG5L kernel: Timecounter "timebase" frequency 33333333 Hz quality 0
Mar  6 03:54:36 FBSDG5L kernel: Event timer "decrementer" frequency 33333333 Hz quality 1000
Mar  6 03:54:36 FBSDG5L kernel: Timecounters tick every 1.000 msec
Mar  6 03:54:36 FBSDG5L kernel: firewire0: 2 nodes, maxhop <= 1 cable IRM irm(1)  (me) 
Mar  6 03:54:36 FBSDG5L kernel: firewire0: bus manager 1 
Mar  6 03:54:36 FBSDG5L kernel: bge0: link state changed to UP
Mar  6 03:54:36 FBSDG5L kernel: usbus0: 12Mbps Full Speed USB v1.0
Mar  6 03:54:36 FBSDG5L kernel: usbus1: 12Mbps Full Speed USB v1.0
Mar  6 03:54:36 FBSDG5L kernel: max66900: 2 sensors detected.
Mar  6 03:54:36 FBSDG5L kernel: max66901: 2 sensors detected.
Mar  6 03:54:36 FBSDG5L kernel: ugen1.1: <NEC OHCI root HUB> at usbus1
Mar  6 03:54:36 FBSDG5L kernel: uhub0: <NEC OHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus1
Mar  6 03:54:36 FBSDG5L kernel: ugen0.1: <NEC OHCI root HUB> at usbus0
Mar  6 03:54:36 FBSDG5L kernel: uhub1: <NEC OHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus0
Mar  6 03:54:36 FBSDG5L kernel: usbus2: 480Mbps High Speed USB v2.0
Mar  6 03:54:36 FBSDG5L kernel: ugen2.1: <NEC EHCI root HUB> at usbus2
Mar  6 03:54:36 FBSDG5L kernel: uhub2: <NEC EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus2
Mar  6 03:54:36 FBSDG5L kernel: uhub0: 2 ports with 2 removable, self powered
Mar  6 03:54:36 FBSDG5L kernel: uhub1: 3 ports with 3 removable, self powered
Mar  6 03:54:36 FBSDG5L kernel: uhub2: 5 ports with 5 removable, self powered
Mar  6 03:54:36 FBSDG5L kernel: ada0 at ata2 bus 0 scbus0 target 0 lun 0
Mar  6 03:54:36 FBSDG5L kernel: ada0: <OWC Mercury Electra 3G SSD 541ABBF0> ATA8-ACS SATA 2.x device
Mar  6 03:54:36 FBSDG5L kernel: ada0: Serial Number . . .
Mar  6 03:54:36 FBSDG5L kernel: ada0: 150.000MB/s transfers (SATA 1.x, UDMA5, PIO 512bytes)
Mar  6 03:54:36 FBSDG5L kernel: ada0: 457862MB (937703088 512 byte sectors)
Mar  6 03:54:36 FBSDG5L kernel: ada1 at ata3 bus 0 scbus1 target 0 lun 0
Mar  6 03:54:36 FBSDG5L kernel: ada1: <OWC Mercury Electra 3G SSD 560ABBF0> ATA8-ACS SATA 2.x device
Mar  6 03:54:36 FBSDG5L kernel: ada1: Serial Number . . .
Mar  6 03:54:36 FBSDG5L kernel: ada1: 150.000MB/s transfers (SATA 1.x, UDMA5, PIO 512bytes)
Mar  6 03:54:36 FBSDG5L kernel: ada1: 114473MB (234441648 512 byte sectors)
Mar  6 03:54:36 FBSDG5L kernel: SMP: AP CPU #1 launched
Mar  6 03:54:36 FBSDG5L kernel: SMP: AP CPU #2 launched
Mar  6 03:54:36 FBSDG5L kernel: SMP: AP CPU #3 launched
Mar  6 03:54:36 FBSDG5L kernel: cd0 at ata0 bus 0 scbus4 target 0 lun 0
Mar  6 03:54:36 FBSDG5L kernel: cd0: <HL-DT-ST DVD-RW GWA-4165B C006> Removable CD-ROM SCSI device
Mar  6 03:54:36 FBSDG5L kernel: cd0: Serial Number . . .
Mar  6 03:54:36 FBSDG5L kernel: cd0: 66.700MB/s transfers (UDMA4, ATAPI 12bytes, PIO 65534bytes)
Mar  6 03:54:36 FBSDG5L kernel: cd0: Attempt to query device size failed: NOT READY, Medium not present
Mar  6 03:54:36 FBSDG5L kernel: Trying to mount root from ufs:/dev/ufs/FBSDG5Lrootfs [rw,noatime]...
Mar  6 03:54:36 FBSDG5L kernel: (aprobe0:ata0:0:0:0): ATAPI_IDENTIFY. ACB: a1 00 00 00 00 40 00 00 00 00 00 00
Mar  6 03:54:36 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  6 03:54:36 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  6 03:54:36 FBSDG5L kernel: bge0: link state changed to DOWN
Mar  6 03:54:36 FBSDG5L kernel: bge0: link state changed to UP
Mar  6 04:11:48 FBSDG5L ntpd[865]: ntpd 4.2.8p9-a (2): Starting
Mar  6 04:13:58 FBSDG5L ntpd[866]: leapsecond file ('/var/db/ntpd.leap-seconds.list'): good hash signature
Mar  6 04:13:58 FBSDG5L ntpd[866]: leapsecond file ('/var/db/ntpd.leap-seconds.list'): loaded, expire=2017-06-01T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
Mar  6 04:22:34 FBSDG5L kernel: (aprobe0:ata0:0:0:0): INQUIRY. CDB: 12 00 00 00 24 00 
Mar  6 04:22:34 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  6 04:22:34 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  6 05:05:38 FBSDG5L kernel: (ada0:ata2:0:0:0): WRITE_DMA48. ACB: 35 00 a8 78 94 40 1d 00 00 00 08 00
Mar  6 05:05:38 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  6 05:05:38 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  6 05:07:48 FBSDG5L kernel: (ada0:ata2:0:0:0): WRITE_DMA48. ACB: 35 00 58 78 94 40 1d 00 00 00 08 00
Mar  6 05:07:48 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  6 05:07:48 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  6 05:44:25 FBSDG5L kernel: (aprobe0:ata0:0:0:0): INQUIRY. CDB: 12 00 00 00 24 00 
Mar  6 05:44:25 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  6 05:44:25 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  6 05:50:54 FBSDG5L kernel: (ada0:ata2:0:0:0): WRITE_DMA48. ACB: 35 00 30 82 94 40 1d 00 00 00 08 00
Mar  6 05:50:54 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  6 05:50:54 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  6 06:10:16 FBSDG5L kernel: (aprobe0:ata0:0:0:0): INQUIRY. CDB: 12 00 00 00 24 00 
Mar  6 06:10:16 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  6 06:10:16 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  6 06:23:09 FBSDG5L kernel: (aprobe0:ata0:0:0:0): ATAPI_IDENTIFY. ACB: a1 00 00 00 00 40 00 00 00 00 00 00
Mar  6 06:23:09 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  6 06:23:09 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  6 07:04:07 FBSDG5L kernel: (aprobe0:ata0:0:0:0): INQUIRY. CDB: 12 00 00 00 24 00 
Mar  6 07:04:07 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  6 07:04:07 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  6 07:34:16 FBSDG5L kernel: (aprobe0:ata0:0:0:0): INQUIRY. CDB: 12 00 00 00 24 00 
Mar  6 07:34:16 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  6 07:34:16 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  6 08:02:14 FBSDG5L kernel: (ada0:ata2:0:0:0): DSM TRIM. ACB: 06 01 00 00 00 40 00 00 00 00 01 00
Mar  6 08:02:14 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  6 08:02:14 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  6 08:19:27 FBSDG5L kernel: (ada0:ata2:0:0:0): DSM TRIM. ACB: 06 01 00 00 00 40 00 00 00 00 01 00
Mar  6 08:19:27 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  6 08:19:27 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  6 08:36:42 FBSDG5L kernel: (aprobe0:ata0:0:0:0): INQUIRY. CDB: 12 00 00 00 24 00 
Mar  6 08:36:42 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  6 08:36:42 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  6 09:00:21 FBSDG5L kernel: (aprobe0:ata0:0:0:0): ATAPI_IDENTIFY. ACB: a1 00 00 00 00 40 00 00 00 00 00 00
Mar  6 09:00:21 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  6 09:00:21 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  6 09:45:37 FBSDG5L kernel: (aprobe0:ata0:0:0:0): INQUIRY. CDB: 12 00 00 00 24 00 
Mar  6 09:45:37 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  6 09:45:37 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  6 10:50:23 FBSDG5L kernel: (ada0:ata2:0:0:0): DSM TRIM. ACB: 06 01 00 00 00 40 00 00 00 00 01 00
Mar  6 10:50:23 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  6 10:50:23 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  6 03:17:46 FBSDG5L kernel: (ada0:ata2:0:0:0): READ_DMA48. ACB: 25 00 40 71 54 40 14 00 00 00 40 00
Mar  6 03:17:46 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  6 03:17:46 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  6 03:41:25 FBSDG5L kernel: (ada0:ata2:0:0:0): READ_DMA48. ACB: 25 00 c8 ef b0 40 25 00 00 00 08 00
Mar  6 03:41:25 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  6 03:41:25 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  6 04:13:41 FBSDG5L kernel: (aprobe0:ata0:0:0:0): ATAPI_IDENTIFY. ACB: a1 00 00 00 00 40 00 00 00 00 00 00
Mar  6 04:13:41 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  6 04:13:41 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  6 08:51:07 FBSDG5L kernel: (aprobe0:ata0:0:0:0): INQUIRY. CDB: 12 00 00 00 24 00 
Mar  6 08:51:07 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  6 08:51:07 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  6 10:29:59 FBSDG5L kernel: (ada0:ata2:0:0:0): DSM TRIM. ACB: 06 01 00 00 00 40 00 00 00 00 01 00
Mar  6 10:29:59 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  6 10:29:59 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  6 11:47:27 FBSDG5L kernel: (ada0:ata2:0:0:0): DSM TRIM. ACB: 06 01 00 00 00 40 00 00 00 00 01 00
Mar  6 11:47:27 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  6 11:47:27 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  6 13:19:52 FBSDG5L ntpd[866]: error resolving pool 0.freebsd.pool.ntp.org: hostname nor servname provided, or not known (8)
Mar  6 13:22:02 FBSDG5L kernel: (ada0:ata2:0:0:0): READ_DMA48. ACB: 25 00 b8 bf 5e 40 1a 00 00 00 08 00
Mar  6 13:22:02 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  6 13:22:02 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  6 13:58:33 FBSDG5L kernel: (aprobe0:ata0:0:0:0): ATAPI_IDENTIFY. ACB: a1 00 00 00 00 40 00 00 00 00 00 00
Mar  6 13:58:33 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  6 13:58:33 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  6 18:25:00 FBSDG5L ntpd[866]: error resolving pool 0.freebsd.pool.ntp.org: hostname nor servname provided, or not known (8)
Mar  7 00:21:41 FBSDG5L kernel: (aprobe0:ata0:0:0:0): ATAPI_IDENTIFY. ACB: a1 00 00 00 00 40 00 00 00 00 00 00
Mar  7 00:21:41 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  7 00:21:41 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  7 01:32:36 FBSDG5L kernel: (aprobe0:ata0:0:0:0): ATAPI_IDENTIFY. ACB: a1 00 00 00 00 40 00 00 00 00 00 00
Mar  7 01:32:36 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  7 01:32:36 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  7 02:11:18 FBSDG5L kernel: (aprobe0:ata0:0:0:0): ATAPI_IDENTIFY. ACB: a1 00 00 00 00 40 00 00 00 00 00 00
Mar  7 02:11:18 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  7 02:11:18 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  7 02:15:36 FBSDG5L kernel: (ada0:ata2:0:0:0): READ_DMA48. ACB: 25 00 20 11 55 40 14 00 00 00 08 00
Mar  7 02:15:36 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  7 02:15:36 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  7 03:29:14 FBSDG5L kernel: (ada0:ata2:0:0:0): DSM TRIM. ACB: 06 01 00 00 00 40 00 00 00 00 01 00
Mar  7 03:29:14 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  7 03:29:14 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  7 03:33:32 FBSDG5L kernel: (ada0:ata2:0:0:0): SETFEATURES ENABLE RCACHE. ACB: ef aa 00 00 00 40 00 00 00 00 00 00
Mar  7 03:33:32 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  7 03:33:32 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  7 03:48:35 FBSDG5L kernel: (aprobe0:ata0:0:0:0): INQUIRY. CDB: 12 00 00 00 60 00 
Mar  7 03:48:35 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  7 03:48:35 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  7 05:19:02 FBSDG5L kernel: (aprobe0:ata0:0:0:0): INQUIRY. CDB: 12 00 00 00 24 00 
Mar  7 05:19:02 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  7 05:19:02 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  7 05:55:36 FBSDG5L kernel: (ada0:ata2:0:0:0): DSM TRIM. ACB: 06 01 00 00 00 40 00 00 00 00 01 00
Mar  7 05:55:36 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  7 05:55:36 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  7 07:56:31 FBSDG5L kernel: (aprobe0:ata0:0:0:0): INQUIRY. CDB: 12 00 00 00 24 00 
Mar  7 07:56:31 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  7 07:56:31 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  7 08:22:23 FBSDG5L kernel: (aprobe0:ata0:0:0:0): ATAPI_IDENTIFY. ACB: a1 00 00 00 00 40 00 00 00 00 00 00
Mar  7 08:22:23 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  7 08:22:23 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  7 09:20:35 FBSDG5L kernel: (aprobe0:ata0:0:0:0): INQUIRY. CDB: 12 00 00 00 24 00 
Mar  7 09:20:35 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  7 09:20:35 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  7 09:27:01 FBSDG5L kernel: (ada0:ata2:0:0:0): DSM TRIM. ACB: 06 01 00 00 00 40 00 00 00 00 01 00
Mar  7 09:27:01 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  7 09:27:01 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  7 10:03:33 FBSDG5L kernel: (ada0:ata2:0:0:0): DSM TRIM. ACB: 06 01 00 00 00 40 00 00 00 00 01 00
Mar  7 10:03:33 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  7 10:03:33 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  7 10:03:33 FBSDG5L kernel: (ada0:ata2:0:0:0): DSM TRIM. ACB: 06 01 00 00 00 40 00 00 00 00 01 00
Mar  7 10:03:33 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  7 10:03:33 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  7 10:03:33 FBSDG5L kernel: (ada0:ata2:0:0:0): DSM TRIM. ACB: 06 01 00 00 00 40 00 00 00 00 01 00
Mar  7 10:03:33 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  7 10:03:33 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  7 10:10:01 FBSDG5L kernel: (aprobe0:ata0:0:0:0): ATAPI_IDENTIFY. ACB: a1 00 00 00 00 40 00 00 00 00 00 00
Mar  7 10:10:01 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  7 10:10:01 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command
Mar  7 10:16:28 FBSDG5L kernel: (ada0:ata2:0:0:0): DSM TRIM. ACB: 06 01 00 00 00 40 00 00 00 00 01 00
Mar  7 10:16:28 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  7 10:16:28 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  7 10:20:46 FBSDG5L kernel: (ada0:ata2:0:0:0): SETFEATURES ENABLE RCACHE. ACB: ef aa 00 00 00 40 00 00 00 00 00 00
Mar  7 10:20:46 FBSDG5L kernel: (ada0:ata2:0:0:0): CAM status: Command timeout
Mar  7 10:20:46 FBSDG5L kernel: (ada0:ata2:0:0:0): Retrying command
Mar  7 10:33:41 FBSDG5L kernel: (aprobe0:ata0:0:0:0): ATAPI_IDENTIFY. ACB: a1 00 00 00 00 40 00 00 00 00 00 00
Mar  7 10:33:41 FBSDG5L kernel: (aprobe0:ata0:0:0:0): CAM status: Command timeout
Mar  7 10:33:41 FBSDG5L kernel: (aprobe0:ata0:0:0:0): Retrying command


===
Mark Millard
markmi at dsl-only.net



More information about the freebsd-ppc mailing list