Backup Failed

Got the new release, enabled the artificial delay option and it failed.
It showed the Internal Server Error and Backup failed! messages.
The backup.active file is there.
The zipfile is not complete, its 21Mb size;
winton/backup/winton.20140321.050853.zip.HifAlP 21,263,116

This is the status.log;
99
Archiving SQL dump…
active
/homepages/35/d30352212/htdocs/winton
2014-03-21 05:09:020

Here are the last few lines of the log file;
[2014-03-21 05:09:02] Archiving /homepages/35/d30352212/htdocs/winton/index.php…
[2014-03-21 05:09:02] Archiving /homepages/35/d30352212/htdocs/winton/backup…
[2014-03-21 05:09:02] Archiving /homepages/35/d30352212/htdocs/winton/wp-phpinfo.php…
[2014-03-21 05:09:02] Archiving /homepages/35/d30352212/htdocs/winton/makezip.php…
[2014-03-21 05:09:02] Archiving /homepages/35/d30352212/htdocs/winton/plugins…
[2014-03-21 05:09:02] Archiving SQL dump…
[2014-03-21 05:09:02] src: /homepages/35/d30352212/htdocs/winton/backup/winton.20140321.050854.sql dst: winton.20140321.050854.sql

How big is your SQL dump file?

Its just over 1.2 Mb;
winton/backup/winton.20140321.125908.sql 1,271,145

A large part of it appears to be taken up with entries like this (this is just a few lines of one of the entries);
<img alt=”” src=”data:image/png;base64,iVBORw0KGgoAAAANSUhEUgAAAPoAAAC8CAIAAADejyQbAAAgAElEQVR4nCTY9ZMsiGEg5nnS0nvzZqanmWGgh5kZGmemmXuamZmZmWH44e5bLVu7K5YlWdbJVlQyVWTfJXX2JecqX1WuKj8kqeQqTrybH/L9Gd/Az4NLfyhs/LG78c8v9/7bV9Rvf8v/5nf0//3Ls//8ieA/vOL/zbXs9+3L33SUv6ypf1G3/bSt/UnV/lXZ8nlJ/2le/2Fa8SopeRVXvB8wPcR0z+KG537DVVLXi2tqAUXDZWwGdY2gvhHStOKGdtTbSpg7KVsnYv8k4vz223/+8MN2JersFEP9dKiXD3eKmdtK7K5c6DWK97XSdSPVrqdv67leM3vdrT7vFl706896tX67fFUtdhq5q0r2vp7rtQp3vcZ1o9CvlvvNwk2n1qqlqolkIuRMuD0mt8ph0Vi0Kr5BLHFIxE7ZpVXKdZsYFtGZVcq3KKk6Fkcno2g5h2r2sZZPUzN2ZfRDOWtHwt7nnx+eUfZIR2vbhzu7R3uk7TnGNpPLoChoLLOU75EKCyrXR7XGn9wn63GWk6P0KMx2hdMmj1qEwaAm7LWEPZKcTRnw2YIBYyyki4aUGb8ukNCGo0Z/WJN06hN+Vyquy6TM2awrlTdkcrpaSVOpGPNlQ6XgKuZt1YKhUJSXC/Zy2lLOmXJFc6FkKuUcuYqpmHek/cpEUBHx2QsZVbVgqhbV+aytmDOVc7p0XpcsmEppXT1jKCWdyZQ+l1VEi6pKUpXKy/I5ZTajjWbEuYwqmVaWY8pUQpmISZNhSSokDnsksYDQ5RUEdJKgjq4X03UCuktG10h4Lh7DLaYbLiUS5onieH9/Y28GR5hF4xfHcTtTsyebK9ydQ9M52aKQ+KRym0JgkfFscpFGwbdxuQ4B2yBk6mQsmVJskktsGqZWxbZq9Z5LqdOkT4ejpVLKZrWw2GeGiwsD5dyo48ronNn5NQIEPjg8PDQIQY48QYIhOCgONgpcnZpbHp/bHlta31hawqLRw+B9NmXteHuBvEa55M9e7K7SycdM2vQsmggbW5kbW8JjJhHIgV+Fp/+2uv7H/u6/vFn71y8PvvnV9jdfc/+Xr7j/

