https://bugzilla.novell.com/show_bug.cgi?id=214348 ------- Comment #5 from ian.grant@cl.cam.ac.uk 2006-10-25 04:47 MST ------- Here's another nscd trace, this time with -f to follow forks and with relative timestamps. [pid 30907] 0.000037 epoll_wait(10, [ ... id command started ...] {{EPOLLRDNORM, {u32=9, u64=9}}}, 100, 29988) = 1 [pid 30907] 27.941328 accept(9, 0, NULL) = 56 [pid 30907] 0.000042 epoll_ctl(10, EPOLL_CTL_ADD, 56, {EPOLLRDNORM, {u32=56, u64=56}}) = 0 [pid 30907] 0.000043 epoll_wait(10, {{EPOLLRDNORM, {u32=56, u64=56}}}, 100, 29976) = 1 [pid 30907] 0.000152 epoll_ctl(10, EPOLL_CTL_DEL, 56, NULL) = 0 [pid 30907] 0.000039 epoll_wait(10, {{EPOLLRDNORM, {u32=9, u64=9}}}, 100, 29988) = 1 [pid 30907] 4.998639 accept(9, 0, NULL) = 57 [pid 30907] 0.000112 epoll_ctl(10, EPOLL_CTL_ADD, 57, {EPOLLRDNORM, {u32=57, u64=57}}) = 0 [pid 30907] 0.000076 epoll_wait(10, {{EPOLLRDNORM, {u32=57, u64=57}}}, 100, 29976) = 1 [pid 30907] 0.000046 epoll_ctl(10, EPOLL_CTL_DEL, 57, NULL) = 0 [pid 30907] 0.000037 epoll_wait(10, {{EPOLLRDNORM, {u32=9, u64=9}}}, 100, 29988) = 1 [pid 30907] 5.008894 accept(9, 0, NULL) = 58 [pid 30907] 0.000115 epoll_ctl(10, EPOLL_CTL_ADD, 58, {EPOLLRDNORM, {u32=58, u64=58}}) = 0 [pid 30907] 0.000076 epoll_wait(10, {{EPOLLRDNORM, {u32=58, u64=58}}}, 100, 29976) = 1 [pid 30907] 0.000046 epoll_ctl(10, EPOLL_CTL_DEL, 58, NULL) = 0 [pid 30907] 0.000040 epoll_wait(10, {{EPOLLRDNORM, {u32=9, u64=9}}}, 100, 29988) = 1 [pid 30907] 4.998630 accept(9, 0, NULL) = 59 [pid 30907] 0.000110 epoll_ctl(10, EPOLL_CTL_ADD, 59, {EPOLLRDNORM, {u32=59, u64=59}}) = 0 [pid 30907] 0.000078 epoll_wait(10, {{EPOLLRDNORM, {u32=59, u64=59}}}, 100, 29976) = 1 [pid 30907] 0.000045 epoll_ctl(10, EPOLL_CTL_DEL, 59, NULL) = 0 [pid 30907] 0.000038 epoll_wait(10, {{EPOLLRDNORM, {u32=9, u64=9}}}, 100, 29988) = 1 [pid 30907] 5.012453 accept(9, 0, NULL) = 60 [pid 30907] 0.000043 epoll_ctl(10, EPOLL_CTL_ADD, 60, {EPOLLRDNORM, {u32=60, u64=60}}) = 0 [pid 30907] 0.000041 epoll_wait(10, {{EPOLLRDNORM, {u32=60, u64=60}}}, 100, 29976) = 1 [pid 30907] 0.000101 epoll_ctl(10, EPOLL_CTL_DEL, 60, NULL) = 0 [pid 30907] 0.000036 epoll_wait(10, {{EPOLLRDNORM, {u32=9, u64=9}}}, 100, 29988) = 1 [pid 30907] 4.999439 accept(9, 0, NULL) = 61 [pid 30907] 0.000041 epoll_ctl(10, EPOLL_CTL_ADD, 61, {EPOLLRDNORM, {u32=61, u64=61}}) = 0 [pid 30907] 0.000040 epoll_wait(10, {{EPOLLRDNORM, {u32=61, u64=61}}}, 100, 29976) = 1 [pid 30907] 0.000160 epoll_ctl(10, EPOLL_CTL_DEL, 61, NULL) = 0 [pid 30907] 0.000040 epoll_wait(10, {}, 100, 29988) = 0 [pid 30907] 29.987293 epoll_wait(10, During the above, the id command strace output shows: [ ... snipped ... ] open("/lib64/libresolv.so.2", O_RDONLY) = 3 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0`9\0\0\0"..., 832) = 832 fstat(3, {st_mode=S_IFREG|0755, st_size=85405, ...}) = 0 mmap(NULL, 1129320, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x2b2ce754e000 madvise(0x2b2ce754e000, 1129320, MADV_SEQUENTIAL|0x1) = 0 mprotect(0x2b2ce755f000, 1044480, PROT_NONE) = 0 mmap(0x2b2ce765e000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x10000) = 0x2b2ce765e000 mmap(0x2b2ce7660000, 7016, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x2b2ce7660000 close(3) = 0 open("/usr/lib64/libsasl2.so.2", O_RDONLY) = 3 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0`G\0\0\0"..., 832) = 832 fstat(3, {st_mode=S_IFREG|0755, st_size=99504, ...}) = 0 mmap(NULL, 1145760, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x2b2ce7662000 madvise(0x2b2ce7662000, 1145760, MADV_SEQUENTIAL|0x1) = 0 mprotect(0x2b2ce7679000, 1048576, PROT_NONE) = 0 mmap(0x2b2ce7779000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x17000) = 0x2b2ce7779000 close(3) = 0 open("/usr/lib64/libssl.so.0.9.8", O_RDONLY) = 3 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220\33\1"..., 832) = 832 fstat(3, {st_mode=S_IFREG|0555, st_size=289696, ...}) = 0 mmap(NULL, 1335008, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x2b2ce777a000 madvise(0x2b2ce777a000, 1335008, MADV_SEQUENTIAL|0x1) = 0 mprotect(0x2b2ce77ba000, 1048576, PROT_NONE) = 0 mmap(0x2b2ce78ba000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x40000) = 0x2b2ce78ba000 close(3) = 0 open("/usr/lib64/libcrypto.so.0.9.8", O_RDONLY) = 3 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0p\350\5\0"..., 832) = 832 fstat(3, {st_mode=S_IFREG|0555, st_size=1455080, ...}) = 0 mmap(NULL, 2501752, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x2b2ce78c0000 madvise(0x2b2ce78c0000, 2501752, MADV_SEQUENTIAL|0x1) = 0 mprotect(0x2b2ce79fe000, 1044480, PROT_NONE) = 0 mmap(0x2b2ce7afd000, 143360, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x13d000) = 0x2b2ce7afd000 mmap(0x2b2ce7b20000, 11384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x2b2ce7b20000 close(3) = 0 open("/usr/lib64/libkrb5.so.3", O_RDONLY) = 3 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0000k\1\0"..., 832) = 832 fstat(3, {st_mode=S_IFREG|0755, st_size=494128, ...}) = 0 mmap(NULL, 1535216, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x2b2ce7b23000 madvise(0x2b2ce7b23000, 1535216, MADV_SEQUENTIAL|0x1) = 0 mprotect(0x2b2ce7b97000, 1044480, PROT_NONE) = 0 mmap(0x2b2ce7c96000, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x73000) = 0x2b2ce7c96000 close(3) = 0 open("/usr/lib64/libk5crypto.so.3", O_RDONLY) = 3 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\240T\0\0"..., 832) = 832 fstat(3, {st_mode=S_IFREG|0755, st_size=145144, ...}) = 0 mmap(NULL, 1190376, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x2b2ce7c9a000 madvise(0x2b2ce7c9a000, 1190376, MADV_SEQUENTIAL|0x1) = 0 mprotect(0x2b2ce7cbc000, 1044480, PROT_NONE) = 0 mmap(0x2b2ce7dbb000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x21000) = 0x2b2ce7dbb000 close(3) = 0 open("/lib64/libcom_err.so.2", O_RDONLY) = 3 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\220\r\0"..., 832) = 832 fstat(3, {st_mode=S_IFREG|0755, st_size=8520, ...}) = 0 mmap(NULL, 1055024, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x2b2ce7dbd000 madvise(0x2b2ce7dbd000, 1055024, MADV_SEQUENTIAL|0x1) = 0 mprotect(0x2b2ce7dbf000, 1044480, PROT_NONE) = 0 mmap(0x2b2ce7ebe000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1000) = 0x2b2ce7ebe000 close(3) = 0 open("/usr/lib64/libkrb5support.so.0", O_RDONLY) = 3 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340\16\0"..., 832) = 832 fstat(3, {st_mode=S_IFREG|0755, st_size=12128, ...}) = 0 mmap(NULL, 1058664, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x2b2ce7ebf000 madvise(0x2b2ce7ebf000, 1058664, MADV_SEQUENTIAL|0x1) = 0 mprotect(0x2b2ce7ec2000, 1044480, PROT_NONE) = 0 mmap(0x2b2ce7fc1000, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x2b2ce7fc1000 close(3) = 0 munmap(0x2b2ce6d8a000, 136009) = 0 rt_sigaction(SIGPIPE, {SIG_IGN}, {SIG_DFL}, 8) = 0 getpid() = 32232 geteuid() = 1696 open("/etc/ldap.conf", O_RDONLY) = 3 fstat(3, {st_mode=S_IFREG|0644, st_size=385, ...}) = 0 fstat(3, {st_mode=S_IFREG|0644, st_size=385, ...}) = 0 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b2ce6d8a000 read(3, "host 128.232.0.41 128.232.0.42 1"..., 4096) = 385 read(3, "", 4096) = 0 close(3) = 0 munmap(0x2b2ce6d8a000, 4096) = 0 uname({sys="Linux", node="vignemale", ...}) = 0 open("/etc/resolv.conf", O_RDONLY) = 3 fstat(3, {st_mode=S_IFREG|0644, st_size=1318, ...}) = 0 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b2ce6d8a000 read(3, "### BEGIN INFO\n#\n# Modified_by: "..., 4096) = 1318 read(3, "", 4096) = 0 close(3) = 0 munmap(0x2b2ce6d8a000, 4096) = 0 socket(PF_FILE, SOCK_STREAM, 0) = 3 fcntl(3, F_GETFL) = 0x2 (flags O_RDWR) fcntl(3, F_SETFL, O_RDWR|O_NONBLOCK) = 0 connect(3, {sa_family=AF_FILE, path="/var/run/nscd/socket"}, 110) = 0 poll([{fd=3, events=POLLOUT|POLLERR|POLLHUP, revents=POLLOUT}], 1, 5000) = 1 sendto(3, "\2\0\0\0\r\0\0\0\6\0\0\0hosts\0\0\0", 20, MSG_NOSIGNAL, NULL, 0) = 20 poll( [ ... wait 5 seconds ... ] [{fd=3, events=POLLIN|POLLERR|POLLHUP}], 1, 5000) = 0 close(3) = 0 socket(PF_FILE, SOCK_STREAM, 0) = 3 fcntl(3, F_GETFL) = 0x2 (flags O_RDWR) fcntl(3, F_SETFL, O_RDWR|O_NONBLOCK) = 0 connect(3, {sa_family=AF_FILE, path="/var/run/nscd/socket"}, 110) = 0 poll([{fd=3, events=POLLOUT|POLLERR|POLLHUP, revents=POLLOUT}], 1, 5000) = 1 writev(3, [{"\2\0\0\0\4\0\0\0\n\0\0\0", 12}, {"vignemale\0", 10}], 2) = 22 poll( [ ... wait 5 seconds ... ] [{fd=3, events=POLLIN|POLLERR|POLLHUP}], 1, 5000) = 0 close(3) = 0 open("/etc/host.conf", O_RDONLY) = 3 fstat(3, {st_mode=S_IFREG|0644, st_size=370, ...}) = 0 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b2ce6d8a000 read(3, "#\n# /etc/host.conf - resolver co"..., 4096) = 370 read(3, "", 4096) = 0 close(3) = 0 munmap(0x2b2ce6d8a000, 4096) = 0 open("/etc/hosts", O_RDONLY) = 3 fcntl(3, F_GETFD) = 0 fcntl(3, F_SETFD, FD_CLOEXEC) = 0 fstat(3, {st_mode=S_IFREG|0644, st_size=712, ...}) = 0 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b2ce6d8a000 read(3, "#\n# hosts This file desc"..., 4096) = 712 read(3, "", 4096) = 0 close(3) = 0 munmap(0x2b2ce6d8a000, 4096) = 0 open("/etc/openldap/ldap.conf", O_RDONLY) = 3 fstat(3, {st_mode=S_IFREG|0644, st_size=235, ...}) = 0 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b2ce6d8a000 read(3, "#\n# LDAP Defaults\n#\n\n# See ldap."..., 4096) = 235 read(3, "", 4096) = 0 close(3) = 0 munmap(0x2b2ce6d8a000, 4096) = 0 getuid() = 1696 geteuid() = 1696 open("/home/ig206/ldaprc", O_RDONLY) = -1 ENOENT (No such file or directory) open("/home/ig206/.ldaprc", O_RDONLY) = 3 fstat(3, {st_mode=S_IFREG|0644, st_size=0, ...}) = 0 mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x2b2ce6d8a000 read(3, "", 8192) = 0 close(3) = 0 munmap(0x2b2ce6d8a000, 8192) = 0 open("ldaprc", O_RDONLY) = -1 ENOENT (No such file or directory) stat("/etc/ldap.conf", {st_mode=S_IFREG|0644, st_size=385, ...}) = 0 geteuid() = 1696 socket(PF_INET, SOCK_STREAM, IPPROTO_IP) = 3 setsockopt(3, SOL_TCP, TCP_NODELAY, [1], 4) = 0 fcntl(3, F_GETFL) = 0x2 (flags O_RDWR) fcntl(3, F_SETFL, O_RDWR|O_NONBLOCK) = 0 connect(3, {sa_family=AF_INET, sin_port=htons(389), sin_addr=inet_addr("128.232.0.41")}, 16) = -1 EINPROGRESS (Operation now in progress) poll([{fd=3, events=POLLOUT|POLLERR|POLLHUP, revents=POLLOUT}], 1, 5000) = 1 getpeername(3, {sa_family=AF_INET, sin_port=htons(389), sin_addr=inet_addr("128.232.0.41")}, [4294967312]) = 0 fcntl(3, F_GETFL) = 0x802 (flags O_RDWR|O_NONBLOCK) fcntl(3, F_SETFL, O_RDWR) = 0 write(3, "0\f\2\1\1`\7\2\1\3\4\0\200\0", 14) = 14 poll([{fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP, revents=POLLIN}], 1, 5000) = 1 read(3, "0\f\2\1\1a\7\n", 8) = 8 read(3, "\1\0\4\0\4\0", 6) = 6 setsockopt(3, SOL_SOCKET, SO_KEEPALIVE, [0], 4) = 0 fcntl(3, F_SETFD, FD_CLOEXEC) = 0 getsockname(3, {sa_family=AF_INET, sin_port=htons(13521), sin_addr=inet_addr("128.232.11.51")}, [16]) = 0 getpeername(3, {sa_family=AF_INET, sin_port=htons(389), sin_addr=inet_addr("128.232.0.41")}, [68719476752]) = 0 write(3, "0\201\326\2\1\2c\201\320\4"ou=People,dc=cl,dc=ca"..., 217) = 217 poll([{fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP, revents=POLLIN}], 1, 5000) = 1 read(3, "0\202\1\2\2\1\2d", 8) = 8 read(3, "\201\374\4,uid=ig206,ou=People,dc=cl,dc"..., 254) = 254 poll([{fd=3, events=POLLIN|POLLPRI|POLLERR|POLLHUP, revents=POLLIN}], 1, 5000) = 1 read(3, "0\f\2\1\2e\7\n", 8) = 8 read(3, "\1\0\4\0\4\0", 6) = 6 rt_sigaction(SIGPIPE, {SIG_DFL}, NULL, 8) = 0 socket(PF_FILE, SOCK_STREAM, 0) = 4 fcntl(4, F_GETFL) = 0x2 (flags O_RDWR) fcntl(4, F_SETFL, O_RDWR|O_NONBLOCK) = 0 connect(4, {sa_family=AF_FILE, path="/var/run/nscd/socket"}, 110) = 0 poll([{fd=4, events=POLLOUT|POLLERR|POLLHUP, revents=POLLOUT}], 1, 5000) = 1 sendto(4, "\2\0\0\0\f\0\0\0\6\0\0\0group\0\353\346", 20, MSG_NOSIGNAL, NULL, 0) = 20 poll( [ ... wait 5 seconds ... ] [{fd=4, events=POLLIN|POLLERR|POLLHUP}], 1, 5000) = 0 close(4) = 0 socket(PF_FILE, SOCK_STREAM, 0) = 4 fcntl(4, F_GETFL) = 0x2 (flags O_RDWR) fcntl(4, F_SETFL, O_RDWR|O_NONBLOCK) = 0 connect(4, {sa_family=AF_FILE, path="/var/run/nscd/socket"}, 110) = 0 poll([{fd=4, events=POLLOUT|POLLERR|POLLHUP, revents=POLLOUT}], 1, 5000) = 1 writev(4, [{"\2\0\0\0\3\0\0\0\5\0\0\0", 12}, {"1696\0", 5}], 2) = 17 poll( [ ... wait 5 seconds ... ] [{fd=4, events=POLLIN|POLLERR|POLLHUP}], 1, 5000) = 0 -- Configure bugmail: https://bugzilla.novell.com/userprefs.cgi?tab=email ------- You are receiving this mail because: ------- You are on the CC list for the bug, or are watching someone who is.