I have a fresh FreePBX deployment here. The first thing I did once I rearched the admin interface, was set up FTP in the filestore, then create a backup. I then made an extension so I can see if a restore worked. I then run that backup, it works great, Filezilla Server shows normal behaviour without errors, and I can see the backup on the FTP server machine.
If I then go to FreePBX backup and restore, I can see in the “restore from the cloud” section the backup I had just made. All details are correct. I hit the play icon to restore the backup. It fails.
The FreePBX admin interface says:
Running with: /usr/sbin/fwconsole backup --filestore='974c7f48-52ea-46d6-9a12-0b2c44c8c517' --restore='ftp 55186644 May 21 20:28 20220521-192726-1653161246-16.0.19-309585354.tar.gz' --transaction='b976a2a6-e782-440a-bb66-499b32e0aa32'
Retrieving ftp 55186644 May 21 20:28 20220521-192726-1653161246-16.0.19-309585354.tar.gz from FTP:Windows VPS...
In Filesystem.php line 389:
File not found at path: ftp 55186644 May 21 20:28 20220521-192726-165
3161246-16.0.19-309585354.tar.gz
And the Filezilla Server says:
(000499)21/05/2022 20:43:45 - (not logged in) (REMOVED-MY-IP)> AUTH TLS
(000499)21/05/2022 20:43:45 - (not logged in) (REMOVED-MY-IP)> 234 Using authentication type TLS
(000499)21/05/2022 20:43:45 - (not logged in) (REMOVED-MY-IP)> TLS connection established
(000499)21/05/2022 20:43:45 - (not logged in) (REMOVED-MY-IP)> PBSZ 0
(000499)21/05/2022 20:43:45 - (not logged in) (REMOVED-MY-IP)> 200 PBSZ=0
(000499)21/05/2022 20:43:45 - (not logged in) (REMOVED-MY-IP)> PROT P
(000499)21/05/2022 20:43:45 - (not logged in) (REMOVED-MY-IP)> 200 Protection level set to P
(000499)21/05/2022 20:43:45 - (not logged in) (REMOVED-MY-IP)> USER FreePBX
(000499)21/05/2022 20:43:45 - (not logged in) (REMOVED-MY-IP)> 331 Password required for freepbx
(000499)21/05/2022 20:43:45 - (not logged in) (REMOVED-MY-IP)> PASS **********
(000499)21/05/2022 20:43:45 - freepbx (REMOVED-MY-IP)> 230 Logged on
(000499)21/05/2022 20:43:45 - freepbx (REMOVED-MY-IP)> PASV
(000499)21/05/2022 20:43:45 - freepbx (REMOVED-MY-IP)> 227 Entering Passive Mode (207,180,193,163,43,69)
(000499)21/05/2022 20:43:45 - freepbx (REMOVED-MY-IP)> CWD /
(000499)21/05/2022 20:43:45 - freepbx (REMOVED-MY-IP)> 250 CWD successful. "/" is current directory.
(000499)21/05/2022 20:43:45 - freepbx (REMOVED-MY-IP)> PWD
(000499)21/05/2022 20:43:45 - freepbx (REMOVED-MY-IP)> 257 "/" is current directory.
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> HELP
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> 214-The following commands are recognized:
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> ABOR ADAT ALLO APPE AUTH CDUP CLNT CWD
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> DELE EPRT EPSV FEAT HASH HELP LIST MDTM
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> MFMT MKD MLSD MLST MODE NLST NOOP NOP
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> OPTS PASS PASV PBSZ PORT PROT PWD QUIT
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> REST RETR RMD RNFR RNTO SITE SIZE STOR
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> STRU SYST TYPE USER XCUP XCWD XMKD XPWD
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> XRMD
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> 214 Have a nice day.
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> CWD ftp 55186644 May 21 20:28 20220521-192726-1653161246-16.0.19-309585354.tar.gz
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> 550 CWD failed. "/ftp 55186644 May 21 20:28 20220521-192726-1653161246-16.0.19-309585354.tar.gz": directory not found.
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> NOOP
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> 200 OK
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> TYPE A
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> 200 Type set to A
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> LIST -A ftp 55186644 May 21 20:28 20220521-192726-1653161246-16.0.19-309585354.tar.gz
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> 550 Directory not found.
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> QUIT
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> 221 Goodbye
(000499)21/05/2022 20:43:46 - freepbx (REMOVED-MY-IP)> disconnected.
But the file is there, and is completely untouched from the backup I made only minutes before. If I try the download button intead, it downloads a 0 bytes file.
I then tried to upload the file under “Upload your restore files”. This restore takes forever, way longer than any other restore I’ve ever done on any other system, and it then fails. The log is huge so I won’t paste it all here, but this is the last bit:
Reloading...... DONE
Running Post Restore Hooks.. Please note that hook will restart httpd service so please refresh your page (using new ports)
Running Post Restore Hooks DONE
Finished
There were warnings during the restore process
Can't find the module data for ttsengines
Can't find the module data for callback
Can't find the module data for callerid
Can't find the module data for calllimit
Can't find the module data for conferencespro
Can't find the module data for miscdests
Can't find the module data for phonebook
Can't find the module data for setcid
npm WARN deprecated [email protected]: Package no longer supported. Contact [email protected] for more info.
npm WARN deprecated [email protected]: This version of 'is-buffer' is out-of-date. You must update to v1.1.6 or newer
The backup did remove the extension, so it seems some of it did work, but for both restores to fail on a fresh system, as a new FreePBX user, is extremely concerning.
If I did do any future deployments, I wouldn’t feel comfortable doing it anywhere else than a Virtual Machine where I could use the VM’s snapshot features to restore a backup which would also be significantly faster. A bare metal install is clearly a poor idea.