710771 – NFSv4 shares fail to mount on boot

Bug 710771 - NFSv4 shares fail to mount on boot

Summary: NFSv4 shares fail to mount on boot

Keywords:
Status:	CLOSED DUPLICATE of bug 692008
Alias:	None
Product:	Fedora
Classification:	Fedora
Component:	NetworkManager
Sub Component:
Version:	15
Hardware:	x86_64
OS:	Linux
Priority:	unspecified
Severity:	medium
Target Milestone:	---
Assignee:	Dan Williams
QA Contact:	Fedora Extras Quality Assurance
Docs Contact:
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+	depends on / blocked

Reported:	2011-06-04 17:24 UTC by Jeffrey Laramie
Modified:	2011-10-06 14:41 UTC (History)
CC List:	5 users (show)
Fixed In Version:
Clone Of:
Environment:
Last Closed:	2011-10-06 14:41:23 UTC
Type:	---
Embargoed:
Dependent Products:

Attachments	(Terms of Use)

Description Jeffrey Laramie 2011-06-04 17:24:06 UTC

Description of problem:
NFSv4 shares fail to mount on boot but mount correctly with "mount -a".

Version-Release number of selected component (if applicable):
nfs-utils-1.2.3-11.fc15.x86_64

How reproducible:
Boot the machine

Actual results:
Shares fail to mount. /var/log/messages shows this error:

[...]
Jun  4 13:12:37 JeffDesk NetworkManager[805]: <info> WWAN enabled by radio killswitch; enabled by state file
Jun  4 13:12:37 JeffDesk NetworkManager[805]: <info> WiMAX enabled by radio killswitch; enabled by state file
Jun  4 13:12:37 JeffDesk NetworkManager[805]: <info> Networking is enabled by state file
Jun  4 13:12:37 JeffDesk dbus: [system] Activation via systemd failed for unit 'dbus-org.bluez.service': Unit dbus-org.bluez.service failed to load: No such file or directory. See system logs and 'systemctl status' for details.
Jun  4 13:12:37 JeffDesk NetworkManager[805]: <info> (p6p1): carrier is OFF
Jun  4 13:12:37 JeffDesk NetworkManager[805]: <info> (p6p1): new Ethernet device (driver: 'r8169' ifindex: 2)
Jun  4 13:12:37 JeffDesk NetworkManager[805]: <info> (p6p1): exported as /org/freedesktop/NetworkManager/Devices/0
Jun  4 13:12:37 JeffDesk NetworkManager[805]: <info> (p6p1): now managed
Jun  4 13:12:37 JeffDesk NetworkManager[805]: <info> (p6p1): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Jun  4 13:12:37 JeffDesk NetworkManager[805]: <info> (p6p1): bringing up device.
Jun  4 13:12:37 JeffDesk kernel: [   21.238775] r8169 0000:02:00.0: p6p1: link down
Jun  4 13:12:37 JeffDesk NetworkManager[805]: <info> (p6p1): preparing device.
Jun  4 13:12:37 JeffDesk kernel: [   21.239818] ADDRCONF(NETDEV_UP): p6p1: link is not ready
Jun  4 13:12:37 JeffDesk NetworkManager[805]: <info> (p6p1): deactivating device (reason: 2).
Jun  4 13:12:37 JeffDesk NetworkManager[805]: <info> modem-manager is now available
Jun  4 13:12:37 JeffDesk NetworkManager[805]: <warn> bluez error getting default adapter: Unit dbus-org.bluez.service failed to load: No such file or directory. See system logs and 'systemctl status' for details.
Jun  4 13:12:38 JeffDesk ntpd_intres[811]: host name not found: NetServer2.DMZ
Jun  4 13:12:38 JeffDesk NetworkManager[805]: <warn> connection /org/freedesktop/NetworkManager/Settings/0 failed to activate: (2) Device not managed by NetworkManager or unavailable
Jun  4 13:12:38 JeffDesk systemd[1]: network.service: control process exited, code=exited status=1
Jun  4 13:12:38 JeffDesk systemd[1]: Unit network.service entered failed state.
Jun  4 13:12:38 JeffDesk systemd[1]: NetServer2.mount mount process exited, code=exited status=1
Jun  4 13:12:38 JeffDesk systemd[1]: Job remote-fs.target/start failed with result 'dependency'.
Jun  4 13:12:38 JeffDesk systemd[1]: Unit NetServer2.mount entered failed state.
Jun  4 13:12:38 JeffDesk auditd[1043]: Started dispatcher: /sbin/audispd pid: 1045
Jun  4 13:12:38 JeffDesk kernel: [   22.481540] auditd (1043): /proc/1043/oom_adj is deprecated, please use /proc/1043/oom_score_adj instead.
Jun  4 13:12:39 JeffDesk audispd: audispd initialized with q_depth=80 and 1 active plugins
Jun  4 13:12:39 JeffDesk auditd[1043]: Init complete, auditd 2.1.1 listening for events (startup state enable)
Jun  4 13:12:39 JeffDesk kernel: [   22.707290] powernow-k8: Found 1 AMD Athlon(tm) 64 X2 Dual Core Processor 5000+ (2 cpu cores) (version 2.20.00)
Jun  4 13:12:39 JeffDesk kernel: [   22.707307] [Firmware Bug]: powernow-k8: No compatible ACPI _PSS objects found.
Jun  4 13:12:39 JeffDesk kernel: [   22.707308] [Firmware Bug]: powernow-k8: Try again with latest BIOS.
Jun  4 13:12:39 JeffDesk kernel: [   22.937461] r8169 0000:02:00.0: p6p1: link up
Jun  4 13:12:39 JeffDesk kernel: [   22.938140] ADDRCONF(NETDEV_CHANGE): p6p1: link becomes ready
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> (p6p1): carrier now ON (device state 20)
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> (p6p1): device state change: unavailable -> disconnected (reason 'carrier-changed') [20 30 40]
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> Activation (p6p1) starting connection 'System p6p1'
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> (p6p1): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> Activation (p6p1) Stage 1 of 5 (Device Prepare) scheduled...
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> Activation (p6p1) Stage 1 of 5 (Device Prepare) started...
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> Activation (p6p1) Stage 2 of 5 (Device Configure) scheduled...
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> Activation (p6p1) Stage 1 of 5 (Device Prepare) complete.
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> Activation (p6p1) Stage 2 of 5 (Device Configure) starting...
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> (p6p1): device state change: prepare -> config (reason 'none') [40 50 0]
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> Activation (p6p1) Stage 2 of 5 (Device Configure) successful.
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> Activation (p6p1) Stage 3 of 5 (IP Configure Start) scheduled.
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> Activation (p6p1) Stage 2 of 5 (Device Configure) complete.
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> Activation (p6p1) Stage 3 of 5 (IP Configure Start) started...
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> (p6p1): device state change: config -> ip-config (reason 'none') [50 70 0]
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> Activation (p6p1) Stage 4 of 5 (IP4 Configure Get) scheduled...
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> Activation (p6p1) Stage 3 of 5 (IP Configure Start) complete.
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> Activation (p6p1) Stage 4 of 5 (IP4 Configure Get) started...
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> Activation (p6p1) Stage 5 of 5 (IP Configure Commit) scheduled...
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> Activation (p6p1) Stage 4 of 5 (IP4 Configure Get) complete.
Jun  4 13:12:39 JeffDesk NetworkManager[805]: <info> Activation (p6p1) Stage 5 of 5 (IP Configure Commit) started...
Jun  4 13:12:39 JeffDesk avahi-daemon[806]: Joining mDNS multicast group on interface p6p1.IPv4 with address 192.168.0.51.
Jun  4 13:12:39 JeffDesk avahi-daemon[806]: New relevant interface p6p1.IPv4 for mDNS.
Jun  4 13:12:39 JeffDesk avahi-daemon[806]: Registering new address record for 192.168.0.51 on p6p1.IPv4.
Jun  4 13:12:39 JeffDesk rpc.statd[1265]: Version 1.2.3 starting
Jun  4 13:12:39 JeffDesk sm-notify[1267]: Version 1.2.3 starting
Jun  4 13:12:40 JeffDesk dbus: [system] Activating service name='org.freedesktop.ColorManager' (using servicehelper)
Jun  4 13:12:40 JeffDesk kernel: [   23.690457] drbd: initialized. Version: 8.3.9 (api:88/proto:86-95)
Jun  4 13:12:40 JeffDesk kernel: [   23.690462] drbd: srcversion: CF228D42875CF3A43F2945A 
Jun  4 13:12:40 JeffDesk kernel: [   23.690464] drbd: registered as block device major 147
Jun  4 13:12:40 JeffDesk kernel: [   23.690467] drbd: minor_table @ 0xffff8800a4babb00
Jun  4 13:12:40 JeffDesk systemd[1]: drbd.service: control process exited, code=exited status=20
Jun  4 13:12:40 JeffDesk dbus: [system] Successfully activated service 'org.freedesktop.ColorManager'
Jun  4 13:12:40 JeffDesk systemd[1]: Unit drbd.service entered failed state.
Jun  4 13:12:40 JeffDesk NetworkManager[805]: <info> (p6p1): device state change: ip-config -> activated (reason 'none') [70 100 0]
Jun  4 13:12:40 JeffDesk kernel: [   24.005138] RPC: Registered udp transport module.
Jun  4 13:12:40 JeffDesk kernel: [   24.005142] RPC: Registered tcp transport module.
Jun  4 13:12:40 JeffDesk kernel: [   24.005144] RPC: Registered tcp NFSv4.1 backchannel transport module.
Jun  4 13:12:40 JeffDesk NetworkManager[805]: <info> Policy set 'System p6p1' (p6p1) as default for IPv4 routing and DNS.
Jun  4 13:12:40 JeffDesk NetworkManager[805]: <info> Activation (p6p1) successful, device activated.
Jun  4 13:12:40 JeffDesk NetworkManager[805]: <info> Activation (p6p1) Stage 5 of 5 (IP Configure Commit) complete.
[...]

Expected results:
Any share that mounts from the command line should also mount during boot.

Additional info:
From the log it appears that the system is not waiting for the networking interface to come up before attempting to mount nfs shares.

Comment 1 Jeffrey Laramie 2011-06-27 14:51:34 UTC

I've discovered that if I disable NetworkManager with "chkconfig --del NetworkManager" the shares will mount correctly on reboot. I've also installed updated NetworkManager and systemctl packages but the problem remains.

Comment 2 Steve Dickson 2011-10-04 17:18:33 UTC

This appears to be the problem

failed to activate: (2) Device not managed by NetworkManager or unavailable

Which has nothing to do with NFS... So for now I would like
to reasing this bz to NetworkManager...

Comment 3 Jirka Klimes 2011-10-06 14:41:23 UTC

You should enable NetworkManager-wait-online.service so that services dependent on active network connection wait for it:
# systemctl enable NetworkManager-wait-online.service

It's not enabled by default, see bug 692008 of which I make this one duplicate.

https://bugzilla.redhat.com/show_bug.cgi?id=692008#c28

*** This bug has been marked as a duplicate of bug 692008 ***

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