SafetyNet error on FMS11

[Paul D.]

Recently, we

* Uninstalled FileMaker 10 Server (previously used SafetyNet 1.02)

* Installed FileMaker 11 Server [11.0.2.217]

* Installed SafetyNet 1.12

Receive the following error message daily:

Mon Aug 09 08:47:01 EDT 2010: Offsite backup failed for folder C:Program FilesFileMakerFileMaker ServerDataBackupsSafetyNet: org.jets3t.service.S3ServiceException: Request Error. PUT '/SafetyNet_2010-08-07_0300%2FDatabases%2FCES%20Library%20Database.fp7.zip' on Host 'com.prosc.s3backup.14385b05-95bc-41ad-9037-ef9b78c6c754.s3.amazonaws.com'

This is the 3rd file (of 25 in the "C:Program FilesFileMakerFileMaker ServerDataBackupsSafetyNetSafetyNet_2010-08-09_0300Databases").

Web interface for 360Works Safetynet Manage Files shows the two prior to this one backed up, but no others!?!

When I run manually, it looks likes currently status gets to about ~23% of "writing the file" and then go back to idle/next execution.

Windows Server 2003 R2 SP2

Most recent log entries:

> Aug 9, 2010 8:56:39 AM com.prosc.s3backup.BackupManager run

> INFO: Sending out notification e-mails

> Aug 9, 2010 8:56:39 AM com.prosc.s3backup.BackupManager$1 run

> INFO: Sent notification e-mail to [email protected]

> Aug 9, 2010 9:00:04 AM com.prosc.s3backup.BackupManager run

> INFO: Getting list of remote files

> Aug 9, 2010 9:00:04 AM com.prosc.s3backup.BackupManager s3Login

> INFO: Logging in to remote site

> Aug 9, 2010 9:00:04 AM org.jets3t.service.impl.rest.httpclient.RestS3Service listAllBucketsImpl

> FINE: Listing all buckets for AWS user: AKIAJKGTAE5PPF5462GQ

> Aug 9, 2010 9:00:04 AM org.jets3t.service.impl.rest.httpclient.RestS3Service setupConnection

> FINE: S3 URL: https://s3.amazonaws.com:443/

> Aug 9, 2010 9:00:04 AM org.jets3t.service.impl.rest.httpclient.RestS3Service setupConnection

> FINE: Including DevPay user and product tokens in request: x-amz-security-token={UserToken}AAIHVXNlclRrbohwAeS4umTFLg5jsP/sud6uBnQ2QyMkpo0dUkjWKiHn1PBYOgKOQcl2rQQVc2VvKvgNPoWeDYAtFwpjZv0HhbHNooYfns+nzFuEFCukvJG/1e8Ik0evpqxfmTKVshyt/MMp4gH9IL8rFJvQ885F4Xt9GWsaQp8RBkfzOEopYeN9LVP7I4xoZu64Nz/VaZX0phjRI1UYpZgYKjKeI6uHf1+y6kpbRBmOyBeAGz9cgNp/1RMb9E1kSl/K3/6O8dDwbqI11ib/plOyy7XVTyqpXGHa2f3Sisat6DJ2pHZOPNvJzRbpkxfYB6w8q+gQ0+j2jLbYjMAdGDVLUEMe3YkbD8e2TwtSF5xMnuDbnrPTodqWgZk7xaMRbKJ7AOGMQm36dw==,{ProductToken}AAkGQXBwVGtuyemkxHaUJfFXd0vFtoqXhlkWq1Jsze8qM+kWOqM3pySWJbMlp4cEzfO5KTYMrUu6TvWg1Or7zLKHp3VBBhfejaTclipZxH09JYFFKMi0yXm8VyDaA8aKChdF5oPkGlDa1oW45+dgiZAdE5Y8ukFjBCtoIrKpv9D1+gr6PnVw1pyIAYLs+ucBVHzuizGim6/P51zM/uEQvGcakvu86jrqDtYdouadcuyNxNC0DzuE0DgMVCj4A1D/SJ30N/pYYRQUYW56HSqD//3gCVKlO23xMzb4K7UY5FToamDpA1n7Suw=

