PHP Error and ZIP Problems prevents backup

  • Resolved mg2c

    (@mg2c)


    10 years, 9 months ago

    I just discovered an error, that a.) does not allow “Updraftplus” to complete backups and b.) seems to produce a PHP error, that I can see in the PHP error log of BPSPro. First I thought, this was caused by BSPPro security plugin blocking some function of Updraftplus, but after completely deinstalling BPSPro and reinstalling Updraftplus (incl. wiping settings), the problem persists.

    The php error reads like this and is repeatedly shown in the error log:

    PHP Warning: popen(cd '/home/xxxxxxxxxxx/public_html/xxxx/wp-content/updraft'; /usr/bin/zip -n .jpg:.JPG:.jpeg:.JPEG:.png:.PNG:.gif:.GIF:.zip:.ZIP:.gz:.bz2:.xz.:.rar:.RAR:.mp3:.MP3:.mp4:.MP4:.mpeg:.MPEG:.avi:.AVI:.mov:.MOV -v -u -r binziptest/test.zip binziptest/subdir1,r): Resource temporarily unavailable in /home/xxxxxxxx/public_html/xxxx/wp-content/plugins/updraftplus/class-updraftplus.php on line 937

    Updraftplus has been working flawlessly since I installed it a year ago, the error seem to have slipped in since about 1 – 2 months or so, without me noticing it. I was running the 4.2.4 Version of wordpress, now I updated to 4.3, the error persists

    As I can see via ftp-ing into my server, updraftplus creates the first set of smaller zip-files properly in its updraft-folder, but once it starts zipping up the largest set (the directory “uploads”) it stops adding chunks to that very zip file at about 120 MB (final zip-file should have about 230MB). So this happens BEFORE anything is uploaded to google drive (remote storage). Since I have not hugely altered my website for a few months, the “uploads” directory has not increased in size very much – so I guess, since backup has worked, that size might not the culprit here.

    Would you mind me helping to sort out, what measures to take and how to correct the problem?

    https://ww.wp.xz.cn/plugins/updraftplus/

Viewing 10 replies - 1 through 10 (of 10 total)
  • Thread Starter mg2c

    (@mg2c)

    10 years, 9 months ago

    Addendum: while a manual backup process is running, sometimes my website and the admin page is not reachable, I do get a server misconfiguration error 500 page….
    After a few minutes, by constantly reloading the page, it will eventually come back online….

    Thread Starter mg2c

    (@mg2c)

    10 years, 9 months ago

    Update: furthermore, the next resumption counts NEGATIVE values such as:

    Wed, August 26, 2015 16:42 – next resumption: 1 (after -155s)

    Thread Starter mg2c

    (@mg2c)

    10 years, 9 months ago

    Update: even lowering the filesize value from the default 500MB, that were working once, to 80MB did not help.

    Plugin Author David Anderson / Team Updraft

    (@davidanderson)

    10 years, 9 months ago

    It sounds like your web hosting server doesn’t provide the resources to create larger zips. Do this:

    1) Go to the UpdraftPlus settings tab

    2) Scroll to the bottom, and open the ‘expert’ section

    3) Reduce the ‘split’ size down to 60Mb, and then save the settings

    Then start a fresh backup.

    Best wishes,
    David

    Plugin Author David Anderson / Team Updraft

    (@davidanderson)

    10 years, 9 months ago

    Looks like our postings crossed. Please can you provide a link to your log file for the problematic backup \with the lower split value)? (You can download it from the “Existing Backups” tab of your UpdraftPlus settings page).

    It’ll be too long to paste into the forum here, but you can download it to your computer, and share it with Dropbox, or paste it in pastebin.com, or any similar service, and post the link here.

    Best wishes,
    David

    Thread Starter mg2c

    (@mg2c)

    10 years, 9 months ago

    David,
    Thanks for the answer. I try to lower from 80MB to 60MB.
    But let me point out: it has been working for over a year with the default settings (500MB), I never had a problem, that ua backup would not have been created,no zip-problems, there were no PHP errors and such .
    This only must have happened within the last months. My assumption is, that this MIGHT have been caused by some update of the plug-in – could that be?

    Thread Starter mg2c

    (@mg2c)

    10 years, 9 months ago

    David

    here is part of the log file (still running). Important: starting a backup seems to crash the server for some time. When I reload the page (since there’s no display of the progress bar for some time), I do get a blank page with a server misconfiguration warning.

    [ Moderator note: code fixed. Please wrap code in the backtick character or use the code button. That includes log data. ]

    Here’s the log so far:

    0000.000 (0) Opened log file at time: Wed, 26 Aug 2015 23:20:49 +0000 on http://xxxxxxxxxxxx
