i386/122745: 8.0-CURRENT - Messages about hard drives

[Bill Tillman]

>Number:         122745
>Category:       i386
>Synopsis:       8.0-CURRENT - Messages about hard drives
>Confidential:   no
>Severity:       non-critical
>Priority:       low
>Responsible:    freebsd-i386
>State:          open
>Quarter:        
>Keywords:       
>Date-Required:
>Class:          update
>Submitter-Id:   current-users
>Arrival-Date:   Mon Apr 14 10:00:09 UTC 2008
>Closed-Date:
>Last-Modified:
>Originator:     Bill Tillman
>Release:        8.0-CURRENT
>Organization:
n/a
>Environment:
FreeBSD FreeBSD1.flgmsi.com 8.0-CURRENT-200803 FreeBSD 8.0-CURRENT-200803 #0: Wed Mar 12 04:44:42 UTC 2008     root at almeida.cse.buffalo.edu:/usr/obj/usr/src/sys/GENERIC  i386
>Description:
Don't want to make a big deal out of this but I am running 8.0-CURRENT. The console tty0 screen has been filling up with these kinds of messages since I updated to 6.x. It happened again in 7.0-STABLE and is still apparent in 8.0-CURRENT.

I cannot say that this is not defective hardware. This is an old P166 machine that has literally been running 24/7 since 1997 and 2.2.5-RELEASE. It's very stable and reliable albeit slow. The motherboard want recognize big hard drives so I installed an IDE controller which worked with FreeBSD right out of the box for several years now.

The controller card is a I/O Flex PCI Ultra DMA/100 Dual Channel IDE Controller Card, model #IOFLEX-571A. Here is a sample of the messages that have been printing on main terminal. Notice this block happened at 3:02 AM, about the same time that the cron jobs kick off to do the log reporting.

Apr 14 03:02:02 FreeBSD1 kernel: ad4: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing request directly
Apr 14 03:03:43 FreeBSD1 kernel: ad4: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing request directly
Apr 14 03:03:43 FreeBSD1 kernel: ad4: WARNING - SETFEATURES ENABLE RCACHE taskqueue timeout - completing request directly
Apr 14 03:03:43 FreeBSD1 kernel: swap_pager: indefinite wait buffer: bufobj: 0, blkno: 9209, size: 28672
Apr 14 03:03:43 FreeBSD1 kernel: swap_pager: indefinite wait buffer: bufobj: 0, blkno: 12206, size: 32768
Apr 14 03:03:43 FreeBSD1 kernel: ad4: WARNING - SETFEATURES ENABLE WCACHE taskqueue timeout - completing request directly
Apr 14 03:03:43 FreeBSD1 kernel: ad4: WARNING - SET_MULTI taskqueue timeout - completing request directly
Apr 14 03:03:43 FreeBSD1 kernel: ad4: TIMEOUT - READ_DMA retrying (1 retry left) LBA=1122311
Apr 14 03:03:43 FreeBSD1 kernel: ad7: TIMEOUT - READ_DMA retrying (1 retry left) LBA=133605279
Apr 14 03:03:43 FreeBSD1 kernel: ad4: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing request directly
Apr 14 03:03:43 FreeBSD1 kernel: ad4: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing request directly
Apr 14 03:03:43 FreeBSD1 kernel: ad4: WARNING - SETFEATURES ENABLE RCACHE taskqueue timeout - completing request directly
Apr 14 03:03:44 FreeBSD1 kernel: swap_pager: indefinite wait buffer: bufobj: 0, blkno: 9042, size: 28672
Apr 14 03:03:44 FreeBSD1 kernel: swap_pager: indefinite wait buffer: bufobj: 0, blkno: 12214, size: 16384
Apr 14 03:03:44 FreeBSD1 kernel: ad4: WARNING - SETFEATURES ENABLE WCACHE taskqueue timeout - completing request directly
Apr 14 03:03:44 FreeBSD1 kernel: ad4: WARNING - SET_MULTI taskqueue timeout - completing request directly
Apr 14 03:03:44 FreeBSD1 kernel: ad4: TIMEOUT - READ_MUL retrying (1 retry left) LBA=4287967
Apr 14 03:03:44 FreeBSD1 kernel: ad6: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing request directly
Apr 14 03:03:44 FreeBSD1 kernel: ad6: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing request directly
Apr 14 03:03:44 FreeBSD1 kernel: ad6: WARNING - SETFEATURES ENABLE RCACHE taskqueue timeout - completing request directly
Apr 14 03:03:44 FreeBSD1 kernel: swap_pager: indefinite wait buffer: bufobj: 0, blkno: 9042, size: 28672
Apr 14 03:03:44 FreeBSD1 kernel: swap_pager: indefinite wait buffer: bufobj: 0, blkno: 12214, size: 16384
Apr 14 03:03:44 FreeBSD1 kernel: ad6: WARNING - SETFEATURES ENABLE WCACHE taskqueue timeout - completing request directly
Apr 14 03:03:44 FreeBSD1 kernel: ad6: WARNING - SET_MULTI taskqueue timeout - completing request directly
Apr 14 03:03:44 FreeBSD1 kernel: ad7: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing request directly
Apr 14 03:03:44 FreeBSD1 kernel: ad7: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing request directly
Apr 14 03:03:44 FreeBSD1 kernel: ad7: WARNING - SETFEATURES ENABLE RCACHE taskqueue timeout - completing request directly
Apr 14 03:03:44 FreeBSD1 kernel: swap_pager: indefinite wait buffer: bufobj: 0, blkno: 9042, size: 28672
Apr 14 03:03:44 FreeBSD1 kernel: swap_pager: indefinite wait buffer: bufobj: 0, blkno: 12214, size: 16384
Apr 14 03:03:44 FreeBSD1 kernel: ad7: WARNING - SETFEATURES ENABLE WCACHE taskqueue timeout - completing request directly
Apr 14 03:03:44 FreeBSD1 kernel: ad7: WARNING - SET_MULTI taskqueue timeout - completing request directly
Apr 14 03:03:44 FreeBSD1 kernel: ad6: TIMEOUT - WRITE_DMA retrying (1 retry left) LBA=34624703
Apr 14 03:03:44 FreeBSD1 kernel: ad4: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing request directly
Apr 14 03:03:44 FreeBSD1 kernel: ad4: WARNING - SETFEATURES SET TRANSFER MODE taskqueue timeout - completing request directly
Apr 14 03:03:44 FreeBSD1 kernel: ad4: WARNING - SETFEATURES ENABLE RCACHE taskqueue timeout - completing request directly
Apr 14 03:03:44 FreeBSD1 kernel: swap_pager: indefinite wait buffer: bufobj: 0, blkno: 9042, size: 28672
Apr 14 03:03:44 FreeBSD1 kernel: swap_pager: indefinite wait buffer: bufobj: 0, blkno: 12214, size: 16384
Apr 14 03:03:44 FreeBSD1 kernel: ad4: WARNING - SETFEATURES ENABLE WCACHE taskqueue timeout - completing request directly

>How-To-Repeat:
The event seems to occur whenever I do large writes to the hard drives. The drives are less than a year old and were purchased brand new. The controller card is about 5 years old now.
>Fix:
Just reporting to hopefully help with the development of 8.0

>Release-Note:
>Audit-Trail:
>Unformatted: