This document:

http://www.freebsd.org/doc/en_US.ISO8859-
1/books/faq/troubleshoot.html#INDEFINITE-WAIT-BUFFER

includes:

>5.30. What does the error ``swap_pager: indefinite wait buffer:'' mean?
>
>This means that a process is trying to page memory to disk, and the page 
>attempt has hung trying to access the disk for more than 20 seconds. It might 
>be caused by bad blocks on the disk drive, disk wiring, cables, or any other 
>disk I/O-related hardware. If the drive itself is actually bad, you will also 
>see disk errors in /var/log/messages and in the output of dmesg. Otherwise, 
>check your cables and connections.

I am seeing occasional "swap_pager: indefinite wait buffer" on 4 systems under a
heavy simultaneous sequential i/o test on ATA devices ad0 and ad1 (on the
same channel).  swap is on ad0.  ad1 is mounted as /test and ad1 is filled
to capacity and read back repeatedly.  ad0 is filled to maybe 50%
capacitity and read back repeatedly.

System hardware is:

http://www.freebsd.uwaterloo.ca/twiki/bin/view/Freebsd/IntelP4

o/s is:

FreeBSD ecserv1.uwaterloo.ca 4.7-RELEASE FreeBSD 4.7-RELEASE #0: Wed Oct  9 
15:08:34 GMT 2002     
[EMAIL PROTECTED]:/usr/obj/usr/src/sys/GENERIC  i386

I am not using tagged queueing.  Both disks are reported as UDMA33.

Logs are below.  There are no disk errors.  During the test, ad0 and ad1
are each reading or writing around 25 megabytes per second.  Load
average is around 0.25, but system is very slow to log in to, or
to respond to keyboard, during the test.

Same test on 4 dual processor AMD systems (with the same disks) does not
yield this particular problem. 

Same test with just one disk under test does not yield this problem.

Under normal type usage, the problem never happens.  I'm just reporting
this to indicate that there appears to be some other cause than disk
errors for this problem.

Logs of 4 systems are:

