All,
I think i saw a discussion of this issue once before but I don't
remember
the cause nor any resolution (and I can' t think of suitable keywords
to
search on).
Symptom:
Example 1:
$ sudo service autofs restart
$ cd /some/automount/path
sh: cd: /some/automount/path: No such file or directory
$ cd /some/automount/path
# <--- Repeated
$
# <--- Succeeds
Example 2:
$ sudo service autofs restart
$ ls /some/automount/path
# <--- 'ls' works!
....
Example 3
$ sudo service autofs restart
$ cat /some/automount/path/somefile.txt
sh: cat: /some/automount/path/somefile.txt: No such file or
directory
This is on a RHEL5.1 (x86_64) system using autofs-5.0.1-0.rc2.55 with
LDAP driven autofs maps. It's running under the 2.6.18-53.el5xen
kernel
(in Dom0). (I see the same behavior from a system running on the
2.6.18-53.el5 (non-Xen) kernel.
/etc/sysconfig/autofs only has the following 3 non-comment lines
(sanitized):
DEFAULT_TIMEOUT=600
DEFAULT_BROWSE_MODE="no"
OPTIONS="-DOURPLATFORM=2008 -DOSREL=`cat /etc/IT/osrel`"
(Where osrel is a simple string describing our internal release
versioning
for dynamically mapping to NFS subdirectories).
Note that initial user logins succeed (users are chdir()'d to their
automounted
home directories without any reported error messages).
Here is a sanitized log of everything related to autofs, ldap and nscd
(and the
one entry that relates to FS-Cache).
Jan 22 18:05:38 rhel51svr automount[2389]: lookup_read_master:
lookup(nisplus): couldn't locat nis+ table auto.master
Jan 22 18:09:02 rhel51svr kernel: FS-Cache: netfs 'nfs' registered for
caching
Jan 22 18:21:21 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.1.10
Jan 22 20:29:24 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.2.20
Jan 23 00:15:01 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.1.10
Jan 23 04:15:20 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.3.30 after 1 attempt
Jan 23 05:00:01 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.3.30 after 1 attempt
Jan 23 05:08:20 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.1.10
Jan 23 08:20:12 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.1.10
Jan 23 13:25:03 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.3.30 after 1 attempt
Jan 23 14:25:05 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.3.30 after 1 attempt
Jan 23 15:25:06 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.3.30 after 1 attempt
Jan 23 17:25:09 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.3.30 after 1 attempt
Jan 23 18:30:01 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.3.30 after 1 attempt
Jan 23 18:42:29 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.2.20
Jan 23 18:54:47 rhel51svr automount[5677]: do_mount_indirect: failed to
get group info from getgrgid_r
Jan 23 19:00:08 rhel51svr automount[5677]: umount_autofs_indirect: ask
umount returned busy /home
Jan 23 20:21:54 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.1.10
Jan 24 00:20:53 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.3.30 after 1 attempt
Jan 24 00:34:46 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.2.20
Jan 24 02:21:31 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.3.30
Jan 24 04:35:23 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.2.20
Jan 24 09:02:25 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.1.10
Jan 24 13:45:06 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.3.30 after 1 attempt
Jan 24 14:21:35 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.3.30 after 1 attempt
Jan 24 14:31:40 rhel51svr nscd: nss_ldap: reconnected to LDAP server
ldap://123.45.1.10
Jan 24 14:33:15 rhel51svr automount[18844]: umount_autofs_indirect: ask
umount returned busy /home
Jan 24 14:33:15 rhel51svr automount[18844]: umount_autofs_indirect: ask
umount returned busy /repo/it
Jan 24 14:33:16 rhel51svr kernel: automount[18858]: segfault at
00002aaaacf04d80 rip 00002aaaacf04d80 rsp 00000000410290e8 error 14
Jan 24 14:35:14 rhel51svr automount[32662]: umount_autofs_indirect: ask
umount returned busy /net
Jan 24 14:35:39 rhel51svr kernel: automount[4150]: segfault at
00002aaaacf04d80 rip 00002aaaacf04d80 rsp 00000000410290e8 error 14
Jan 24 14:38:49 rhel51svr kernel: automount[5540]: segfault at
00002aaaacf04d80 rip 00002aaaacf04d80 rsp 00000000422320e8 error 14
Jan 24 14:41:57 rhel51svr kernel: automount[17825]: segfault at
00002aaaacf04d80 rip 00002aaaacf04d80 rsp 00000000410290e8 error 14
The early automount lookup_read_master:lookup(nisplus) seems to to be
something in the
initrd or very early in the boot process (it's not part of our
configuration).
The segfaults are worrisome --- and it's notable that they all occurred
during the interval when I was
restarting autofs for reproducing the behavior here.
I will try these tests with a fresh reboot and nscd disable.
Meanwhile if any of this rings a bell, I'd like to hear about it (and
especially any pointers to related discussions
from the past.
JDennis
_______________________________________________
autofs mailing list
[email protected]
http://linux.kernel.org/mailman/listinfo/autofs