Note: this site is still in development phase and not yet live. There are only 8 test posts, 37 static pages and a handful of images at present. Hardly any content at all, so to speak.

Let’s try something radical, edit cyan-backup/includes/class-wp-backuper.php. Change line 662 from:

$zip->addFile($this->dump_file, basename($this->dump_file));

to:

//$zip->addFile($this->dump_file, basename($this->dump_file));

This will exclude the SQL dump from the backup.

See if the backup completes without it.

I put this in class-wp-backuper.php;
// exclude the SQL dump file
// $zip->addFile($this->dump_file, basename($this->dump_file));
$this->write_log_file( __(‘SQL dump is excluded’, $this->textdomain) );

I added the last line so I could see it in the log file.

I tried it with and without the artificial delay and it failed both times. I do not have any wp plugin dirs excluded at the moment.

Here is the status.log;
99
Archiving SQL dump…
active
/homepages/35/d30352212/htdocs/winton
2014-03-22 02:23:500

and the last few lines of the log file;
[2014-03-22 02:23:50] Archiving /homepages/35/d30352212/htdocs/winton/index.php…
[2014-03-22 02:23:50] Archiving /homepages/35/d30352212/htdocs/winton/backup…
[2014-03-22 02:23:50] Archiving /homepages/35/d30352212/htdocs/winton/wp-phpinfo.php…
[2014-03-22 02:23:50] Archiving /homepages/35/d30352212/htdocs/winton/makezip.php…
[2014-03-22 02:23:50] Archiving /homepages/35/d30352212/htdocs/winton/plugins…
[2014-03-22 02:23:50] Archiving SQL dump…
[2014-03-22 02:23:50] src: /homepages/35/d30352212/htdocs/winton/backup/winton.20140322.022346.sql dst: winton.20140322.022346.sql
[2014-03-22 02:23:50] SQL dump is excluded

Let’s add some more log lines around that code, replace:

if (file_exists($dump_file)) {
	$this->write_log_file( __("Archiving SQL dump...", $this->textdomain) );
	$this->write_status_file( $this->last_percentage, __("Archiving SQL dump...", $this->textdomain) );

	$zip->addFile($dump_file, basename($this->dump_file));
}

$zip->close();

with

if (file_exists($dump_file)) {
	$this->write_log_file( __("Archiving SQL dump...", $this->textdomain) );
	$this->write_status_file( $this->last_percentage, __("Archiving SQL dump...", $this->textdomain) );

	$zip->addFile($dump_file, basename($this->dump_file));
	$this->write_log_file( __("SQL dump complete...", $this->textdomain) );
					}

$zip->close();
$this->write_log_file( __("Zip file closed...", $this->textdomain) );

trying it now..

I put the changes in, ran the backup and it failed.

Here is the status.log;
99
Archiving /homepages/35/d30352212/htdocs/winton/images/stories/general/bowling_02.jpg…
active
/homepages/35/d30352212/htdocs/winton
2014-03-23 06:23:060

and the last few lines of the logfile;
[2014-03-23 06:23:06] Archiving /homepages/35/d30352212/htdocs/winton/index.php…
[2014-03-23 06:23:06] Archiving /homepages/35/d30352212/htdocs/winton/backup…
[2014-03-23 06:23:06] Archiving /homepages/35/d30352212/htdocs/winton/wp-phpinfo.php…
[2014-03-23 06:23:06] Archiving /homepages/35/d30352212/htdocs/winton/makezip.php…
[2014-03-23 06:23:06] Archiving /homepages/35/d30352212/htdocs/winton/plugins…

I searched the logfile for “SQL dump complete”, it not found.
I searched the logfile for “Zip file closed”, it not found.

It didn’t even get to the SQL dump this time. This has to be something external to the plugin.

The last thing we can try is to play around with the artificial delay value and see if that helps. In class-wp-backuper.php, line 637 should be:

if( $cur_time - $last_time > 10 ) {
	$this->write_log_file( __("Artificial delay of .25 sec...", $this->textdomain) );
	$last_time = $cur_time;
	usleep(250000);
}

change it to:

if( $cur_time - $last_time > 10 ) {
	$this->write_log_file( __("Artificial delay of .25 sec...", $this->textdomain) );
	$last_time = $cur_time;
	usleep(1000000);
}