> Aug 9, 2010 9:00:04 AM org.jets3t.service.impl.rest.httpclient.RestS3Service performRequest

> FINE: Performing GET request for 'https://s3.amazonaws.com/', expecting response codes: [200]

> Aug 9, 2010 9:00:04 AM org.jets3t.service.impl.rest.httpclient.RestS3Service authorizeHttpRequest

> FINE: Adding authorization for AWS Access Key 'AKIAJKGTAE5PPF5462GQ'.

> Aug 9, 2010 9:00:04 AM org.jets3t.service.impl.rest.httpclient.RestS3Service authorizeHttpRequest

> FINE: Canonical string ('|' is a newline) GET|||Mon, 09 Aug 2010 13:00:04 GMT|x-amz-security-token:{UserToken}AAIHVXNlclRrbohwAeS4umTFLg5jsP/sud6uBnQ2QyMkpo0dUkjWKiHn1PBYOgKOQcl2rQQVc2VvKvgNPoWeDYAtFwpjZv0HhbHNooYfns+nzFuEFCukvJG/1e8Ik0evpqxfmTKVshyt/MMp4gH9IL8rFJvQ885F4Xt9GWsaQp8RBkfzOEopYeN9LVP7I4xoZu64Nz/VaZX0phjRI1UYpZgYKjKeI6uHf1+y6kpbRBmOyBeAGz9cgNp/1RMb9E1kSl/K3/6O8dDwbqI11ib/plOyy7XVTyqpXGHa2f3Sisat6DJ2pHZOPNvJzRbpkxfYB6w8q+gQ0+j2jLbYjMAdGDVLUEMe3YkbD8e2TwtSF5xMnuDbnrPTodqWgZk7xaMRbKJ7AOGMQm36dw==,{ProductToken}AAkGQXBwVGtuyemkxHaUJfFXd0vFtoqXhlkWq1Jsze8qM+kWOqM3pySWJbMlp4cEzfO5KTYMrUu6TvWg1Or7zLKHp3VBBhfejaTclipZxH09JYFFKMi0yXm8VyDaA8aKChdF5oPkGlDa1oW45+dgiZAdE5Y8ukFjBCtoIrKpv9D1+gr6PnVw1pyIAYLs+ucBVHzuizGim6/P51zM/uEQvGcakvu86jrqDtYdouadcuyNxNC0DzuE0DgMVCj4A1D/SJ30N/pYYRQUYW56HSqD//3gCVKlO23xMzb4K7UY5FToamDpA1n7Suw=|/

> Aug 9, 2010 9:00:04 AM org.jets3t.service.impl.rest.httpclient.RestS3Service performRequest

> FINE: Response for '/'. Content-Type: application/xml, Headers: [x-amz-id-2: aJhj4+ZdlK1CHtWb1uMMcg1Q7cpcePYfN7uXjI5T6+kEMhWCQe8TjJQMO3BK+0or

> , x-amz-request-id: 0B26608A809CA145

> , Date: Mon, 09 Aug 2010 13:00:09 GMT

> , Content-Type: application/xml

> , Transfer-Encoding: chunked

> , Server: AmazonS3

> ]

> Aug 9, 2010 9:00:04 AM com.prosc.s3backup.BackupManager getBucket

> FINE: Found 1 bucket(s)

> Aug 9, 2010 9:00:04 AM com.prosc.s3backup.BackupManager getBucket

> INFO: Found destination bucket: com.prosc.s3backup.14385b05-95bc-41ad-9037-ef9b78c6c754

> Aug 9, 2010 9:00:04 AM org.jets3t.service.impl.rest.httpclient.RestS3Service setupConnection

> FINE: S3 URL: https://com.prosc.s3backup.14385b05-95bc-41ad-9037-ef9b78c6c754.s3.amazonaws.com:443/

> Aug 9, 2010 9:00:04 AM org.jets3t.service.impl.rest.httpclient.RestS3Service addRequestParametersToUrlPath

> FINE: Added request parameter: max-keys=1000