0000.004 (0) UpdraftPlus WordPress backup plugin (https://updraftplus.com): 1.11.5 WP: 4.3 PHP: 5.5.26 (Linux d112.x-mailer.de 3.2.54-pn #1 SMP Wed Jan 22 10:39:43 CET 2014 i686) MySQL: 5.0.51 Server: Apache safe_mode: 0 max_execution_time: 900 memory_limit: 256M (used: 25.2M | 25.5M) multisite: N mcrypt: Y LANG:  ZipArchive::addFile: Y
0000.006 (0) Free space on disk containing Updraft's temporary directory: 93942 Mb
0000.010 (0) Tasks: Backup files: 1 (schedule: weekly) Backup DB: 1 (schedule: weekly)
0000.014 (0) Requesting semaphore lock (fd)
0000.015 (0) Semaphore (fd) was stuck, set lock time to 2015-08-26 23:20:49
0000.017 (0) Semaphore (fd) reset to 1
0000.018 (0) Set semaphore last lock (fd) time to 2015-08-26 23:20:49
0000.019 (0) Semaphore lock (fd) complete
0000.024 (0) Backup run: resumption=0, nonce=7893c5ddd32b, begun at=1440631249 (0s ago), job type=backup
0000.026 (0) Scheduling a resumption (1) after 300 seconds (1440631549) in case this run gets aborted
0000.037 (0) Checking if we have a zip executable available
0000.038 (0) Testing: /usr/bin/zip
0000.039 (0) PHP event: code E_WARNING: popen(cd '/home/xxxxxxxxxx/public_html/xxxxxx/wp-content/updraft'; /usr/bin/zip -n .jpg:.JPG:.jpeg:.JPEG:.png:.PNG:.gif:.GIF:.zip:.ZIP:.gz:.bz2:.xz.:.rar:.RAR:.mp3:.MP3:.mp4:.MP4:.mpeg:.MPEG:.avi:.AVI:.mov:.MOV -v -u -r binziptest/test.zip binziptest/subdir1,r): Resource temporarily unavailable (line 937, wp-content/plugins/updraftplus/class-updraftplus.php)
0000.040 (0) Error: popen failed
0000.042 (0) Testing: /usr/local/bin/zip
0000.043 (0) PHP event: code E_WARNING: popen(cd '/home/xxxxxxxxxxxx/public_html/xxxxxx/wp-content/updraft'; /usr/local/bin/zip -n .jpg:.JPG:.jpeg:.JPEG:.png:.PNG:.gif:.GIF:.zip:.ZIP:.gz:.bz2:.xz.:.rar:.RAR:.mp3:.MP3:.mp4:.MP4:.mpeg:.MPEG:.avi:.AVI:.mov:.MOV -v -u -r binziptest/test.zip binziptest/subdir1,r): Resource temporarily unavailable (line 937, wp-content/plugins/updraftplus/class-updraftplus.php)
0000.044 (0) Error: popen failed
0000.047 (0) Creation of backups of directories: beginning
0000.051 (0) Beginning creation of dump of plugins (split every: 50 Mb)
0000.110 (0) Total entities for the zip file: 440 directories, 2666 files (0 skipped as non-modified), 36.7 Mb
0000.124 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 100 files added (on-disk size: 0 Kb)
0000.132 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 200 files added (on-disk size: 0 Kb)
0000.140 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 300 files added (on-disk size: 0 Kb)
0000.150 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 400 files added (on-disk size: 0 Kb)
0000.159 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 500 files added (on-disk size: 0 Kb)
0000.161 (0) Adding batch to zip file (UpdraftPlus_ZipArchive): over 500 files added on this batch (6.8 Mb, 2666 files batched, 501 (501) added so far); re-opening (prior size: 0 Kb)
0000.728 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 600 files added (on-disk size: 2543.3 Kb)
0000.738 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 700 files added (on-disk size: 2543.3 Kb)
0000.755 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 800 files added (on-disk size: 2543.3 Kb)
0000.777 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 900 files added (on-disk size: 2543.3 Kb)
0000.797 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 1000 files added (on-disk size: 2543.3 Kb)
0000.800 (0) Adding batch to zip file (UpdraftPlus_ZipArchive): over 500 files added on this batch (6.5 Mb, 2666 files batched, 501 (1002) added so far); re-opening (prior size: 2543.3 Kb)
0001.737 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 1100 files added (on-disk size: 5629.7 Kb)
0001.759 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 1200 files added (on-disk size: 5629.7 Kb)
0001.781 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 1300 files added (on-disk size: 5629.7 Kb)
0001.806 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 1400 files added (on-disk size: 5629.7 Kb)
0001.828 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 1500 files added (on-disk size: 5629.7 Kb)
0001.830 (0) Adding batch to zip file (UpdraftPlus_ZipArchive): over 500 files added on this batch (15 Mb, 2666 files batched, 501 (1503) added so far); re-opening (prior size: 5629.7 Kb)
0004.187 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 1600 files added (on-disk size: 11397.9 Kb)
0004.209 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 1700 files added (on-disk size: 11397.9 Kb)
0004.233 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 1800 files added (on-disk size: 11397.9 Kb)
0004.258 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 1900 files added (on-disk size: 11397.9 Kb)
0004.284 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 2000 files added (on-disk size: 11397.9 Kb)
0004.287 (0) Adding batch to zip file (UpdraftPlus_ZipArchive): over 500 files added on this batch (2.3 Mb, 2666 files batched, 501 (2004) added so far); re-opening (prior size: 11397.9 Kb)
0004.610 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 2100 files added (on-disk size: 12204.9 Kb)
0004.639 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 2200 files added (on-disk size: 12204.9 Kb)
0004.663 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 2300 files added (on-disk size: 12204.9 Kb)
0004.683 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 2400 files added (on-disk size: 12204.9 Kb)
0004.707 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 2500 files added (on-disk size: 12204.9 Kb)
0004.710 (0) Adding batch to zip file (UpdraftPlus_ZipArchive): over 500 files added on this batch (5.2 Mb, 2666 files batched, 501 (2505) added so far); re-opening (prior size: 12204.9 Kb)
0005.519 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-plugins.zip.tmp: 2600 files added (on-disk size: 14148.5 Kb)
0005.844 (0) Created plugins zip (0) - 14491.8 Kb in 5.8 s (2502.4 Kb/s) (SHA1 checksum: c1103a39ed184a6d78af8819e9afda5ee09b47a1)
0005.848 (0) Beginning creation of dump of themes (split every: 50 Mb)
0005.867 (0) Total entities for the zip file: 80 directories, 1018 files (0 skipped as non-modified), 14.2 Mb
0005.874 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-themes.zip.tmp: 100 files added (on-disk size: 0 Kb)
0005.884 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-themes.zip.tmp: 200 files added (on-disk size: 0 Kb)
0005.897 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-themes.zip.tmp: 300 files added (on-disk size: 0 Kb)
0005.908 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-themes.zip.tmp: 400 files added (on-disk size: 0 Kb)
0005.917 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-themes.zip.tmp: 500 files added (on-disk size: 0 Kb)
0005.918 (0) Adding batch to zip file (UpdraftPlus_ZipArchive): over 500 files added on this batch (7.6 Mb, 1018 files batched, 501 (501) added so far); re-opening (prior size: 0 Kb)
0006.401 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-themes.zip.tmp: 600 files added (on-disk size: 6946.6 Kb)
0006.414 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-themes.zip.tmp: 700 files added (on-disk size: 6946.6 Kb)
0006.427 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-themes.zip.tmp: 800 files added (on-disk size: 6946.6 Kb)
0006.436 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-themes.zip.tmp: 900 files added (on-disk size: 6946.6 Kb)
0006.445 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-themes.zip.tmp: 1000 files added (on-disk size: 6946.6 Kb)
0006.446 (0) Adding batch to zip file (UpdraftPlus_ZipArchive): over 500 files added on this batch (6.7 Mb, 1018 files batched, 501 (1002) added so far); re-opening (prior size: 6946.6 Kb)
0007.233 (0) Created themes zip (0) - 10438.6 Kb in 1.4 s (7545.2 Kb/s) (SHA1 checksum: 70a77b808bc80b5157f288e82897dd41e13c5dba)
0007.239 (0) Exclusion option setting (uploads): backup*,*backups,backwpup*,wp-clone
0007.240 (0) Looking for candidates to back up in: /home/xxxxxxxxxxe/public_html/xxxx/wp-content/uploads
0007.242 (0) finding files: 2014: adding to list (1)
0007.244 (0) finding files: 2015: adding to list (2)
0007.246 (0) finding files: profiles: adding to list (3)
0007.247 (0) finding files: themify-builder: adding to list (4)
0007.248 (0) finding files: wpcf7_captcha: adding to list (5)
0007.250 (0) finding files: .htaccess: adding to list (6)
0007.251 (0) Beginning creation of dump of uploads (split every: 50 Mb)
0007.273 (0) Total entities for the zip file: 22 directories, 1432 files (0 skipped as non-modified), 258.5 Mb
0007.278 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-uploads.zip.tmp: 100 files added (on-disk size: 0 Kb)
0007.281 (0) Adding batch to zip file (UpdraftPlus_ZipArchive): over 25 Mb added on this batch (25 Mb, 1432 files batched, 145 (145) added so far); re-opening (prior size: 0 Kb)
0009.034 (0) A useful amount of data was added after this amount of zip processing: 2.6 s (normalised: 2.6 s, rate: 9792.9 Kb/s)
0009.037 (0) Performance is good - will increase the amount of data we attempt to batch (time=2.6156249046326, normalised_time=2.614147027628, max_time=-1, data points known=0, old_max_bytes=26214400, new_max_bytes=52428800)
0009.042 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-uploads.zip.tmp: 200 files added (on-disk size: 22141.9 Kb)
0009.048 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-uploads.zip.tmp: 300 files added (on-disk size: 22141.9 Kb)
0009.052 (0) Adding batch to zip file (UpdraftPlus_ZipArchive): possibly approaching split limit (30 Mb, 215 (360) files added so far); last ratio: 0.8644; re-opening (prior size: 22141.9 Kb)
0011.130 (0) Zip size is at/near split limit (46.8 Mb / 50 Mb) - bumping index (from: 0)
0011.447 (0) Created uploads zip (0) - 47912.1 Kb in 3.9 s (12352.1 Kb/s) (SHA1 checksum: 59c207888b025426cdfbc5cea0300c8696f3968a)
0011.451 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-uploads2.zip.tmp: 400 files added (on-disk size: 0 Kb)
0011.455 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-uploads2.zip.tmp: 500 files added (on-disk size: 0 Kb)
0011.460 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-uploads2.zip.tmp: 600 files added (on-disk size: 0 Kb)
0011.463 (0) Adding batch to zip file (UpdraftPlus_ZipArchive): over 50 Mb added on this batch (50.7 Mb, 1432 files batched, 285 (645) added so far); re-opening (prior size: 0 Kb)
0014.927 (0) A useful amount of data was added after this amount of zip processing: 4.5 s (normalised: 4.4 s, rate: 11518.5 Kb/s)
0014.929 (0) Performance is good - but we will not increase the amount of data we batch, as we are already at the present limit (time=4.5085179805756, normalised_time=4.4450062638779, max_time=-1, data points known=0, max_bytes=52428800)
0014.935 (0) Adding batch to zip file (UpdraftPlus_ZipArchive): possibly approaching split limit (4.8 Mb, 27 (672) files added so far); last ratio: 0.894; re-opening (prior size: 46429 Kb)
0015.582 (0) Zip size is at/near split limit (49 Mb / 50 Mb) - bumping index (from: 1)
0015.934 (0) Created uploads zip (1) - 50169.7 Kb in 4.1 s (12131.3 Kb/s) (SHA1 checksum: 3bdfda5fd6733fc484888a311f77168814940d6b)
0015.938 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-uploads3.zip.tmp: 700 files added (on-disk size: 0 Kb)
0015.943 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-uploads3.zip.tmp: 800 files added (on-disk size: 0 Kb)
0015.948 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-uploads3.zip.tmp: 900 files added (on-disk size: 0 Kb)
0015.954 (0) Zip: backup_2015-08-27-0020_blogsite_7893c5ddd32b-uploads3.zip.tmp: 1000 files added (on-disk size: 0 Kb)
0015.959 (0) Adding batch to zip file (UpdraftPlus_ZipArchive): over 50 Mb added on this batch (50.3 Mb, 1432 files batched, 394 (1066) added so far); re-opening (prior size: 0 Kb)

    Plugin Author David Anderson / Team Updraft

    (@davidanderson)

    10 years, 9 months ago

    Hi,

    That log only covers 15 seconds – is there nothing more after that in the log, even if you download it afresh now?

    David

    Thread Starter mg2c

    (@mg2c)

    10 years, 9 months ago

    Hi David

    below is the link to the entire log: I used a file limit of 50MB

    The things, that I am most worried about are:
    – server still crashes, when I start the backup and sometimes during the backup process (error page with “misconfiguration” error) and at the end.
    – the popen / zip error
    – the fact, that the 500 MB file limit never has been a problem until now, backups were running fast and smoothly
    – a backup schedule problem: “Time now: Thu, August 27, 2015 13:46”, but “next scheduled backup” is some time in the PAST: Wed, August 26, 2015 01:05 (I do have enough visitors, and the scheduled backups always have worked until now)

    You can view the last log here:

    https://gist.github.com/anonymous/99d6dfbe09787b857c1a

    Thanks for you kind help and support

    Plugin Author David Anderson / Team Updraft

    (@davidanderson)

    10 years, 9 months ago

    Hi,

    It sounds like the server is overloaded, and can’t cope with the extra load of performing a backup. Presumably other uses of the server, other than your website, have been increasing over time – you’d need to speak to the webserver admin about this. Lowering the ‘split’ limit has the largest effect of anything on resources (the lower the split, the lower the resources needed), so this ties in with that.

    – the popen / zip error

    This just means that use of the PHP popen() function is not allowed on the server. That’s not problematic – UD has 3 different zip engines, and if one can’t be used, it moves on to the next.

    UD uses WP’s cron system – so, if the scheduled task seems not to have run, then the best way to investigate is by looking at your list of scheduled tasks with a plugin like WP-Crontrol.

    David

Viewing 10 replies - 1 through 10 (of 10 total)

The topic ‘PHP Error and ZIP Problems prevents backup’ is closed to new replies.