Backup not completing

Tagged: Backup Failure

Viewing 3 posts - 1 through 3 (of 3 total)

Author

Posts
September 7, 2021 at 7:26 pm #42540
Robert Bedford
Guest
For some reason, my backups have stopped completing. They report completed. The log files report completion, but the directory still has temporary files .sql files, but not zip files.

In the backup directory:
```
$ ls -alt
total 801304
drwx--x--- 33 bedfordr adm           4096 Sep  6 06:29 ..
drwx------  4 bedfordr pg838920      4096 Sep  6 05:29 .
-rw-------  1 bedfordr pg838920  67773118 Sep  6 05:21 boldgrid-backup-preview.2022.grcanational.com-7644495c-20210906-122001.zip.T3up7f
-rw-------  1 bedfordr pg838920   2927385 Sep  6 05:19 preview_2022_grcanationa_1.20210906-121952.sql
drwx------  2 bedfordr pg838920      4096 Sep  6 05:19 logs-7644495c-2678a6b491d338ba
-rw-------  1 bedfordr pg838920  41670281 Sep  6 04:20 boldgrid-backup-preview.2022.grcanational.com-7644495c-20210906-111917.zip.ynEidq
-rw-------  1 bedfordr pg838920   2868764 Sep  6 04:18 preview_2022_grcanationa_1.20210906-111818.sql
-rw-r--r--  1 bedfordr pg838920      1107 Jul 22 22:51 boldgrid-backup-preview.2022.grcanational.com-7644495c-20210723-055023.log
-rw-r--r--  1 bedfordr pg838920 142307239 Jul 22 22:51 boldgrid-backup-preview.2022.grcanational.com-7644495c-20210723-055023.zip
...
```
In the log file from Sept 6, I see:
```
$ cat archive-1630927096.log

[2021-09-06 11:18:17 UTC] PHP Version: 7.4.20
[2021-09-06 11:18:17 UTC] WordPress Version: 5.8
[2021-09-06 11:18:17 UTC] Total Upkeep version: 1.14.12
[2021-09-06 11:18:17 UTC] Backup process initialized.
[2021-09-06 11:18:18 UTC] --------------------------------------------------------------------------------
[2021-09-06 11:18:18 UTC] Starting dump of database...
[2021-09-06 11:18:18 UTC] Memory usage - limit / current / peak memory usage: 1073741824 / 63510952 (60.57 MB) / 64276176 (61 MB)
[2021-09-06 11:18:18 UTC] Database info: Array
(
[...Truncated for length...]
)

[2021-09-06 11:18:19 UTC] Dump of database complete! $status = 1
[2021-09-06 11:18:19 UTC] Memory usage - limit / current / peak memory usage: 1073741824 / 63515000 (60.57 MB) / 67218264 (64 MB)
[2021-09-06 11:18:19 UTC] --------------------------------------------------------------------------------
[2021-09-06 11:19:17 UTC] Database dump file added to file list: ~/boldgrid_backup_preview/preview_2022_grcanationa_1.20210906-111818.sql / 2868764 (2.74 MB)
[2021-09-06 11:19:17 UTC] Starting archiving of files. Chosen compressor: php_zip
[2021-09-06 11:19:17 UTC] Memory usage - limit / current / peak memory usage: 1073741824 / 78032496 (74.42 MB) / 79603856 (76 MB)
[2021-09-06 11:19:23 UTC] Total files / size archived: 21102 / 363404310 (346.57 MB)
[2021-09-06 11:19:23 UTC] Starting to close the zip file.
[2021-09-06 11:19:23 UTC] Memory usage - limit / current / peak memory usage: 1073741824 / 78546344 (74.91 MB) / 79603856 (76 MB)
```
It generates an sql file and claims to close the zip file, but doesn’t seem to complete that. Any help would be appreciated!
September 7, 2021 at 7:33 pm #42570

Jesse Owens
Keymaster

Hi Robert-

Thanks for reaching out, and thanks for including all of the detailed log information.

First off, I’d like to recommend updating to Total Upkeep to the latest version, 1.14.13, because that update includes more detailed logging for errors like this that might help us troubleshoot further.

Specifically in this log, we see that it was Starting to close the zip file, but there should be another line that confirms the zip file finished closing successfully. The log ends prematurely without any additional errors or information being logged.

