Backup - Requested path not found?

fausp · October 25, 2018, 7:13am

NethServer Version: 7.5

Suddenly I get an error from the Backup-Module when I try to backup data to an NFS-Share… I guess it has something to do with - Requested path not found - ?

Oct 25 09:10:04 neth30 esmith::event[11230]: ===== Report for configuration backup =====
Oct 25 09:10:04 neth30 esmith::event[11230]:
Oct 25 09:10:04 neth30 esmith::event[11230]: Backup started at 2018-10-25 09:10:04
Oct 25 09:10:04 neth30 esmith::event[11233]: Event: pre-backup-config
Oct 25 09:10:04 neth30 esmith::event[11233]: expanding /etc/backup-config.d/nethserver-sssd.include
Oct 25 09:10:04 neth30 dbus[603]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
Oct 25 09:10:04 neth30 systemd: Starting Time & Date Service...
Oct 25 09:10:04 neth30 dbus[603]: [system] Successfully activated service 'org.freedesktop.timedate1'
Oct 25 09:10:04 neth30 systemd: Started Time & Date Service.
Oct 25 09:10:04 neth30 esmith::event[11233]: Action: /etc/e-smith/events/actions/generic_template_expand SUCCESS [0.637475]
Oct 25 09:10:06 neth30 esmith::event[11233]: Action: /etc/e-smith/events/pre-backup-config/S40nethserver-dc-pre-backup SUCCESS [1.799567]
Oct 25 09:10:06 neth30 esmith::event[11233]: Action: /etc/e-smith/events/pre-backup-config/S40nethserver-mail-shrmbx-cfgbackup SUCCESS [0.036265]
Oct 25 09:10:06 neth30 esmith::event[11233]: Action: /etc/e-smith/events/pre-backup-config/S40nethserver-sssd-backup-tdb SUCCESS [0.013172]
Oct 25 09:10:09 neth30 esmith::event[11233]: Action: /etc/e-smith/events/pre-backup-config/S50nethserver-backup-config-list-packages SUCCESS [2.367111]
Oct 25 09:10:09 neth30 esmith::event[11233]: Event: pre-backup-config SUCCESS
Oct 25 09:10:09 neth30 esmith::event[11230]: Event pre-backup-config: SUCCESS
Oct 25 09:10:23 neth30 esmith::event[11230]: Action backup-config-execute: SUCCESS
Oct 25 09:10:23 neth30 esmith::event[11383]: Event: post-backup-config
Oct 25 09:10:23 neth30 esmith::event[11383]: Action: /etc/e-smith/events/post-backup-config/S40nethserver-dc-post-backup SUCCESS [0.079249]
Oct 25 09:10:23 neth30 esmith::event[11383]: Event: post-backup-config SUCCESS
Oct 25 09:10:23 neth30 esmith::event[11230]: Event post-backup-config: SUCCESS
Oct 25 09:10:23 neth30 esmith::event[11230]: Backup status: SUCCESS
Oct 25 09:10:23 neth30 esmith::event[11230]: Backup ended at 2018-10-25 09:10:23
Oct 25 09:10:23 neth30 esmith::event[11230]: Time elapsed: 0 hours, 0 minutes, 19 seconds
Oct 25 09:10:23 neth30 esmith::event[11230]: Action: /etc/e-smith/events/pre-backup-data/S20nethserver-backup-config-predatabackup SUCCESS [19.959714]
Oct 25 09:10:26 neth30 esmith::event[11230]: Requested path not found
Oct 25 09:10:26 neth30 esmith::event[11230]: Action: /etc/e-smith/events/pre-backup-data/S20nethserver-restore-data-duc-index SUCCESS [3.409179]
Oct 25 09:10:27 neth30 esmith::event[11230]: Action: /etc/e-smith/events/pre-backup-data/S50mysql-dump-tables SUCCESS [0.840749]
Oct 25 09:10:27 neth30 esmith::event[11230]: Action: /etc/e-smith/events/pre-backup-data/S50nethserver-ibays-dump-acls SUCCESS [0.029663]
Oct 25 09:10:27 neth30 su: (to postgres) root on none
Oct 25 09:10:29 neth30 esmith::event[11230]: Action: /etc/e-smith/events/pre-backup-data/S50nethserver-zabbix-backup SUCCESS [1.823477]
Oct 25 09:10:30 neth30 esmith::event[11230]: Action: /etc/e-smith/events/pre-backup-data/S70mount-cifs SUCCESS [0.468075]
Oct 25 09:10:30 neth30 esmith::event[11230]: Action: /etc/e-smith/events/pre-backup-data/S70mount-nfs SUCCESS [0.478474]
Oct 25 09:10:31 neth30 esmith::event[11230]: Action: /etc/e-smith/events/pre-backup-data/S70mount-usb SUCCESS [0.445613]
Oct 25 09:10:31 neth30 esmith::event[11230]: Action: /etc/e-smith/events/pre-backup-data/S70mount-webdav SUCCESS [0.453453]
Oct 25 09:10:31 neth30 esmith::event[11230]: Event: pre-backup-data SUCCESS
Oct 25 09:12:49 neth30 dbus[603]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
Oct 25 09:12:49 neth30 systemd: Starting Time & Date Service...

