NFS Mount/Unmount Bug in 4.1.7

[jfceklosky]

Support,

I updated to 4.1.7 on a NV unit. Mount and umount commands were taking a very long time, perhaps about 30 seconds each. I dropped back to 4.1.6 and the problems are gone.

Any thoughts on this?

[de_niro]

Could you please provide me more detailed information?I have tested nfs mounting from NAS to NAS,it just taking less than 3 sec.
Also I compared version and size of mount file between 4.1.6 and 4.1.7,there's no difference,both are 2.13.1-1.netgear1.

[sphardy1]

Do you have disk spindown enabled?

When the disks are spundown I find it can take ~20 seconds to mount the first share over NFS - but that mount spins the disks up and subsequent mounts of other shares are virtually instantaneous.

Also I compared version and size of mount file between 4.1.6 and 4.1.7,there's no difference,both are 2.13.1-1.netgear1.

What is a "mount file"?

[jfceklosky]

The NFS client to the ready NAS with 4.1.7 was Linux running Fedora Core 14.
I did not have the spindown active.

The mount was issued as:

mount -t nfs rnas:/mountpoint /net -O async,rsize=32768,wsize=32768,hard,intr,nodev,nosuid

This called eventually comes down to a mount.nfs, which hangs when trying to
perform the mount.

Both the mount and umount calls will hang for about 20-30 seconds before doing their job.
I also have a friend who updated to 4.1.7 and he experienced the same mount hangs while running Ubuntu 10.10, so the problem is NOT with the client. side

If I run the same client Fedora Core 14 with ready nas 4.1.6 all is fine.

I have all FIREWALLS turned off on the client Fedora 14 box.

I can preform an strace of the mount if go and put 4.1.7 back on the NAS.

[Skywalker]

Hmm... I run Fedora 14 as well, and here's what happens when I mount a Duo over NFS:

[root@localhost ~]# cat /etc/fedora-release 
Fedora release 14 (Laughlin)
[root@localhost ~]# time mount -t nfs 10.20.1.229:/c/backup /net -O async,rsize=32768,wsize=32768,hard,intr,nodev,nosuid

real	0m0.258s
user	0m0.002s
sys	0m0.014s

[jfceklosky]

Here is the strace from the following:

strace -o /tmp/bad mount.nfs rnas:/backup /cdr -o nfsvers=3

