Hello Theodore,
That is good to read.
Until recently, I never experienced this bug, and I run e2fsck around
once or twice a month, either because it it be upcoming after next
boot or because I enlarge LVM.
I retried the test case, and it fails again. I attached the strace, I
cannot make anything meaningful out of it (except, that it actually
fails in the open call:
open("/dev/system_vg/video_lv", O_RDONLY|O_EXCL) = -1 EBUSY (Device or resource busy)
There is no other system admin, I'm only logged in as normal user and
on one single VT as admin. I never had this problem before. And it is
repeatible across reboots. (And I did not change my kernel recently).
…
Thanks for the explanation, but would there be a way to figure out
this second process? I tried fuser and lsopen, both to no avail (but
I've might have missed the right set of options, of course).
I'm willing to experiement further, however, I've no clue what to try
(and at some time I will definitly want to enlarge my LVM volumes).
Thanks for your help,
Helge
Here is the strace output:
execve("/sbin/e2fsck", ["e2fsck", "/dev/system_vg/video_lv"], [/* 14 vars */]) = 0
brk(0) = 0x22ad290
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3422af81000
access("/etc/ld.so.preload", R_OK) = -1 ENOENT (No such file or directory)
open("/etc/ld.so.cache", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=194016, ...}) = 0
mmap(NULL, 194016, PROT_READ, MAP_PRIVATE, 3, 0) = 0x3422af51000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/x86_64-linux-gnu/libext2fs.so.2", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\200\336\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=285248, ...}) = 0
mmap(NULL, 2381192, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3422ab1f000
mprotect(0x3422ab63000, 2093056, PROT_NONE) = 0
mmap(0x3422ad62000, 12288, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x43000) = 0x3422ad62000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/x86_64-linux-gnu/libcom_err.so.2", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0p\25\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=14640, ...}) = 0
mmap(NULL, 2109960, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3422a91b000
mprotect(0x3422a91e000, 2093056, PROT_NONE) = 0
mmap(0x3422ab1d000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x3422ab1d000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/x86_64-linux-gnu/libblkid.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\340\210\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=258688, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3422af50000
mmap(NULL, 2358248, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3422a6db000
mprotect(0x3422a716000, 2097152, PROT_NONE) = 0
mmap(0x3422a916000, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3b000) = 0x3422a916000
mmap(0x3422a91a000, 3048, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3422a91a000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/x86_64-linux-gnu/libuuid.so.1", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0 \26\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=18904, ...}) = 0
mmap(NULL, 2113952, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3422a4d6000
mprotect(0x3422a4da000, 2093056, PROT_NONE) = 0
mmap(0x3422a6d9000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3000) = 0x3422a6d9000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/x86_64-linux-gnu/libe2p.so.2", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\20 \0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0644, st_size=32592, ...}) = 0
mmap(NULL, 2128528, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x3422a2ce000
mprotect(0x3422a2d5000, 2093056, PROT_NONE) = 0
mmap(0x3422a4d4000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x6000) = 0x3422a4d4000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/x86_64-linux-gnu/libc.so.6", O_RDONLY|O_CLOEXEC) = 3
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
fstat(3, {st_mode=S_IFREG|0755, st_size=1729984, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3422af4f000
mmap(NULL, 3836448, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x34229f25000
mprotect(0x3422a0c4000, 2097152, PROT_NONE) = 0
mmap(0x3422a2c4000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x19f000) = 0x3422a2c4000
mmap(0x3422a2ca000, 14880, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x3422a2ca000
close(3) = 0
access("/etc/ld.so.nohwcap", F_OK) = -1 ENOENT (No such file or directory)
open("/lib/x86_64-linux-gnu/libpthread.so.0", O_RDONLY|O_CLOEXEC) = 3
read(3, "\177ELF\2\1\1\0\0\0\0\0\0\0\0\0\3\0>\0\1\0\0\0\20o\0\0\0\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=137440, ...}) = 0
mmap(NULL, 2213008, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x34229d08000
mprotect(0x34229d20000, 2093056, PROT_NONE) = 0
mmap(0x34229f1f000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x17000) = 0x34229f1f000
mmap(0x34229f21000, 13456, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x34229f21000
close(3) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3422af4e000
mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3422af4c000
arch_prctl(ARCH_SET_FS, 0x3422af4c780) = 0
mprotect(0x3422a2c4000, 16384, PROT_READ) = 0
mprotect(0x34229f1f000, 4096, PROT_READ) = 0
mprotect(0x3422a4d4000, 4096, PROT_READ) = 0
mprotect(0x3422a6d9000, 4096, PROT_READ) = 0
mprotect(0x3422a916000, 12288, PROT_READ) = 0
mprotect(0x3422ab1d000, 4096, PROT_READ) = 0
mprotect(0x3422ad62000, 4096, PROT_READ) = 0
mprotect(0x639000, 4096, PROT_READ) = 0
mprotect(0x3422af85000, 4096, PROT_READ) = 0
munmap(0x3422af51000, 194016) = 0
set_tid_address(0x3422af4ca50) = 3961
set_robust_list(0x3422af4ca60, 24) = 0
rt_sigaction(SIGRTMIN, {0x34229d0e9f0, [], SA_RESTORER|SA_SIGINFO, 0x34229d178d0}, NULL, 8) = 0
rt_sigaction(SIGRT_1, {0x34229d0ea80, [], SA_RESTORER|SA_RESTART|SA_SIGINFO, 0x34229d178d0}, NULL, 8) = 0
rt_sigprocmask(SIG_UNBLOCK, [RTMIN RT_1], NULL, 8) = 0
getrlimit(RLIMIT_STACK, {rlim_cur=8192*1024, rlim_max=RLIM64_INFINITY}) = 0
rt_sigaction(SIGFPE, {0x427d30, [], SA_RESTORER|SA_SIGINFO, 0x34229f5a180}, NULL, 8) = 0
rt_sigaction(SIGILL, {0x427d30, [], SA_RESTORER|SA_SIGINFO, 0x34229f5a180}, NULL, 8) = 0
rt_sigaction(SIGBUS, {0x427d30, [], SA_RESTORER|SA_SIGINFO, 0x34229f5a180}, NULL, 8) = 0
rt_sigaction(SIGSEGV, {0x427d30, [], SA_RESTORER|SA_SIGINFO, 0x34229f5a180}, NULL, 8) = 0
brk(0) = 0x22ad290
brk(0x22ce290) = 0x22ce290
brk(0x22cf000) = 0x22cf000
open("/usr/lib/locale/locale-archive", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=3149360, ...}) = 0
mmap(NULL, 3149360, PROT_READ, MAP_PRIVATE, 3, 0) = 0x34229a07000
close(3) = 0
ioctl(0, SNDCTL_TMR_TIMEBASE or SNDRV_TIMER_IOCTL_NEXT_DEVICE or TCGETS, {B38400 opost isig icanon echo ...}) = 0
ioctl(1, SNDCTL_TMR_TIMEBASE or SNDRV_TIMER_IOCTL_NEXT_DEVICE or TCGETS, 0x3d0446aa400) = -1 ENOTTY (Inappropriate ioctl for device)
getuid() = 0
geteuid() = 0
getgid() = 0
getegid() = 0
prctl(PR_GET_DUMPABLE) = 1
getuid() = 0
geteuid() = 0
getgid() = 0
getegid() = 0
prctl(PR_GET_DUMPABLE) = 1
open("/etc/blkid.conf", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
open("/run/blkid/blkid.tab", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
openat(AT_FDCWD, "/etc/e2fsck.conf", O_RDONLY|O_NONBLOCK|O_DIRECTORY|O_CLOEXEC) = -1 ENOENT (No such file or directory)
rt_sigaction(SIGINT, {0x40c720, [], SA_RESTORER, 0x34229f5a180}, NULL, 8) = 0
rt_sigaction(SIGTERM, {0x40c720, [], SA_RESTORER, 0x34229f5a180}, NULL, 8) = 0
rt_sigaction(SIGUSR1, {0x40c700, [], SA_RESTORER|SA_RESTART, 0x34229f5a180}, NULL, 8) = 0
rt_sigaction(SIGUSR2, {0x40d760, [], SA_RESTORER|SA_RESTART, 0x34229f5a180}, NULL, 8) = 0
open("/dev/null", O_RDWR) = 3
getrusage(RUSAGE_SELF, {ru_utime={0, 0}, ru_stime={0, 4000}, ...}) = 0
write(2, "e2fsck 1.42.12 (29-Aug-2014)\n", 29e2fsck 1.42.12 (29-Aug-2014)
) = 29
stat("/dev/system_vg/video_lv", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 7), ...}) = 0
open("/proc/swaps", O_RDONLY) = 4
fstat(4, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3422af80000
read(4, "Filename\t\t\t\tType\t\tSize\tUsed\tPrio"..., 1024) = 101
stat("/dev/dm-0", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 0), ...}) = 0
read(4, "", 1024) = 0
close(4) = 0
munmap(0x3422af80000, 4096) = 0
open("/proc/mounts", O_RDONLY|O_CLOEXEC) = 4
stat("/dev/system_vg/video_lv", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 7), ...}) = 0
futex(0x3422a2cb088, FUTEX_WAKE_PRIVATE, 2147483647) = 0
fstat(4, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3422af80000
read(4, "rootfs / rootfs rw 0 0\n/dev/root"..., 1024) = 1024
stat("/dev/root", 0x3d0446aa310) = -1 ENOENT (No such file or directory)
stat("/dev/sda2", {st_mode=S_IFBLK|0660, st_rdev=makedev(8, 2), ...}) = 0
stat("/dev/mapper/system_vg-home_vg", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 3), ...}) = 0
stat("/dev/mapper/system_vg-tmp_lv", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 4), ...}) = 0
read(4, "=ordered 0 0\n/dev/mapper/system_"..., 1024) = 1003
stat("/dev/mapper/system_vg-usr_vg", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 1), ...}) = 0
stat("/dev/mapper/system_vg-var_vg", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 2), ...}) = 0
stat("/dev/mapper/system_vg-build_lv", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 5), ...}) = 0
stat("/dev/mapper/system_vg-mirror_lv", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 8), ...}) = 0
stat("/dev/mapper/system_vg-cache_lv", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 9), ...}) = 0
stat("/dev/mapper/system_vg-media_lv", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 6), ...}) = 0
stat("/dev/mapper/system_vg-aptcache_lv", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 10), ...}) = 0
stat("/dev/mapper/backup_vg-mediabackup", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 12), ...}) = 0
read(4, "", 1024) = 0
stat("/", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
close(4) = 0
munmap(0x3422af80000, 4096) = 0
open("/etc/mtab", O_RDONLY|O_CLOEXEC) = 4
stat("/dev/system_vg/video_lv", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 7), ...}) = 0
fstat(4, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3422af80000
read(4, "rootfs / rootfs rw 0 0\n/dev/root"..., 1024) = 1024
stat("/dev/root", 0x3d0446aa310) = -1 ENOENT (No such file or directory)
stat("/dev/sda2", {st_mode=S_IFBLK|0660, st_rdev=makedev(8, 2), ...}) = 0
stat("/dev/mapper/system_vg-home_vg", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 3), ...}) = 0
stat("/dev/mapper/system_vg-tmp_lv", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 4), ...}) = 0
read(4, "=ordered 0 0\n/dev/mapper/system_"..., 1024) = 1003
stat("/dev/mapper/system_vg-usr_vg", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 1), ...}) = 0
stat("/dev/mapper/system_vg-var_vg", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 2), ...}) = 0
stat("/dev/mapper/system_vg-build_lv", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 5), ...}) = 0
stat("/dev/mapper/system_vg-mirror_lv", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 8), ...}) = 0
stat("/dev/mapper/system_vg-cache_lv", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 9), ...}) = 0
stat("/dev/mapper/system_vg-media_lv", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 6), ...}) = 0
stat("/dev/mapper/system_vg-aptcache_lv", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 10), ...}) = 0
stat("/dev/mapper/backup_vg-mediabackup", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 12), ...}) = 0
read(4, "", 1024) = 0
stat("/", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0
close(4) = 0
munmap(0x3422af80000, 4096) = 0
stat("/dev/system_vg/video_lv", {st_mode=S_IFBLK|0660, st_rdev=makedev(254, 7), ...}) = 0
open("/dev/system_vg/video_lv", O_RDONLY|O_EXCL) = -1 EBUSY (Device or resource busy)
open("/usr/share/locale/locale.alias", O_RDONLY|O_CLOEXEC) = 4
fstat(4, {st_mode=S_IFREG|0644, st_size=2492, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x3422af80000
read(4, "# Locale name alias data base.\n#"..., 4096) = 2492
read(4, "", 4096) = 0
close(4) = 0
munmap(0x3422af80000, 4096) = 0
open("/usr/share/locale/de_DE.UTF-8/LC_MESSAGES/e2fsprogs.mo", O_RDONLY) = -1 ENOENT (No such file or directory)
open("/usr/share/locale/de_DE.utf8/LC_MESSAGES/e2fsprogs.mo", O_RDONLY) = -1 ENOENT (No such file or directory)
open("/usr/share/locale/de_DE/LC_MESSAGES/e2fsprogs.mo", O_RDONLY) = -1 ENOENT (No such file or directory)
open("/usr/share/locale/de.UTF-8/LC_MESSAGES/e2fsprogs.mo", O_RDONLY) = -1 ENOENT (No such file or directory)
open("/usr/share/locale/de.utf8/LC_MESSAGES/e2fsprogs.mo", O_RDONLY) = -1 ENOENT (No such file or directory)
open("/usr/share/locale/de/LC_MESSAGES/e2fsprogs.mo", O_RDONLY) = 4
fstat(4, {st_mode=S_IFREG|0644, st_size=152801, ...}) = 0
mmap(NULL, 152801, PROT_READ, MAP_PRIVATE, 4, 0) = 0x3422af5b000
close(4) = 0
open("/usr/lib/x86_64-linux-gnu/gconv/gconv-modules.cache", O_RDONLY) = 4
fstat(4, {st_mode=S_IFREG|0644, st_size=26258, ...}) = 0
mmap(NULL, 26258, PROT_READ, MAP_SHARED, 4, 0) = 0x3422af54000
close(4) = 0
futex(0x3422a2c98f8, FUTEX_WAKE_PRIVATE, 2147483647) = 0
write(1, "/dev/system_vg/video_lv wird ver"..., 40/dev/system_vg/video_lv wird verwendet.
) = 40
write(2, "e2fsck: Fortsetzung nicht m\303\266gli"..., 56e2fsck: Fortsetzung nicht möglich, wird abgebrochen.
) = 56
exit_group(8) = ?
+++ exited with 8 +++