> Aug 9, 2010 9:00:04 AM org.jets3t.service.impl.rest.httpclient.RestS3Service setupConnection

> FINE: Including DevPay user and product tokens in request: x-amz-security-token={UserToken}AAIHVXNlclRrbohwAeS4umTFLg5jsP/sud6uBnQ2QyMkpo0dUkjWKiHn1PBYOgKOQcl2rQQVc2VvKvgNPoWeDYAtFwpjZv0HhbHNooYfns+nzFuEFCukvJG/1e8Ik0evpqxfmTKVshyt/MMp4gH9IL8rFJvQ885F4Xt9GWsaQp8RBkfzOEopYeN9LVP7I4xoZu64Nz/VaZX0phjRI1UYpZgYKjKeI6uHf1+y6kpbRBmOyBeAGz9cgNp/1RMb9E1kSl/K3/6O8dDwbqI11ib/plOyy7XVTyqpXGHa2f3Sisat6DJ2pHZOPNvJzRbpkxfYB6w8q+gQ0+j2jLbYjMAdGDVLUEMe3YkbD8e2TwtSF5xMnuDbnrPTodqWgZk7xaMRbKJ7AOGMQm36dw==,{ProductToken}AAkGQXBwVGtuyemkxHaUJfFXd0vFtoqXhlkWq1Jsze8qM+kWOqM3pySWJbMlp4cEzfO5KTYMrUu6TvWg1Or7zLKHp3VBBhfejaTclipZxH09JYFFKMi0yXm8VyDaA8aKChdF5oPkGlDa1oW45+dgiZAdE5Y8ukFjBCtoIrKpv9D1+gr6PnVw1pyIAYLs+ucBVHzuizGim6/P51zM/uEQvGcakvu86jrqDtYdouadcuyNxNC0DzuE0DgMVCj4A1D/SJ30N/pYYRQUYW56HSqD//3gCVKlO23xMzb4K7UY5FToamDpA1n7Suw=

> Aug 9, 2010 9:00:04 AM org.jets3t.service.impl.rest.httpclient.RestS3Service performRequest

> FINE: Performing GET request for 'https://com.prosc.s3backup.14385b05-95bc-41ad-9037-ef9b78c6c754.s3.amazonaws.com/?max-keys=1000', expecting response codes: [200]

> Aug 9, 2010 9:00:04 AM org.jets3t.service.impl.rest.httpclient.RestS3Service authorizeHttpRequest

> FINE: Adding authorization for AWS Access Key 'AKIAJKGTAE5PPF5462GQ'.

> Aug 9, 2010 9:00:04 AM org.jets3t.service.impl.rest.httpclient.RestS3Service authorizeHttpRequest

> FINE: Canonical string ('|' is a newline) GET|||Mon, 09 Aug 2010 13:00:04 GMT|x-amz-security-token:{UserToken}AAIHVXNlclRrbohwAeS4umTFLg5jsP/sud6uBnQ2QyMkpo0dUkjWKiHn1PBYOgKOQcl2rQQVc2VvKvgNPoWeDYAtFwpjZv0HhbHNooYfns+nzFuEFCukvJG/1e8Ik0evpqxfmTKVshyt/MMp4gH9IL8rFJvQ885F4Xt9GWsaQp8RBkfzOEopYeN9LVP7I4xoZu64Nz/VaZX0phjRI1UYpZgYKjKeI6uHf1+y6kpbRBmOyBeAGz9cgNp/1RMb9E1kSl/K3/6O8dDwbqI11ib/plOyy7XVTyqpXGHa2f3Sisat6DJ2pHZOPNvJzRbpkxfYB6w8q+gQ0+j2jLbYjMAdGDVLUEMe3YkbD8e2TwtSF5xMnuDbnrPTodqWgZk7xaMRbKJ7AOGMQm36dw==,{ProductToken}AAkGQXBwVGtuyemkxHaUJfFXd0vFtoqXhlkWq1Jsze8qM+kWOqM3pySWJbMlp4cEzfO5KTYMrUu6TvWg1Or7zLKHp3VBBhfejaTclipZxH09JYFFKMi0yXm8VyDaA8aKChdF5oPkGlDa1oW45+dgiZAdE5Y8ukFjBCtoIrKpv9D1+gr6PnVw1pyIAYLs+ucBVHzuizGim6/P51zM/uEQvGcakvu86jrqDtYdouadcuyNxNC0DzuE0DgMVCj4A1D/SJ30N/pYYRQUYW56HSqD//3gCVKlO23xMzb4K7UY5FToamDpA1n7Suw=|/com.prosc.s3backup.14385b05-95bc-41ad-9037-ef9b78c6c754/

