Skip site navigation (1)Skip section navigation (2)
Date:      Sat, 9 Nov 2013 08:46:30 -0600
From:      Larry Rosenman <ler@lerctr.org>
To:        freebsd-stable@freebsd.org
Subject:   10-BETA3: Bad negotiation on AHD controller
Message-ID:  <20131109144626.GA1672@thebighonker.lerctr.org>

next in thread | raw e-mail | index | archive | help
Ever since I put 10 on this box (source upgrade from 8), I've been getting
slow disk negotiations on 2 of my 4 disks, and weird messages at boot from=
=20
the ahd controller.

Can someone tell if it's my disk or the code?

# for i in 0 1 2 3
> do
> camcontrol inquiry 0:${i}:0
> done
pass0: <FUJITSU MAW3147NC 0104> Fixed Direct Access SCSI-3 device
pass0: Serial Number DAA0P7A054GD
pass0: 320.000MB/s transfers (160.000MHz, offset 127, 16bit), Command Queue=
ing Enabled
pass1: <FUJITSU MAW3147NC 0104> Fixed Direct Access SCSI-3 device
pass1: Serial Number DAA0P7A054G3
pass1: 320.000MB/s transfers (160.000MHz, offset 127, 16bit), Command Queue=
ing Enabled
pass2: <SEAGATE ST3146707LC 0004> Fixed Direct Access SCSI-3 device
pass2: Serial Number 3KS2NFJG00007611CFPX
pass2: 3.300MB/s transfers, Command Queueing Enabled
pass3: <SEAGATE ST3146707LC 0004> Fixed Direct Access SCSI-3 device
pass3: Serial Number 3KS1Z2KF00007620X9K1
pass3: 3.300MB/s transfers, Command Queueing Enabled
#

dmesg.boot:

Copyright (c) 1992-2013 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 10.0-BETA3 #3 r257864: Fri Nov  8 15:20:07 CST 2013
    root@thebighonker.lerctr.org:/usr/obj/usr/src/sys/THEBIGHONKER amd64
FreeBSD clang version 3.3 (tags/RELEASE_33/final 183502) 20130610
CPU: Intel(R) Xeon(TM) CPU 3.00GHz (2992.56-MHz K8-class CPU)
  Origin =3D "GenuineIntel"  Id =3D 0xf43  Family =3D 0xf  Model =3D 0x4  S=
tepping =3D 3
  Features=3D0xbfebfbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PG=
E,MCA,CMOV,PAT,PSE36,CLFLUSH,DTS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE>
  Features2=3D0x641d<SSE3,DTES64,MON,DS_CPL,CNXT-ID,CX16,xTPR>
  AMD Features=3D0x20100800<SYSCALL,NX,LM>
  TSC: P-state invariant
real memory  =3D 9395240960 (8960 MB)
avail memory =3D 8282472448 (7898 MB)
Event timer "LAPIC" quality 400
ACPI APIC Table: <PTLTD  	 APIC  >
FreeBSD/SMP: Multiprocessor System Detected: 4 CPUs
FreeBSD/SMP: 2 package(s) x 1 core(s) x 2 HTT threads
 cpu0 (BSP): APIC ID:  0
 cpu1 (AP/HT): APIC ID:  1
 cpu2 (AP): APIC ID:  6
 cpu3 (AP/HT): APIC ID:  7
