All vhosts are backed up under the same S3 account but randomly one of them fails

Thanks a lot, I’m staying in touch with AWS Support so we can work on it. I have also enabled S3 logging to gather complete info and what I noticed is that lot’s of responses for PUT and DELETE are not 2xx but 301 Permament redirects. I’m not very familar with AWS API so I can’t tell if it is an issue or not but I think that 301 redirect are actually empty besides the headers so maybe that is at least a hint for you.

EDIT:
Below is a filtered log from S3 for a single domain that has failed last night to upload. I have cenzored private information. S3 logs to a set of files so I just did cat * > file on them and the log entries are not in order but since the time stamps are not very precise anyway I just did not bother to sort it as it does not seem to make sense anyway. What seems interesting to me is that all the entries with 301 redirects have the “requester” field empty (dash mark between IP and RequesID) which, I think, indicates an issue somewhere in the area of authentication against S3. S3 log format explanation I used: http://docs.aws.amazon.com/AmazonS3/latest/dev/LogFormat.html .

79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:12 +0000] 54.217.X.Y arn:aws:iam::ACCOUNTID:user/virtualmin D6661E214D40B083 REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.info "DELETE /PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Einfo HTTP/1.1" 204 - - - 17 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:38 +0000] 54.217.X.Y - 7F39D54D44A9D0D9 REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.dom "DELETE /bucketname/PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Edom HTTP/1.1" 301 PermanentRedirect 451 - 2 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:14:03 +0000] 54.217.X.Y arn:aws:iam::ACCOUNTID:user/virtualmin D953A7C00443AC96 REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.dom "DELETE /PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Edom HTTP/1.1" 204 - - - 22 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:14:03 +0000] 54.217.X.Y - 6AD6C2FF2C27FFE7 REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.dom "DELETE /bucketname/PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Edom HTTP/1.1" 301 PermanentRedirect 451 - 5 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:14:16 +0000] 54.217.X.Y - 540EF2D1EAD3B928 REST.PUT.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz "PUT /bucketname/PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz HTTP/1.0" 301 PermanentRedirect 451 - 4 - "-" "-" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:38 +0000] 54.217.X.Y - C3C0BECC2D63E0A7 REST.PUT.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz "PUT /bucketname/PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz HTTP/1.0" 301 PermanentRedirect 451 - 2 - "-" "-" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:12 +0000] 54.217.X.Y arn:aws:iam::ACCOUNTID:user/virtualmin 6D69AC203FF98C08 REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.dom "DELETE /PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Edom HTTP/1.1" 204 - - - 21 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:24 +0000] 54.217.X.Y arn:aws:iam::ACCOUNTID:user/virtualmin 1B959C46A4F9DB49 REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.info "DELETE /PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Einfo HTTP/1.1" 204 - - - 16 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:25 +0000] 54.217.X.Y arn:aws:iam::ACCOUNTID:user/virtualmin 78F680105E729E6D REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.dom "DELETE /PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Edom HTTP/1.1" 204 - - - 19 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:50 +0000] 54.217.X.Y - 3C6235B6990D9055 REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.info "DELETE /bucketname/PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Einfo HTTP/1.1" 301 PermanentRedirect 451 - 5 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:14:15 +0000] 54.217.X.Y - 91114288E4089419 REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.dom "DELETE /bucketname/PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Edom HTTP/1.1" 301 PermanentRedirect 451 - 5 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:50 +0000] 54.217.X.Y - 7301F2438009C10A REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.dom "DELETE /bucketname/PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Edom HTTP/1.1" 301 PermanentRedirect 451 - 5 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:14:03 +0000] 54.217.X.Y - 4E13FA77A206330A REST.PUT.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz "PUT /bucketname/PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz HTTP/1.0" 301 PermanentRedirect 451 - 5 - "-" "-" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:14:02 +0000] 54.217.X.Y - 1E6A72CB1665D9EC REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.info "DELETE /bucketname/PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Einfo HTTP/1.1" 301 PermanentRedirect 451 - 5 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:14:15 +0000] 54.217.X.Y - 957E8E0DFF542030 REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.info "DELETE /bucketname/PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Einfo HTTP/1.1" 301 PermanentRedirect 451 - 5 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:14:15 +0000] 54.217.X.Y arn:aws:iam::ACCOUNTID:user/virtualmin 4CE889B92C2FD3E6 REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.dom "DELETE /PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Edom HTTP/1.1" 204 - - - 19 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:37 +0000] 54.217.X.Y - FE308A809668CA26 REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.info "DELETE /bucketname/PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Einfo HTTP/1.1" 301 PermanentRedirect 451 - 3 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:51 +0000] 54.217.X.Y - 0CCE957045A7F7CC REST.PUT.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz "PUT /bucketname/PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz HTTP/1.0" 301 PermanentRedirect 451 - 5 - "-" "-" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:14:15 +0000] 54.217.X.Y arn:aws:iam::ACCOUNTID:user/virtualmin FFE1DF799FCB803A REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.info "DELETE /PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Einfo HTTP/1.1" 204 - - - 20 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:50 +0000] 54.217.X.Y arn:aws:iam::ACCOUNTID:user/virtualmin 7F84240580ED5C3D REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.info "DELETE /PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Einfo HTTP/1.1" 204 - - - 18 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:37 +0000] 54.217.X.Y arn:aws:iam::ACCOUNTID:user/virtualmin F61230938AC30666 REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.info "DELETE /PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Einfo HTTP/1.1" 204 - - - 19 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:12 +0000] 54.217.X.Y - F8BB4603BFD47860 REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.dom "DELETE /bucketname/PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Edom HTTP/1.1" 301 PermanentRedirect 451 - 3 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:38 +0000] 54.217.X.Y arn:aws:iam::ACCOUNTID:user/virtualmin 3DBE1484B6283574 REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.dom "DELETE /PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Edom HTTP/1.1" 204 - - - 18 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:13 +0000] 54.217.X.Y - C87F712F17BA9A70 REST.PUT.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz "PUT /bucketname/PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz HTTP/1.0" 301 PermanentRedirect 451 - 2 - "-" "-" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:26 +0000] 54.217.X.Y - AFAC1CA89C11B9FF REST.PUT.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz "PUT /bucketname/PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz HTTP/1.0" 301 PermanentRedirect 451 - 2 - "-" "-" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:25 +0000] 54.217.X.Y - E5FA16806F75F8B2 REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.dom "DELETE /bucketname/PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Edom HTTP/1.1" 301 PermanentRedirect 451 - 98 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:50 +0000] 54.217.X.Y arn:aws:iam::ACCOUNTID:user/virtualmin 88CDA58EFE9EFC2D REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.dom "DELETE /PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Edom HTTP/1.1" 204 - - - 24 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:14:02 +0000] 54.217.X.Y arn:aws:iam::ACCOUNTID:user/virtualmin 54179B7AE7732EB4 REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.info "DELETE /PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Einfo HTTP/1.1" 204 - - - 28 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:12 +0000] 54.217.X.Y - 1A33BA3E3968E51F REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.info "DELETE /bucketname/PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Einfo HTTP/1.1" 301 PermanentRedirect 451 - 3 - "-" "libwww-perl/6.03" - 79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [21/Jul/2014:00:13:24 +0000] 54.217.X.Y - 662FC96E9AF20102 REST.DELETE.OBJECT PRO/daily/Mon-2014-07-21/domainname.co.uk.tar.gz.info "DELETE /bucketname/PRO%2Fdaily%2FMon-2014-07-21%2Fdomainname%2Eco%2Euk%2Etar%2Egz%2Einfo HTTP/1.1" 301 PermanentRedirect 451 - 2 - "-" "libwww-perl/6.03" -