Jan  5 00:00:00 ecserv2 newsyslog[1784]: logfile turned over
Jan  5 21:33:07 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 640, size: 4096
Jan  5 21:33:37 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 640, size: 4096
Jan  5 22:45:21 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 432, size: 4096
Jan  5 22:46:18 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 432, size: 4096
Jan  5 22:46:18 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 432, size: 4096
Jan  6 00:00:00 ecserv2 newsyslog[9854]: logfile turned over
Jan  6 00:00:00 ecserv2 newsyslog[9854]: logfile turned over
Jan  6 01:50:20 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 312, size: 4096
Jan  6 01:51:18 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 312, size: 4096
Jan  6 02:40:37 ecserv2 /kernel: pid 9894 (file1), uid 0 on /test: file system 
full
Jan  6 07:56:50 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 264, size: 4096
Jan  6 07:57:02 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 272, size: 4096
Jan  6 08:56:55 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 328, size: 4096
Jan  6 09:40:33 ecserv2 /kernel: pid 10624 (file1), uid 0 on /test: file system 
full
Jan  6 10:46:50 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 432, size: 4096
Jan  6 10:46:50 ecserv2 last message repeated 4 times
Jan  6 12:55:21 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 432, size: 4096
Jan  6 12:56:40 ecserv2 last message repeated 3 times
Jan  6 16:39:44 ecserv2 /kernel: pid 11112 (file1), uid 0 on /test: file system 
full
Jan  6 19:45:20 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 432, size: 4096
Jan  6 20:56:51 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 288, size: 4096
Jan  6 20:58:15 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 312, size: 4096
Jan  6 20:58:45 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 312, size: 4096
Jan  6 20:58:45 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 328, size: 4096
Jan  6 22:56:51 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 344, size: 4096
Jan  6 23:05:40 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 432, size: 4096
Jan  6 23:38:43 ecserv2 /kernel: pid 11570 (file1), uid 0 on /test: file system 
full
Jan  7 00:00:00 ecserv2 newsyslog[11731]: logfile turned over
Jan  7 00:00:00 ecserv2 newsyslog[11731]: logfile turned over
Jan  7 01:46:23 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 528, size: 4096
Jan  7 02:56:51 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 264, size: 4096
Jan  7 02:58:29 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 272, size: 4096
Jan  7 02:58:29 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 264, size: 4096
Jan  7 02:58:29 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 272, size: 4096
Jan  7 02:58:29 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 296, size: 4096
Jan  7 02:58:29 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 328, size: 4096
Jan  7 03:56:52 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 528, size: 4096
Jan  7 03:58:31 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 528, size: 4096
Jan  7 04:01:40 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 432, size: 4096
Jan  7 04:01:40 ecserv2 last message repeated 4 times
Jan  7 06:39:14 ecserv2 /kernel: pid 12323 (file1), uid 0 on /test: file system 
full
Jan  7 08:56:52 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 272, size: 4096
Jan  7 08:57:39 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 320, size: 4096
Jan  7 08:57:39 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 272, size: 4096
Jan  7 08:57:39 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 320, size: 4096
Jan  7 08:57:39 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 272, size: 4096
Jan  7 08:57:39 ecserv2 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 320, size: 4096
Jan  5 00:00:00 ecserv4 newsyslog[63561]: logfile turned over
Jan  6 00:00:00 ecserv4 newsyslog[69772]: logfile turned over
Jan  6 00:00:00 ecserv4 newsyslog[69772]: logfile turned over
Jan  6 01:54:21 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 296, size: 4096
Jan  6 01:56:04 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 296, size: 4096
Jan  6 01:56:04 ecserv4 last message repeated 4 times
Jan  6 03:06:41 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 512, size: 4096
Jan  6 03:06:41 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 512, size: 4096
Jan  6 04:31:20 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 512, size: 4096
Jan  6 04:35:45 ecserv4 /kernel: pid 69877 (file1), uid 0 on /test: file system 
full
Jan  6 06:25:20 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 616, size: 4096
Jan  6 06:26:05 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 616, size: 4096
Jan  6 06:26:05 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 616, size: 4096
Jan  6 07:54:22 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 296, size: 4096
Jan  6 09:04:35 ecserv4 sshd[70607]: fatal: Timeout before authentication for 
129.97.50.121.
Jan  6 09:06:04 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 312, size: 4096
Jan  6 09:06:36 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 312, size: 4096
Jan  6 09:07:21 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 432, size: 4096
Jan  6 09:07:21 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 312, size: 4096
Jan  6 09:07:21 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 432, size: 4096
Jan  6 09:07:21 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 312, size: 4096
Jan  6 09:07:21 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 432, size: 4096
Jan  6 09:07:21 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 1008, size: 4096
Jan  6 09:06:46 ecserv4 sshd[70608]: fatal: Timeout before authentication for 
129.97.50.121.
Jan  6 13:12:20 ecserv4 /kernel: pid 70723 (file1), uid 0 on /test: file system 
full
Jan  6 18:54:22 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 296, size: 4096
Jan  6 21:47:50 ecserv4 /kernel: pid 71268 (file1), uid 0 on /test: file system 
full
Jan  7 00:00:00 ecserv4 newsyslog[71586]: logfile turned over
Jan  7 00:00:00 ecserv4 newsyslog[71586]: logfile turned over
Jan  7 04:24:23 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 272, size: 4096
Jan  7 04:25:20 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 256, size: 4096
Jan  7 04:25:46 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 256, size: 4096
Jan  7 04:33:08 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 352, size: 4096
Jan  7 04:34:09 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 352, size: 4096
Jan  7 04:34:09 ecserv4 last message repeated 2 times
Jan  7 06:23:48 ecserv4 /kernel: pid 72089 (file1), uid 0 on /test: file system 
full
Jan  7 07:54:23 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 296, size: 4096
Jan  7 07:55:37 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 296, size: 4096
Jan  7 07:55:37 ecserv4 last message repeated 2 times
Jan  7 09:01:21 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 376, size: 4096
Jan  7 10:25:20 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 512, size: 4096
Jan  7 10:27:36 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 512, size: 4096
Jan  7 10:27:36 ecserv4 last message repeated 3 times
Jan  7 10:27:36 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 336, size: 4096
Jan  7 10:27:36 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 512, size: 4096
Jan  7 10:27:36 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 392, size: 4096
Jan  7 10:27:36 ecserv4 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 376, size: 4096
Jan  5 00:00:00 ecserv12 newsyslog[62526]: logfile turned over
Jan  5 22:35:21 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 504, size: 4096
Jan  5 22:36:03 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 592, size: 4096
Jan  5 22:37:05 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 600, size: 4096
Jan  6 00:00:00 ecserv12 newsyslog[66950]: logfile turned over
Jan  6 00:00:00 ecserv12 newsyslog[66950]: logfile turned over
Jan  6 00:15:21 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 600, size: 4096
Jan  6 01:54:22 ecserv12 /kernel: pid 66738 (file1), uid 0 on /test: file 
system full
Jan  6 09:15:22 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 504, size: 4096
Jan  6 09:21:15 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 272, size: 4096
Jan  6 09:21:22 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 344, size: 4096
Jan  6 09:21:25 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 272, size: 4096
Jan  6 09:21:32 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 344, size: 4096
Jan  6 09:22:08 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 272, size: 4096
Jan  6 09:23:07 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 344, size: 4096
Jan  6 09:25:40 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 408, size: 4096
Jan  6 09:39:53 ecserv12 /kernel: pid 67530 (file1), uid 0 on /test: file 
system full
Jan  6 14:01:20 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 376, size: 4096
Jan  6 15:25:20 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 504, size: 4096
Jan  6 15:26:31 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 504, size: 4096
Jan  6 15:26:38 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 504, size: 4096
Jan  6 17:28:49 ecserv12 /kernel: pid 67966 (file1), uid 0 on /test: file 
system full
Jan  6 21:55:20 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 504, size: 4096
Jan  6 21:57:25 ecserv12 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 504, size: 4096
Jan  6 21:57:25 ecserv12 last message repeated 5 times
Jan  7 00:00:00 ecserv12 newsyslog[68602]: logfile turned over
Jan  7 00:00:00 ecserv12 newsyslog[68602]: logfile turned over
Jan  7 01:22:35 ecserv12 /kernel: pid 68417 (file1), uid 0 on /test: file 
system full
Jan  7 09:16:24 ecserv12 /kernel: pid 69143 (file1), uid 0 on /test: file 
system full
Jan  5 00:00:01 ecserv15 newsyslog[61750]: logfile turned over
Jan  6 00:00:00 ecserv15 newsyslog[67959]: logfile turned over
Jan  6 00:00:00 ecserv15 newsyslog[67959]: logfile turned over
Jan  6 04:53:36 ecserv15 /kernel: pid 68078 (file1), uid 0 on /test: file 
system full
Jan  6 09:25:27 ecserv15 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 576, size: 4096
Jan  6 10:45:34 ecserv15 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 456, size: 4096
Jan  6 10:45:57 ecserv15 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 456, size: 4096
Jan  6 13:35:39 ecserv15 /kernel: pid 68914 (file1), uid 0 on /test: file 
system full
Jan  6 17:50:20 ecserv15 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 504, size: 4096
Jan  6 17:50:50 ecserv15 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 504, size: 4096
Jan  6 22:16:43 ecserv15 /kernel: pid 69461 (file1), uid 0 on /test: file 
system full
Jan  7 00:00:45 ecserv15 newsyslog[69749]: logfile turned over
Jan  7 00:00:45 ecserv15 newsyslog[69749]: logfile turned over
Jan  7 03:50:20 ecserv15 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 504, size: 4096
Jan  7 03:50:57 ecserv15 /kernel: swap_pager: indefinite wait buffer: device: 
#ad/0x20001, blkno: 504, size: 4096
Jan  7 06:56:44 ecserv15 /kernel: pid 70291 (file1), uid 0 on /test: file 
system full


-- 
Bruce Campbell
Engineering Computing
CPH-2374B
University of Waterloo
(519)888-4567 ext 5889

----------------------------------------
This mail sent through www.mywaterloo.ca

To Unsubscribe: send mail to [EMAIL PROTECTED]
with "unsubscribe freebsd-questions" in the body of the message

Reply via email to