> Aug 9, 2010 9:00:05 AM org.jets3t.service.impl.rest.httpclient.RestS3Service performRequest

> FINE: Response for '/'. Content-Type: application/xml, Headers: [x-amz-id-2: 6FR4lq7T49906i1w0ALB6zi0hMlUIknnLJw71dNxVPFEK/8dygqKy1pqOuumX/YA

> , x-amz-request-id: F6E9AB6493011FE9

> , Date: Mon, 09 Aug 2010 13:00:09 GMT

> , Content-Type: application/xml

> , Transfer-Encoding: chunked

> , Server: AmazonS3

> ]

> Aug 9, 2010 9:00:05 AM org.jets3t.service.impl.rest.httpclient.RestS3Service listObjectsInternal

> FINE: Found 2 objects in one batch

> Aug 9, 2010 9:00:05 AM org.jets3t.service.impl.rest.httpclient.RestS3Service listObjectsInternal

> FINE: Found 0 common prefixes in one batch

> Aug 9, 2010 9:00:05 AM org.jets3t.service.impl.rest.httpclient.RestS3Service listObjectsInternal

> FINE: Found 2 objects in total

> Aug 9, 2010 9:00:05 AM com.prosc.s3backup.BackupManager run

> INFO: Found 2 remote files on S3 account

> Aug 9, 2010 9:00:05 AM com.prosc.s3backup.BackupManager run

> FINE: Remote files list with modification timestamps:

> Remote file SafetyNet_2010-08-07_0300/Databases/Berlin_Database.fp7.zip has timestamp Sat Aug 07 03:01:47 EDT 2010

> Remote file SafetyNet_2010-08-07_0300/Databases/Berlin_Directories.fp7.zip has timestamp Sat Aug 07 03:01:51 EDT 2010

> Aug 9, 2010 9:00:05 AM com.prosc.s3backup.BackupManager$BackupProcess run

> INFO: --> Starting backup of folder C:Program FilesFileMakerFileMaker ServerDataBackupsSafetyNet

> Aug 9, 2010 9:00:05 AM com.prosc.s3backup.BackupManager$BackupProcess run

> FINE: New file at SafetyNet_2010-08-07_0300/Databases/CES Library Database.fp7.zip has never been backed up.

> Aug 9, 2010 9:00:06 AM com.prosc.s3backup.BackupManager$BackupProcess run

> INFO: Compressing file C:Program FilesFileMakerFileMaker ServerDataBackupsSafetyNetSafetyNet_2010-08-07_0300DatabasesCES Library Database.fp7

> Aug 9, 2010 9:01:07 AM com.prosc.s3backup.BackupManager$BackupProcess run

> INFO: Writing file: C:Program FilesFileMakerFileMaker ServerDataBackupsSafetyNetSafetyNet_2010-08-07_0300DatabasesCES Library Database.fp7

> Aug 9, 2010 9:01:11 AM org.jets3t.service.impl.rest.httpclient.RestS3Service putObjectImpl

> FINE: Creating Object with key SafetyNet_2010-08-07_0300/Databases/CES Library Database.fp7.zip in bucket com.prosc.s3backup.14385b05-95bc-41ad-9037-ef9b78c6c754

> Aug 9, 2010 9:01:11 AM org.jets3t.service.impl.rest.httpclient.RestS3Service putObjectImpl

> FINE: Uploading object data with Content-Length: 140792356

> Aug 9, 2010 9:01:11 AM org.jets3t.service.impl.rest.httpclient.RestS3Service createObjectImpl

