by hfs99 » Thu Jul 01, 2021 4:37 pm
I wasn't really able to get any additional info. The only clue I see is
$this->bbcode_second_pass_code('', 'rpc.mountd[288]: Unable to watch /proc/fs/nfsd/clients: No such file or directory')
That (virtual) directory doesn't exist under 2.5.4-1; FWIW it doesn't exist in 2.5.3-1 either.
--------
This is all TL:DR - included for the sake of completeness.
command line and status
$this->bbcode_second_pass_code('', '[root@srvalot hfs2]# pacman -U /var/cache/pacman/pkg/nfs-utils-2.5.4-1-armv7h.pkg.tar.xz
[root@srvalot hfs2]# pacman -U /var/cache/pacman/pkg/nfsidmap-2.5.4-1-armv7h.pkg.tar.xz
[root@srvalot hfs2]# systemctl status nfs-idmapd.servce nfs-mountd.service nfs-server.service nfsdcld.service
Unit nfs-idmapd.servce.service could not be found.
* nfs-mountd.service - NFS Mount Daemon
Loaded: loaded (/usr/lib/systemd/system/nfs-mountd.service; static)
Active: active (running) since Sat 2021-06-26 13:38:58 CDT; 4 days ago
Main PID: 287 (rpc.mountd)
Memory: 2.3M
CGroup: /system.slice/nfs-mountd.service
`-287 /usr/sbin/rpc.mountd
Jun 26 13:38:58 srvalot systemd[1]: Starting NFS Mount Daemon...
Jun 26 13:38:58 srvalot rpc.mountd[287]: Version 2.5.3 starting
Jun 26 13:38:58 srvalot systemd[1]: Started NFS Mount Daemon.
* nfs-server.service - NFS server and services
Loaded: loaded (/usr/lib/systemd/system/nfs-server.service; enabled; vendor preset: disabled)
Drop-In: /run/systemd/generator/nfs-server.service.d
`-order-with-mounts.conf
Active: active (exited) since Sat 2021-06-26 13:38:58 CDT; 4 days ago
Main PID: 289 (code=exited, status=0/SUCCESS)
Memory: 0B
CGroup: /system.slice/nfs-server.service
Jun 26 13:38:58 srvalot systemd[1]: Starting NFS server and services...
Jun 26 13:38:58 srvalot systemd[1]: Finished NFS server and services.
* nfsdcld.service - NFSv4 Client Tracking Daemon
Loaded: loaded (/usr/lib/systemd/system/nfsdcld.service; static)
Active: active (running) since Wed 2021-05-19 09:11:58 CDT; 1 months 12 days ago
Main PID: 257 (nfsdcld)
Memory: 1.8M
CGroup: /system.slice/nfsdcld.service
`-257 /usr/sbin/nfsdcld
May 19 09:11:58 srvalot systemd[1]: Starting NFSv4 Client Tracking Daemon...
May 19 09:11:58 srvalot systemd[1]: Started NFSv4 Client Tracking Daemon.
[root@srvalot hfs2]# systemctl restart nfs-mountd.service
[root@srvalot hfs2]# systemctl status nfs-mountd.service
x nfs-mountd.service - NFS Mount Daemon
Loaded: loaded (/usr/lib/systemd/system/nfs-mountd.service; static)
Active: failed (Result: exit-code) since Thu 2021-07-01 09:32:52 CDT; 17s ago
Process: 17682 ExecStart=/usr/sbin/rpc.mountd (code=exited, status=0/SUCCESS)
Main PID: 17686 (code=exited, status=1/FAILURE)
Jul 01 09:32:52 srvalot systemd[1]: Starting NFS Mount Daemon...
Jul 01 09:32:52 srvalot rpc.mountd[17686]: Unable to watch /proc/fs/nfsd/clients: No such file or directory
Jul 01 09:32:52 srvalot systemd[1]: Started NFS Mount Daemon.
Jul 01 09:32:52 srvalot systemd[1]: nfs-mountd.service: Main process exited, code=exited, status=1/FAILURE
Jul 01 09:32:52 srvalot systemd[1]: nfs-mountd.service: Failed with result 'exit-code'.
[root@srvalot hfs2]#
[root@srvalot hfs2]# systemctl reboot
[root@srvalot hfs2]# systemctl status nfs-idmapd.service
* nfs-idmapd.service - NFSv4 ID-name mapping service
Loaded: loaded (/usr/lib/systemd/system/nfs-idmapd.service; static)
Active: active (running) since Thu 2021-07-01 09:36:11 CDT; 5min ago
Process: 256 ExecStart=/usr/sbin/rpc.idmapd (code=exited, status=0/SUCCESS)
Main PID: 260 (rpc.idmapd)
Memory: 1.3M
CGroup: /system.slice/nfs-idmapd.service
`-260 /usr/sbin/rpc.idmapd
Jul 01 09:36:11 srvalot systemd[1]: Starting NFSv4 ID-name mapping service...
Jul 01 09:36:11 srvalot rpc.idmapd[260]: Setting log level to 0
Jul 01 09:36:11 srvalot systemd[1]: Started NFSv4 ID-name mapping service.
[root@srvalot hfs2]# systemctl status nfs-mountd.service
x nfs-mountd.service - NFS Mount Daemon
Loaded: loaded (/usr/lib/systemd/system/nfs-mountd.service; static)
Active: failed (Result: exit-code) since Thu 2021-07-01 09:36:21 CDT; 7min ago
Process: 285 ExecStart=/usr/sbin/rpc.mountd (code=exited, status=0/SUCCESS)
Main PID: 290 (code=exited, status=1/FAILURE)
Jul 01 09:36:22 srvalot systemd[1]: Starting NFS Mount Daemon...
Jul 01 09:36:21 srvalot systemd[1]: Started NFS Mount Daemon.
Jul 01 09:36:21 srvalot systemd[1]: nfs-mountd.service: Main process exited, code=exited, status=1/FAILURE
Jul 01 09:36:21 srvalot systemd[1]: nfs-mountd.service: Failed with result 'exit-code'.
[root@srvalot hfs2]# systemctl status nfs-server.service
* nfs-server.service - NFS server and services
Loaded: loaded (/usr/lib/systemd/system/nfs-server.service; enabled; vendor preset: disabled)
Drop-In: /run/systemd/generator/nfs-server.service.d
`-order-with-mounts.conf
Active: active (exited) since Thu 2021-07-01 09:36:23 CDT; 9min ago
Process: 291 ExecStartPre=/usr/sbin/exportfs -r (code=exited, status=0/SUCCESS)
Process: 292 ExecStart=/usr/sbin/rpc.nfsd (code=exited, status=0/SUCCESS)
Main PID: 292 (code=exited, status=0/SUCCESS)
Jul 01 09:36:24 srvalot rpc.nfsd[292]: rpc.nfsd: Unable to request RDMA services: Protocol not supported
Jul 01 09:36:21 srvalot systemd[1]: Starting NFS server and services...
Jul 01 09:36:23 srvalot systemd[1]: Finished NFS server and services.
[root@srvalot hfs2]# systemctl status nfsdcld.service
* nfsdcld.service - NFSv4 Client Tracking Daemon
Loaded: loaded (/usr/lib/systemd/system/nfsdcld.service; static)
Active: active (running) since Thu 2021-07-01 09:36:11 CDT; 10min ago
Process: 257 ExecStart=/usr/sbin/nfsdcld (code=exited, status=0/SUCCESS)
Main PID: 259 (nfsdcld)
Memory: 2.0M
CGroup: /system.slice/nfsdcld.service
`-259 /usr/sbin/nfsdcld
Jul 01 09:36:11 srvalot systemd[1]: Starting NFSv4 Client Tracking Daemon...
Jul 01 09:36:11 srvalot systemd[1]: Started NFSv4 Client Tracking Daemon.
')
journal
$this->bbcode_second_pass_code('', '### boots:
### a7f3 -- booted with 2.5.3-1, updated to 2.5.4-1, restarted nfs-mountd.service
### 9294 -- first reboot after update to 2.5.4-1
### e068 -- /etc/idmapd.conf Verbosity = 6 (was 0)
### a7d7 -- Verbosity = 7
[root@srvalot hfs2]# journalctl --unit=nfs-mountd.service
...
-- Boot a0ad1ec38d6e4223b614ec978a6ca7f3 --
Jun 26 13:38:58 srvalot systemd[1]: Starting NFS Mount Daemon...
Jun 26 13:38:58 srvalot rpc.mountd[287]: Version 2.5.3 starting
Jun 26 13:38:58 srvalot systemd[1]: Started NFS Mount Daemon.
Jul 01 09:32:52 srvalot rpc.mountd[287]: Caught signal 15, un-registering and exiting.
Jul 01 09:32:52 srvalot systemd[1]: Stopping NFS Mount Daemon...
Jul 01 09:32:52 srvalot systemd[1]: nfs-mountd.service: Deactivated successfully.
Jul 01 09:32:52 srvalot systemd[1]: Stopped NFS Mount Daemon.
Jul 01 09:32:52 srvalot systemd[1]: Starting NFS Mount Daemon...
Jul 01 09:32:52 srvalot rpc.mountd[17686]: Unable to watch /proc/fs/nfsd/clients: No such file or directory
Jul 01 09:32:52 srvalot systemd[1]: Started NFS Mount Daemon.
Jul 01 09:32:52 srvalot systemd[1]: nfs-mountd.service: Main process exited, code=exited, status=1/FAILURE
Jul 01 09:32:52 srvalot systemd[1]: nfs-mountd.service: Failed with result 'exit-code'.
-- Boot 3b6a1be21d1e4b75a06501481bb29294 --
Jul 01 09:36:22 srvalot systemd[1]: Starting NFS Mount Daemon...
Jul 01 09:36:21 srvalot systemd[1]: Started NFS Mount Daemon.
Jul 01 09:36:21 srvalot systemd[1]: nfs-mountd.service: Main process exited, code=exited, status=1/FAILURE
Jul 01 09:36:21 srvalot systemd[1]: nfs-mountd.service: Failed with result 'exit-code'.
-- Boot e7c491d94ae040d2a4d35b5719ede068 --
Jul 01 10:20:58 srvalot systemd[1]: Starting NFS Mount Daemon...
Jul 01 10:20:58 srvalot rpc.mountd[288]: Unable to watch /proc/fs/nfsd/clients: No such file or directory
Jul 01 10:20:58 srvalot systemd[1]: Started NFS Mount Daemon.
Jul 01 10:20:58 srvalot systemd[1]: nfs-mountd.service: Main process exited, code=exited, status=1/FAILURE
Jul 01 10:20:58 srvalot systemd[1]: nfs-mountd.service: Failed with result 'exit-code'.
-- Boot 373f485d4a6d48dbaa66806deda3a7d7 --
Jul 01 10:57:17 srvalot systemd[1]: Starting NFS Mount Daemon...
Jul 01 10:57:18 srvalot rpc.mountd[281]: Unable to watch /proc/fs/nfsd/clients: No such file or directory
Jul 01 10:57:18 srvalot systemd[1]: Started NFS Mount Daemon.
Jul 01 10:57:18 srvalot systemd[1]: nfs-mountd.service: Main process exited, code=exited, status=1/FAILURE
Jul 01 10:57:18 srvalot systemd[1]: nfs-mountd.service: Failed with result 'exit-code'.
lines 600-654/654 (END)
[root@srvalot hfs2]# journalctl --unit=nfs-idmapd.service
...
-- Boot a0ad1ec38d6e4223b614ec978a6ca7f3 --
Jul 01 09:32:52 srvalot rpc.idmapd[256]: exiting on signal 15
Jul 01 09:32:52 srvalot systemd[1]: Stopping NFSv4 ID-name mapping service...
Jul 01 09:32:52 srvalot systemd[1]: nfs-idmapd.service: Main process exited, code=exited, status=1/FAILURE
Jul 01 09:32:52 srvalot systemd[1]: nfs-idmapd.service: Failed with result 'exit-code'.
Jul 01 09:32:52 srvalot systemd[1]: Stopped NFSv4 ID-name mapping service.
Jul 01 09:32:52 srvalot systemd[1]: Starting NFSv4 ID-name mapping service...
Jul 01 09:32:52 srvalot rpc.idmapd[17683]: Setting log level to 0
Jul 01 09:32:52 srvalot systemd[1]: Started NFSv4 ID-name mapping service.
Jul 01 09:35:31 srvalot systemd[1]: Stopping NFSv4 ID-name mapping service...
Jul 01 09:35:31 srvalot systemd[1]: nfs-idmapd.service: Main process exited, code=exited, status=1/FAILURE
Jul 01 09:35:31 srvalot systemd[1]: nfs-idmapd.service: Failed with result 'exit-code'.
Jul 01 09:35:31 srvalot systemd[1]: Stopped NFSv4 ID-name mapping service.
-- Boot 3b6a1be21d1e4b75a06501481bb29294 --
Jul 01 09:36:11 srvalot systemd[1]: Starting NFSv4 ID-name mapping service...
Jul 01 09:36:11 srvalot rpc.idmapd[260]: Setting log level to 0
Jul 01 09:36:11 srvalot systemd[1]: Started NFSv4 ID-name mapping service.
Jul 01 10:20:14 srvalot rpc.idmapd[260]: exiting on signal 15
Jul 01 10:20:14 srvalot systemd[1]: Stopping NFSv4 ID-name mapping service...
Jul 01 10:20:14 srvalot systemd[1]: nfs-idmapd.service: Main process exited, code=exited, status=1/FAILURE
Jul 01 10:20:14 srvalot systemd[1]: nfs-idmapd.service: Failed with result 'exit-code'.
Jul 01 10:20:14 srvalot systemd[1]: Stopped NFSv4 ID-name mapping service.
-- Boot e7c491d94ae040d2a4d35b5719ede068 --
Jul 01 10:20:50 srvalot systemd[1]: Starting NFSv4 ID-name mapping service...
Jul 01 10:20:50 srvalot rpc.idmapd[257]: Setting log level to 6
Jul 01 10:20:51 srvalot rpc.idmapd[257]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf
Jul 01 10:20:51 srvalot rpc.idmapd[257]: libnfsidmap: using (default) domain: localdomain
Jul 01 10:20:51 srvalot rpc.idmapd[257]: libnfsidmap: Realms list: 'LOCALDOMAIN'
Jul 01 10:20:51 srvalot rpc.idmapd[257]: libnfsidmap: loaded plugin /usr/lib/libnfsidmap/nsswitch.so for method nsswitch
Jul 01 10:20:51 srvalot rpc.idmapd[257]: Expiration time is 600 seconds.
Jul 01 10:20:51 srvalot rpc.idmapd[257]: Opened /proc/net/rpc/nfs4.nametoid/channel
Jul 01 10:20:51 srvalot rpc.idmapd[257]: Opened /proc/net/rpc/nfs4.idtoname/channel
Jul 01 10:20:51 srvalot systemd[1]: Started NFSv4 ID-name mapping service.
Jul 01 10:56:36 srvalot rpc.idmapd[257]: exiting on signal 15
Jul 01 10:56:36 srvalot systemd[1]: Stopping NFSv4 ID-name mapping service...
Jul 01 10:56:37 srvalot systemd[1]: nfs-idmapd.service: Main process exited, code=exited, status=1/FAILURE
Jul 01 10:56:37 srvalot systemd[1]: nfs-idmapd.service: Failed with result 'exit-code'.
Jul 01 10:56:37 srvalot systemd[1]: Stopped NFSv4 ID-name mapping service.
-- Boot 373f485d4a6d48dbaa66806deda3a7d7 --
Jul 01 10:57:10 srvalot systemd[1]: Starting NFSv4 ID-name mapping service...
Jul 01 10:57:10 srvalot rpc.idmapd[257]: Setting log level to 7
Jul 01 10:57:10 srvalot rpc.idmapd[257]: libnfsidmap: Unable to determine the NFSv4 domain; Using 'localdomain' as the NFSv4 domain which means UIDs will be mapped to the 'Nobody-User' user defined in /etc/idmapd.conf
Jul 01 10:57:10 srvalot rpc.idmapd[257]: libnfsidmap: using (default) domain: localdomain
Jul 01 10:57:10 srvalot rpc.idmapd[257]: libnfsidmap: Realms list: 'LOCALDOMAIN'
Jul 01 10:57:10 srvalot rpc.idmapd[257]: libnfsidmap: loaded plugin /usr/lib/libnfsidmap/nsswitch.so for method nsswitch
Jul 01 10:57:10 srvalot rpc.idmapd[257]: Expiration time is 600 seconds.
Jul 01 10:57:10 srvalot rpc.idmapd[257]: Opened /proc/net/rpc/nfs4.nametoid/channel
Jul 01 10:57:10 srvalot rpc.idmapd[257]: Opened /proc/net/rpc/nfs4.idtoname/channel
Jul 01 10:57:10 srvalot systemd[1]: Started NFSv4 ID-name mapping service.
lines 647-701/701 (END)
[root@srvalot hfs2]# journalctl --unit=nfs-server.service
...
-- Boot a0ad1ec38d6e4223b614ec978a6ca7f3 --
Jun 26 13:38:58 srvalot systemd[1]: Starting NFS server and services...
Jun 26 13:38:58 srvalot systemd[1]: Finished NFS server and services.
Jul 01 09:32:52 srvalot systemd[1]: Stopping NFS server and services...
Jul 01 09:32:52 srvalot systemd[1]: nfs-server.service: Deactivated successfully.
Jul 01 09:32:52 srvalot systemd[1]: Stopped NFS server and services.
Jul 01 09:32:52 srvalot systemd[1]: Starting NFS server and services...
Jul 01 09:32:53 srvalot rpc.nfsd[17688]: rpc.nfsd: Unable to request RDMA services: Protocol not supported
Jul 01 09:32:53 srvalot systemd[1]: Finished NFS server and services.
Jul 01 09:35:30 srvalot systemd[1]: Stopping NFS server and services...
Jul 01 09:35:30 srvalot systemd[1]: nfs-server.service: Deactivated successfully.
Jul 01 09:35:30 srvalot systemd[1]: Stopped NFS server and services.
-- Boot 3b6a1be21d1e4b75a06501481bb29294 --
Jul 01 09:36:24 srvalot rpc.nfsd[292]: rpc.nfsd: Unable to request RDMA services: Protocol not supported
Jul 01 09:36:21 srvalot systemd[1]: Starting NFS server and services...
Jul 01 09:36:23 srvalot systemd[1]: Finished NFS server and services.
Jul 01 10:20:13 srvalot systemd[1]: Stopping NFS server and services...
Jul 01 10:20:14 srvalot systemd[1]: nfs-server.service: Deactivated successfully.
Jul 01 10:20:14 srvalot systemd[1]: Stopped NFS server and services.
-- Boot e7c491d94ae040d2a4d35b5719ede068 --
Jul 01 10:20:58 srvalot systemd[1]: Starting NFS server and services...
Jul 01 10:20:58 srvalot rpc.nfsd[290]: rpc.nfsd: Unable to request RDMA services: Protocol not supported
Jul 01 10:20:58 srvalot systemd[1]: Finished NFS server and services.
Jul 01 10:56:36 srvalot systemd[1]: Stopping NFS server and services...
Jul 01 10:56:36 srvalot systemd[1]: nfs-server.service: Deactivated successfully.
Jul 01 10:56:36 srvalot systemd[1]: Stopped NFS server and services.
-- Boot 373f485d4a6d48dbaa66806deda3a7d7 --
Jul 01 10:57:18 srvalot systemd[1]: Starting NFS server and services...
Jul 01 10:57:18 srvalot rpc.nfsd[283]: rpc.nfsd: Unable to request RDMA services: Protocol not supported
Jul 01 10:57:18 srvalot systemd[1]: Finished NFS server and services.
lines 586-640/640 (END)
[root@srvalot hfs2]# journalctl --unit=nfsdcld.service
...
-- Boot a0ad1ec38d6e4223b614ec978a6ca7f3 --
Jul 01 09:35:31 srvalot systemd[1]: Stopping NFSv4 Client Tracking Daemon...
Jul 01 09:35:31 srvalot systemd[1]: nfsdcld.service: Deactivated successfully.
Jul 01 09:35:31 srvalot systemd[1]: Stopped NFSv4 Client Tracking Daemon.
-- Boot 3b6a1be21d1e4b75a06501481bb29294 --
Jul 01 09:36:11 srvalot systemd[1]: Starting NFSv4 Client Tracking Daemon...
Jul 01 09:36:11 srvalot systemd[1]: Started NFSv4 Client Tracking Daemon.
Jul 01 10:20:14 srvalot systemd[1]: Stopping NFSv4 Client Tracking Daemon...
Jul 01 10:20:14 srvalot systemd[1]: nfsdcld.service: Deactivated successfully.
Jul 01 10:20:14 srvalot systemd[1]: Stopped NFSv4 Client Tracking Daemon.
-- Boot e7c491d94ae040d2a4d35b5719ede068 --
Jul 01 10:20:50 srvalot systemd[1]: Starting NFSv4 Client Tracking Daemon...
Jul 01 10:20:51 srvalot systemd[1]: Started NFSv4 Client Tracking Daemon.
Jul 01 10:56:37 srvalot systemd[1]: Stopping NFSv4 Client Tracking Daemon...
Jul 01 10:56:37 srvalot systemd[1]: nfsdcld.service: Deactivated successfully.
Jul 01 10:56:37 srvalot systemd[1]: Stopped NFSv4 Client Tracking Daemon.
-- Boot 373f485d4a6d48dbaa66806deda3a7d7 --
Jul 01 10:57:10 srvalot systemd[1]: Starting NFSv4 Client Tracking Daemon...
Jul 01 10:57:10 srvalot systemd[1]: Started NFSv4 Client Tracking Daemon.
lines 455-509/509 (END)
')
I didn't have much success increasing log verbosity. I did change /etc/idmapd.conf Verbosity from 0 to 7. I also looked at /etc/nfs.conf and etc/nfsmount.conf for clues about /proc/fs/nfsd/clients, as well as increased verbosity, and didn't see anything that looked useful to me.
verbose fail
$this->bbcode_second_pass_code('', '[root@srvalot hfs2]# systemctl service-log-level nfs-idmapd.service
Unit nfs-idmapd.service doesn't declare BusName=.
Hint: the service must declare BusName= and implement the appropriate D-Bus interface.
See the org.freedesktop.LogControl1(5) man page for details.
[root@srvalot hfs2]# systemctl service-log-level nfs-mountd.service
Unit nfs-mountd.service doesn't declare BusName=.
Hint: the service must declare BusName= and implement the appropriate D-Bus interface.
See the org.freedesktop.LogControl1(5) man page for details.
[root@srvalot hfs2]# systemctl service-log-level nfs-server.service
Unit nfs-server.service doesn't declare BusName=.
Hint: the service must declare BusName= and implement the appropriate D-Bus interface.
See the org.freedesktop.LogControl1(5) man page for details.
[root@srvalot hfs2]# systemctl service-log-level nfsdcld.service
Unit nfsdcld.service doesn't declare BusName=.
Hint: the service must declare BusName= and implement the appropriate D-Bus interface.
See the org.freedesktop.LogControl1(5) man page for details.
')