Backup plugin event system command is not working properly

Update:

Did a reboot and now it's working again. :person_shrugging:
Still, I would like to know what was happening.

2024-09-16 02:52:24,900 - octoprint.plugins.backup - INFO - Creating backup zip at /home/pi/.octoprint/data/backup/.Ender3-daily-20240916-025224.zip (excluded: uploads,timelapse,timelapse_tmp)...
2024-09-16 02:52:29,519 - octoprint.plugins.backup - INFO - ... done creating backup zip.
2024-09-16 02:52:29,521 - octoprint.events.fire - DEBUG - Firing event: plugin_backup_backup_created (Payload: {'name': 'Ender3-daily-20240916-025224.zip', 'path': '/home/pi/.octoprint/data/backup/Ender3-daily-20240916-025224.zip', 'excludes': ['uploads', 'timelapse', 'timelapse_tmp']})
2024-09-16 02:52:29,521 - octoprint.events - DEBUG - Sending action to <bound method CommandTrigger.eventCallback of <octoprint.events.CommandTrigger object at 0xaf028550>>
2024-09-16 02:52:29,524 - octoprint.events - INFO - Executing system command: mv /home/pi/.octoprint/data/backup/Ender3-daily-20240916-025224.zip /mnt/Unraid/OctoPiBackups/
2024-09-16 02:52:29,524 - octoprint.events - DEBUG - Sending action to <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0xa2223880>>

Original post:

What is the problem?

Event plugin_backup_backup_created is failing to move files to my NAS.

2024-09-16 02:20:10,305 - octoprint.plugins.backup - INFO - Creating backup zip at /home/pi/.octoprint/data/backup/.Ender3-daily-20240916-022010.zip (excluded: uploads,timelapse,timelapse_tmp)...
2024-09-16 02:20:14,942 - octoprint.plugins.backup - INFO - ... done creating backup zip.
2024-09-16 02:20:14,943 - octoprint.events.fire - DEBUG - Firing event: plugin_backup_backup_created (Payload: {'name': 'Ender3-daily-20240916-022010.zip', 'path': '/home/pi/.octoprint/data/backup/Ender3-daily-20240916-022010.zip', 'excludes': ['uploads', 'timelapse', 'timelapse_tmp']})
2024-09-16 02:20:14,944 - octoprint.events - DEBUG - Sending action to <bound method CommandTrigger.eventCallback of <octoprint.events.CommandTrigger object at 0xaf05d508>>
2024-09-16 02:20:14,945 - octoprint.events - INFO - Executing a system command
2024-09-16 02:20:14,949 - octoprint.events - DEBUG - Sending action to <bound method PrinterStateConnection._onEvent of <octoprint.server.util.sockjs.PrinterStateConnection object at 0x9eb10400>>
2024-09-16 02:20:14,987 - octoprint.events - WARNING - Command failed with return code 1, enable debug logging on target 'octoprint.events' for details

This is the fstab mount to that network path:

//unraid.nas/Backups/RaspberryPi/OctoPi/AutomatedBackups        /mnt/unraid/OctoPiBackups       cifs    vers=3.1.1,credentials=/home/pi/.smb/ghost,rw,iocharset=utf8,file_mode=0600,dir_mode=0700,uid=pi,gid=pi,noexec,nosuid    0       0

And it's properly mounted and showing what's in the remote host:

image

What did you already try to solve it?

Enabled debug logging for octoprint.events and enabled debug in the event. Tried several commands. Ensued that the paths exists and everything is mounted.

Have you tried running in safe mode? Did running in safe mode solve the problem?

No. This is related to a plugin.

Systeminfo Bundle

octoprint-systeminfo-20240916023248.zip (2.0 MB)

Additional information about your setup

  • OctoPrint version: OctoPrint 1.10.2.
  • OctoPi version: based on OctoPi 1.0.0cam (build 2023.06.27.083607) running on Raspberry Pi 4 Model B Rev 1.4.
  • Python 3.9.2.

I forgot to say that this has been working perfectly for months. Last backup in my server is from back the 25th of august of 2024 so SOMETHING changed and no longer works.

Update:

Did a reboot and now it's working again. :person_shrugging:
Still, I would like to know what was happening.

Update 2:

image

Damn I think I have become blind to yellow warning signs. :man_facepalming:
I wasted one hour on this for nothing HAHAHA