xecve("/sbin/mount.nfs", ["mount.nfs", "rnas:/backup", "/cdr", "-o", "nfsvers=3"], [/* 65 vars */]) = 0
brk(0)                                  = 0xb7deb000
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77b6000
access("/etc/ld.so.preload", R_OK)      = -1 ENOENT (No such file or directory)
open("/etc/ld.so.cache", O_RDONLY)      = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=133480, ...}) = 0
mmap2(NULL, 133480, PROT_READ, MAP_PRIVATE, 3, 0) = 0xb7795000
close(3)                                = 0
open("/usr/lib/libtirpc.so.1", O_RDONLY) = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\340]\0\0004\0\0\0"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0755, st_size=159264, ...}) = 0
mmap2(NULL, 159152, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb776e000
mmap2(0xb7794000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x26) = 0xb7794000
close(3)                                = 0
open("/lib/libc.so.6", O_RDONLY)        = 3
read(3, "\177ELF\1\1\1\3\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0p\17$F4\0\0\0"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0755, st_size=1889628, ...}) = 0
mmap2(NULL, 1649160, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb75db000
mmap2(0xb7768000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x18c) = 0xb7768000
mmap2(0xb776b000, 10760, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb776b000
close(3)                                = 0
open("/lib/libnsl.so.1", O_RDONLY)      = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\360`.L4\0\0\0"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0755, st_size=115636, ...}) = 0
mmap2(NULL, 108456, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb75c0000
mmap2(0xb75d7000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x16) = 0xb75d7000
mmap2(0xb75d9000, 6056, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb75d9000
close(3)                                = 0
open("/usr/lib/libgssglue.so.1", O_RDONLY) = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\320\24\0\0004\0\0\0"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0755, st_size=33308, ...}) = 0
mmap2(NULL, 36264, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb75b7000
mmap2(0xb75bf000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x7) = 0xb75bf000
close(3)                                = 0
open("/lib/libdl.so.2", O_RDONLY)       = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0`\272>F4\0\0\0"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0755, st_size=19780, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb75b6000
mmap2(NULL, 16500, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb75b1000
mmap2(0xb75b4000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2) = 0xb75b4000
close(3)                                = 0
open("/lib/libpthread.so.0", O_RDONLY)  = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0`l?F4\0\0\0"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0755, st_size=138056, ...}) = 0
mmap2(NULL, 111100, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7595000
mmap2(0xb75ad000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x17) = 0xb75ad000
mmap2(0xb75af000, 4604, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb75af000
close(3)                                = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7594000
set_thread_area({entry_number:-1 -> 6, base_addr:0xb75948d0, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}) = 0
mprotect(0xb75ad000, 4096, PROT_READ)   = 0
mprotect(0xb75b4000, 4096, PROT_READ)   = 0
mprotect(0xb75d7000, 4096, PROT_READ)   = 0
mprotect(0xb7768000, 8192, PROT_READ)   = 0
mprotect(0xb77d8000, 4096, PROT_READ)   = 0
munmap(0xb7795000, 133480)              = 0
set_tid_address(0xb7594938)             = 31482
set_robust_list(0xb7594940, 0xc)        = 0
rt_sigaction(SIGRTMIN, {0xb7599720, [], SA_SIGINFO}, NULL, 😎 = 0
rt_sigaction(SIGRT_1, {0xb75997a0, [], SA_RESTART|SA_SIGINFO}, NULL, 😎 = 0
rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 😎 = 0
getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024, rlim_max=RLIM_INFINITY}) = 0
uname({sys="Linux", node="saturn.home.com", ...}) = 0
getuid32()                              = 0
uname({sys="Linux", node="saturn.home.com", ...}) = 0
rt_sigaction(SIGUSR1, {0xb77eb360, [USR1], SA_RESTART}, {SIG_DFL, [], 0}, 😎 = 0
rt_sigaction(SIGUSR2, {0xb77eb360, [USR2], SA_RESTART}, {SIG_DFL, [], 0}, 😎 = 0
stat64("/etc/nfsmount.conf", {st_mode=S_IFREG|0644, st_size=3390, ...}) = 0
open("/etc/nfsmount.conf", O_RDONLY|O_LARGEFILE) = 3
brk(0)                                  = 0xb7deb000
brk(0xb7e0c000)                         = 0xb7e0c000
brk(0)                                  = 0xb7e0c000
read(3, "#\n# /etc/nfsmount.conf - see nfs"..., 3390) = 3390
close(3)                                = 0
lstat64("/cdr", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
stat64("/cdr", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
access("/cdr", X_OK)                    = 0
socket(PF_NETLINK, SOCK_RAW, 0)         = 3
bind(3, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
getsockname(3, {sa_family=AF_NETLINK, pid=31482, groups=00000000}, [12]) = 0
time(NULL)                              = 1290137863
sendto(3, "\24\0\0\0\26\0\1\3\7\361\345L\0\0\0\0\0\0\0\0", 20, 0, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"0\0\0\0\24\0\2\0\7\361\345L\372z\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 228
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"@\0\0\0\24\0\2\0\7\361\345L\372z\0\0\n\200\200\376\1\0\0\0\24\0\1\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 256
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"\24\0\0\0\3\0\2\0\7\361\345L\372z\0\0\0\0\0\0\1\0\0\0\24\0\1\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
close(3)                                = 0
socket(PF_FILE, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 3
connect(3, {sa_family=AF_FILE, path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
close(3)                                = 0
socket(PF_FILE, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 3
connect(3, {sa_family=AF_FILE, path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
close(3)                                = 0
open("/etc/nsswitch.conf", O_RDONLY)    = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=1720, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77b5000
read(3, "#\n# /etc/nsswitch.conf\n#\n# An ex"..., 4096) = 1720
read(3, "", 4096)                       = 0
close(3)                                = 0
munmap(0xb77b5000, 4096)                = 0
open("/etc/host.conf", O_RDONLY)        = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=26, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77b5000
read(3, "multi on\norder hosts,bind\n", 4096) = 26
read(3, "", 4096)                       = 0
close(3)                                = 0
munmap(0xb77b5000, 4096)                = 0
futex(0xb776cba4, FUTEX_WAKE_PRIVATE, 2147483647) = 0
open("/etc/resolv.conf", O_RDONLY)      = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=76, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77b5000
read(3, "; generated by /sbin/dhclient-sc"..., 4096) = 76
read(3, "", 4096)                       = 0
close(3)                                = 0
munmap(0xb77b5000, 4096)                = 0
open("/etc/ld.so.cache", O_RDONLY)      = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=133480, ...}) = 0
mmap2(NULL, 133480, PROT_READ, MAP_PRIVATE, 3, 0) = 0xb7795000
close(3)                                = 0
open("/lib/libnss_files.so.2", O_RDONLY) = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\320\31\0\0004\0\0\0"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0755, st_size=54456, ...}) = 0
mmap2(NULL, 49852, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7587000
mmap2(0xb7592000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0xa) = 0xb7592000
close(3)                                = 0
mprotect(0xb7592000, 4096, PROT_READ)   = 0
munmap(0xb7795000, 133480)              = 0
open("/etc/hosts", O_RDONLY|O_CLOEXEC)  = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=138, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77b5000
read(3, "127.0.0.1\tsaturn.home.com\tsaturn"..., 4096) = 138
read(3, "", 4096)                       = 0
close(3)                                = 0
munmap(0xb77b5000, 4096)                = 0
open("/etc/ld.so.cache", O_RDONLY)      = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=133480, ...}) = 0
mmap2(NULL, 133480, PROT_READ, MAP_PRIVATE, 3, 0) = 0xb7795000
close(3)                                = 0
open("/lib/libnss_mdns4_minimal.so.2", O_RDONLY) = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\20\10\0\0004\0\0\0"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0755, st_size=8332, ...}) = 0
mmap2(NULL, 11076, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7584000
mmap2(0xb7586000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1) = 0xb7586000
close(3)                                = 0
munmap(0xb7795000, 133480)              = 0
open("/etc/ld.so.cache", O_RDONLY)      = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=133480, ...}) = 0
mmap2(NULL, 133480, PROT_READ, MAP_PRIVATE, 3, 0) = 0xb7795000
close(3)                                = 0
open("/lib/libnss_dns.so.2", O_RDONLY)  = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\0\f\0\0004\0\0\0"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0755, st_size=33992, ...}) = 0
mmap2(NULL, 28800, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb757c000
mmap2(0xb7582000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x6) = 0xb7582000
close(3)                                = 0
open("/lib/libresolv.so.2", O_RDONLY)   = 3
read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0P6nF4\0\0\0"..., 512) = 512
fstat64(3, {st_mode=S_IFREG|0755, st_size=105476, ...}) = 0
mmap2(NULL, 104516, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7562000
mmap2(0xb7578000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x15) = 0xb7578000
mmap2(0xb757a000, 6212, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb757a000
close(3)                                = 0
mprotect(0xb7578000, 4096, PROT_READ)   = 0
mprotect(0xb7582000, 4096, PROT_READ)   = 0
munmap(0xb7795000, 133480)              = 0
socket(PF_INET, SOCK_DGRAM|SOCK_NONBLOCK, IPPROTO_IP) = 3
connect(3, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("192.168.1.1")}, 16) = 0
gettimeofday({1290137863, 962509}, NULL) = 0
poll([{fd=3, events=POLLOUT}], 1, 0)    = 1 ([{fd=3, revents=POLLOUT}])
send(3, "}\242\1\0\0\1\0\0\0\0\0\0\4rnas\4home\3com\0\0\1\0\1", 31, MSG_NOSIGNAL) = 31
poll([{fd=3, events=POLLIN|POLLOUT}], 1, 5000) = 1 ([{fd=3, revents=POLLOUT}])
send(3, "\351/\1\0\0\1\0\0\0\0\0\0\4rnas\4home\3com\0\0\34\0\1", 31, MSG_NOSIGNAL) = 31
gettimeofday({1290137863, 963317}, NULL) = 0
poll([{fd=3, events=POLLIN}], 1, 4999)  = 1 ([{fd=3, revents=POLLIN}])
ioctl(3, FIONREAD, [47])                = 0
recvfrom(3, "}\242\205\200\0\1\0\1\0\0\0\0\4rnas\4home\3com\0\0\1\0\1\300"..., 2048, 0, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("192.168.1.1")}, [16]) = 47
gettimeofday({1290137863, 965418}, NULL) = 0
poll([{fd=3, events=POLLIN}], 1, 4997)  = 1 ([{fd=3, revents=POLLIN}])
ioctl(3, FIONREAD, [31])                = 0
recvfrom(3, "\351/\201\200\0\1\0\0\0\0\0\0\4rnas\4home\3com\0\0\34\0\1", 2001, 0, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("192.168.1.1")}, [16]) = 31
close(3)                                = 0
time(NULL)                              = 1290137863
open("/etc/rpc", O_RDONLY|O_CLOEXEC)    = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=1615, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77b5000
read(3, "#ident\t\"@(#)rpc\t1.11\t95/07/14 SM"..., 4096) = 1615
close(3)                                = 0
munmap(0xb77b5000, 4096)                = 0
open("/etc/rpc", O_RDONLY|O_CLOEXEC)    = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=1615, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77b5000
read(3, "#ident\t\"@(#)rpc\t1.11\t95/07/14 SM"..., 4096) = 1615
close(3)                                = 0
munmap(0xb77b5000, 4096)                = 0
socket(PF_NETLINK, SOCK_RAW, 0)         = 3
bind(3, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
getsockname(3, {sa_family=AF_NETLINK, pid=31482, groups=00000000}, [12]) = 0
time(NULL)                              = 1290137863
sendto(3, "\24\0\0\0\26\0\1\3\7\361\345L\0\0\0\0\0\0\0\0", 20, 0, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"0\0\0\0\24\0\2\0\7\361\345L\372z\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 228
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"@\0\0\0\24\0\2\0\7\361\345L\372z\0\0\n\200\200\376\1\0\0\0\24\0\1\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 256
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"\24\0\0\0\3\0\2\0\7\361\345L\372z\0\0\0\0\0\0\1\0\0\0\24\0\1\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
close(3)                                = 0
socket(PF_FILE, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 3
connect(3, {sa_family=AF_FILE, path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
close(3)                                = 0
socket(PF_FILE, SOCK_STREAM|SOCK_CLOEXEC|SOCK_NONBLOCK, 0) = 3
connect(3, {sa_family=AF_FILE, path="/var/run/nscd/socket"}, 110) = -1 ENOENT (No such file or directory)
close(3)                                = 0
open("/etc/services", O_RDONLY|O_CLOEXEC) = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=644275, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77b5000
read(3, "# /etc/services:\n# $Id: services"..., 4096) = 4096
read(3, "ervice\nfinger          79/tcp\nfi"..., 4096) = 4096
close(3)                                = 0
munmap(0xb77b5000, 4096)                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_UDP) = 3
bind(3, {sa_family=AF_INET, sin_port=htons(0), sin_addr=inet_addr("0.0.0.0")}, 16) = 0
fcntl64(3, F_GETFL)                     = 0x2 (flags O_RDWR)
fcntl64(3, F_SETFL, O_RDWR|O_NONBLOCK)  = 0
connect(3, {sa_family=AF_INET, sin_port=htons(111), sin_addr=inet_addr("127.0.0.1")}, 16) = 0
fcntl64(3, F_SETFL, O_RDWR)             = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
getrlimit(RLIMIT_NOFILE, {rlim_cur=1024, rlim_max=4*1024}) = 0
mmap2(NULL, 200704, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7531000
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
getsockname(3, {sa_family=AF_INET, sin_port=htons(43230), sin_addr=inet_addr("127.0.0.1")}, [16]) = 0
getsockopt(3, SOL_SOCKET, SO_TYPE, [2], [4]) = 0
gettimeofday({1290137863, 971234}, NULL) = 0
setsockopt(3, SOL_IP, IP_RECVERR, [1], 4) = 0
ioctl(3, FIONBIO, [1])                  = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
futex(0xb7794a88, FUTEX_WAKE_PRIVATE, 1) = 0
sendto(3, "L\353Z \0\0\0\0\0\0\0\2\0\1\206\240\0\0\0\2\0\0\0\3\0\0\0\0\0\0\0\0"..., 56, 0, {sa_family=AF_INET, sin_port=htons(111), sin_addr=inet_addr("127.0.0.1")}, 16) = 56
poll([{fd=3, events=POLLIN}], 1, 1000)  = 1 ([{fd=3, revents=POLLIN}])
recvfrom(3, "L\353Z \0\0\0\1\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\212\272", 8800, 0, NULL, NULL) = 28
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
close(3)                                = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_UDP) = 3
bind(3, {sa_family=AF_INET, sin_port=htons(0), sin_addr=inet_addr("0.0.0.0")}, 16) = 0
fcntl64(3, F_GETFL)                     = 0x2 (flags O_RDWR)
fcntl64(3, F_SETFL, O_RDWR|O_NONBLOCK)  = 0
connect(3, {sa_family=AF_INET, sin_port=htons(35514), sin_addr=inet_addr("127.0.0.1")}, 16) = 0
fcntl64(3, F_SETFL, O_RDWR)             = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
getsockname(3, {sa_family=AF_INET, sin_port=htons(40554), sin_addr=inet_addr("127.0.0.1")}, [16]) = 0
getsockopt(3, SOL_SOCKET, SO_TYPE, [2], [4]) = 0
gettimeofday({1290137863, 974638}, NULL) = 0
setsockopt(3, SOL_IP, IP_RECVERR, [1], 4) = 0
ioctl(3, FIONBIO, [1])                  = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
futex(0xb7794a88, FUTEX_WAKE_PRIVATE, 1) = 0
sendto(3, "L\353T\324\0\0\0\0\0\0\0\2\0\1\206\270\0\0\0\1\0\0\0\0\0\0\0\0\0\0\0\0"..., 40, 0, {sa_family=AF_INET, sin_port=htons(35514), sin_addr=inet_addr("127.0.0.1")}, 16) = 40
poll([{fd=3, events=POLLIN}], 1, 1000)  = 1 ([{fd=3, revents=POLLIN}])
recvfrom(3, "L\353T\324\0\0\0\1\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 8800, 0, NULL, NULL) = 24
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
close(3)                                = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
socket(PF_NETLINK, SOCK_RAW, 0)         = 3
bind(3, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
getsockname(3, {sa_family=AF_NETLINK, pid=31482, groups=00000000}, [12]) = 0
time(NULL)                              = 1290137863
sendto(3, "\24\0\0\0\26\0\1\3\7\361\345L\0\0\0\0\0\0\0\0", 20, 0, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"0\0\0\0\24\0\2\0\7\361\345L\372z\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 228
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"@\0\0\0\24\0\2\0\7\361\345L\372z\0\0\n\200\200\376\1\0\0\0\24\0\1\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 256
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"\24\0\0\0\3\0\2\0\7\361\345L\372z\0\0\0\0\0\0\1\0\0\0\24\0\1\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
close(3)                                = 0
open("/etc/rpc", O_RDONLY|O_CLOEXEC)    = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=1615, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7530000
read(3, "#ident\t\"@(#)rpc\t1.11\t95/07/14 SM"..., 4096) = 1615
close(3)                                = 0
munmap(0xb7530000, 4096)                = 0
open("/etc/rpc", O_RDONLY|O_CLOEXEC)    = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=1615, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7530000
read(3, "#ident\t\"@(#)rpc\t1.11\t95/07/14 SM"..., 4096) = 1615
close(3)                                = 0
munmap(0xb7530000, 4096)                = 0
open("/etc/rpc", O_RDONLY|O_CLOEXEC)    = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=1615, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7530000
read(3, "#ident\t\"@(#)rpc\t1.11\t95/07/14 SM"..., 4096) = 1615
close(3)                                = 0
munmap(0xb7530000, 4096)                = 0
socket(PF_NETLINK, SOCK_RAW, 0)         = 3
bind(3, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
getsockname(3, {sa_family=AF_NETLINK, pid=31482, groups=00000000}, [12]) = 0
time(NULL)                              = 1290137863
sendto(3, "\24\0\0\0\26\0\1\3\7\361\345L\0\0\0\0\0\0\0\0", 20, 0, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"0\0\0\0\24\0\2\0\7\361\345L\372z\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 228
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"@\0\0\0\24\0\2\0\7\361\345L\372z\0\0\n\200\200\376\1\0\0\0\24\0\1\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 256
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"\24\0\0\0\3\0\2\0\7\361\345L\372z\0\0\0\0\0\0\1\0\0\0\24\0\1\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
close(3)                                = 0
open("/etc/services", O_RDONLY|O_CLOEXEC) = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=644275, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7530000
read(3, "# /etc/services:\n# $Id: services"..., 4096) = 4096
read(3, "ervice\nfinger          79/tcp\nfi"..., 4096) = 4096
close(3)                                = 0
munmap(0xb7530000, 4096)                = 0
socket(PF_INET, SOCK_STREAM, IPPROTO_TCP) = 3
bind(3, {sa_family=AF_INET, sin_port=htons(0), sin_addr=inet_addr("0.0.0.0")}, 16) = 0
fcntl64(3, F_GETFL)                     = 0x2 (flags O_RDWR)
fcntl64(3, F_SETFL, O_RDWR|O_NONBLOCK)  = 0
connect(3, {sa_family=AF_INET, sin_port=htons(111), sin_addr=inet_addr("192.168.1.6")}, 16) = -1 EINPROGRESS (Operation now in progress)
select(4, NULL, [3], NULL, {10, 0})     = 1 (out [3], left {9, 999872})
getsockopt(3, SOL_SOCKET, SO_ERROR, [0], [4]) = 0
fcntl64(3, F_SETFL, O_RDWR)             = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
mmap2(NULL, 200704, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7500000
getpeername(3, {sa_family=AF_INET, sin_port=htons(111), sin_addr=inet_addr("192.168.1.6")}, [16]) = 0
getsockname(3, {sa_family=AF_INET, sin_port=htons(34315), sin_addr=inet_addr("192.168.1.8")}, [16]) = 0
getsockopt(3, SOL_SOCKET, SO_TYPE, [1], [4]) = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
gettimeofday({1290137863, 985648}, NULL) = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
brk(0)                                  = 0xb7e0c000
brk(0xb7e36000)                         = 0xb7e36000
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
futex(0xb7794a88, FUTEX_WAKE_PRIVATE, 1) = 0
write(3, "\200\0\0008\362E\351\f\0\0\0\0\0\0\0\2\0\1\206\240\0\0\0\2\0\0\0\3\0\0\0\0"..., 60) = 60
poll([{fd=3, events=POLLIN}], 1, 9999)  = 1 ([{fd=3, revents=POLLIN}])
read(3, "\200\0\0\34\362E\351\f\0\0\0\1\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\10\1", 65536) = 32
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
close(3)                                = 0
brk(0)                                  = 0xb7e36000
brk(0)                                  = 0xb7e36000
brk(0xb7e16000)                         = 0xb7e16000
brk(0)                                  = 0xb7e16000
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
socket(PF_INET, SOCK_STREAM, IPPROTO_TCP) = 3
bind(3, {sa_family=AF_INET, sin_port=htons(0), sin_addr=inet_addr("0.0.0.0")}, 16) = 0
fcntl64(3, F_GETFL)                     = 0x2 (flags O_RDWR)
fcntl64(3, F_SETFL, O_RDWR|O_NONBLOCK)  = 0
connect(3, {sa_family=AF_INET, sin_port=htons(2049), sin_addr=inet_addr("192.168.1.6")}, 16) = -1 EINPROGRESS (Operation now in progress)
select(4, NULL, [3], NULL, {10, 0})     = 1 (out [3], left {9, 999969})
getsockopt(3, SOL_SOCKET, SO_ERROR, [0], [4]) = 0
fcntl64(3, F_SETFL, O_RDWR)             = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
getpeername(3, {sa_family=AF_INET, sin_port=htons(2049), sin_addr=inet_addr("192.168.1.6")}, [16]) = 0
getsockname(3, {sa_family=AF_INET, sin_port=htons(42644), sin_addr=inet_addr("192.168.1.8")}, [16]) = 0
getsockopt(3, SOL_SOCKET, SO_TYPE, [1], [4]) = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
gettimeofday({1290137863, 991393}, NULL) = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
futex(0xb7794a88, FUTEX_WAKE_PRIVATE, 1) = 0
write(3, "\200\0\0(\362E\303\236\0\0\0\0\0\0\0\2\0\1\206\243\0\0\0\3\0\0\0\0\0\0\0\0"..., 44) = 44
poll([{fd=3, events=POLLIN}], 1, 9999)  = 1 ([{fd=3, revents=POLLIN}])
read(3, "\200\0\0\30\362E\303\236\0\0\0\1\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 65536) = 28
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
close(3)                                = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
open("/etc/rpc", O_RDONLY|O_CLOEXEC)    = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=1615, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77b5000
read(3, "#ident\t\"@(#)rpc\t1.11\t95/07/14 SM"..., 4096) = 1615
close(3)                                = 0
munmap(0xb77b5000, 4096)                = 0
socket(PF_NETLINK, SOCK_RAW, 0)         = 3
bind(3, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
getsockname(3, {sa_family=AF_NETLINK, pid=31482, groups=00000000}, [12]) = 0
time(NULL)                              = 1290137863
sendto(3, "\24\0\0\0\26\0\1\3\7\361\345L\0\0\0\0\0\0\0\0", 20, 0, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"0\0\0\0\24\0\2\0\7\361\345L\372z\0\0\2\10\200\376\1\0\0\0\10\0\1\0\177\0\0\1"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 228
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"@\0\0\0\24\0\2\0\7\361\345L\372z\0\0\n\200\200\376\1\0\0\0\24\0\1\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 256
recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"\24\0\0\0\3\0\2\0\7\361\345L\372z\0\0\0\0\0\0\1\0\0\0\24\0\1\0\0\0\0\0"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 20
close(3)                                = 0
open("/etc/services", O_RDONLY|O_CLOEXEC) = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=644275, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77b5000
read(3, "# /etc/services:\n# $Id: services"..., 4096) = 4096
read(3, "ervice\nfinger          79/tcp\nfi"..., 4096) = 4096
close(3)                                = 0
munmap(0xb77b5000, 4096)                = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_UDP) = 3
bind(3, {sa_family=AF_INET, sin_port=htons(0), sin_addr=inet_addr("0.0.0.0")}, 16) = 0
fcntl64(3, F_GETFL)                     = 0x2 (flags O_RDWR)
fcntl64(3, F_SETFL, O_RDWR|O_NONBLOCK)  = 0
connect(3, {sa_family=AF_INET, sin_port=htons(111), sin_addr=inet_addr("192.168.1.6")}, 16) = 0
fcntl64(3, F_SETFL, O_RDWR)             = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
getsockname(3, {sa_family=AF_INET, sin_port=htons(45532), sin_addr=inet_addr("192.168.1.8")}, [16]) = 0
getsockopt(3, SOL_SOCKET, SO_TYPE, [2], [4]) = 0
gettimeofday({1290137863, 996507}, NULL) = 0
setsockopt(3, SOL_IP, IP_RECVERR, [1], 4) = 0
ioctl(3, FIONBIO, [1])                  = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
futex(0xb7794a88, FUTEX_WAKE_PRIVATE, 1) = 0
sendto(3, "L\352\277g\0\0\0\0\0\0\0\2\0\1\206\240\0\0\0\2\0\0\0\3\0\0\0\0\0\0\0\0"..., 56, 0, {sa_family=AF_INET, sin_port=htons(111), sin_addr=inet_addr("192.168.1.6")}, 16) = 56
poll([{fd=3, events=POLLIN}], 1, 1000)  = 1 ([{fd=3, revents=POLLIN}])
recvfrom(3, "L\352\277g\0\0\0\1\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\4\37", 8800, 0, NULL, NULL) = 28
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
close(3)                                = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
socket(PF_INET, SOCK_DGRAM, IPPROTO_UDP) = 3
bind(3, {sa_family=AF_INET, sin_port=htons(0), sin_addr=inet_addr("0.0.0.0")}, 16) = 0
fcntl64(3, F_GETFL)                     = 0x2 (flags O_RDWR)
fcntl64(3, F_SETFL, O_RDWR|O_NONBLOCK)  = 0
connect(3, {sa_family=AF_INET, sin_port=htons(1055), sin_addr=inet_addr("192.168.1.6")}, 16) = 0
fcntl64(3, F_SETFL, O_RDWR)             = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
getsockname(3, {sa_family=AF_INET, sin_port=htons(39944), sin_addr=inet_addr("192.168.1.8")}, [16]) = 0
getsockopt(3, SOL_SOCKET, SO_TYPE, [2], [4]) = 0
gettimeofday({1290137864, 897}, NULL)   = 0
setsockopt(3, SOL_IP, IP_RECVERR, [1], 4) = 0
ioctl(3, FIONBIO, [1])                  = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
futex(0xb7794a88, FUTEX_WAKE_PRIVATE, 1) = 0
sendto(3, "L\345\210t\0\0\0\0\0\0\0\2\0\1\206\245\0\0\0\3\0\0\0\0\0\0\0\0\0\0\0\0"..., 40, 0, {sa_family=AF_INET, sin_port=htons(1055), sin_addr=inet_addr("192.168.1.6")}, 16) = 40
poll([{fd=3, events=POLLIN}], 1, 1000)  = 1 ([{fd=3, revents=POLLIN}])
recvfrom(3, "L\345\210t\0\0\0\1\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 8800, 0, NULL, NULL) = 24
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
rt_sigprocmask(SIG_SETMASK, ~[RTMIN RT_1], [], 😎 = 0
close(3)                                = 0
rt_sigprocmask(SIG_SETMASK, [], NULL, 😎 = 0
open("/etc/protocols", O_RDONLY|O_CLOEXEC) = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=6516, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77b5000
read(3, "# /etc/protocols:\n# $Id: protoco"..., 4096) = 4096
close(3)                                = 0
munmap(0xb77b5000, 4096)                = 0
open("/etc/netconfig", O_RDONLY)        = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=767, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77b5000
read(3, "#\n# The network configuration fi"..., 4096) = 767
close(3)                                = 0
munmap(0xb77b5000, 4096)                = 0
open("/etc/protocols", O_RDONLY|O_CLOEXEC) = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=6516, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77b5000
read(3, "# /etc/protocols:\n# $Id: protoco"..., 4096) = 4096
close(3)                                = 0
munmap(0xb77b5000, 4096)                = 0
open("/etc/netconfig", O_RDONLY)        = 3
fstat64(3, {st_mode=S_IFREG|0644, st_size=767, ...}) = 0
mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77b5000
read(3, "#\n# The network configuration fi"..., 4096) = 767
close(3)                                = 0
munmap(0xb77b5000, 4096)                = 0

HANGS HERE FOR ABOUT 16 SECONDS------>mount("rnas:/backup", "/cdr", "nfs", 0, "addr=192.168.1.6,vers=3,proto=tc"...) = 0

lstat64("/etc/mtab", {st_mode=S_IFREG|0644, st_size=638, ...}) = 0
open("/etc/mtab", O_RDWR|O_CREAT|O_LARGEFILE, 0644) = 3
close(3)  
... 
rest is CUT

[sphardy1]

Try mounting using the ip address instead of name - would narrow down if this is truly a mounting issue or perhaps a name resolution issue

[jfceklosky]

Using the IP has no difference.

The name was already resolved before the hang.

[markbeh]

We have also been experiencing NFS problems in our environment. I had and existing NV(4.1.6) whish was working well with shares mounted by NFS on HPUX, SLES and OpenSuse servers.
I have jst purchased a new NV+ and initialised the disks with 4.1.7. I immediately experienced problem with slow mounts on all servers linking to BOTH NAS Devices, with one running 4.1.6 and the other 4.1.7.

The situation is now critical with the GroupWise mail server running on SLES/OES unable to mount either of the NAS devices giving a "mount: RPC: Timed out" error. (remenber this has been working fine for many months) I have tried downgrading the 4.1.7 unit to 4.1.6, without any change.

In contrast mounting a share on the HPUX server by any of the other severs takes less than 2 secs, so why are the Netgear units so slow. generally taking about 40secs when it does work.

de Niro mentioned that his NAS to NAS mounts take 3 secs. How are you achieving this without being able to telnet/ssh to the NAS device?

Any help would be appreciated

[Skywalker]

Have you tried both tcp and udp mounts (-o tcp or -o udp)? How about with -o nolock?

[jceklosk]

I only tried TCP and did not try the -o nolock. I have 4.1.6 on the NAS now, it's a pain to keep flipping back and forth when 4.1.7 appears busted.

I have always used TCP even with 4.1.6.
Were there any changes in 4.1.7 could require the nolock options?

I misplaced my password for my original account, this is still jfceklosky, the one who first reported the NFS bug.

[Skywalker]

No, no changes that should require -o nolock, but that would point to something related to rpc.statd on one end.

[jceklosk]

Here is what nfsstat shows about my current NFS mounts with 4.1.6:

Flags: rw,nosuid,nodev,relatime,vers=3,rsize=32768,wsize=32768,namlen=255,hard,proto=tcp,timeo=600,retrans=2,sec=sys,mountaddr=192.168.1.6,mountvers=3,mountport=1024,mountproto=udp,addr=192.168.1.XX


What seems odd is that the proto was tcp, but the mount defaults to udp.

I guess I have to update to 4.1.7 and tinker around yet again..

[markbeh]

I've tried all the suggestions give, udp, nolock etc. I have also tried with the parameter from another server (Ubuntu) that does manage eventually to mount after about 60 secs, in respect of rsize, wsize timeo etc... to no avail. I cannot get the SUSE SLE server to mount at all. I've checked the DNS settings, firewall (off) routing tables etc. I still get RPC timed out.

The strange thing is that mounting an exported file system from any other server is almost instantaneous, only the NAS is slow to mount, and both 4.1.6 and 4,1.7 versions.

Any help would be appreciated. Is there any way to SSH to the NV+ and check/change NFS settings

[markbeh]

I found and installed the EnableRootSSH_1.0.bin file and have checked as many nfs configs as I know, to no avail. NFS mounts are still exeptionally slow and on some operating systems (Suse Enterprise Server) impossible.

I really need to resolve this issue as soon as possible, and would applreciate any help

[yoh-dah]

markbeh wrote:
I found and installed the EnableRootSSH_1.0.bin file and have checked as many nfs configs as I know, to no avail. NFS mounts are still exeptionally slow and on some operating systems (Suse Enterprise Server) impossible. I really need to resolve this issue as soon as possible, and would applreciate any help

Can you send us the logs (see my sig) right after you try your mount?

[IanSav]

Hi,

This is may be unrelated, but just in case it is... I am having connection delay issues using Windows (SMB) type connections. Given that 4.1.7 is in production I made my full post here 4.1.7 Slow To Respond To SMB Network Discovery....

Basically when I try to browse for shares on my DUO running 4.1.7 the DUO can take up to a minute to produce the share list. This was not the case or an issue with 4.1.6. It is not a problem on my NVX running 4.2.15. While my PCs pause and wait for the share list to eventually be generated my PVR/Media Players time out and declare that the device does not exist (and has no shares available).

This is a problem for which I need to find an urgent solution, please.

Regards,
Ian.

[jceklosk]

I think the smb is showing the same signs as NFS mounts.

I can repeat the hang with 4.1.7 over and over again, but dropped back to 4.1.6 because I was getting tired of waiting for mounts (I use the autofs for mountings.)

Can someone from Infrant please look deeper into these issues.

[Jedi_Knight]

I checked this out with my ReadyNAS NV+ (4.1.7). Fedora 13, Fedora 14, Ubuntu 10 and openSUSE 11.3 .
I wasn't able to reproduce the NFS mount issue. it mounted and unmounted less than one seconds.

those of you who's have problem, could you post how many NFS threads running on ReadyNAS? I have 5 Threads running..

[CharlesLaCour]

I tried mounting my NV running 4.1.7 from Ubuntu, RedHat Enterprise 5 and 6, Fedora 13 with no issue.

The most common issue when NFS mounts work but are slow is with RPC/portmap.

What do you get if you run the command:

rpcinfo -p nasname

Where you substitute "nasname" with the name you are using in your mount command for the NAS.

Also another thing that can be illusive is reverse name resolution. If the export file uses an entry like * or a name in the definition of what hosts are allowed can make the NFS server do a look up on the incoming IP address and if IP to name resolution is not working it can delay things. What are you using for name resolution?

[mh5bl]

I have also been having trouble with slow connection and timing out
here is some time attempts at connecting.
as you see they all connect in the end. I am running suse 11.4

desktop:~ # mount -a
mount.nfs: mount to NFS server 'readynas-nv00:/c/YellxPV' failed: timed out, giving up
mount.nfs: mount to NFS server 'readynas-nv00:/c/home' failed: timed out, giving up
mount.nfs: mount to NFS server 'readynas-nv00:/c/media' failed: timed out, giving up

desktop:~ # time mount -a -t nfs
mount.nfs: mount to NFS server 'readynas-nv00:/c/home' failed: timed out, giving up

real    1m37.386s
user    0m0.020s
sys     0m0.024s
desktop:~ # time mount -a -t nfs

real    0m24.348s
user    0m0.008s
sys     0m0.008s
desktop:~ # 

I have 8 NFS connection in total

tomorrow I will try and run some more tests. and will update you

[IanSav]

Hi,

If you are able, can you guys having this issue try out the new 4.1.8-T5 beta. It appears to have fixed the SMB issues I mentioned above.

Regards,
Ian.