Bug 873983 - systemd-remount-fs[2515]: mount: /dev/mapper/myvg-usrlv is already mounted or /usr busy
Summary: systemd-remount-fs[2515]: mount: /dev/mapper/myvg-usrlv is already mounted or...
Keywords:
Status: CLOSED CURRENTRELEASE
Alias: None
Product: Fedora
Classification: Fedora
Component: util-linux
Version: 17
Hardware: Unspecified
OS: Linux
unspecified
medium
Target Milestone: ---
Assignee: Karel Zak
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
: 873986 (view as bug list)
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2012-11-07 08:06 UTC by udo
Modified: 2012-12-01 08:43 UTC (History)
12 users (show)

Fixed In Version:
Doc Type: Bug Fix
Doc Text:
Clone Of:
Environment:
Last Closed: 2012-12-01 08:43:48 UTC
Type: Bug
Embargoed:


Attachments (Terms of Use)

Description udo 2012-11-07 08:06:50 UTC
Description of problem:
'systemd-remount-fs[2515]: mount: /dev/mapper/myvg-usrlv is already mounted or /usr busy' causes a 'failed' message to flash by during booting.
I guess that /usr is mounted by dracut because all essential stuff now lives there. So please explain what went wrong here.
System functions normally as far as I can discern.

Version-Release number of selected component (if applicable):
systemd-44-21.fc17.x86_64

How reproducible:
Boot system, observe and check dmesg.
  
Actual results:
# systemctl status systemd-remount-fs.service
systemd-remount-fs.service - Remount Root and Kernel File Systems
	  Loaded: loaded (/usr/lib/systemd/system/systemd-remount-fs.service; static)
	  Active: failed (Result: exit-code) since Wed, 07 Nov 2012 08:29:14 +0100; 31min ago
	 Process: 2515 ExecStart=/usr/lib/systemd/systemd-remount-fs (code=exited, status=1/FAILURE)
	  CGroup: name=systemd:/system/systemd-remount-fs.service

Nov 07 08:29:14 surfplank2.hierzo systemd-remount-fs[2515]: mount: /dev/mapper/myvg-usrlv is already mounted or /usr busy
Nov 07 08:29:14 surfplank2.hierzo systemd-remount-fs[2515]: /dev/mapper/myvg-usrlv is already mounted on /usr
Nov 07 08:29:14 surfplank2.hierzo systemd-remount-fs[2515]: /bin/mount for /usr exited with exit status 32.


Expected results:
No complaints about mounting /usr which is already mounted.


Additional info:

Comment 1 udo 2012-11-07 08:21:15 UTC
*** Bug 873986 has been marked as a duplicate of this bug. ***

Comment 2 Michal Schmidt 2012-11-07 08:35:53 UTC
Does this command also produce any errors?:
/bin/mount /usr -o remount

Comment 3 udo 2012-11-07 09:31:15 UTC
# /bin/mount /usr -o remount
mount: /dev/mapper/myvg-usrlv is already mounted or /usr busy
       /dev/mapper/myvg-usrlv is already mounted on /usr
#

Comment 4 Michal Schmidt 2012-11-07 11:07:30 UTC
Is /etc/mtab a symlink to /proc/mounts?
What is the filesystem type on /usr?

Comment 5 udo 2012-11-07 16:41:10 UTC
yes
ext4

any kernel options required?

Comment 6 Michal Schmidt 2012-11-07 22:56:02 UTC
I don't know. Does it happen with a Fedora kernel?

What is the version of util-linux?
Could you run the command under strace and attach the result?