We have a detailed guide on steps you can take to troubleshoot this issue. In your case, the most likely step to help is switching the Zip Compressor to System Zip, if it’s available.

The most common cause of this problem is that the backup process was terminated by the server without any errors. Usually this is due to resource limitations. Often, using the system’s native zip command will be successful where the PHP Zip fails in this case.

September 13, 2021 at 4:30 pm #42688

Robert Bedford
Guest

Thank you, @Jesse!

I changed the zip file to system_zip and that seems to have done the trick, just as you said. The zip closing in the log file now looks like:

[2021-09-11 11:31:07 UTC] Starting to close the zip file. [2021-09-11 11:31:07 UTC] Memory usage - limit / current / peak memory usage: 1073741824 / 77168216 (73.59 MB) / 82494896 (79 MB) [2021-09-11 11:31:08 UTC] Chunk closed in 1.1332030296326 seconds. 7% complete closing [2021-09-11 11:31:08 UTC] Memory usage - limit / current / peak memory usage: 1073741824 / 78317088 (74.69 MB) / 82494896 (79 MB) [2021-09-11 11:31:09 UTC] Chunk closed in 1.7428970336914 seconds. 14% complete closing [2021-09-11 11:31:09 UTC] Memory usage - limit / current / peak memory usage: 1073741824 / 78435872 (74.80 MB) / 82494896 (79 MB) [2021-09-11 11:31:11 UTC] Chunk closed in 1.7038679122925 seconds. 21% complete closing [2021-09-11 11:31:11 UTC] Memory usage - limit / current / peak memory usage: 1073741824 / 78267936 (74.64 MB) / 82494896 (79 MB) [2021-09-11 11:31:13 UTC] Chunk closed in 1.8084840774536 seconds. 29% complete closing [2021-09-11 11:31:13 UTC] Memory usage - limit / current / peak memory usage: 1073741824 / 78374432 (74.74 MB) / 82494896 (79 MB) [2021-09-11 11:31:15 UTC] Chunk closed in 2.0033979415894 seconds. 36% complete closing [2021-09-11 11:31:15 UTC] Memory usage - limit / current / peak memory usage: 1073741824 / 78370336 (74.74 MB) / 82494896 (79 MB) [2021-09-11 11:31:17 UTC] Chunk closed in 1.9400398731232 seconds. 43% complete closing [2021-09-11 11:31:17 UTC] Memory usage - limit / current / peak memory usage: 1073741824 / 78362144 (74.73 MB) / 82494896 (79 MB) [2021-09-11 11:31:25 UTC] Chunk closed in 8.1402881145477 seconds. 50% complete closing [2021-09-11 11:31:25 UTC] Memory usage - limit / current / peak memory usage: 1073741824 / 78292512 (74.67 MB) / 82494896 (79 MB) [2021-09-11 11:31:43 UTC] Chunk closed in 18.254516124725 seconds. 57% complete closing [2021-09-11 11:31:43 UTC] Memory usage - limit / current / peak memory usage: 1073741824 / 78374432 (74.74 MB) / 82494896 (79 MB) [2021-09-11 11:32:01 UTC] Chunk closed in 17.486237049103 seconds. 64% complete closing [2021-09-11 11:32:01 UTC] Memory usage - limit / current / peak memory usage: 1073741824 / 78333472 (74.70 MB) / 82494896 (79 MB) [2021-09-11 11:32:23 UTC] Chunk closed in 21.86914396286 seconds. 71% complete closing [2021-09-11 11:32:23 UTC] Memory usage - limit / current / peak memory usage: 1073741824 / 78374432 (74.74 MB) / 82494896 (79 MB) [2021-09-11 11:32:36 UTC] Chunk closed in 13.121779918671 seconds. 79% complete closing [2021-09-11 11:32:36 UTC] Memory usage - limit / current / peak memory usage: 1073741824 / 78296608 (74.67 MB) / 82494896 (79 MB) [2021-09-11 11:32:54 UTC] Chunk closed in 17.717432975769 seconds. 86% complete closing [2021-09-11 11:32:54 UTC] Memory usage - limit / current / peak memory usage: 1073741824 / 78280224 (74.65 MB) / 82494896 (79 MB)

I will spend a little more time looking at the trouble-shooting guide to make sure things are working the best that they can.

I truly appreciate your help.
Author

Posts