ioapic0 <Version 2.0> irqs 0-23 on motherboard
ioapic1 <Version 2.0> irqs 24-47 on motherboard
ioapic2 <Version 2.0> irqs 48-71 on motherboard
random: <Software, Yarrow> initialized
kbd1 at kbdmux0
acpi0: <PTLTD   RSDT> on motherboard
acpi0: Power Button (fixed)
cpu0: <ACPI CPU> on acpi0
cpu1: <ACPI CPU> on acpi0
cpu2: <ACPI CPU> on acpi0
cpu3: <ACPI CPU> on acpi0
hpet0: <High Precision Event Timer> iomem 0xfed00000-0xfed003ff irq 0,8 on =
acpi0
Timecounter "HPET" frequency 14318180 Hz quality 950
Event timer "HPET" frequency 14318180 Hz quality 450
Event timer "HPET1" frequency 14318180 Hz quality 440
Event timer "HPET2" frequency 14318180 Hz quality 440
atrtc0: <AT realtime clock> port 0x70-0x77 on acpi0
Event timer "RTC" frequency 32768 Hz quality 0
attimer0: <AT timer> port 0x40-0x43 on acpi0
Timecounter "i8254" frequency 1193182 Hz quality 0
Event timer "i8254" frequency 1193182 Hz quality 100
Timecounter "ACPI-fast" frequency 3579545 Hz quality 900
acpi_timer0: <24-bit timer at 3.579545MHz> port 0x1008-0x100b on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
pci0: <unknown> at device 0.1 (no driver attached)
pci0: <base peripheral> at device 1.0 (no driver attached)
pcib1: <ACPI PCI-PCI bridge> irq 16 at device 2.0 on pci0
pci1: <ACPI PCI bus> on pcib1
pcib2: <ACPI PCI-PCI bridge> at device 0.0 on pci1
pci2: <ACPI PCI bus> on pcib2
ahd0: <Adaptec AIC7902 Ultra320 SCSI adapter> port 0x2400-0x24ff,0x2000-0x2=
0ff mem 0xdd200000-0xdd201fff irq 32 at device 2.0 on pci2
aic7902: Ultra320 Wide Channel A, SCSI Id=3D7, PCI-X 101-133MHz, 512 SCBs
ahd1: <Adaptec AIC7902 Ultra320 SCSI adapter> port 0x2c00-0x2cff,0x2800-0x2=
8ff mem 0xdd202000-0xdd203fff irq 33 at device 2.1 on pci2
aic7902: Ultra320 Wide Channel B, SCSI Id=3D7, PCI-X 101-133MHz, 512 SCBs
pcib3: <ACPI PCI-PCI bridge> at device 0.2 on pci1
pci3: <ACPI PCI bus> on pcib3
em0: <Intel(R) PRO/1000 Legacy Network Connection 1.0.6> port 0x3000-0x303f=
 mem 0xdd300000-0xdd31ffff irq 54 at device 2.0 on pci3
em0: Ethernet address: 00:30:48:2e:99:ba
em1: <Intel(R) PRO/1000 Legacy Network Connection 1.0.6> port 0x3040-0x307f=
 mem 0xdd320000-0xdd33ffff irq 55 at device 2.1 on pci3