Comment 7 udo 2012-11-08 14:07:39 UTC
# rpm -q util-linux
util-linux-2.21.2-2.fc17.x86_64
# /bin/mount /var -o remount
#
# mount|grep var
/dev/mapper/myvg-varlv on /var type ext4 (rw,nosuid,nodev,noatime,nodiratime,stripe=256,data=ordered)
sunrpc on /var/lib/nfs/rpc_pipefs type rpc_pipefs (rw,relatime)
/dev/mapper/myvg-wwwlv on /var/www type ext4 (rw,nodev,noatime,nodiratime,stripe=256,data=ordered)
# mount|grep usr
/dev/mapper/myvg-usrlv on /usr type ext4 (rw,noatime,stripe=256,data=ordered)
cgroup on /sys/fs/cgroup/systemd type cgroup (rw,nosuid,nodev,noexec,relatime,release_agent=/usr/lib/systemd/systemd-cgroups-agent,name=systemd)
/dev/mapper/myvg-datalv on /usr/local type ext4 (rw,nodev,noatime,nodiratime,stripe=256,data=ordered)
/dev/mapper/myvg-srclv on /usr/src type ext4 (rw,nodev,noatime,nodiratime,stripe=256,data=ordered)
#
# strace /bin/mount /usr -o remount 
execve("/bin/mount", ["/bin/mount", "/usr", "-o", "remount"], [/* 39 vars */]) = 0
brk(0)                                  = 0x7ff101008000
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7ff1003c7000
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=170386, ...}) = 0
mmap(NULL, 170386, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7ff100390000
close(3)                                = 0
open("/lib64/libmount.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\220x\240\0379\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=183096, ...}) = 0
mmap(NULL, 2275512, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7ff0fff70000
mprotect(0x7ff0fff9a000, 2097152, PROT_NONE) = 0
mmap(0x7ff10019a000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2a000) = 0x7ff10019a000
close(3)                                = 0
open("/lib64/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\240j\240'9\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=162496, ...}) = 0
mmap(NULL, 2255080, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7ff0ffd48000
mprotect(0x7ff0ffd6c000, 2093056, PROT_NONE) = 0
mmap(0x7ff0fff6b000, 16384, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x23000) = 0x7ff0fff6b000
close(3)                                = 0
open("/lib64/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\320\26\340'9\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=21696, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7ff1003d0000
mmap(NULL, 2113960, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7ff0ffb40000
mprotect(0x7ff0ffb44000, 2093056, PROT_NONE) = 0
mmap(0x7ff0ffd43000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3000) = 0x7ff0ffd43000
close(3)                                = 0
open("/lib64/libselinux.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\300_ \"9\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=128888, ...}) = 0
mmap(NULL, 2230304, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7ff0ff918000
mprotect(0x7ff0ff936000, 2093056, PROT_NONE) = 0
mmap(0x7ff0ffb35000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1d000) = 0x7ff0ffb35000
mmap(0x7ff0ffb37000, 6176, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7ff0ffb37000
close(3)                                = 0
open("/lib64/libsepol.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\320@ '9\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=267088, ...}) = 0
mmap(NULL, 2361600, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7ff0ff6d0000
mprotect(0x7ff0ff710000, 2093056, PROT_NONE) = 0
mmap(0x7ff0ff90f000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x3f000) = 0x7ff0ff90f000
close(3)                                = 0
open("/lib64/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\0@\30b\0379\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=2065544, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7ff1003c6000
mmap(NULL, 3892376, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7ff0ff318000
mprotect(0x7ff0ff4c4000, 2097152, PROT_NONE) = 0
mmap(0x7ff0ff6c4000, 24576, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1ac000) = 0x7ff0ff6c4000
mmap(0x7ff0ff6ca000, 17560, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x7ff0ff6ca000
close(3)                                = 0
open("/lib64/libdl.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\240\16  9\0\0\0"..., 832) = 832
fstat(3, {st_mode=S_IFREG|0755, st_size=22480, ...}) = 0
mmap(NULL, 2109704, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7ff0ff110000
mprotect(0x7ff0ff113000, 2093056, PROT_NONE) = 0
mmap(0x7ff0ff312000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x2000) = 0x7ff0ff312000
close(3)                                = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7ff1003c5000
mmap(NULL, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7ff1003c3000
arch_prctl(ARCH_SET_FS, 0x7ff1003c3840) = 0
mprotect(0x7ff0ff6c4000, 16384, PROT_READ) = 0
mprotect(0x7ff0ff312000, 4096, PROT_READ) = 0
mprotect(0x7ff0ffb35000, 4096, PROT_READ) = 0
mprotect(0x7ff0ffd43000, 4096, PROT_READ) = 0
mprotect(0x7ff0fff6b000, 12288, PROT_READ) = 0
mprotect(0x7ff10019a000, 4096, PROT_READ) = 0
mprotect(0x7ff1005d0000, 4096, PROT_READ) = 0
mprotect(0x7ff1003bf000, 4096, PROT_READ) = 0
munmap(0x7ff100390000, 170386)          = 0
statfs("/sys/fs/selinux", 0x7fff5e4f9140) = -1 ENOENT (No such file or directory)
statfs("/selinux", 0x7fff5e4f9140)      = -1 ENOENT (No such file or directory)
brk(0)                                  = 0x7ff101008000
brk(0x7ff101029000)                     = 0x7ff101029000
open("/proc/filesystems", O_RDONLY)     = 3
fstat(3, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7ff1003c2000
read(3, "nodev\tsysfs\nnodev\trootfs\nnodev\tb"..., 1024) = 337
read(3, "", 1024)                       = 0
close(3)                                = 0
munmap(0x7ff1003c2000, 4096)            = 0
open("/usr/lib/locale/locale-archive", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=105038240, ...}) = 0
mmap(NULL, 105038240, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7ff0f8ce0000
close(3)                                = 0
getuid()                                = 0
geteuid()                               = 0
getuid()                                = 0
geteuid()                               = 0
getgid()                                = 0
getegid()                               = 0
prctl(PR_GET_DUMPABLE)                  = 1
lstat("/etc/mtab", {st_mode=S_IFLNK|0777, st_size=12, ...}) = 0
getuid()                                = 0
geteuid()                               = 0
getgid()                                = 0
getegid()                               = 0
prctl(PR_GET_DUMPABLE)                  = 1
stat("/run", {st_mode=S_IFDIR|0755, st_size=1220, ...}) = 0
lstat("/run/mount/utab", {st_mode=S_IFREG|0644, st_size=86, ...}) = 0
open("/run/mount/utab", O_RDWR|O_CREAT, 0644) = 3
close(3)                                = 0
getuid()                                = 0
geteuid()                               = 0
getgid()                                = 0
getegid()                               = 0
prctl(PR_GET_DUMPABLE)                  = 1
stat("/etc/fstab", {st_mode=S_IFREG|0664, st_size=2374, ...}) = 0
open("/etc/fstab", O_RDONLY)            = 3
fstat(3, {st_mode=S_IFREG|0664, st_size=2374, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7ff1003c2000
read(3, "#\n# /etc/fstab\n# Created by anac"..., 4096) = 2374
read(3, "", 4096)                       = 0
close(3)                                = 0
munmap(0x7ff1003c2000, 4096)            = 0
readlink("/dev", 0x7fff5e4f6ef0, 4096)  = -1 EINVAL (Invalid argument)
readlink("/dev/myvg", 0x7fff5e4f6ef0, 4096) = -1 EINVAL (Invalid argument)
readlink("/dev/myvg/usrlv", "../dm-2", 4096) = 7
readlink("/dev/dm-2", 0x7fff5e4f6ef0, 4096) = -1 EINVAL (Invalid argument)
open("/sys/block/dm-2/dm/name", O_RDONLY) = 3
fstat(3, {st_mode=S_IFREG|0444, st_size=4096, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7ff1003c2000
read(3, "myvg-usrlv\n", 4096)           = 11
close(3)                                = 0
munmap(0x7ff1003c2000, 4096)            = 0
stat("/dev/mapper/myvg-usrlv", {st_mode=S_IFBLK|0660, st_rdev=makedev(253, 2), ...}) = 0
readlink("/usr", 0x7fff5e4f6f00, 4096)  = -1 EINVAL (Invalid argument)
stat("/sbin/mount.ext4", 0x7fff5e4f7ec0) = -1 ENOENT (No such file or directory)
stat("/sbin/fs.d/mount.ext4", 0x7fff5e4f7ec0) = -1 ENOENT (No such file or directory)
stat("/sbin/fs/mount.ext4", 0x7fff5e4f7ec0) = -1 ENOENT (No such file or directory)
mount("/dev/mapper/myvg-usrlv", "/usr", "ext4", MS_MGC_VAL|MS_NOATIME, NULL) = -1 EBUSY (Device or resource busy)
open("/usr/share/locale/locale.alias", O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=2512, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7ff1003c2000
read(3, "# Locale name alias data base.\n#"..., 4096) = 2512
read(3, "", 4096)                       = 0
close(3)                                = 0
munmap(0x7ff1003c2000, 4096)            = 0
open("/usr/share/locale/en_US.UTF-8/LC_MESSAGES/util-linux.mo", O_RDONLY) = -1 ENOENT (No such file or directory)
open("/usr/share/locale/en_US.utf8/LC_MESSAGES/util-linux.mo", O_RDONLY) = -1 ENOENT (No such file or directory)
open("/usr/share/locale/en_US/LC_MESSAGES/util-linux.mo", O_RDONLY) = -1 ENOENT (No such file or directory)
open("/usr/share/locale/en.UTF-8/LC_MESSAGES/util-linux.mo", O_RDONLY) = -1 ENOENT (No such file or directory)
open("/usr/share/locale/en.utf8/LC_MESSAGES/util-linux.mo", O_RDONLY) = -1 ENOENT (No such file or directory)
open("/usr/share/locale/en/LC_MESSAGES/util-linux.mo", O_RDONLY) = -1 ENOENT (No such file or directory)
write(2, "mount: ", 7mount: )                  = 7
write(2, "/dev/mapper/myvg-usrlv is alread"..., 54/dev/mapper/myvg-usrlv is already mounted or /usr busy) = 54
write(2, "\n", 1
)                       = 1
lstat("/etc/mtab", {st_mode=S_IFLNK|0777, st_size=12, ...}) = 0
open("/proc/self/mountinfo", O_RDONLY)  = 3
fstat(3, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7ff1003c2000
read(3, "14 20 0:3 / /proc rw,relatime - "..., 1024) = 1024
read(3, " rw,nosuid,nodev,noexec,relatime"..., 1024) = 1024
read(3, " / /opt rw,nodev,noatime,nodirat"..., 1024) = 873
read(3, "", 1024)                       = 0
close(3)                                = 0
munmap(0x7ff1003c2000, 4096)            = 0
getuid()                                = 0
geteuid()                               = 0
getgid()                                = 0
getegid()                               = 0
prctl(PR_GET_DUMPABLE)                  = 1
stat("/run", {st_mode=S_IFDIR|0755, st_size=1220, ...}) = 0
stat("/run/mount/utab", {st_mode=S_IFREG|0644, st_size=86, ...}) = 0
open("/run/mount/utab", O_RDONLY)       = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=86, ...}) = 0
mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7ff1003c2000
read(3, "SRC=/dev/sr0 TARGET=/run/media/u"..., 4096) = 86
read(3, "", 4096)                       = 0
close(3)                                = 0
munmap(0x7ff1003c2000, 4096)            = 0
write(2, "       /dev/mapper/myvg-usrlv is"..., 57       /dev/mapper/myvg-usrlv is already mounted on /usr
) = 57
exit_group(32)                          = ?
+++ exited with 32 +++
#

Comment 8 Michal Schmidt 2012-11-10 19:37:39 UTC
(In reply to comment #7)
> # strace /bin/mount /usr -o remount 
> execve("/bin/mount", ["/bin/mount", "/usr", "-o", "remount"], [/* 39 vars
> */]) = 0
...
> mount("/dev/mapper/myvg-usrlv", "/usr", "ext4", MS_MGC_VAL|MS_NOATIME, NULL)
> = -1 EBUSY (Device or resource busy)

Karle,
I can't explain why MS_REMOUNT is missing here. Can you see what's going on?

Comment 9 udo 2012-11-11 13:45:38 UTC
fstab had a line of:

/dev/myvg/usrlv         /usr                    ext4    defaults,noatime,,nodiratime,nodev        1 2

(note the extra comma)

Removing the extra comma made things work.

Now where is the bug?

Comment 10 Karel Zak 2012-11-13 09:06:00 UTC
(In reply to comment #9)
> fstab had a line of:
> 
> /dev/myvg/usrlv         /usr                    ext4   
> defaults,noatime,,nodiratime,nodev        1 2
> 
> (note the extra comma)
> 
> Removing the extra comma made things work.
>
> Now where is the bug?

This is libmount bug, fixed by upstream commit 5e7b834002aa0ce97a1b0104e8710664ad954595, the Fedora package will be fixed ASAP.

(It's already fixed in f18 where is util-linux 2.22).)

Thanks!

Comment 11 Fedora Update System 2012-11-16 11:45:57 UTC
util-linux-2.21.2-3.fc17 has been submitted as an update for Fedora 17.
https://admin.fedoraproject.org/updates/util-linux-2.21.2-3.fc17

Comment 12 Fedora Update System 2012-11-17 23:51:24 UTC
Package util-linux-2.21.2-3.fc17:
* should fix your issue,
* was pushed to the Fedora 17 testing repository,
* should be available at your local mirror within two days.
Update it with:
# su -c 'yum update --enablerepo=updates-testing util-linux-2.21.2-3.fc17'
as soon as you are able to.
Please go to the following url:
https://admin.fedoraproject.org/updates/FEDORA-2012-18417/util-linux-2.21.2-3.fc17
then log in and leave karma (feedback).

Comment 13 Fedora Update System 2012-12-01 08:43:52 UTC
util-linux-2.21.2-3.fc17 has been pushed to the Fedora 17 stable repository.  If problems still persist, please make note of it in this bug report.


Note You need to log in before you can comment on or make changes to this bug.