Just a word to report that, for the first time since the issue appeared (end of May), no backup failed last night, after changing “S3 retry” from 3 to 10.

Hi again,

I received another response from AWS support this night. I mentioned them that I noticed the pattern that the logs indicate authentication issue as the redirects are issued to the requests without the “requester” field. AWS Support responded as follows:

"I can see from the logs that some requests are not authenticated (sent anonymously) and S3 responds with redirect for these requests.
Probably there are authentication headers missing, as I would expect different answer for wrong signature.

You are right, there are request IDs in the log.
Unfortunately these are useless to us without second part of the ID, which is twice as long and is returned in “x-amz-id-2” header [1].

Even then, I doubt if any valuable information is there for us, as we don’t log HTTP headers, so we will only see some “Unathenticated” status, similar to the log you provided."

  1. http://docs.aws.amazon.com/AmazonS3/latest/API/RESTCommonResponseHeaders.html "

So it seems we really need those headers.

Kind regards,
Leszek Eljasz

I will pass along the above info from Amazon to Jamie.

Sorry for the delay in providing a way to view the headers – Jamie is on vacation now, and although he’s still working while away, his communication is a bit more delayed :slight_smile:

He and I have been discussing how best to build what Amazon is requesting, and late last night Jamie responded with a patch. With the updated code included in that patch, anytime the above error is thrown, /var/webmin/miniserv.error will contain the headers sent to and from Amazon during the failed request.