> FINE: Creating object bucketName=com.prosc.s3backup.14385b05-95bc-41ad-9037-ef9b78c6c754, objectKey=SafetyNet_2010-08-07_0300/Databases/CES Library Database.fp7.zip. Content-Type=application/octet-stream Including data? true Metadata: {Content-Length=140792356, Content-MD5=l4c3giB2gTMQge8PPr9toQ==, md5-hash=97873782207681331081ef0f3ebf6da1, Content-Type=application/octet-stream} ACL: null

> Aug 9, 2010 9:01:11 AM org.jets3t.service.impl.rest.httpclient.RestS3Service setupConnection

> FINE: S3 URL: https://com.prosc.s3backup.14385b05-95bc-41ad-9037-ef9b78c6c754.s3.amazonaws.com:443/SafetyNet_2010-08-07_0300%2FDatabases%2FCES%20Library%20Database.fp7.zip

> Aug 9, 2010 9:01:11 AM org.jets3t.service.impl.rest.httpclient.RestS3Service setupConnection

> FINE: Including DevPay user and product tokens in request: x-amz-security-token={UserToken}AAIHVXNlclRrbohwAeS4umTFLg5jsP/sud6uBnQ2QyMkpo0dUkjWKiHn1PBYOgKOQcl2rQQVc2VvKvgNPoWeDYAtFwpjZv0HhbHNooYfns+nzFuEFCukvJG/1e8Ik0evpqxfmTKVshyt/MMp4gH9IL8rFJvQ885F4Xt9GWsaQp8RBkfzOEopYeN9LVP7I4xoZu64Nz/VaZX0phjRI1UYpZgYKjKeI6uHf1+y6kpbRBmOyBeAGz9cgNp/1RMb9E1kSl/K3/6O8dDwbqI11ib/plOyy7XVTyqpXGHa2f3Sisat6DJ2pHZOPNvJzRbpkxfYB6w8q+gQ0+j2jLbYjMAdGDVLUEMe3YkbD8e2TwtSF5xMnuDbnrPTodqWgZk7xaMRbKJ7AOGMQm36dw==,{ProductToken}AAkGQXBwVGtuyemkxHaUJfFXd0vFtoqXhlkWq1Jsze8qM+kWOqM3pySWJbMlp4cEzfO5KTYMrUu6TvWg1Or7zLKHp3VBBhfejaTclipZxH09JYFFKMi0yXm8VyDaA8aKChdF5oPkGlDa1oW45+dgiZAdE5Y8ukFjBCtoIrKpv9D1+gr6PnVw1pyIAYLs+ucBVHzuizGim6/P51zM/uEQvGcakvu86jrqDtYdouadcuyNxNC0DzuE0DgMVCj4A1D/SJ30N/pYYRQUYW56HSqD//3gCVKlO23xMzb4K7UY5FToamDpA1n7Suw=

> Aug 9, 2010 9:01:11 AM org.jets3t.service.impl.rest.httpclient.RestS3Service performRequest

> FINE: Performing PUT request for 'https://com.prosc.s3backup.14385b05-95bc-41ad-9037-ef9b78c6c754.s3.amazonaws.com/SafetyNet_2010-08-07_0300%2FDatabases%2FCES%20Library%20Database.fp7.zip', expecting response codes: [200]

> Aug 9, 2010 9:01:11 AM org.jets3t.service.impl.rest.httpclient.RestS3Service authorizeHttpRequest

> FINE: Adding authorization for AWS Access Key 'AKIAJKGTAE5PPF5462GQ'.

> Aug 9, 2010 9:01:11 AM org.jets3t.service.impl.rest.httpclient.RestS3Service authorizeHttpRequest