if that doesn’t work try:

if( $cur_time - $last_time > 30 ) {
	$this->write_log_file( __("Artificial delay of .25 sec...", $this->textdomain) );
	$last_time = $cur_time;
	usleep(5000000);
}

Tried both edits, it failed both times.

Here is the status.log from last one;
99
Archiving /homepages/35/d30352212/htdocs/winton/new.php…
active
/homepages/35/d30352212/htdocs/winton
2014-03-24 00:35:530

and the last few lines of the logfile;
[2014-03-24 00:35:53] Archiving /homepages/35/d30352212/htdocs/winton/new.php…
[2014-03-24 00:35:53] Archiving /homepages/35/d30352212/htdocs/winton/index.php…
[2014-03-24 00:35:53] Archiving /homepages/35/d30352212/htdocs/winton/backup…
[2014-03-24 00:35:53] Archiving /homepages/35/d30352212/htdocs/winton/wp-phpinfo.php…
[2014-03-24 00:35:53] Archiving /homepages/35/d30352212/htdocs/winton/makezip.php…
[2014-03-24 00:35:53] Archiving /homepages/35/d30352212/htdocs/winton/plugins…

I searched the logfile for “SQL dump complete”, it not found.
I searched the logfile for “Zip file closed”, it not found.

I forgot to say, I searched the logfile for “Artificial delay”, it not found either.

Make sure the artificial delay option is enabled.

It was already enabled.
I unchecked it and saved, then cheecked it again and saved just to be sure.
I ran the backup again and it failed as before.
I searched the logfile as before and none of the phrases found.

Try removing the plugin and re-installing it, it looks like something has gone wrong with the install if your not getting the “Artificial delay” string in the log file.

I removed and re-installed the plugin but that made no difference.

Howver, the hosting support came back to me and apologised that my account was limited to 30Mb php memory and it should have been 80Mb.
They would upgrade my account asap.

I tried the backup again and it worked as far as the zip file was there, but a strange result, see below, maybe my hosting was in the process of being changed;
The status.log;
98
Archiving /homepages/35/d30352212/htdocs/winton/wp-includes/feed-atom-comments.php…
active
/homepages/35/d30352212/htdocs/winton
2014-04-02 06:00:070

The last few lines of the log file;
[2014-04-02 06:00:07] Archiving /homepages/35/d30352212/htdocs/winton/wp-signup.php…
[2014-04-02 06:00:07] Archiving /homepages/35/d30352212/htdocs/winton/wp-trackback.php…
[2014-04-02 06:00:07] Archiving /homepages/35/d30352212/htdocs/winton/xmlrpc.php…
[2014-04-02 06:00:26] Zip file closed…
[2014-04-02 06:00:26] Archive file does not exist after the backup is complete!

The hosting support has confirmed the new settings on my account as follows;
PHP Version: 5.4.26
PHP Memory Usage: 38 MB
PHP Memory Limit: 256M
PHP Max Upload Size: 64M
PHP Max Post Size: 64M
PHP Max Script Execution Time: 300 Seconds

Now the backup works fine and completes ok;
the last status.log;
100
Backup complete!
complete
/homepages/35/d30352212/htdocs/winton/backup/winton.20140404.060215.zip
2014-04-04 06:02:29
67.511735916138

The last few lines of the last log file;
[2014-04-04 06:02:18] Archiving /homepages/35/d30352212/htdocs/winton/wp-signup.php…
[2014-04-04 06:02:18] Archiving /homepages/35/d30352212/htdocs/winton/wp-trackback.php…
[2014-04-04 06:02:18] Archiving /homepages/35/d30352212/htdocs/winton/xmlrpc.php…
[2014-04-04 06:02:18] Archiving SQL dump…
[2014-04-04 06:02:29] Updating permission on zip file.
[2014-04-04 06:02:29] Removing temporary files…
[2014-04-04 06:02:29] Elapsed Time: 14 seconds
[2014-04-04 06:02:29] Backup complete!
[2014-04-04 06:02:29] Backup size: 67.51MB

It seems like the problem all along may have been the php memory limit or some issue with the server which has now gone after they changed my account.

Anyway, its all working fine now, thank you for your time, patience and help with getting this working.

Now I just need to get it to sync the zip file with my OwnCloud remote storage..