em1: Ethernet address: 00:30:48:2e:99:bb
pcib4: <ACPI PCI-PCI bridge> irq 16 at device 4.0 on pci0
pci4: <ACPI PCI bus> on pcib4
pcib5: <ACPI PCI-PCI bridge> irq 16 at device 6.0 on pci0
pci5: <ACPI PCI bus> on pcib5
uhci0: <Intel 82801EB (ICH5) USB controller USB-A> port 0x1400-0x141f irq 1=
6 at device 29.0 on pci0
usbus0 on uhci0
uhci1: <Intel 82801EB (ICH5) USB controller USB-B> port 0x1420-0x143f irq 1=
9 at device 29.1 on pci0
usbus1 on uhci1
uhci2: <Intel 82801EB (ICH5) USB controller USB-C> port 0x1440-0x145f irq 1=
8 at device 29.2 on pci0
usbus2 on uhci2
uhci3: <Intel 82801EB (ICH5) USB controller USB-D> port 0x1460-0x147f irq 1=
6 at device 29.3 on pci0
usbus3 on uhci3
ehci0: <Intel 82801EB/R (ICH5) USB 2.0 controller> mem 0xdd001000-0xdd0013f=
f irq 23 at device 29.7 on pci0
usbus4: EHCI version 1.0
usbus4 on ehci0
pcib6: <ACPI PCI-PCI bridge> at device 30.0 on pci0
pci6: <ACPI PCI bus> on pcib6
vgapci0: <VGA-compatible display> port 0x4000-0x40ff mem 0xde000000-0xdefff=
fff,0xdd400000-0xdd400fff irq 17 at device 1.0 on pci6
drm0: <Rage XL> on vgapci0
info: [drm] Initialized mach64 2.0.0 20060718
isab0: <PCI-ISA bridge> at device 31.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <Intel ICH5 UDMA100 controller> port 0x1f0-0x1f7,0x3f6,0x170-0x177=
,0x376,0x14a0-0x14af at device 31.1 on pci0
ata0: <ATA channel> at channel 0 on atapci0
ata1: <ATA channel> at channel 1 on atapci0
ichsmb0: <Intel 82801EB (ICH5) SMBus controller> port 0x1100-0x111f irq 17 =
at device 31.3 on pci0
smbus0: <System Management Bus> on ichsmb0
smb0: <SMBus generic I/O> on smbus0
acpi_button0: <Power Button> on acpi0
atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64 irq 1 on acpi0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOCKED]
uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
uart1: <16550 or compatible> port 0x2f8-0x2ff irq 3 on acpi0
fdc0: <floppy drive controller> port 0x3f0-0x3f5,0x3f7 irq 6 drq 2 on acpi0
fd0: <1440-KB 3.5" drive> on fdc0 drive 0
orm0: <ISA Option ROMs> at iomem 0xc0000-0xc7fff,0xc8000-0xc8fff on isa0
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=3D0x300>
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
ppc0: cannot reserve I/O port range
p4tcc0: <CPU Frequency Thermal Control> on cpu0
p4tcc1: <CPU Frequency Thermal Control> on cpu1
p4tcc2: <CPU Frequency Thermal Control> on cpu2
p4tcc3: <CPU Frequency Thermal Control> on cpu3
ZFS filesystem version: 5
ZFS storage pool version: features support (5000)
Timecounters tick every 1.000 msec
random: unblocking device.
usbus0: 12Mbps Full Speed USB v1.0
usbus1: 12Mbps Full Speed USB v1.0
usbus2: 12Mbps Full Speed USB v1.0
usbus3: 12Mbps Full Speed USB v1.0
usbus4: 480Mbps High Speed USB v2.0
ugen1.1: <Intel> at usbus1
uhub0: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus1
ugen0.1: <Intel> at usbus0
uhub1: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus0
ugen3.1: <Intel> at usbus3
uhub2: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus3
ugen2.1: <Intel> at usbus2
uhub3: <Intel UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus2
ugen4.1: <Intel> at usbus4
uhub4: <Intel EHCI root HUB, class 9/0, rev 2.00/1.00, addr 1> on usbus4
uhub0: 2 ports with 2 removable, self powered
uhub1: 2 ports with 2 removable, self powered
uhub2: 2 ports with 2 removable, self powered
uhub3: 2 ports with 2 removable, self powered
uhub4: 8 ports with 8 removable, self powered
ahd0: Invalid Sequencer interrupt occurred.
>>>>>>>>>>>>>>>>>> Dump Card State Begins <<<<<<<<<<<<<<<<<
ahd0: Dumping Card State at program address 0x23c Mode 0x0
Card was paused
INTSTAT[0x0] SELOID[0x2] SELID[0x30] HS_MAILBOX[0x0]=20
INTCTL[0x80]:(SWTMINTMASK) SEQINTSTAT[0x0] SAVED_MODE[0x11]=20
DFFSTAT[0x33]:(CURRFIFO_NONE|FIFO0FREE|FIFO1FREE)=20
SCSISIGI[0x0]:(P_DATAOUT) SCSIPHASE[0x0] SCSIBUS[0x0]=20
LASTPHASE[0x1]:(P_DATAOUT|P_BUSFREE) SCSISEQ0[0x0]=20
SCSISEQ1[0x12]:(ENAUTOATNP|ENRSELI) SEQCTL0[0x0] SEQINTCTL[0x6]:(INTMASK1|I=
NTMASK2)=20
SEQ_FLAGS[0x0] SEQ_FLAGS2[0x0] QFREEZE_COUNT[0x5]=20
KERNEL_QFREEZE_COUNT[0x5] MK_MESSAGE_SCB[0xff00] MK_MESSAGE_SCSIID[0xff]=20
SSTAT0[0x0] SSTAT1[0x8]:(BUSFREE) SSTAT2[0x0] SSTAT3[0x0]=20
PERRDIAG[0x0] SIMODE1[0xa4]:(ENSCSIPERR|ENSCSIRST|ENSELTIMO)=20
LQISTAT0[0x0] LQISTAT1[0x0] LQISTAT2[0x0] LQOSTAT0[0x0]=20
LQOSTAT1[0x0] LQOSTAT2[0x0]=20

SCB Count =3D 512 CMDS_PENDING =3D 0 LASTSCB 0xffff CURRSCB 0x1fb NEXTSCB 0=
xff40
qinstart =3D 59 qinfifonext =3D 61
QINFIFO: 0x1fa 0x1f9
WAITING_TID_QUEUES:
Pending list:
505 FIFO_USE[0x0] SCB_CONTROL[0x48]:(STATUS_RCVD|DISCENB) SCB_SCSIID[0x7]=
=20
506 FIFO_USE[0x0] SCB_CONTROL[0x48]:(STATUS_RCVD|DISCENB) SCB_SCSIID[0x17]=
=20
Total 2
Kernel Free SCB lists:=20
  Any Device: 497 507 500 498 499 501 502 503 504 508 509 510 511 496 495 4=