> FINE: Canonical string ('|' is a newline): PUT|l4c3giB2gTMQge8PPr9toQ==|application/octet-stream|Mon, 09 Aug 2010 13:01:11 GMT|x-amz-meta-md5-hash:97873782207681331081ef0f3ebf6da1|x-amz-security-token:{UserToken}AAIHVXNlclRrbohwAeS4umTFLg5jsP/sud6uBnQ2QyMkpo0dUkjWKiHn1PBYOgKOQcl2rQQVc2VvKvgNPoWeDYAtFwpjZv0HhbHNooYfns+nzFuEFCukvJG/1e8Ik0evpqxfmTKVshyt/MMp4gH9IL8rFJvQ885F4Xt9GWsaQp8RBkfzOEopYeN9LVP7I4xoZu64Nz/VaZX0phjRI1UYpZgYKjKeI6uHf1+y6kpbRBmOyBeAGz9cgNp/1RMb9E1kSl/K3/6O8dDwbqI11ib/plOyy7XVTyqpXGHa2f3Sisat6DJ2pHZOPNvJzRbpkxfYB6w8q+gQ0+j2jLbYjMAdGDVLUEMe3YkbD8e2TwtSF5xMnuDbnrPTodqWgZk7xaMRbKJ7AOGMQm36dw==,{ProductToken}AAkGQXBwVGtuyemkxHaUJfFXd0vFtoqXhlkWq1Jsze8qM+kWOqM3pySWJbMlp4cEzfO5KTYMrUu6TvWg1Or7zLKHp3VBBhfejaTclipZxH09JYFFKMi0yXm8VyDaA8aKChdF5oPkGlDa1oW45+dgiZAdE5Y8ukFjBCtoIrKpv9D1+gr6PnVw1pyIAYLs+ucBVHzuizGim6/P51zM/uEQvGcakvu86jrqDtYdouadcuyNxNC0DzuE0DgMVCj4A1D/SJ30N/pYYRQUYW56HSqD//3gCVKlO23xMzb4K7UY5FToamDpA1n7Suw=|/com.prosc.s3backup.14385b05-95bc-41ad-9037-ef9b78c6c754/SafetyNet_2010-08-07_0300%2FDatabases%2FCES%20Library%20Database.fp7.zip

> Aug 9, 2010 9:01:43 AM org.jets3t.service.impl.rest.httpclient.RestS3Service performRequest

> FINE: Releasing HttpClient connection after error: Java heap space

> Aug 9, 2010 9:01:43 AM com.prosc.s3backup.BackupManager run

> WARNING: Offsite backup failed for folder: C:Program FilesFileMakerFileMaker ServerDataBackupsSafetyNet

> org.jets3t.service.S3ServiceException: Request Error. PUT '/SafetyNet_2010-08-07_0300%2FDatabases%2FCES%20Library%20Database.fp7.zip' on Host 'com.prosc.s3backup.14385b05-95bc-41ad-9037-ef9b78c6c754.s3.amazonaws.com'

> at org.jets3t.service.impl.rest.httpclient.RestS3Service.performRequest(RestS3Service.java:551)

> at org.jets3t.service.impl.rest.httpclient.RestS3Service.performRestPut(RestS3Service.java:940)

> at org.jets3t.service.impl.rest.httpclient.RestS3Service.createObjectImpl(RestS3Service.java:1754)

> at org.jets3t.service.impl.rest.httpclient.RestS3Service.putObjectImpl(RestS3Service.java:1685)

> at org.jets3t.service.S3Service.putObject(S3Service.java:2145)

> at org.jets3t.service.S3Service.putObject(S3Service.java:2508)

> at com.prosc.s3backup.BackupManager$BackupProcess.run(BackupManager.java:849)

> at com.prosc.s3backup.BackupManager$BackupProcess.access$100(BackupManager.java:740)

> at com.prosc.s3backup.BackupManager.run(BackupManager.java:240)

> at com.prosc.safetynet.Application$1.run(Application.java:41)

> Caused by: java.lang.OutOfMemoryError: Java heap space

> Aug 9, 2010 9:01:43 AM com.prosc.s3backup.BackupManager run

> INFO: Sending out notification e-mails

> Aug 9, 2010 9:01:43 AM com.prosc.s3backup.BackupManager$1 run

> INFO: Sent notification e-mail to [email protected]

[Jesse Barnum]

This should be fixed in SafetyNet 1.13