grafik

Any suggestions ?

m.traeumner · October 25, 2018, 8:57am

Hi @fausp,
could you try to mount the shared folder at the terminal and put the output here.
Have you other computers in your network, so you could also try to mount it from the other computers to find out if the problem is at the nethserver side or at the backup destination.

fausp · October 25, 2018, 10:39am

[root@neth30 ~]# mount -t nfs <my-nfs-server-ip>:/nfsbackup /media/test/
[root@neth30 ~]#
[root@neth30 ~]# mount
...
<my-nfs-server-ip>:nfsbackup/ on /mnt/backup-backup-data type nfs (rw,relatime,vers=3,rsize=524288,wsize=524288,namlen=255,hard,nolock,proto=tcp,timeo=600,retrans=2,sec=sys,mountaddr=<my-nfs-server-ip>,mountvers=3,mountport=30000,mountproto=udp,local_lock=all,addr=<my-nfs-server-ip>)
...
<my-nfs-server-ip>:/nfsbackup on /media/test type nfs (rw,relatime,vers=3,rsize=524288,wsize=524288,namlen=255,hard,proto=tcp,timeo=600,retrans=2,sec=sys,mountaddr=<my-nfs-server-ip>,mountvers=3,mountport=30000,mountproto=udp,local_lock=none,addr=<my-nfs-server-ip>)

The mount (/media/test) went well

m.traeumner · October 25, 2018, 12:58pm

I’ve found another guy with problems at the backup to nfs, perhaps this can help you too:

fausp · October 26, 2018, 9:16am

In the meantime I installed a new Server and updated it… NFS-Backup works on the new Server.

I still have problems on the old server:

Oct 26 11:12:04 neth30 esmith::event[2433]: ===== Report for configuration backup =====
Oct 26 11:12:04 neth30 esmith::event[2433]:
Oct 26 11:12:04 neth30 esmith::event[2433]: Backup started at 2018-10-26 11:12:04
Oct 26 11:12:04 neth30 esmith::event[2436]: Event: pre-backup-config
Oct 26 11:12:04 neth30 esmith::event[2436]: expanding /etc/backup-config.d/nethserver-sssd.include
Oct 26 11:12:04 neth30 dbus[600]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service'
Oct 26 11:12:04 neth30 systemd: Starting Time & Date Service...
Oct 26 11:12:05 neth30 dbus[600]: [system] Successfully activated service 'org.freedesktop.timedate1'
Oct 26 11:12:05 neth30 systemd: Started Time & Date Service.
Oct 26 11:12:05 neth30 esmith::event[2436]: Action: /etc/e-smith/events/actions/generic_template_expand SUCCESS [0.651221]
Oct 26 11:12:06 neth30 esmith::event[2436]: Action: /etc/e-smith/events/pre-backup-config/S40nethserver-dc-pre-backup SUCCESS [1.848048]
Oct 26 11:12:07 neth30 esmith::event[2436]: Action: /etc/e-smith/events/pre-backup-config/S40nethserver-mail-shrmbx-cfgbackup SUCCESS [0.0857599999999999]
Oct 26 11:12:07 neth30 esmith::event[2436]: Action: /etc/e-smith/events/pre-backup-config/S40nethserver-sssd-backup-tdb SUCCESS [0.019088]
Oct 26 11:12:15 neth30 esmith::event[2436]: Action: /etc/e-smith/events/pre-backup-config/S50nethserver-backup-config-list-packages SUCCESS [8.208406]
Oct 26 11:12:15 neth30 esmith::event[2436]: Event: pre-backup-config SUCCESS
Oct 26 11:12:15 neth30 esmith::event[2433]: Event pre-backup-config: SUCCESS
Oct 26 11:12:28 neth30 esmith::event[2433]: Action backup-config-execute: SUCCESS
Oct 26 11:12:28 neth30 esmith::event[2567]: Event: post-backup-config
Oct 26 11:12:28 neth30 esmith::event[2567]: Action: /etc/e-smith/events/post-backup-config/S40nethserver-dc-post-backup SUCCESS [0.065726]
Oct 26 11:12:28 neth30 esmith::event[2567]: Event: post-backup-config SUCCESS
Oct 26 11:12:28 neth30 esmith::event[2433]: Event post-backup-config: SUCCESS
Oct 26 11:12:28 neth30 esmith::event[2433]: Backup status: SUCCESS
Oct 26 11:12:28 neth30 esmith::event[2433]: Backup ended at 2018-10-26 11:12:28
Oct 26 11:12:28 neth30 esmith::event[2433]: Time elapsed: 0 hours, 0 minutes, 24 seconds
Oct 26 11:12:28 neth30 esmith::event[2433]: Action: /etc/e-smith/events/pre-backup-data/S20nethserver-backup-config-predatabackup SUCCESS [24.960292]
Oct 26 11:12:29 neth30 systemd: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 2590 (duc)
Oct 26 11:12:29 neth30 systemd: Mounting Arbitrary Executable File Formats File System...
Oct 26 11:12:29 neth30 systemd: Mounted Arbitrary Executable File Formats File System.
Oct 26 11:12:55 neth30 esmith::event[2433]: Requested path not found
Oct 26 11:12:55 neth30 esmith::event[2433]: Action: /etc/e-smith/events/pre-backup-data/S20nethserver-restore-data-duc-index SUCCESS [27.284822]
Oct 26 11:12:58 neth30 esmith::event[2433]: Action: /etc/e-smith/events/pre-backup-data/S50mysql-dump-tables SUCCESS [2.042357]
Oct 26 11:12:58 neth30 esmith::event[2433]: Action: /etc/e-smith/events/pre-backup-data/S50nethserver-ibays-dump-acls SUCCESS [0.063693]
Oct 26 11:12:58 neth30 su: (to postgres) root on none
Oct 26 11:13:02 neth30 esmith::event[2433]: Action: /etc/e-smith/events/pre-backup-data/S50nethserver-zabbix-backup SUCCESS [4.287241]
Oct 26 11:13:02 neth30 esmith::event[2433]: Action: /etc/e-smith/events/pre-backup-data/S70mount-cifs SUCCESS [0.56369]
Oct 26 11:13:03 neth30 kernel: FS-Cache: Loaded
Oct 26 11:13:03 neth30 kernel: FS-Cache: Netfs 'nfs' registered for caching
Oct 26 11:13:03 neth30 kernel: Key type dns_resolver registered
Oct 26 11:13:03 neth30 kernel: NFS: Registering the id_resolver key type
Oct 26 11:13:03 neth30 kernel: Key type id_resolver registered
Oct 26 11:13:03 neth30 kernel: Key type id_legacy registered
Oct 26 11:13:03 neth30 rpc.gssd[657]: ERROR: can't openat nfs/clnt0: No such file or directory
Oct 26 11:13:03 neth30 esmith::event[2433]: Action: /etc/e-smith/events/pre-backup-data/S70mount-nfs SUCCESS [0.992796]
Oct 26 11:13:04 neth30 esmith::event[2433]: Action: /etc/e-smith/events/pre-backup-data/S70mount-usb SUCCESS [0.454467]
Oct 26 11:13:04 neth30 esmith::event[2433]: Action: /etc/e-smith/events/pre-backup-data/S70mount-webdav SUCCESS [0.468154]
Oct 26 11:13:04 neth30 esmith::event[2433]: Event: pre-backup-data SUCCESS