94 493 492 491 490 489 488 487 486 485 484 483 482 481 480 479 478 477 476 =
475 474 473 472 471 470 469 468 467 466 465 464 463 462 461 460 459 458 457=
 456 455 454 453 452 451 450 449 448 447 446 445 444 443 442 441 440 439 43=
8 437 436 435 434 433 432 431 430 429 428 427 426 425 424 423 422 421 420 4=
19 418 417 416 415 414 413 412 411 410 409 408 407 406 405 404 403 402 401 =
400 399 398 397 396 395 394 393 392 391 390 389 388 387 386 385 384 383 382=
 381 380 379 378 377 376 375 374 373 372 371 370 369 368 367 366 365 364 36=
3 362 361 360 359 358 357 356 355 354 353 352 351 350 349 348 347 346 345 3=
44 343 342 341 340 339 338 337 336 335 334 333 332 331 330 329 328 327 326 =
325 324 323 322 321 320 319 318 317 316 315 314 313 312 311 310 309 308 307=
 306 305 304 303 302 301 300 299 298 297 296 295 294 293 292 291 290 289 28=
8 287 286 285 284 283 282 281 280 279 278 277 276 275 274 273 272 271 270 2=
69 268 267 266 265 264 263 262 261 260 259 258 257 256 255 254 253 252 251 =
250 249 248 247 246 245 244 243 242 241 240 239 238 237 236 235 234 233 232=
 231 230 229 228 227 226 225 224 223 222 221 220 219 218 217 216 215 214 21=
3 212 211 210 209 208 207 206 205 204 203 202 201 200 199 198 197 196 195 1=
94 193 192 191 190 189 188 187 186 185 184 183 182 181 180 179 178 177 176 =
175 174 173 172 171 170 169 168 167 166 165 164 163 162 161 160 159 158 157=
 156 155 154 153 152 151 150 149 148 147 146 145 144 143 142 141 140 139 13=
8 137 136 135 134 133 132 131 130 129 128 127 126 125 124 123 122 121 120 1=
19 118 117 116 115 114 113 112 111 110 109 108 107 106 105 104 103 102 101 =
100 99 98 97 96 95 94 93 92 91 90 89 88 87 86 85 84 83 82 81 80 79 78 77 76=
 75 74 73 72 71 70 69 68 67 66 65 64 63 62 61 60 59 58 57 56 55 54 53 52 51=
 50 49 48 47 46 45 44 43 42 41 40 39 38 37 36 35 34 33 32 31 30 29 28 27 26=
 25 24 23 22 21 20 19 18 17 16 15 14 13 12 11 10 9 8 7 6 5 4 3 2 1 0=20
Sequencer Complete DMA-inprog list:=20
Sequencer Complete list:=20
Sequencer DMA-Up and Complete list:=20
Sequencer On QFreeze and Complete list:=20


ahd0: FIFO0 Free, LONGJMP =3D=3D 0x8000, SCB 0x1f1
SEQIMODE[0x3f]:(ENCFG4TCMD|ENCFG4ICMD|ENCFG4TSTAT|ENCFG4ISTAT|ENCFG4DATA|EN=
SAVEPTRS)=20
SEQINTSRC[0x0] DFCNTRL[0x0] DFSTATUS[0x89]:(FIFOEMP|HDONE|PRELOAD_AVAIL)=20
SG_CACHE_SHADOW[0x2]:(LAST_SEG) SG_STATE[0x0] DFFSXFRCTL[0x0]=20
SOFFCNT[0x0] MDFFSTAT[0x5]:(FIFOFREE|DLZERO) SHADDR =3D 0x00, SHCNT =3D 0x0=
=20
HADDR =3D 0x00, HCNT =3D 0x0 CCSGCTL[0x10]:(SG_CACHE_AVAIL)=20

