recently I'm experiencing a very weird, very annoying problem when I move or copy files from my Samba share to any other location. There's a delay of half a minute before the actual file operation starts. I have no idea what might have triggered this problem, I'm using this installation for years, and I have another system with exactly the same Debian & kernel version which doesn't experience the problem. Here's a trace from moving a little file from the share to the home directory:
Code: Select all
18:12:54 execve("/bin/mv", ["mv", "h.png", "/home/user"], [/* 41 vars */]) = 0
18:12:54 brk(0) = 0x1847000
18:12:54 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
18:12:54 mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0d540c1000
18:12:54 access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
18:12:54 open("/opt/thinlinc/lib64/tls/x86_64/libselinux.so.1", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 stat("/opt/thinlinc/lib64/tls/x86_64", 0x7ffc339d09f0) = -1 ENOENT (No such file or directory)
18:12:54 open("/opt/thinlinc/lib64/tls/libselinux.so.1", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 stat("/opt/thinlinc/lib64/tls", 0x7ffc339d09f0) = -1 ENOENT (No such file or directory)
18:12:54 open("/opt/thinlinc/lib64/x86_64/libselinux.so.1", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 stat("/opt/thinlinc/lib64/x86_64", 0x7ffc339d09f0) = -1 ENOENT (No such file or directory)
18:12:54 open("/opt/thinlinc/lib64/libselinux.so.1", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 stat("/opt/thinlinc/lib64", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
18:12:54 open("/opt/thinlinc/lib/tls/x86_64/libselinux.so.1", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 stat("/opt/thinlinc/lib/tls/x86_64", 0x7ffc339d09f0) = -1 ENOENT (No such file or directory)
18:12:54 open("/opt/thinlinc/lib/tls/libselinux.so.1", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 stat("/opt/thinlinc/lib/tls", 0x7ffc339d09f0) = -1 ENOENT (No such file or directory)
18:12:54 open("/opt/thinlinc/lib/x86_64/libselinux.so.1", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 stat("/opt/thinlinc/lib/x86_64", 0x7ffc339d09f0) = -1 ENOENT (No such file or directory)
18:12:54 open("/opt/thinlinc/lib/libselinux.so.1", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 stat("/opt/thinlinc/lib", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
18:12:54 open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
18:12:54 fstat(3, {st_mode=S_IFREG|0644, st_size=95164, ...}) = 0
18:12:54 mmap(NULL, 95164, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f0d540a9000
18:12:54 close(3) = 0
18:12:54 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
18:12:54 open("/lib/x86_64-linux-gnu/libselinux.so.1", O_RDONLY|O_CLOEXEC) = 3
18:12:54 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\20c\0\0\0\0\0\0"..., 832) = 832
18:12:54 fstat(3, {st_mode=S_IFREG|0644, st_size=142728, ...}) = 0
18:12:54 mmap(NULL, 2246896, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0d53c7e000
18:12:54 mprotect(0x7f0d53c9f000, 2097152, PROT_NONE) = 0
18:12:54 mmap(0x7f0d53e9f000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x21000) = 0x7f0d53e9f000
18:12:54 mmap(0x7f0d53ea1000, 6384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f0d53ea1000
18:12:54 close(3) = 0
18:12:54 open("/opt/thinlinc/lib64/libacl.so.1", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 open("/opt/thinlinc/lib/libacl.so.1", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
18:12:54 open("/lib/x86_64-linux-gnu/libacl.so.1", O_RDONLY|O_CLOEXEC) = 3
18:12:54 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200\37\0\0\0\0\0\0"..., 832) = 832
18:12:54 fstat(3, {st_mode=S_IFREG|0644, st_size=35288, ...}) = 0
18:12:54 mmap(NULL, 2130592, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0d53a75000
18:12:54 mprotect(0x7f0d53a7d000, 2093056, PROT_NONE) = 0
18:12:54 mmap(0x7f0d53c7c000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x7000) = 0x7f0d53c7c000
18:12:54 close(3) = 0
18:12:54 open("/opt/thinlinc/lib64/libattr.so.1", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 open("/opt/thinlinc/lib/libattr.so.1", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
18:12:54 open("/lib/x86_64-linux-gnu/libattr.so.1", O_RDONLY|O_CLOEXEC) = 3
18:12:54 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\23\0\0\0\0\0\0"..., 832) = 832
18:12:54 fstat(3, {st_mode=S_IFREG|0644, st_size=18640, ...}) = 0
18:12:54 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0d540a8000
18:12:54 mmap(NULL, 2113912, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0d53870000
18:12:54 mprotect(0x7f0d53874000, 2093056, PROT_NONE) = 0
18:12:54 mmap(0x7f0d53a73000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3000) = 0x7f0d53a73000
18:12:54 close(3) = 0
18:12:54 open("/opt/thinlinc/lib64/libc.so.6", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 open("/opt/thinlinc/lib/libc.so.6", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
18:12:54 open("/lib/x86_64-linux-gnu/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
18:12:54 read(3, "\177ELF\2\1\1\3\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0P\34\2\0\0\0\0\0"..., 832) = 832
18:12:54 fstat(3, {st_mode=S_IFREG|0755, st_size=1738176, ...}) = 0
18:12:54 mmap(NULL, 3844640, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0d534c5000
18:12:54 mprotect(0x7f0d53666000, 2097152, PROT_NONE) = 0
18:12:54 mmap(0x7f0d53866000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1a1000) = 0x7f0d53866000
18:12:54 mmap(0x7f0d5386c000, 14880, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f0d5386c000
18:12:54 close(3) = 0
18:12:54 open("/opt/thinlinc/lib64/libpcre.so.3", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 open("/opt/thinlinc/lib/libpcre.so.3", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
18:12:54 open("/lib/x86_64-linux-gnu/libpcre.so.3", O_RDONLY|O_CLOEXEC) = 3
18:12:54 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\20\27\0\0\0\0\0\0"..., 832) = 832
18:12:54 fstat(3, {st_mode=S_IFREG|0644, st_size=448440, ...}) = 0
18:12:54 mmap(NULL, 2543976, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0d53257000
18:12:54 mprotect(0x7f0d532c3000, 2097152, PROT_NONE) = 0
18:12:54 mmap(0x7f0d534c3000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x6c000) = 0x7f0d534c3000
18:12:54 close(3) = 0
18:12:54 open("/opt/thinlinc/lib64/libdl.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 open("/opt/thinlinc/lib/libdl.so.2", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
18:12:54 open("/lib/x86_64-linux-gnu/libdl.so.2", O_RDONLY|O_CLOEXEC) = 3
18:12:54 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320\16\0\0\0\0\0\0"..., 832) = 832
18:12:54 fstat(3, {st_mode=S_IFREG|0644, st_size=14664, ...}) = 0
18:12:54 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0d540a7000
18:12:54 mmap(NULL, 2109712, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0d53053000
18:12:54 mprotect(0x7f0d53056000, 2093056, PROT_NONE) = 0
18:12:54 mmap(0x7f0d53255000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x7f0d53255000
18:12:54 close(3) = 0
18:12:54 open("/opt/thinlinc/lib64/libpthread.so.0", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 open("/opt/thinlinc/lib/libpthread.so.0", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
18:12:54 access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
18:12:54 open("/lib/x86_64-linux-gnu/libpthread.so.0", O_RDONLY|O_CLOEXEC) = 3
18:12:54 read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\320n\0\0\0\0\0\0"..., 832) = 832
18:12:54 fstat(3, {st_mode=S_IFREG|0755, st_size=137384, ...}) = 0
18:12:54 mmap(NULL, 2213008, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f0d52e36000
18:12:54 mprotect(0x7f0d52e4e000, 2093056, PROT_NONE) = 0
18:12:54 mmap(0x7f0d5304d000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x17000) = 0x7f0d5304d000
18:12:54 mmap(0x7f0d5304f000, 13456, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7f0d5304f000
18:12:54 close(3) = 0
18:12:54 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0d540a6000
18:12:54 mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0d540a4000
18:12:54 arch_prctl(ARCH_SET_FS, 0x7f0d540a4800) = 0
18:12:54 mprotect(0x7f0d53866000, 16384, PROT_READ) = 0
18:12:54 mprotect(0x7f0d5304d000, 4096, PROT_READ) = 0
18:12:54 mprotect(0x7f0d53255000, 4096, PROT_READ) = 0
18:12:54 mprotect(0x7f0d534c3000, 4096, PROT_READ) = 0
18:12:54 mprotect(0x7f0d53a73000, 4096, PROT_READ) = 0
18:12:54 mprotect(0x7f0d53c7c000, 4096, PROT_READ) = 0
18:12:54 mprotect(0x7f0d53e9f000, 4096, PROT_READ) = 0
18:12:54 mprotect(0x61e000, 4096, PROT_READ) = 0
18:12:54 mprotect(0x7f0d540c3000, 4096, PROT_READ) = 0
18:12:54 munmap(0x7f0d540a9000, 95164) = 0
18:12:54 set_tid_address(0x7f0d540a4ad0) = 24947
18:12:54 set_robust_list(0x7f0d540a4ae0, 24) = 0
18:12:54 rt_sigaction(SIGRTMIN, {0x7f0d52e3c9b0, [], SA_RESTORER|SA_SIGINFO, 0x7f0d52e45890}, NULL, 8) = 0
18:12:54 rt_sigaction(SIGRT_1, {0x7f0d52e3ca40, [], SA_RESTORER|SA_RESTART|SA_SIGINFO, 0x7f0d52e45890}, NULL, 8) = 0
18:12:54 rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
18:12:54 getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0
18:12:54 statfs("/sys/fs/selinux", 0x7ffc339d21d0) = -1 ENOENT (No such file or directory)
18:12:54 statfs("/selinux", 0x7ffc339d21d0) = -1 ENOENT (No such file or directory)
18:12:54 brk(0) = 0x1847000
18:12:54 brk(0x1868000) = 0x1868000
18:12:54 open("/proc/filesystems", O_RDONLY) = 3
18:12:54 fstat(3, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
18:12:54 mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0d540c0000
18:12:54 read(3, "nodev\tsysfs\nnodev\trootfs\nnodev\tr"..., 1024) = 406
18:12:54 read(3, "", 1024) = 0
18:12:54 close(3) = 0
18:12:54 munmap(0x7f0d540c0000, 4096) = 0
18:12:54 open("/usr/lib/locale/locale-archive", O_RDONLY|O_CLOEXEC) = 3
18:12:54 fstat(3, {st_mode=S_IFREG|0644, st_size=1607712, ...}) = 0
18:12:54 mmap(NULL, 1607712, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f0d53f1b000
18:12:54 close(3) = 0
18:12:54 geteuid() = 1000
18:12:54 ioctl(0, SNDCTL_TMR_TIMEBASE or SNDRV_TIMER_IOCTL_NEXT_DEVICE or TCGETS, {B38400 opost isig icanon echo ...}) = 0
18:12:54 stat("/home/user", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
18:12:54 lstat("h.png", {st_mode=S_IFREG|0777, st_size=70121, ...}) = 0
18:12:54 lstat("/home/user/h.png", 0x7ffc339d1e40) = -1 ENOENT (No such file or directory)
18:12:54 rename("h.png", "/home/user/h.png") = -1 EXDEV (Invalid cross-device link)
18:12:54 unlink("/home/user/h.png") = -1 ENOENT (No such file or directory)
18:12:54 open("h.png", O_RDONLY|O_NOFOLLOW) = 3
18:12:54 fstat(3, {st_mode=S_IFREG|0777, st_size=70121, ...}) = 0
18:12:54 open("/home/user/h.png", O_WRONLY|O_CREAT|O_EXCL, 0700) = 4
18:12:54 fstat(4, {st_mode=S_IFREG|0700, st_size=0, ...}) = 0
18:12:54 fadvise64(3, 0, 0, POSIX_FADV_SEQUENTIAL) = 0
18:12:54 mmap(NULL, 139264, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f0d53ef9000
18:12:54 read(3, "\211PNG\r\n\32\n\0\0\0\rIHDR\0\0\1\317\0\0\2K\10\2\0\0\0\16\323\212"..., 131072) = 70121
18:12:54 write(4, "\211PNG\r\n\32\n\0\0\0\rIHDR\0\0\1\317\0\0\2K\10\2\0\0\0\16\323\212"..., 70121) = 70121
18:12:54 read(3, "", 131072) = 0
18:12:54 utimensat(4, NULL, {{1567613554, 990384100}, {1567574768, 163586000}}, 0) = 0
18:12:54 fchown(4, 0, 0) = -1 EPERM (Operation not permitted)
18:12:54 fchown(4, 4294967295, 0) = -1 EPERM (Operation not permitted)
18:12:54 flistxattr(3, NULL, 0) = 0
18:13:24 flistxattr(3, "", 0) = 0
18:13:24 fgetxattr(3, "system.posix_acl_access", 0x7ffc339d1a90, 132) = -1 EOPNOTSUPP (Operation not supported)
18:13:24 fsetxattr(4, "system.posix_acl_access", "\x02\x00\x00\x00\x01\x00\x07\x00\xff\xff\xff\xff\x04\x00\x07\x00\xff\xff\xff\xff \x00\x07\x00\xff\xff\xff\xff", 28, 0) = 0
18:13:24 close(4) = 0
18:13:24 close(3) = 0
18:13:24 munmap(0x7f0d53ef9000, 139264) = 0
18:13:24 lstat("/", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
18:13:24 newfstatat(AT_FDCWD, "h.png", {st_mode=S_IFREG|0777, st_size=70121, ...}, AT_SYMLINK_NOFOLLOW) = 0
18:13:24 unlinkat(AT_FDCWD, "h.png", 0) = 0
18:13:24 lseek(0, 0, SEEK_CUR) = -1 ESPIPE (Illegal seek)
18:13:24 close(0) = 0
18:13:24 close(1) = 0
18:13:24 close(2) = 0
18:13:24 exit_group(0) = ?
18:13:24 +++ exited with 0 +++
Code: Select all
18:12:54 flistxattr(3, NULL, 0) = 0
18:13:24 flistxattr(3, "", 0) = 0
Samba share is on CentOS 7.3 and mounted with the following options:
Code: Select all
rw,relatime,vers=default,cache=strict,username=user,uid=0,noforceuid,gid=0,noforcegid,addr=x.x.x.x,file_mode=0777,dir_mode=0777,soft,nounix,mapposix,noperm,rsize=1048576,wsize=1048576,echo_interval=60,actimeo=1
Thanks,
SH