This is the patch, and I’ll attach the full s3-lib.pl as an attachment to this post:

modules/virtual-server/s3-lib.pl @@ -162,8 +162,10 @@ sub s3_upload "for $page failed : $h"; next; } + local $hinput; foreach my $hfn ($req->header_field_names) { &write_http_connection($h, $hfn.": ".$req->header($hfn)."\r\n"); + $hinput .= $hfn.": ".$req->header($hfn)."\r\n"; } &write_http_connection($h, "\r\n");

@@ -208,6 +210,11 @@ sub s3_upload

if ($line !~ /\S/) {
	$err = "Empty response to HTTP request. Headers were : $htext";
  •  print STDERR "host=$host port=$port page=$page\n";
    
  •  print STDERR "Request headers :\n";
    
  •  print STDERR $hinput;
    
  •  print STDERR "Reply headers :\n";
    
  •  print STDERR $htext;
     }
    
    elsif ($line !~ /^HTTP/1…\s+(200|30[0-9])(\s+|$)/) {
    $err = “Invalid HTTP response : $line”;

Edit: sorry for all the typos (they should be corrected now), it looks like the ‘n’ key on my laptop is wearing out :slight_smile:

Hi Eric,

Is this helpful in any way? I doesn’t seem to be complete info to me as there is no HTTP method logged for instance, also the reply headers are empty.
Please take a look:

host=s3.amazonaws.com port=443 page=/bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz
Request headers :
Date: Fri, 25 Jul 2014 00:42:18 GMT
Authorization: AWS AWS_ACCESS_KEY_ID:I8M1SF9k/WDcCPIbBd3bxTScNzA=
Host: s3.amazonaws.com
Content-Length: 66299370
Reply headers :
host=s3.amazonaws.com port=443 page=/bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz
Request headers :
Date: Fri, 25 Jul 2014 00:42:31 GMT
Authorization: AWS AWS_ACCESS_KEY_ID:o7CDVreSEg5lgpyxRu4kE96+lhk=
Host: s3.amazonaws.com
Content-Length: 66299370
Reply headers :
host=s3.amazonaws.com port=443 page=/bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz
Request headers :
Date: Fri, 25 Jul 2014 00:42:54 GMT
Authorization: AWS AWS_ACCESS_KEY_ID:gWE74zUEHdPPRdNZSJAogHRyzKg=
Host: s3.amazonaws.com
Content-Length: 66299370
Reply headers :
host=s3.amazonaws.com port=443 page=/bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz
Request headers :
Date: Fri, 25 Jul 2014 00:43:27 GMT
Authorization: AWS AWS_ACCESS_KEY_ID:jjndyR4NOeQLw+ErEf8a55CX/oo=
Host: s3.amazonaws.com
Content-Length: 66299370
Reply headers :
host=s3.amazonaws.com port=443 page=/bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz
Request headers :
Date: Fri, 25 Jul 2014 00:44:10 GMT
Authorization: AWS AWS_ACCESS_KEY_ID:0Be6agAU8nGI2rRx8tWuVZf/nTM=
Host: s3.amazonaws.com
Content-Length: 66299370
Reply headers :
host=s3.amazonaws.com port=443 page=/bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz
Request headers :
Date: Fri, 25 Jul 2014 00:45:04 GMT
Authorization: AWS AWS_ACCESS_KEY_ID:bH1Z3PnWZQw+XDh96Oy3sxtJa6A=
Host: s3.amazonaws.com
Content-Length: 66299370
Reply headers :
host=s3.amazonaws.com port=443 page=/bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz
Request headers :
Date: Fri, 25 Jul 2014 00:46:07 GMT
Authorization: AWS AWS_ACCESS_KEY_ID:AiROi1wxbi/yiIOWhSTkLS7cqJ4=
Host: s3.amazonaws.com
Content-Length: 66299370
Reply headers :
host=s3.amazonaws.com port=443 page=/bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz
Request headers :
Date: Fri, 25 Jul 2014 00:47:21 GMT
Authorization: AWS AWS_ACCESS_KEY_ID:ANKOkM/cTkC9UdxAZKSXpF0+sGU=
Host: s3.amazonaws.com
Content-Length: 66299370
Reply headers :
host=s3.amazonaws.com port=443 page=/bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz
Request headers :
Date: Fri, 25 Jul 2014 00:48:44 GMT
Authorization: AWS AWS_ACCESS_KEY_ID:/h3Cjs1lXE5sLakuJxix77NO+LM=
Host: s3.amazonaws.com
Content-Length: 66299370
Reply headers :
host=s3.amazonaws.com port=443 page=/bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz
Request headers :
Date: Fri, 25 Jul 2014 00:50:18 GMT
Authorization: AWS AWS_ACCESS_KEY_ID:Z4Zg4NVjTxbMcxkH+qPNWGNd5Ig=
Host: s3.amazonaws.com
Content-Length: 66299370
Reply headers :

Corresponding S3 log entires:

2014-07-25-01-21-44-A94E019966C80758:79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [25/Jul/2014:00:50:17 +0000] 54.X.Y.Z - 881701437ECFBECC REST.PUT.OBJECT PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz “PUT /bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz HTTP/1.0” 301 PermanentRedirect 463 - 2 - “-” “-” -
2014-07-25-01-21-54-1807F64F1B8E82DC:79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [25/Jul/2014:00:44:10 +0000] 54.X.Y.Z - B9585724DDAC5808 REST.PUT.OBJECT PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz “PUT /bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz HTTP/1.0” 301 PermanentRedirect 451 - 2 - “-” “-” -
2014-07-25-01-22-08-2D4352E69FD38B14:79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [25/Jul/2014:00:42:17 +0000] 54.X.Y.Z - 68B7F095F3D2262E REST.PUT.OBJECT PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz “PUT /bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz HTTP/1.0” 301 PermanentRedirect 463 - 2 - “-” “-” -
2014-07-25-01-28-56-AF74355EB64ABF32:79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [25/Jul/2014:00:43:27 +0000] 54.X.Y.Z - 1D3BCFC5EE516372 REST.PUT.OBJECT PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz “PUT /bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz HTTP/1.0” 301 PermanentRedirect 451 - 2 - “-” “-” -
2014-07-25-01-30-00-A7C3528A1AD65F2B:79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [25/Jul/2014:00:47:21 +0000] 54.X.Y.Z - 661B0AC7383968CC REST.PUT.OBJECT PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz “PUT /bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz HTTP/1.0” 301 PermanentRedirect 451 - 2 - “-” “-” -
2014-07-25-01-38-14-70D7F84266889FAB:79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [25/Jul/2014:00:45:03 +0000] 54.X.Y.Z - 72E4A5B6D75A9F18 REST.PUT.OBJECT PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz “PUT /bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz HTTP/1.0” 301 PermanentRedirect 451 - 2 - “-” “-” -
2014-07-25-01-39-04-67F5B519B8DA69A0:79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [25/Jul/2014:00:42:54 +0000] 54.X.Y.Z - 208E8D97FEC32B5F REST.PUT.OBJECT PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz “PUT /bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz HTTP/1.0” 301 PermanentRedirect 463 - 3 - “-” “-” -
2014-07-25-01-40-35-AFE8001C3D232AE9:79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [25/Jul/2014:00:42:30 +0000] 54.X.Y.Z - AF359983C48646A3 REST.PUT.OBJECT PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz “PUT /bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz HTTP/1.0” 301 PermanentRedirect 463 - 2 - “-” “-” -
2014-07-25-01-43-11-EB261D46E4EEAAFA:79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [25/Jul/2014:00:48:44 +0000] 54.X.Y.Z - 7AF0A976133FAFBB REST.PUT.OBJECT PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz “PUT /bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz HTTP/1.0” 301 PermanentRedirect 451 - 3 - “-” “-” -
2014-07-25-01-48-22-8E6DEDCD19FD8FF0:79a59df900b949e55d96a1e698fbacedfd6e09d98eacf8f8d5218e7cd47ef2be bucketname [25/Jul/2014:00:46:07 +0000] 54.X.Y.Z - 80AF3138B2CFF698 REST.PUT.OBJECT PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz “PUT /bucketname/PRO/daily/Fri-2014-07-25/domain.co.uk.tar.gz HTTP/1.0” 301 PermanentRedirect 463 - 2 - “-” “-” -

It also seems that user agent is gone now.

I still get errors every day. Is there any progress?

Thank you

I havent’ received the warning in a while.

No changes on my side, we still observe the issue. Recently I raised the amount of repeated attempts to 15 which seems to help for incremental backups but the full weekly backups never make it.

Eric, is there anything else I can do to help you guys resolve this issue?

Yes, it happened today again.

I have Debian 7 server with Virtualmin 4.10 GPL and have the same issue. Backups are randomly failing with random vservers. Even not so big incremental backups are failing from time to time.

The debugging output we’ve been seeing hasn’t led to any sort of resolution… it certainly appears that all requests to S3 are being authenticated, and that, in some cases, the response is blank. Which is very odd!

What Jamie is doing is revamping the S3 support to use the latest code available in the S3 library that it was based on. Virtualmin’s S3 support was built some years ago, and not much with it has changed since then.

So to rule out any sort of unusual compatibility issue, Jamie is redoing it to include all the changes and updates in the S3 library. Let’s see if that helps!

-Eric

This thread isn’t too old to add to I hope, but I’ve been experiencing irregular backup issues since July I think. Always “no buffer space available” errors after 10-15 of 20 vhosts. A 2nd box with even more vhosts backs up smooth as butter.

Uploading archive to Amazon’s S3 service …
… upload failed! HTTP connection to s3.amazonaws.com:443 for /backup1/10-12-2014/domain.com.tar.gz failed : Failed to connect to s3.amazonaws.com:443 : No buffer space available

I’ve tried all sorts of experimenting with the backup settings - even splitting the entire schedule into two different nights. Nothing makes a difference.

My question is simply, does a timeline exist for the rollout of Jamie’s S£ updates as mentioned in the last post?

Many thanks!

I am getting exactly the same issue as in this thread on occasion as well. I just recently replaced one of my backup servers with S3. It made sense in terms of price and reliability. Except now I have this issue.

Hi.
i had the same problem with D.O. kvm ubuntu 12.04 image. Virtualmin 4.11 latest.
found this thread so i have changed retry number to 10 but only solved 2 more vhosts. success was 11 of 20 always. so i kept examining the logs & i thought it’s about filesize of vhost backup and if it is bigger than chunk size that problem appears more. so i have changed chunk size to 100Mb. now i don’t get that error. several times had backup. all fine now. please try & confirm.

Can you please note where chunk size is changed?

@risyasin Many thanks for that, I’ll give it a try. I had changed chunk size previously to 25mb without much benefit.

@RealGecko Virtualmin > System Settings (bottom left) > Virtualmin Configuration > Backup & Restore > S3 upload chunk size

@monsieurQ: Just be sure your chunk size is larger than your vhost backup file size. ( I believe that is the problem, chunking the backup file size to upload makes problem on S3 side. i think it’s S3 bc i am also developer related with S3 & l respect JamieCameron more than Amazon :slight_smile: )

@RealGecko: monsieurQ was faster than me :slight_smile:

Thanks monsieurQ and risyasin, Ive increased chunk size from default 5MB to 100MB. However my backup sizes are much larger than 5MB and fails happen only from time to time. Ill monitor the way things going for the next week and report any results.

@risyasin “l respect JamieCameron more than Amazon :)”

Well said :wink:

One of my vhosts is 500mb. Is there any reason I could/should not set chunk size to say 750mb for example? Would this have a negative effect on other vhosts?

@RealGecko Thanks - please do report back any progress

Having previously got the “No buffer space available” error with up to 10 vhosts failing, significantly upping the chunk size has resulted in only 2-3 vhosts failing on average with the “upload failed! Empty response to HTTP request” error.

I know this particular error is what most of you have been getting anyway but this is actually progress for me!