ahd0: FIFO1 Free, LONGJMP =3D=3D 0x8063, SCB 0x1fb
SEQIMODE[0x3f]:(ENCFG4TCMD|ENCFG4ICMD|ENCFG4TSTAT|ENCFG4ISTAT|ENCFG4DATA|EN=
SAVEPTRS)=20
SEQINTSRC[0x0] DFCNTRL[0x0] DFSTATUS[0x89]:(FIFOEMP|HDONE|PRELOAD_AVAIL)=20
SG_CACHE_SHADOW[0x2]:(LAST_SEG) SG_STATE[0x0] DFFSXFRCTL[0x0]=20
SOFFCNT[0x0] MDFFSTAT[0x5]:(FIFOFREE|DLZERO) SHADDR =3D 0x00, SHCNT =3D 0x0=
=20
HADDR =3D 0x00, HCNT =3D 0x0 CCSGCTL[0x10]:(SG_CACHE_AVAIL)=20
LQIN: 0x8 0x0 0x1 0xf1 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 0x0 =
0x0 0x0 0x0=20
ahd0: LQISTATE =3D 0x0, LQOSTATE =3D 0x0, OPTIONMODE =3D 0x42
ahd0: OS_SPACE_CNT =3D 0x20 MAXCMDCNT =3D 0x1
ahd0: SAVED_SCSIID =3D 0x0 SAVED_LUN =3D 0x0

SIMODE0[0xc]:(ENOVERRUN|ENIOERR)=20
CCSCBCTL[0x0]=20
ahd0: REG0 =3D=3D 0xb860, SINDEX =3D 0x10e, DINDEX =3D 0x106
ahd0: SCBPTR =3D=3D 0x1f1, SCB_NEXT =3D=3D 0xff40, SCB_NEXT2 =3D=3D 0x1fb
CDB 12 0 0 80 88 9
STACK: 0x237 0x2 0x0 0x0 0x0 0x0 0x0 0x0
<<<<<<<<<<<<<<<<< Dump Card State Ends >>>>>>>>>>>>>>>>>>
(probe3:ahd0:0:3:0): inquiry data fails comparison at DV2 step
(probe2:ahd0:0:2:0): inquiry data fails comparison at DV1 step
(ahd0:A:3:0): refuses synchronous negotiation. Using asynchronous transfers
(ahd0:A:2:0): refuses synchronous negotiation. Using asynchronous transfers
da0 at ahd0 bus 0 scbus0 target 0 lun 0
da0: <FUJITSU MAW3147NC 0104> Fixed Direct Access SCSI-3 device=20
da0: Serial Number DAA0P7A054GD
da0: 320.000MB/s transfers (160.000MHz DT, offset 127, 16bit)
da0: Command Queueing enabled
da0: 140272MB (287277984 512 byte sectors: 255H 63S/T 17882C)
cd0 at ata1 bus 0 scbus3 target 1 lun 0
cd0: <MATSHITA DVD-ROM SR-8177 NE16> Removable CD-ROM SCSI-0 device=20
cd0: 33.300MB/s transfers (UDMA2, ATAPI 12bytes, PIO 65534bytes)
cd0: Attempt to query device size failed: NOT READY, Medium not present
da2 at ahd0 bus 0 scbus0 target 2 lun 0
da2: <SEAGATE ST3146707LC 0004> Fixed Direct Access SCSI-3 device=20
da2: Serial Number 3KS2NFJG00007611CFPX
da2: 3.300MB/s transfers
da2: Command Queueing enabled
da2: 140014MB (286749488 512 byte sectors: 255H 63S/T 17849C)
da1 at ahd0 bus 0 scbus0 target 1 lun 0
da1: <FUJITSU MAW3147NC 0104> Fixed Direct Access SCSI-3 device=20
da1: Serial Number DAA0P7A054G3
da1: 320.000MB/s transfers (160.000MHz DT, offset 127, 16bit)
da1: Command Queueing enabled
da1: 140272MB (287277984 512 byte sectors: 255H 63S/T 17882C)
da3 at ahd0 bus 0 scbus0 target 3 lun 0
da3: <SEAGATE ST3146707LC 0004> Fixed Direct Access SCSI-3 device=20
da3: Serial Number 3KS1Z2KF00007620X9K1
da3: 3.300MB/s transfers
da3: Command Queueing enabled
da3: 140014MB (286749488 512 byte sectors: 255H 63S/T 17849C)
ses0 at ahd0 bus 0 scbus0 target 6 lun 0
ses0: <SUPER GEM318 0> Fixed Processor SCSI-2 device=20
ses0: 3.300MB/s transfers
ses0: SAF-TE Compliant Device
SMP: AP CPU #1 Launched!
SMP: AP CPU #3 Launched!
SMP: AP CPU #2 Launched!
Timecounter "TSC-low" frequency 1496279088 Hz quality 1000
Trying to mount root from ufs:/dev/da0s1a [rw]...
--=20
Larry Rosenman                     http://www.lerctr.org/~ler
Phone: +1 214-642-9640                 E-Mail: ler@lerctr.org
US Mail: 108 Turvey Cove, Hutto, TX 78634-5688



Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20131109144626.GA1672>