[linux-cifs-client] files disappearing randomly - kernel 2.6.27 cifs.ko 1.54 - VFS: No response for cmd 50

Wed Apr 22 07:07:29 GMT 2009

Thanks Jeff,

> It's ultimately a server problem I think

I am not sure but there a doubts cause I was never able
to reproduce that problem on my 2.6.26-1-686 lenny/sid 
Debian GNU/Linux dektop with cifs.ko version 1.53 . 
Only our gentoo servers seem to suffer this problem.
But it's like compare apples and oranges since my desktop 
has got no real load while our gentoo servers get/make a lot of smb traffic.
But on the other hand it's possible that the debian kernel contains a patch that the gentoo kernel doesn't.

> but it would be good to
> ascertain whether the kernel is returning an error here. Can you run
> one of these failing "ls" calls inside of strace and tell whether the
> readdir() or getdents() calls are returning an error?

vam-uti1:/data/dpa2msu/red-fs2/bild/agentur/DPA # strace -fo /tmp/ls.log ls | wc -l
148

10700 execve("/bin/ls", ["ls"], [/* 42 vars */]) = 0
10700 brk(0)                            = 0x9ed2000
10700 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
10700 open("/etc/ld.so.cache", O_RDONLY) = 3
10700 fstat64(3, {st_mode=S_IFREG|0644, st_size=53456, ...}) = 0
10700 mmap2(NULL, 53456, PROT_READ, MAP_PRIVATE, 3, 0) = 0xb808e000
10700 close(3)                          = 0
10700 open("/lib/librt.so.1", O_RDONLY) = 3
10700 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\240\31\0\0004\0\0\0\200"..., 512) = 512
10700 fstat64(3, {st_mode=S_IFREG|0755, st_size=30552, ...}) = 0
10700 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb808d000
10700 mmap2(NULL, 33388, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb8084000
10700 mmap2(0xb808b000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x6) = 0xb808b000
10700 close(3)                          = 0
10700 open("/lib/libacl.so.1", O_RDONLY) = 3
10700 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\260\31\0\0004\0\0\0\20"..., 512) = 512
10700 fstat64(3, {st_mode=S_IFREG|0755, st_size=26144, ...}) = 0
10700 mmap2(NULL, 29024, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb807c000
10700 mmap2(0xb8082000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x5) = 0xb8082000
10700 close(3)                          = 0
10700 open("/lib/libc.so.6", O_RDONLY)  = 3
10700 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0pg\1\0004\0\0\0\244"..., 512) = 512
10700 fstat64(3, {st_mode=S_IFREG|0755, st_size=1274140, ...}) = 0
10700 mmap2(NULL, 1283664, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7f42000
10700 mmap2(0xb8076000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x133) = 0xb8076000
10700 mmap2(0xb8079000, 9808, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb8079000
10700 close(3)                          = 0
10700 open("/lib/libpthread.so.0", O_RDONLY) = 3
10700 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0000H\0\0004\0\0\0\230"..., 512) = 512
10700 fstat64(3, {st_mode=S_IFREG|0755, st_size=118196, ...}) = 0
10700 mmap2(NULL, 94688, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7f2a000
10700 mmap2(0xb7f3e000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x13) = 0xb7f3e000
10700 mmap2(0xb7f40000, 4576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb7f40000
10700 close(3)                          = 0
10700 open("/lib/libattr.so.1", O_RDONLY) = 3
10700 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\300\20\0\0004\0\0\0t"..., 512) = 512
10700 fstat64(3, {st_mode=S_IFREG|0755, st_size=17796, ...}) = 0
10700 mmap2(NULL, 20660, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0xb7f24000
10700 mmap2(0xb7f28000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3) = 0xb7f28000
10700 close(3)                          = 0
10700 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7f23000
10700 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7f22000
10700 set_thread_area({entry_number:-1 -> 6, base_addr:0xb7f226c0, limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1, seg_not_present:0, useable:1}) = 0
10700 mprotect(0xb7f28000, 4096, PROT_READ) = 0
10700 mprotect(0xb7f3e000, 4096, PROT_READ) = 0
10700 mprotect(0xb8076000, 8192, PROT_READ) = 0
10700 mprotect(0xb8082000, 4096, PROT_READ) = 0
10700 mprotect(0xb808b000, 4096, PROT_READ) = 0
10700 mprotect(0x805f000, 4096, PROT_READ) = 0
10700 mprotect(0xb80b8000, 4096, PROT_READ) = 0
10700 munmap(0xb808e000, 53456)         = 0
10700 set_tid_address(0xb7f22708)       = 10700
10700 set_robust_list(0xb7f22710, 0xc)  = 0
10700 futex(0xbfcb8f74, FUTEX_WAKE_PRIVATE, 1) = 0
10700 rt_sigaction(SIGRTMIN, {0xb7f2e2d0, [], SA_SIGINFO}, NULL, 8) = 0
10700 rt_sigaction(SIGRT_1, {0xb7f2e350, [], SA_RESTART|SA_SIGINFO}, NULL, 8) = 0
10700 rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
10700 getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024, rlim_max=RLIM_INFINITY}) = 0
10700 uname({sys="Linux", node="vam-uti1", ...}) = 0
10700 brk(0)                            = 0x9ed2000
10700 brk(0x9ef3000)                    = 0x9ef3000
10700 open("/usr/lib/locale/locale-archive", O_RDONLY|O_LARGEFILE) = 3
10700 fstat64(3, {st_mode=S_IFREG|0644, st_size=3215920, ...}) = 0
10700 mmap2(NULL, 2097152, PROT_READ, MAP_PRIVATE, 3, 0) = 0xb7d22000
10700 mmap2(NULL, 1196032, PROT_READ, MAP_PRIVATE, 3, 0x1ee) = 0xb7bfe000
10700 close(3)                          = 0
10700 ioctl(1, SNDCTL_TMR_TIMEBASE or TCGETS, 0xbfcb8de8) = -1 EINVAL (Invalid argument)
10700 ioctl(1, TIOCGWINSZ, 0xbfcb8f5c)  = -1 EINVAL (Invalid argument)
10700 open(".", O_RDONLY|O_NONBLOCK|O_LARGEFILE|O_DIRECTORY|0x80000) = 3
10700 fstat64(3, {st_mode=S_IFDIR|0777, st_size=0, ...}) = 0
10700 fcntl64(3, F_GETFD)               = 0x1 (flags FD_CLOEXEC)
10700 getdents64(3, /* 150 entries */, 16384) = 4784
10700 getdents64(3, /* 0 entries */, 16384) = 0
10700 close(3)                          = 0
10700 fstat64(1, {st_mode=S_IFIFO|0600, st_size=0, ...}) = 0
10700 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb809b000
10700 write(1, "49D5EA1C.TXT\n49D77ADB.TXT\n49D7D8A"..., 1924) = 1924
10700 close(1)                          = 0
10700 munmap(0xb809b000, 4096)          = 0
10700 close(2)                          = 0
10700 exit_group(0)                     = ?

And while doing the above, this gets logged:
Apr 22 09:03:09 vam-uti1 CIFS VFS: No response for cmd 50 mid 6076
Apr 22 09:03:09 vam-uti1 CIFS VFS: No response for cmd 50 mid 6080
Apr 22 09:03:09 vam-uti1 CIFS VFS: No response for cmd 50 mid 53756
Apr 22 09:03:09 vam-uti1 CIFS VFS: No response for cmd 50 mid 6090

Thanks
Jodok