Bug 710771

Summary: NFSv4 shares fail to mount on boot
Product: [Fedora] Fedora Reporter: Jeffrey Laramie <imnotpc>
Component: NetworkManagerAssignee: Dan Williams <dcbw>
Status: CLOSED DUPLICATE QA Contact: Fedora Extras Quality Assurance <extras-qa>
Severity: medium Docs Contact:
Priority: unspecified    
Version: 15CC: dcbw, jklimes, jlayton, redhat-bugzilla, steved
Target Milestone: ---   
Target Release: ---   
Hardware: x86_64   
OS: Linux   
Whiteboard:
Fixed In Version: Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: 2011-10-06 14:41:23 UTC Type: ---
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:

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 ***