WordPress.org

Ready to get started?Download WordPress

Forums

UpdraftPlus Backup and Restoration for WordPress
[resolved] Problems with both DropBox and Google Drive (18 posts)

  1. Pat Garner
    Member
    Posted 7 months ago #

    First I tried Google Drive. The setup was successful. Initiated a backup. Five categories of files were backed up, as verified on Google Drive:

    1. database --> backup_2014-01-07-0933_Acme...f32e-db (472 bytes)
    2. others ----> backup_2014-01-07-0933_Acme...others.zip (344 bytes)
    3. plugins ---> backup_2014-01-07-0933_Acme...plugins.zip (4.14 MB)
    4. themes ----> backup_2014-01-07-0933_Acme...themes.zip (2.92 MB)
    5. uploads ---> backup_2014-01-07-0933_Acme...uploads.zip (76.9 MB)

    Only one warning was logged: binziptest, which was resolved by UpdraftPlus prior to doing the actual upload:

    0000.194 (0) Testing: /usr/bin/zip
    0000.200 (0) Output: zip warning: binziptest/test.zip not found or empty
    0000.201 (0) Output: adding: binziptest/subdir1/	(in=0) (out=0) (stored 0%)
    0000.201 (0) Output: adding: binziptest/subdir1/subdir2/	(in=0) (out=0) (stored 0%)
    0000.202 (0) Output: adding: binziptest/subdir1/subdir2/test.html	(in=127) (out=105) (deflated 17%)
    0000.203 (0) Output: total bytes=127, compressed=105 -> 17% savings
    0000.207 (0) Output: adding: binziptest/subdir1/subdir2/test2.html	(in=134) (out=109) (deflated 19%)
    0000.208 (0) Output: total bytes=261, compressed=214 -> 18% savings
    0000.209 (0) Working binary zip found: /usr/bin/zip
    0000.211 (0) Zip engine: found/will use a binary zip: /usr/bin/zip

    Here are the problems:

    1. backup_2014-01-07-0933_Acme...f32e-db.gz was only 472 bytes.
      1. Worse, because the log file did not indicate an error using the words 'error' or 'warning' I was not aware of this problem. I did not notice the file was only 472 bytes and, consequently, when I later needed to restore it was only then that I realized the database backup failed. Looking through the log file I found the following message:

        0297.008 (0) The database backup appears to have failed - the options table was not found

        Again, there should be the word 'error' or 'warning' on this line so that a simple CTRL+F+"warning|error" on the log record would bring such an important error to the user's attention.

      2. The options table, wp_options, exists, contains nearly 300 records, and can be readily backed up and restored via phpAdmin.
    2. After I performed the backup, the UpdraftPlus web screen said:

      Last backup job run: No backup has been completed

      which seemed strange because I was able to verify that all of the backup files were transferred successfully to my Google Drive and, in fact, the last log message said:

      Terminate: This backup job is already finished. (Jan 08 14:09:03)

      Clearly, the UpdraftPlus web screen does not provide accurate information regarding the results of the backup.

    3. Later, when I attempted to restore the UpdraftPlus
      1. the webscreen said:

        Backups, logs & restoring: 0 set(s) available

      2. When I clicked rescan folder for new backup sets no backup sets were found.
      3. My only option, then, was to manually download the files from Google Drive and upload them using UpdraftPlus' upload feature.
      4. So it appears that UpdraftPlus was not querying Google Drive to provide accurate information regarding existing backups, nor was UpdraftPlus obtaining said files.

    Later, I set up a dropbox account and performed a backup with exactly the same results: the files were verified to be uploaded in my dropbox account and yet the database backup was empty and UpdraftPlus said that no backups had occurred and no backup files were available:

    Last backup job run: No backup has been completed

    and

    Backups, logs & restoring: 0 set(s) available

    Tell me what I need to do to make UpdraftPlus work properly.

    http://wordpress.org/plugins/updraftplus/

  2. David Anderson
    Member
    Plugin Author

    Posted 7 months ago #

    Hi,

    Please can you post the log file from the backup?

    The warning message should be shown in your report email, if you configured one.

    David

  3. Pat Garner
    Member
    Posted 7 months ago #

    0000.000 (0) Opened log file at time: Wed, 08 Jan 2014 13:57:00 +0000
    0000.002 (0) UpdraftPlus WordPress backup plugin (http://updraftplus.com): 1.8.5 WP: 3.8 PHP: 5.4.23 (Linux p3plcpnl0103.prod.phx3.secureserver.net 2.6.32-458.18.1.lve1.2.39.el6.x86_64 #1 SMP Mon Sep 16 12:10:28 EEST 2013 x86_64) MySQL: 5.5.32-cll-lve Server: Apache mod_fcgid/2.3.10-dev safe_mode: 0 max_execution_time: 900 memory_limit: 256M (used: 3.2M | 3.5M) multisite: N mcrypt: Y ZipArchive::addFile: Y W3TC/ObjectCache: N
    0000.166 (0) Free space on disk containing Updraft's temporary directory: 3053099.5 Mb
    0000.169 (0) Tasks: Backup files: 1 (schedule: manual) Backup DB: 1 (schedule: manual)
    0000.171 (0) Requesting semaphore lock (fd)
    0000.173 (0) Semaphore (fd) was stuck, set lock time to 2014-01-08 13:57:00
    0000.175 (0) Semaphore (fd) reset to 1
    0000.176 (0) Set semaphore last lock (fd) time to 2014-01-08 13:57:00
    0000.177 (0) Semaphore lock (fd) complete
    0000.182 (0) Backup run: resumption=0, nonce=b717ef74f32e, begun at=1389189420 (0s ago), job type=backup
    0000.183 (0) Scheduling a resumption (1) after 300 seconds (1389189720) in case this run gets aborted
    0000.194 (0) Checking if we have a zip executable available
    0000.194 (0) Testing: /usr/bin/zip
    0000.200 (0) Output: zip warning: binziptest/test.zip not found or empty
    0000.201 (0) Output: adding: binziptest/subdir1/	(in=0) (out=0) (stored 0%)
    0000.201 (0) Output: adding: binziptest/subdir1/subdir2/	(in=0) (out=0) (stored 0%)
    0000.202 (0) Output: adding: binziptest/subdir1/subdir2/test.html	(in=127) (out=105) (deflated 17%)
    0000.203 (0) Output: total bytes=127, compressed=105 -> 17% savings
    0000.207 (0) Output: adding: binziptest/subdir1/subdir2/test2.html	(in=134) (out=109) (deflated 19%)
    0000.208 (0) Output: total bytes=261, compressed=214 -> 18% savings
    0000.209 (0) Working binary zip found: /usr/bin/zip
    0000.211 (0) Zip engine: found/will use a binary zip: /usr/bin/zip
    0000.211 (0) Creation of backups of directories: beginning
    0000.213 (0) Beginning creation of dump of plugins (split every: 800 Mb)
    0000.452 (0) Total entities for the zip file: 115 directories, 1001 files, 9.2 Mb
    0000.458 (0) Zip: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip.tmp: 100 files added (on-disk size: 0 Kb)
    0000.463 (0) Zip: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip.tmp: 200 files added (on-disk size: 0 Kb)
    0000.468 (0) Zip: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip.tmp: 300 files added (on-disk size: 0 Kb)
    0000.474 (0) Zip: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip.tmp: 400 files added (on-disk size: 0 Kb)
    0000.480 (0) Zip: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip.tmp: 500 files added (on-disk size: 0 Kb)
    0000.481 (0) Adding batch to zip file (UpdraftPlus_BinZip): over 500 files added on this batch (2.4 Mb, 1001 files batched, 501 (501) added so far); re-opening (prior size: 0 Kb)
    0003.058 (0) backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip.tmp: size is now: 1.10 Mb
    0003.063 (0) Zip: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip.tmp: 600 files added (on-disk size: 1141.3 Kb)
    0003.066 (0) Zip: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip.tmp: 700 files added (on-disk size: 1141.3 Kb)
    0003.070 (0) Zip: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip.tmp: 800 files added (on-disk size: 1141.3 Kb)
    0003.073 (0) Zip: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip.tmp: 900 files added (on-disk size: 1141.3 Kb)
    0003.076 (0) Zip: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip.tmp: 1000 files added (on-disk size: 1141.3 Kb)
    0008.512 (0) backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip.tmp: size is now: 4.30 Mb
    0008.561 (0) Created plugins zip (0) - 4364 Kb in 8.2 s (533.2 Kb/s) (SHA1 checksum: 019a1fe354cd1ac781ae37612eb965345147f966)
    0008.563 (0) Beginning creation of dump of themes (split every: 800 Mb)
    0008.722 (0) Total entities for the zip file: 43 directories, 331 files, 7.7 Mb
    0008.727 (0) Zip: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-themes.zip.tmp: 100 files added (on-disk size: 0 Kb)
    0008.730 (0) Zip: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-themes.zip.tmp: 200 files added (on-disk size: 0 Kb)
    0008.733 (0) Zip: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-themes.zip.tmp: 300 files added (on-disk size: 0 Kb)
    0009.332 (0) backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-themes.zip.tmp: size is now: 2.90 Mb
    0009.355 (0) Created themes zip (0) - 2999.7 Kb in 0.6 s (4625.2 Kb/s) (SHA1 checksum: ccf82597805b994f8971dd849b5e348a605b9e5c)
    0009.358 (0) Looking for candidates to back up in: /home/patgarner/public_html/wp-content/uploads
    0009.358 (0) finding files: 2014: adding to list
    0009.359 (0) finding files: et_temp: adding to list
    0009.360 (0) Beginning creation of dump of uploads (split every: 800 Mb)
    0009.544 (0) Total entities for the zip file: 3 directories, 247 files, 77.3 Mb
    0009.548 (0) Adding batch to zip file (UpdraftPlus_BinZip): over 25 Mb added on this batch (25.4 Mb, 247 files batched, 78 (78) added so far); re-opening (prior size: 0 Kb)
    0060.603 (0) backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-uploads.zip.tmp: size is now: 25.20 Mb
    0060.605 (0) A useful amount of data was added after this amount of zip processing: 51.1 s (normalised: 50.4 s, rate: 508.1 Kb/s)
    0060.607 (0) Zip: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-uploads.zip.tmp: 100 files added (on-disk size: 25828 Kb)
    0060.609 (0) Adding batch to zip file (UpdraftPlus_BinZip): over 25 Mb added on this batch (25.1 Mb, 247 files batched, 96 (174) added so far); re-opening (prior size: 25828 Kb)
    0136.161 (0) backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-uploads.zip.tmp: size is now: 50.20 Mb
    0136.175 (0) A useful amount of data was added after this amount of zip processing: 75.7 s (normalised: 75.3 s, rate: 340 Kb/s)
    0136.177 (0) Zip: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-uploads.zip.tmp: 200 files added (on-disk size: 51378.2 Kb)
    0136.178 (0) Adding batch to zip file (UpdraftPlus_BinZip): over 25 Mb added on this batch (25.5 Mb, 247 files batched, 65 (239) added so far); re-opening (prior size: 51378.2 Kb)
    0219.289 (0) backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-uploads.zip.tmp: size is now: 75.60 Mb
    0219.293 (0) A useful amount of data was added after this amount of zip processing: 83.8 s (normalised: 82.3 s, rate: 311.1 Kb/s)
    0295.066 (0) The time we have been running (295) is approaching the resumption interval (300) - increasing resumption interval to 326
    0296.267 (0) The time we have been running (296.3) is approaching the resumption interval (326) - increasing resumption interval to 327
    0296.268 (0) The scheduled resumption is within 45 seconds - will reschedule
    0296.268 (0) Rescheduling resumption 1: moving to 372 seconds from now (1389190088)
    0296.269 (0) To decrease the likelihood of overlaps, increasing resumption interval to: 327 + 45 = 372
    0296.788 (0) Created uploads zip (0) - 78746.2 Kb in 287.3 s (274.1 Kb/s) (SHA1 checksum: 53ae05abbc29846c687f22471b69da9db3a7d04e)
    0296.789 (0) Beginning backup of other directories found in the content directory (index: 0)
    0296.790 (0) Looking for candidates to back up in: /home/patgarner/public_html/wp-content
    0296.790 (0) finding files: uploads: skipping: this is the uploads directory
    0296.790 (0) finding files: updraft: skipping: this is the updraft directory
    0296.791 (0) finding files: languages: adding to list
    0296.794 (0) finding files: upgrade: skipping: excluded by options
    0296.794 (0) finding files: index.php: adding to list
    0296.795 (0) finding files: plugins: skipping: this is the plugins directory
    0296.795 (0) finding files: themes: skipping: this is the themes directory
    0296.959 (0) Total entities for the zip file: 1 directories, 1 files, 0 Mb
    0296.967 (0) backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-others.zip.tmp: size is now: 0.00 Mb
    0296.972 (0) Created others zip (0) - 0.3 Kb in 0 s (24.7 Kb/s) (SHA1 checksum: 348f4720d8238ac89392b0c36bd5dcade9dfa400)
    0296.978 (0) Saving backup status to database (elements: 8)
    0296.988 (0) Beginning creation of database dump
    0296.990 (0) Testing: /usr/bin/mysqldump
    0297.006 (0) Working binary mysqldump found: /usr/bin/mysqldump
    0297.008 (0) The database backup appears to have failed - the options table was not found
    0297.009 (0) backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-db.gz: finished writing out complete database file (0 Kb)
    0297.010 (0) Total database tables backed up: 0 (backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-db.gz: checksum (SHA1): 288d65da244f72371de7aff8124f2900219d1a60)
    0297.011 (0) Saving backup history
    0297.013 (0) backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip: plugins: This file has not yet been successfully uploaded: will queue
    0297.013 (0) backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-themes.zip: themes: This file has not yet been successfully uploaded: will queue
    0297.013 (0) backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-uploads.zip: uploads: This file has not yet been successfully uploaded: will queue
    0297.014 (0) backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-others.zip: others: This file has not yet been successfully uploaded: will queue
    0297.014 (0) backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-db.gz: db: This file has not yet been successfully uploaded: will queue
    0297.015 (0) Requesting upload of the files that have not yet been successfully uploaded (5)
    0297.016 (0) Cloud backup selection: dropbox
    0297.017 (0) Beginning dispatch of backup to remote (dropbox)
    0297.017 (0) Dropbox: begin cloud upload
    0297.019 (0) Dropbox: access gained
    0297.441 (0) Dropbox quota usage: normal=0.2 Mb, shared=0 Mb, total=2048 Mb, available=2047.8 Mb
    0297.442 (0) Dropbox: Attempt to upload: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip to: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip
    0298.950 (0) Dropbox chunked upload: 11.7 % uploaded (SuUu8A-5DutJqz-ar8-F7g, 524288)
    0300.624 (0) Dropbox chunked upload: 23.5 % uploaded (SuUu8A-5DutJqz-ar8-F7g, 1048576)
    0300.803 (1) Opened log file at time: Wed, 08 Jan 2014 14:02:01 +0000
    0300.804 (1) UpdraftPlus WordPress backup plugin (http://updraftplus.com): 1.8.5 WP: 3.8 PHP: 5.4.23 (Linux p3plcpnl0103.prod.phx3.secureserver.net 2.6.32-458.18.1.lve1.2.39.el6.x86_64 #1 SMP Mon Sep 16 12:10:28 EEST 2013 x86_64) MySQL: 5.5.32-cll-lve Server: Apache mod_fcgid/2.3.10-dev safe_mode: 0 max_execution_time: 900 memory_limit: 256M (used: 16.4M | 16.8M) multisite: N mcrypt: Y ZipArchive::addFile: Y
    0300.968 (1) Free space on disk containing Updraft's temporary directory: 3053003.3 Mb
    0300.970 (1) Backup run: resumption=1, nonce=b717ef74f32e, begun at=1389189420 (301s ago), job type=backup, previous check-in=219.3s
    0300.971 (1) Scheduling a resumption (2) after 300 seconds (1389190021) in case this run gets aborted
    0300.979 (1) Checking if we have a zip executable available
    0300.980 (1) Zip engine: found/will use a binary zip: /usr/bin/zip
    0300.981 (1) Creation of backups of directories: had begun; will resume
    0300.983 (1) Beginning creation of dump of plugins (split every: 800 Mb)
    0300.983 (1) backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip: this file has already been created (age: 0 s)
    0300.985 (1) Terminate: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip exists with activity within the last 30 seconds (time_mod=1389189721, time_now=1389189721, diff=0, size=n/a). This likely means that another UpdraftPlus run is at work; so we will exit.
    0300.986 (1) Rescheduling resumption 2: moving to 420 seconds from now (1389190141)
    0300.988 (1) To decrease the likelihood of overlaps, increasing resumption interval to: 300 + 120 = 420
    0302.734 (0) Dropbox chunked upload: 35.2 % uploaded (SuUu8A-5DutJqz-ar8-F7g, 1572864)
    0304.577 (0) Dropbox chunked upload: 46.9 % uploaded (SuUu8A-5DutJqz-ar8-F7g, 2097152)
    0306.660 (0) Dropbox chunked upload: 58.7 % uploaded (SuUu8A-5DutJqz-ar8-F7g, 2621440)
    0310.726 (0) Dropbox chunked upload: 70.4 % uploaded (SuUu8A-5DutJqz-ar8-F7g, 3145728)
    0313.038 (0) Dropbox chunked upload: 82.1 % uploaded (SuUu8A-5DutJqz-ar8-F7g, 3670016)
    0315.445 (0) Dropbox chunked upload: 93.9 % uploaded (SuUu8A-5DutJqz-ar8-F7g, 4194304)
    0316.870 (0) Dropbox chunked upload: 100 % uploaded (SuUu8A-5DutJqz-ar8-F7g, 4468739)
    0317.922 (0) Recording as successfully uploaded: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip (061ae1e07600fa726ecb3244f53a671a)
    0317.926 (0) Deleting local file: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip: OK
    0317.927 (0) Dropbox: File upload success (backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-plugins.zip): 4364 Kb in 20s (213 Kb/s)
    0318.158 (0) Dropbox quota usage: normal=4.5 Mb, shared=0 Mb, total=2048 Mb, available=2043.5 Mb
    0318.159 (0) Dropbox: Attempt to upload: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-themes.zip to: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-themes.zip
    0319.579 (0) Dropbox chunked upload: 17.1 % uploaded (ecpmAbixn-7ABvTO5snxuA, 524288)
    0321.165 (0) Dropbox chunked upload: 34.1 % uploaded (ecpmAbixn-7ABvTO5snxuA, 1048576)
    0322.973 (0) Dropbox chunked upload: 51.2 % uploaded (ecpmAbixn-7ABvTO5snxuA, 1572864)
    0325.045 (0) Dropbox chunked upload: 68.3 % uploaded (ecpmAbixn-7ABvTO5snxuA, 2097152)
    0327.197 (0) Dropbox chunked upload: 85.3 % uploaded (ecpmAbixn-7ABvTO5snxuA, 2621440)
    0329.196 (0) Dropbox chunked upload: 100 % uploaded (ecpmAbixn-7ABvTO5snxuA, 3071667)
    0330.879 (0) Recording as successfully uploaded: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-themes.zip (be9e5ed8b2318969d236c4724a0d56e6)
    0330.884 (0) Deleting local file: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-themes.zip: OK
    0330.885 (0) Dropbox: File upload success (backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-themes.zip): 2999 Kb in 12s (235 Kb/s)
    0331.334 (0) Dropbox quota usage: normal=7.4 Mb, shared=0 Mb, total=2048 Mb, available=2040.6 Mb
    0331.335 (0) Dropbox: Attempt to upload: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-uploads.zip to: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-uploads.zip
    0332.891 (0) Dropbox chunked upload: 0.7 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 524288)
    0334.507 (0) Dropbox chunked upload: 1.3 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 1048576)
    0337.866 (0) Dropbox chunked upload: 2 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 1572864)
    0339.797 (0) Dropbox chunked upload: 2.6 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 2097152)
    0342.058 (0) The time we have been running (342.1) is approaching the resumption interval (372) - increasing resumption interval to 373
    0342.059 (0) Dropbox chunked upload: 3.3 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 2621440)
    0344.113 (0) The time we have been running (344.1) is approaching the resumption interval (373) - increasing resumption interval to 375
    0344.115 (0) Dropbox chunked upload: 3.9 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 3145728)
    0346.404 (0) The time we have been running (346.4) is approaching the resumption interval (375) - increasing resumption interval to 377
    0346.405 (0) Dropbox chunked upload: 4.6 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 3670016)
    0349.034 (0) The time we have been running (349) is approaching the resumption interval (377) - increasing resumption interval to 380
    0349.035 (0) Dropbox chunked upload: 5.2 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 4194304)
    0350.533 (0) The time we have been running (350.5) is approaching the resumption interval (380) - increasing resumption interval to 381
    0350.534 (0) Dropbox chunked upload: 5.9 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 4718592)
    0352.343 (0) The time we have been running (352.3) is approaching the resumption interval (381) - increasing resumption interval to 383
    0352.345 (0) Dropbox chunked upload: 6.5 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 5242880)
    0354.034 (0) The time we have been running (354) is approaching the resumption interval (383) - increasing resumption interval to 385
    0354.035 (0) Dropbox chunked upload: 7.2 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 5767168)
    0359.262 (0) The time we have been running (359.3) is approaching the resumption interval (385) - increasing resumption interval to 390
    0359.264 (0) Dropbox chunked upload: 7.8 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 6291456)
    0361.234 (0) The time we have been running (361.2) is approaching the resumption interval (390) - increasing resumption interval to 392
    0361.236 (0) Dropbox chunked upload: 8.5 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 6815744)
    0363.426 (0) The time we have been running (363.4) is approaching the resumption interval (392) - increasing resumption interval to 394
    0363.428 (0) Dropbox chunked upload: 9.1 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 7340032)
    0365.754 (0) The time we have been running (365.8) is approaching the resumption interval (394) - increasing resumption interval to 396
    0365.755 (0) Dropbox chunked upload: 9.8 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 7864320)
    0368.563 (0) The time we have been running (368.6) is approaching the resumption interval (396) - increasing resumption interval to 399
    0368.564 (0) Dropbox chunked upload: 10.4 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 8388608)
    0370.101 (0) The time we have been running (370.1) is approaching the resumption interval (399) - increasing resumption interval to 401
    0370.102 (0) Dropbox chunked upload: 11.1 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 8912896)
    0372.172 (0) The time we have been running (372.2) is approaching the resumption interval (401) - increasing resumption interval to 403
    0372.174 (0) Dropbox chunked upload: 11.7 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 9437184)
    0374.571 (0) The time we have been running (374.6) is approaching the resumption interval (403) - increasing resumption interval to 405
    0374.572 (0) Dropbox chunked upload: 12.4 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 9961472)
    0376.446 (0) The time we have been running (376.4) is approaching the resumption interval (405) - increasing resumption interval to 407
    0376.448 (0) Dropbox chunked upload: 13 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 10485760)
    0378.692 (0) The time we have been running (378.7) is approaching the resumption interval (407) - increasing resumption interval to 409
    0378.693 (0) Dropbox chunked upload: 13.7 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 11010048)
    0380.869 (0) The time we have been running (380.9) is approaching the resumption interval (409) - increasing resumption interval to 411
    0380.871 (0) Dropbox chunked upload: 14.3 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 11534336)
    0383.352 (0) The time we have been running (383.4) is approaching the resumption interval (411) - increasing resumption interval to 414
    0383.353 (0) Dropbox chunked upload: 15 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 12058624)
    0385.730 (0) The time we have been running (385.7) is approaching the resumption interval (414) - increasing resumption interval to 416
    0385.732 (0) Dropbox chunked upload: 15.6 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 12582912)
    0387.226 (0) The time we have been running (387.2) is approaching the resumption interval (416) - increasing resumption interval to 418
    0387.227 (0) Dropbox chunked upload: 16.3 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 13107200)
    0388.779 (0) The time we have been running (388.8) is approaching the resumption interval (418) - increasing resumption interval to 419
    0388.780 (0) Dropbox chunked upload: 16.9 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 13631488)
    0390.473 (0) The time we have been running (390.5) is approaching the resumption interval (419) - increasing resumption interval to 421
    0390.474 (0) Dropbox chunked upload: 17.6 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 14155776)
    0392.235 (0) The time we have been running (392.2) is approaching the resumption interval (421) - increasing resumption interval to 423
    0392.236 (0) Dropbox chunked upload: 18.2 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 14680064)
    0394.391 (0) The time we have been running (394.4) is approaching the resumption interval (423) - increasing resumption interval to 425
    0394.392 (0) Dropbox chunked upload: 18.9 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 15204352)
    0397.134 (0) The time we have been running (397.1) is approaching the resumption interval (425) - increasing resumption interval to 428
    0397.135 (0) Dropbox chunked upload: 19.5 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 15728640)
    0399.372 (0) The time we have been running (399.4) is approaching the resumption interval (428) - increasing resumption interval to 430
    0399.372 (0) Dropbox chunked upload: 20.2 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 16252928)
    0402.134 (0) The time we have been running (402.1) is approaching the resumption interval (430) - increasing resumption interval to 433
    0402.135 (0) Dropbox chunked upload: 20.8 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 16777216)
    0403.607 (0) The time we have been running (403.6) is approaching the resumption interval (433) - increasing resumption interval to 434
    0403.609 (0) Dropbox chunked upload: 21.5 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 17301504)
    0405.243 (0) The time we have been running (405.2) is approaching the resumption interval (434) - increasing resumption interval to 436
    0405.245 (0) Dropbox chunked upload: 22.1 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 17825792)
    0408.772 (0) The time we have been running (408.8) is approaching the resumption interval (436) - increasing resumption interval to 439
    0408.773 (0) Dropbox chunked upload: 22.8 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 18350080)
    0410.572 (0) The time we have been running (410.6) is approaching the resumption interval (439) - increasing resumption interval to 441
    0410.573 (0) Dropbox chunked upload: 23.4 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 18874368)
    0412.774 (0) The time we have been running (412.8) is approaching the resumption interval (441) - increasing resumption interval to 443
    0412.776 (0) Dropbox chunked upload: 24.1 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 19398656)
    0414.926 (0) The time we have been running (414.9) is approaching the resumption interval (443) - increasing resumption interval to 445
    0414.927 (0) Dropbox chunked upload: 24.7 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 19922944)
    0417.419 (0) The time we have been running (417.4) is approaching the resumption interval (445) - increasing resumption interval to 448
    0417.420 (0) Dropbox chunked upload: 25.4 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 20447232)
    0420.935 (0) The time we have been running (420.9) is approaching the resumption interval (448) - increasing resumption interval to 451
    0420.936 (0) Dropbox chunked upload: 26 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 20971520)
    0423.280 (0) The time we have been running (423.3) is approaching the resumption interval (451) - increasing resumption interval to 454
    0423.280 (0) Dropbox chunked upload: 26.7 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 21495808)
    0424.909 (0) The time we have been running (424.9) is approaching the resumption interval (454) - increasing resumption interval to 455
    0424.910 (0) Dropbox chunked upload: 27.3 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 22020096)
    0426.773 (0) The time we have been running (426.8) is approaching the resumption interval (455) - increasing resumption interval to 457
    0426.774 (0) Dropbox chunked upload: 28 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 22544384)
    0428.948 (0) The time we have been running (428.9) is approaching the resumption interval (457) - increasing resumption interval to 459
    0428.949 (0) Dropbox chunked upload: 28.6 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 23068672)
    0431.129 (0) The time we have been running (431.1) is approaching the resumption interval (459) - increasing resumption interval to 462
    0431.131 (0) Dropbox chunked upload: 29.3 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 23592960)
    0433.805 (0) The time we have been running (433.8) is approaching the resumption interval (462) - increasing resumption interval to 464
    0433.806 (0) Dropbox chunked upload: 29.9 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 24117248)
    0436.832 (0) The time we have been running (436.8) is approaching the resumption interval (464) - increasing resumption interval to 467
    0436.833 (0) Dropbox chunked upload: 30.6 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 24641536)
    0442.464 (0) The time we have been running (442.5) is approaching the resumption interval (467) - increasing resumption interval to 473
    0442.465 (0) Dropbox chunked upload: 31.2 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 25165824)
    0443.766 (0) The time we have been running (443.8) is approaching the resumption interval (473) - increasing resumption interval to 474
    0443.767 (0) Dropbox chunked upload: 31.9 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 25690112)
    0445.437 (0) The time we have been running (445.4) is approaching the resumption interval (474) - increasing resumption interval to 476
    0445.465 (0) Dropbox chunked upload: 32.5 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 26214400)
    0447.213 (0) The time we have been running (447.2) is approaching the resumption interval (476) - increasing resumption interval to 478
    0447.214 (0) Dropbox chunked upload: 33.2 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 26738688)
    0448.966 (0) The time we have been running (449) is approaching the resumption interval (478) - increasing resumption interval to 479
    0448.967 (0) Dropbox chunked upload: 33.8 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 27262976)
    0451.624 (0) The time we have been running (451.6) is approaching the resumption interval (479) - increasing resumption interval to 482
    0451.624 (0) Dropbox chunked upload: 34.5 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 27787264)
    0453.817 (0) The time we have been running (453.8) is approaching the resumption interval (482) - increasing resumption interval to 484
    0453.818 (0) Dropbox chunked upload: 35.1 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 28311552)
    0456.161 (0) The time we have been running (456.2) is approaching the resumption interval (484) - increasing resumption interval to 487
    0456.162 (0) Dropbox chunked upload: 35.8 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 28835840)
    0458.974 (0) The time we have been running (459) is approaching the resumption interval (487) - increasing resumption interval to 489
    0458.975 (0) Dropbox chunked upload: 36.4 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 29360128)
    0460.320 (0) The time we have been running (460.3) is approaching the resumption interval (489) - increasing resumption interval to 491
    0460.322 (0) Dropbox chunked upload: 37.1 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 29884416)
    0462.592 (0) The time we have been running (462.6) is approaching the resumption interval (491) - increasing resumption interval to 493
    0462.594 (0) Dropbox chunked upload: 37.7 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 30408704)
    0464.227 (0) The time we have been running (464.2) is approaching the resumption interval (493) - increasing resumption interval to 495
    0464.228 (0) Dropbox chunked upload: 38.4 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 30932992)
    0466.364 (0) The time we have been running (466.4) is approaching the resumption interval (495) - increasing resumption interval to 497
    0466.365 (0) Dropbox chunked upload: 39 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 31457280)
    0468.345 (0) The time we have been running (468.3) is approaching the resumption interval (497) - increasing resumption interval to 499
    0468.347 (0) Dropbox chunked upload: 39.7 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 31981568)
    0470.600 (0) The time we have been running (470.6) is approaching the resumption interval (499) - increasing resumption interval to 501
    0470.602 (0) Dropbox chunked upload: 40.3 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 32505856)
    0473.700 (0) The time we have been running (473.7) is approaching the resumption interval (501) - increasing resumption interval to 504
    0473.702 (0) Dropbox chunked upload: 41 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 33030144)
    0476.179 (0) The time we have been running (476.2) is approaching the resumption interval (504) - increasing resumption interval to 507
    0476.180 (0) Dropbox chunked upload: 41.6 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 33554432)
    0477.572 (0) The time we have been running (477.6) is approaching the resumption interval (507) - increasing resumption interval to 508
    0477.573 (0) Dropbox chunked upload: 42.3 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 34078720)
    0479.241 (0) The time we have been running (479.2) is approaching the resumption interval (508) - increasing resumption interval to 510
    0479.242 (0) Dropbox chunked upload: 42.9 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 34603008)
    0481.000 (0) The time we have been running (481) is approaching the resumption interval (510) - increasing resumption interval to 511
    0481.001 (0) Dropbox chunked upload: 43.6 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 35127296)
    0483.664 (0) The time we have been running (483.7) is approaching the resumption interval (511) - increasing resumption interval to 514
    0483.666 (0) Dropbox chunked upload: 44.2 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 35651584)
    0485.683 (0) The time we have been running (485.7) is approaching the resumption interval (514) - increasing resumption interval to 516
    0485.684 (0) Dropbox chunked upload: 44.9 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 36175872)
    0487.801 (0) The time we have been running (487.8) is approaching the resumption interval (516) - increasing resumption interval to 518
    0487.802 (0) Dropbox chunked upload: 45.5 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 36700160)
    0490.177 (0) The time we have been running (490.2) is approaching the resumption interval (518) - increasing resumption interval to 521
    0490.178 (0) Dropbox chunked upload: 46.2 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 37224448)
    0495.726 (0) The time we have been running (495.7) is approaching the resumption interval (521) - increasing resumption interval to 526
    0495.727 (0) Dropbox chunked upload: 46.8 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 37748736)
    0497.271 (0) The time we have been running (497.3) is approaching the resumption interval (526) - increasing resumption interval to 528
    0497.272 (0) Dropbox chunked upload: 47.5 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 38273024)
    0498.853 (0) The time we have been running (498.9) is approaching the resumption interval (528) - increasing resumption interval to 529
    0498.854 (0) Dropbox chunked upload: 48.1 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 38797312)
    0500.464 (0) The time we have been running (500.5) is approaching the resumption interval (529) - increasing resumption interval to 531
    0500.465 (0) Dropbox chunked upload: 48.8 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 39321600)
    0502.331 (0) The time we have been running (502.3) is approaching the resumption interval (531) - increasing resumption interval to 533
    0502.334 (0) Dropbox chunked upload: 49.4 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 39845888)
    0504.712 (0) The time we have been running (504.7) is approaching the resumption interval (533) - increasing resumption interval to 535
    0504.714 (0) Dropbox chunked upload: 50.1 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 40370176)
    0506.720 (0) The time we have been running (506.7) is approaching the resumption interval (535) - increasing resumption interval to 537
    0506.721 (0) Dropbox chunked upload: 50.7 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 40894464)
    0509.239 (0) The time we have been running (509.2) is approaching the resumption interval (537) - increasing resumption interval to 540
    0509.241 (0) Dropbox chunked upload: 51.4 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 41418752)
    0511.696 (0) The time we have been running (511.7) is approaching the resumption interval (540) - increasing resumption interval to 542
    0511.698 (0) Dropbox chunked upload: 52 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 41943040)
    0513.063 (0) The time we have been running (513.1) is approaching the resumption interval (542) - increasing resumption interval to 544
    0513.065 (0) Dropbox chunked upload: 52.7 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 42467328)
    0514.462 (0) The time we have been running (514.5) is approaching the resumption interval (544) - increasing resumption interval to 545
    0514.463 (0) Dropbox chunked upload: 53.3 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 42991616)
    0516.160 (0) The time we have been running (516.2) is approaching the resumption interval (545) - increasing resumption interval to 547
    0516.161 (0) Dropbox chunked upload: 54 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 43515904)
    0517.993 (0) The time we have been running (518) is approaching the resumption interval (547) - increasing resumption interval to 548
    0517.996 (0) Dropbox chunked upload: 54.6 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 44040192)
    0519.879 (0) The time we have been running (519.9) is approaching the resumption interval (548) - increasing resumption interval to 550
    0519.881 (0) Dropbox chunked upload: 55.3 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 44564480)
    0521.848 (0) The time we have been running (521.8) is approaching the resumption interval (550) - increasing resumption interval to 552
    0521.850 (0) Dropbox chunked upload: 55.9 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 45088768)
    0525.341 (0) The time we have been running (525.3) is approaching the resumption interval (552) - increasing resumption interval to 556
    0525.343 (0) Dropbox chunked upload: 56.6 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 45613056)
    0528.234 (0) The time we have been running (528.2) is approaching the resumption interval (556) - increasing resumption interval to 559
    0528.236 (0) Dropbox chunked upload: 57.2 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 46137344)
    0529.558 (0) The time we have been running (529.6) is approaching the resumption interval (559) - increasing resumption interval to 560
    0529.560 (0) Dropbox chunked upload: 57.9 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 46661632)
    0531.072 (0) The time we have been running (531.1) is approaching the resumption interval (560) - increasing resumption interval to 562
    0531.074 (0) Dropbox chunked upload: 58.5 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 47185920)
    0532.724 (0) The time we have been running (532.7) is approaching the resumption interval (562) - increasing resumption interval to 563
    0532.726 (0) Dropbox chunked upload: 59.2 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 47710208)
    0534.731 (0) The time we have been running (534.7) is approaching the resumption interval (563) - increasing resumption interval to 565
    0534.732 (0) Dropbox chunked upload: 59.8 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 48234496)
    0536.682 (0) The time we have been running (536.7) is approaching the resumption interval (565) - increasing resumption interval to 567
    0536.684 (0) Dropbox chunked upload: 60.5 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 48758784)
    0538.742 (0) The time we have been running (538.7) is approaching the resumption interval (567) - increasing resumption interval to 569
    0538.744 (0) Dropbox chunked upload: 61.1 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 49283072)
    0543.348 (0) The time we have been running (543.3) is approaching the resumption interval (569) - increasing resumption interval to 574
    0543.350 (0) Dropbox chunked upload: 61.8 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 49807360)
    0545.687 (0) The time we have been running (545.7) is approaching the resumption interval (574) - increasing resumption interval to 576
    0545.689 (0) Dropbox chunked upload: 62.4 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 50331648)
    0547.304 (0) The time we have been running (547.3) is approaching the resumption interval (576) - increasing resumption interval to 578
    0547.306 (0) Dropbox chunked upload: 63.1 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 50855936)
    0548.802 (0) The time we have been running (548.8) is approaching the resumption interval (578) - increasing resumption interval to 579
    0548.803 (0) Dropbox chunked upload: 63.7 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 51380224)
    0550.552 (0) The time we have been running (550.6) is approaching the resumption interval (579) - increasing resumption interval to 581
    0550.554 (0) Dropbox chunked upload: 64.4 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 51904512)
    0552.862 (0) The time we have been running (552.9) is approaching the resumption interval (581) - increasing resumption interval to 583
    0552.863 (0) Dropbox chunked upload: 65 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 52428800)
    0555.326 (0) The time we have been running (555.3) is approaching the resumption interval (583) - increasing resumption interval to 586
    0555.327 (0) Dropbox chunked upload: 65.7 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 52953088)
    0557.745 (0) The time we have been running (557.7) is approaching the resumption interval (586) - increasing resumption interval to 588
    0557.747 (0) Dropbox chunked upload: 66.3 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 53477376)
    0560.607 (0) The time we have been running (560.6) is approaching the resumption interval (588) - increasing resumption interval to 591
    0560.609 (0) Dropbox chunked upload: 67 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 54001664)
    0563.928 (0) The time we have been running (563.9) is approaching the resumption interval (591) - increasing resumption interval to 594
    0563.931 (0) Dropbox chunked upload: 67.6 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 54525952)
    0565.897 (0) The time we have been running (565.9) is approaching the resumption interval (594) - increasing resumption interval to 596
    0565.898 (0) Dropbox chunked upload: 68.3 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 55050240)
    0567.665 (0) The time we have been running (567.7) is approaching the resumption interval (596) - increasing resumption interval to 598
    0567.667 (0) Dropbox chunked upload: 68.9 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 55574528)
    0569.421 (0) The time we have been running (569.4) is approaching the resumption interval (598) - increasing resumption interval to 600
    0569.422 (0) Dropbox chunked upload: 69.6 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 56098816)
    0571.224 (0) The time we have been running (571.2) is approaching the resumption interval (600) - increasing resumption interval to 602
    0571.226 (0) Dropbox chunked upload: 70.2 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 56623104)
    0573.155 (0) The time we have been running (573.2) is approaching the resumption interval (602) - increasing resumption interval to 604
    0573.157 (0) Dropbox chunked upload: 70.9 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 57147392)
    0575.366 (0) The time we have been running (575.4) is approaching the resumption interval (604) - increasing resumption interval to 606
    0575.368 (0) Dropbox chunked upload: 71.5 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 57671680)
    0577.700 (0) The time we have been running (577.7) is approaching the resumption interval (606) - increasing resumption interval to 608
    0577.702 (0) Dropbox chunked upload: 72.2 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 58195968)
    0580.299 (0) The time we have been running (580.3) is approaching the resumption interval (608) - increasing resumption interval to 611
    0580.300 (0) Dropbox chunked upload: 72.8 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 58720256)
    0581.698 (0) The time we have been running (581.7) is approaching the resumption interval (611) - increasing resumption interval to 612
    0581.700 (0) Dropbox chunked upload: 73.5 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 59244544)
    0583.262 (0) The time we have been running (583.3) is approaching the resumption interval (612) - increasing resumption interval to 614
    0583.263 (0) Dropbox chunked upload: 74.1 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 59768832)
    0584.970 (0) The time we have been running (585) is approaching the resumption interval (614) - increasing resumption interval to 615
    0584.971 (0) Dropbox chunked upload: 74.8 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 60293120)
    0586.825 (0) The time we have been running (586.8) is approaching the resumption interval (615) - increasing resumption interval to 617
    0586.826 (0) Dropbox chunked upload: 75.4 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 60817408)
    0588.980 (0) The time we have been running (589) is approaching the resumption interval (617) - increasing resumption interval to 619
    0588.981 (0) Dropbox chunked upload: 76.1 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 61341696)
    0591.063 (0) The time we have been running (591.1) is approaching the resumption interval (619) - increasing resumption interval to 622
    0591.065 (0) Dropbox chunked upload: 76.7 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 61865984)
    0594.602 (0) The time we have been running (594.6) is approaching the resumption interval (622) - increasing resumption interval to 625
    0594.604 (0) Dropbox chunked upload: 77.4 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 62390272)
    0597.648 (0) The time we have been running (597.6) is approaching the resumption interval (625) - increasing resumption interval to 628
    0597.649 (0) Dropbox chunked upload: 78 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 62914560)
    0599.143 (0) The time we have been running (599.1) is approaching the resumption interval (628) - increasing resumption interval to 630
    0599.144 (0) Dropbox chunked upload: 78.7 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 63438848)
    0602.324 (0) The time we have been running (602.3) is approaching the resumption interval (630) - increasing resumption interval to 633
    0602.326 (0) Dropbox chunked upload: 79.3 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 63963136)
    0604.221 (0) The time we have been running (604.2) is approaching the resumption interval (633) - increasing resumption interval to 635
    0604.222 (0) Dropbox chunked upload: 80 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 64487424)
    0606.177 (0) The time we have been running (606.2) is approaching the resumption interval (635) - increasing resumption interval to 637
    0606.179 (0) Dropbox chunked upload: 80.6 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 65011712)
    0608.166 (0) The time we have been running (608.2) is approaching the resumption interval (637) - increasing resumption interval to 639
    0608.167 (0) Dropbox chunked upload: 81.3 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 65536000)
    0610.612 (0) The time we have been running (610.6) is approaching the resumption interval (639) - increasing resumption interval to 641
    0610.613 (0) Dropbox chunked upload: 81.9 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 66060288)
    0613.453 (0) The time we have been running (613.5) is approaching the resumption interval (641) - increasing resumption interval to 644
    0613.455 (0) Dropbox chunked upload: 82.6 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 66584576)
    0616.336 (0) The time we have been running (616.3) is approaching the resumption interval (644) - increasing resumption interval to 647
    0616.338 (0) Dropbox chunked upload: 83.2 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 67108864)
    0618.017 (0) The time we have been running (618) is approaching the resumption interval (647) - increasing resumption interval to 649
    0618.018 (0) Dropbox chunked upload: 83.9 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 67633152)
    0619.609 (0) The time we have been running (619.6) is approaching the resumption interval (649) - increasing resumption interval to 650
    0619.611 (0) Dropbox chunked upload: 84.5 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 68157440)
    0621.426 (0) The time we have been running (621.4) is approaching the resumption interval (650) - increasing resumption interval to 652
    0621.428 (0) Dropbox chunked upload: 85.2 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 68681728)
    0623.256 (0) The time we have been running (623.3) is approaching the resumption interval (652) - increasing resumption interval to 654
    0623.258 (0) The scheduled resumption is within 45 seconds - will reschedule
    0623.260 (0) Rescheduling resumption 1: moving to 699 seconds from now (1389190742)
    0623.262 (0) To decrease the likelihood of overlaps, increasing resumption interval to: 654 + 45 = 699
    0623.262 (0) Dropbox chunked upload: 85.8 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 69206016)
    0625.285 (0) Dropbox chunked upload: 86.5 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 69730304)
    0627.757 (0) Dropbox chunked upload: 87.1 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 70254592)
    0631.718 (0) Dropbox chunked upload: 87.8 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 70778880)
    0634.485 (0) Dropbox chunked upload: 88.4 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 71303168)
    0636.031 (0) Dropbox chunked upload: 89.1 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 71827456)
    0637.760 (0) Dropbox chunked upload: 89.7 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 72351744)
    0639.829 (0) Dropbox chunked upload: 90.4 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 72876032)
    0641.771 (0) Dropbox chunked upload: 91 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 73400320)
    0644.296 (0) Dropbox chunked upload: 91.7 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 73924608)
    0647.185 (0) Dropbox chunked upload: 92.3 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 74448896)
    0649.352 (0) Dropbox chunked upload: 93 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 74973184)
    0651.752 (0) Dropbox chunked upload: 93.6 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 75497472)
    0653.132 (0) Dropbox chunked upload: 94.3 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 76021760)
    0654.823 (0) Dropbox chunked upload: 94.9 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 76546048)
    0656.496 (0) Dropbox chunked upload: 95.6 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 77070336)
    0658.491 (0) Dropbox chunked upload: 96.2 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 77594624)
    0660.425 (0) Dropbox chunked upload: 96.9 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 78118912)
    0663.611 (0) Dropbox chunked upload: 97.5 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 78643200)
    0665.858 (0) Dropbox chunked upload: 98.2 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 79167488)
    0668.300 (0) Dropbox chunked upload: 98.8 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 79691776)
    0669.573 (0) The time we have been running (669.6) is approaching the resumption interval (699) - increasing resumption interval to 700
    0669.574 (0) Dropbox chunked upload: 99.3 % uploaded (IUzDjclfaI_Z9Mr2ihnvEw, 80111798)
    0671.099 (0) Recording as successfully uploaded: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-uploads.zip (e00b248dc6e2defd8906c88d157ba22c)
    0671.124 (0) Deleting local file: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-uploads.zip: OK
    0671.124 (0) Dropbox: File upload success (backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-uploads.zip): 78746 Kb in 339s (231 Kb/s)
    0671.431 (0) Dropbox quota usage: normal=83.8 Mb, shared=0 Mb, total=2048 Mb, available=1964.2 Mb
    0671.432 (0) Dropbox: Attempt to upload: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-others.zip to: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-others.zip
    0672.432 (0) The time we have been running (672.4) is approaching the resumption interval (700) - increasing resumption interval to 703
    0672.433 (0) Dropbox chunked upload: 100 % uploaded (gWHzZYXfZuV0y6s6bOEgVQ, 344)
    0673.405 (0) Recording as successfully uploaded: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-others.zip (5578aaded787ef39eb5a2e83ddd47271)
    0673.408 (0) Deleting local file: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-others.zip: OK
    0673.409 (0) Dropbox: File upload success (backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-others.zip): 0 Kb in 1s (0 Kb/s)
    0673.633 (0) Dropbox quota usage: normal=83.8 Mb, shared=0 Mb, total=2048 Mb, available=1964.2 Mb
    0673.634 (0) Dropbox: Attempt to upload: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-db.gz to: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-db.gz
    0674.797 (0) The time we have been running (674.8) is approaching the resumption interval (703) - increasing resumption interval to 705
    0674.798 (0) Dropbox chunked upload: 100 % uploaded (ZaIIsq9YfnJ40gEwx0qKKA, 473)
    0675.848 (0) Recording as successfully uploaded: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-db.gz (8147028dca054424d54861fec128b315)
    0675.850 (0) Deleting local file: backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-db.gz: OK
    0675.850 (0) Dropbox: File upload success (backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-db.gz): 0 Kb in 2s (0 Kb/s)
    0675.852 (0) Retain: beginning examination of existing backup sets; user setting: retain_files=2, retain_db=2
    0675.853 (0) Number of backup sets in history: 0
    0675.854 (0) Retain: saving new backup history (sets now: 0) and finishing retain operation
    0675.854 (0) Resume backup (b717ef74f32e, 0): finish run
    0675.855 (0) Decremented the semaphore (fd) by 1
    0675.856 (0) Semaphore (fd) still locked
    0675.856 (0) There were no errors in the uploads, so the 'resume' event (1) is being unscheduled
    0675.858 (0) No email will/can be sent - the user has not configured an email address.
    0675.858 (0) The backup apparently succeeded (with warnings) and is now complete
    0722.985 (2) Opened log file at time: Wed, 08 Jan 2014 14:09:03 +0000
    0722.986 (2) UpdraftPlus WordPress backup plugin (http://updraftplus.com): 1.8.5 WP: 3.8 PHP: 5.4.23 (Linux p3plcpnl0103.prod.phx3.secureserver.net 2.6.32-458.18.1.lve1.2.39.el6.x86_64 #1 SMP Mon Sep 16 12:10:28 EEST 2013 x86_64) MySQL: 5.5.32-cll-lve Server: Apache mod_fcgid/2.3.10-dev safe_mode: 0 max_execution_time: 900 memory_limit: 256M (used: 3.3M | 3.5M) multisite: N mcrypt: Y ZipArchive::addFile: Y
    0723.150 (2) Free space on disk containing Updraft's temporary directory: 3053049.7 Mb
    0723.154 (2) Backup run: resumption=2, nonce=b717ef74f32e, begun at=1389189420 (723s ago), job type=backup, previous check-in=0.2s
    0723.154 (2) Terminate: This backup job is already finished.
  4. David Anderson
    Member
    Plugin Author

    Posted 7 months ago #

    The log appears to show a completely successful backup.

    Backups, logs & restoring: 0 set(s) available

    Does that show even after you reload the UpdraftPlus settings page?

    0675.858 (0) No email will/can be sent - the user has not configured an email address.

    That's why you're not receiving an email report upon what warnings there were.

    David

  5. Pat Garner
    Member
    Posted 7 months ago #

    Actually, no, the log does not show a completely successful backup:

    0297.008 (0) The database backup appears to have failed - the options table was not found

    Also, the other problem exists e.g. this is what appears after logging back into WordPress and reloading the UpdraftPlus settings page:

    Last backup job run: No backup has been completed.

    Last log message: Terminate: This backup job is already finished. (Jan 08 14:09:03)

    Backups, logs & restoring: 0 set(s) available

    And finally, just for clarification, this support thread is not about receiving email warnings, as I prefer to simply work with the logs. If you recall, my issue is that the log itself does not use the term 'error' or 'warning' when the database backup fails, and this makes it very easy to miss the failure, just as you have missed it when you said, "The log appears to show a completely successful backup."

    Typically with log files when such a serious error occurs the word 'error' or 'warning' is displayed on the line so that a simple text search (e.g. CTRL+F) using the search term "error" or "warning" will quickly highlight the log entry where the failure occurred. So, my feedback to you is please change this:

    0297.008 (0) The database backup appears to have failed - the options table was not found

    to this:

    0297.008 (0) [Error]The database backup appears to have failed - the options table was not found

    or this:

    0297.008 (0) [WARNING]The database backup appears to have failed - the options table was not found

    or any reasonable facsimile thereof, as long as either of the two words appear on the line. If you would annotate the log entry where a failure occurs with 'error' or 'warning' it would be greatly appreciated.

  6. David Anderson
    Member
    Plugin Author

    Posted 7 months ago #

    Sorry for being slow - I have some kind of fever coming on.

    Got it.

    1) I've now tweaked the code so that [Warning] is added before logged warnings.
    Errors are complicated, because of foreign language translation issues and how we originally solved those issues (in the days before we'd even introduced warnings - when we introduced warnings, we did it right first time), and the net result is that needs more work to achieve. Note, however, that the first error in the log file (if any) is already always followed with these words: "An error condition has occurred for the first time during this job"

    2) I suspect that your DB problem is because by the time it reaches time for the database backup, the database connection has been dropped. On a resumption, it ought to find them (because on the resumption, much less time would have passed, since the files are already finished, so the connection won't have dropped).

    Unfortunately WordPress's database API doesn't have a way to tell plugins that the database connection dropped. There's a bug for that in the WordPress bug tracker somewhere, IIRC.

    Finding zero tables should really be an error condition, rather than a warning, for two reasons: 1) It fits our definition of error (something definitely is wrong!) 2) Warning conditions don't cause resumptions, so this was preventing a second attempt.

    I've tweaked the code to get this right. Please try the dev version (should show as 1.8.6) and let me know how you get on:
    http://updraftplus.com/faqs/devversion

    David

  7. Pat Garner
    Member
    Posted 7 months ago #

    Great! Thanks so much for making that change. I will check out v. 1.8.6.

    Regarding the database problem, so to make sure I understand you correctly, the following log entry:

    0297.008 (0) The database backup appears to have failed - the options table was not found

    leads you to believe that the database connection has dropped, or timed out? It seems to me that this is not the case for two reasons:

    0296.978 (0) Saving backup status to database (elements: 8)
    0296.988 (0) Beginning creation of database dump
    0296.990 (0) Testing: /usr/bin/mysqldump
    0297.006 (0) Working binary mysqldump found: /usr/bin/mysqldump
    0297.008 (0) The database backup appears to have failed - the options table was not found
    0297.009 (0) backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-db.gz: finished writing out complete database file (0 Kb)
    0297.010 (0) Total database tables backed up: 0 (backup_2014-01-08-1357_Acme_Conservation_Cemetery_b717ef74f32e-db.gz: checksum (SHA1): 288d65da244f72371de7aff8124f2900219d1a60)
    1. The timing. It appears that the first time the database is touched is at t=0296.978. By the time the error has occurred, at t=0297.008, only 30 milliseconds have passed. Definitely not enough time to time out the connection. Because this error is repeatable -- it's happening every time, it's not a sporadic problem -- I think the issue is not that the db connection is being dropped.
    2. The error message itself. The error message says that it cannot find the options table. I assume this means it cannot find wp_options? I don't know what the options table is but the wp_options does exist, it contains data and as I said, above, I am able to export and import wp_options via phpAdmin without error.

    To me, it seems that the problem is not the db connection is being dropped; it's that the options table cannot be found, whatever that is. When the db connection is dropped does the error message always say that "The database backup appears to have failed - the options table was not found?"

  8. David Anderson
    Member
    Plugin Author

    Posted 7 months ago #

    Hi Pat,

    1. No. As I said, WP does not indicate to plugins if its database layer is still working. So the message "Saving backup status to database (elements: 8)" really means "we told WP to do it", not that WP actually did it.

    2. The options table is ${table_prefix}options. The error message is generic.

    The main purpose of the log file is so that the developers can debug. It's not written in a way such that any user can read any message and immediately know its meaning. The apparent surface meaning of a log file long may be wrong; the way to interpret the log file is by reading the code: http://plugins.svn.wordpress.org/updraftplus/trunk/

    David

  9. Pat Garner
    Member
    Posted 7 months ago #

    Ok. Got it. The error message is saying that it cannot find the wp_options table.

    So, I'm out of ideas. Is this a common problem that you have encountered? Others must have submitted support requests on this. After all, my WordPress installation is on a standard GoDaddy account. I presume that GoDaddy's database configuration is a pretty standard setup and that db connections are not dropped so readily.

    It's just weird that the problem is deterministic. It's repeatable, happening in exactly the same place in the backup process at exactly the same time.

  10. David Anderson
    Member
    Plugin Author

    Posted 7 months ago #

    Did you try the dev version? http://updraftplus.com/faqs/devversion

    We just had another customer with precisely the same issue (which is quite surprising, as we'd never seen it before, and got to 500,000 downloads). The dev version fixed it for them.

    David

  11. Pat Garner
    Member
    Posted 7 months ago #

    It's also troubling that although the backup files have been successfully uploaded to Google Drive (in the first case) and DropBox (in the second case) that UpdraftPlus is telling me that:

    Last backup job run: No backup has been completed.
    Last log message: Terminate: This backup job is already finished. (Jan 08 14:09:03)
    Backups, logs & restoring: 0 set(s) available

    This aspect of the problems I've been encountering with UpdraftPlus remains unresolved as well.

  12. David Anderson
    Member
    Plugin Author

    Posted 7 months ago #

    Hi Pat,

    Have you actually tried the dev version? If not, how do you know that it's not resolved? I explained in an earlier post that the issue should have been logged as an 'error' instead of a 'warning', and that this has been changed in the dev version - have you tried it?

    David

  13. Pat Garner
    Member
    Posted 7 months ago #

    Sorry, our last posts in this thread crossed at the same time; I didn't get a chance to read your last message until after I sent mine, so sorry for the confusion. Also, I had a meeting to attend so am now just getting back to my workstation, so sorry for the delayed response. After deactivating/deleting the old version of UpdraftPlus I installed the dev version per your instructions and I reran the backup. Here are the results:

    1. backup_2014-01-09-0157_Acme...others.zip --> 344 bytes
    2. backup_2014-01-09-0157_Acme...plugins.zip --> 4.3 MB
    3. backup_2014-01-09-0157_Acme...themes.zip ---> 2.9 MB
    4. backup_2014-01-09-0157_Acme...uploads.zip --> 76.9 MB
    5. backup_2014-01-09-0157_Acme...2684-db.gz ---> 108 kB

    So the database portion of the backup is no longer empty. Awesome! As I suspected, the db was not dropping connections.

    There was an error in the log:

    0236.276 (0) Error: No database tables found (SHOW TABLES returned nothing) - please wait for the rescheduled attempt
    0236.276 (0) An error condition has occurred for the first time during this job

    but the backup appears to have recovered from it:

    0663.861 (1) There were no errors in the uploads, so the 'resume' event (2) is being unscheduled

    Here is the entire log output:

    0000.000 (0) Opened log file at time: Thu, 09 Jan 2014 01:57:45 +0000
    0000.001 (0) UpdraftPlus WordPress backup plugin (http://updraftplus.com): 1.8.6 WP: 3.8 PHP: 5.4.23 (Linux p3plcpnl0103.prod.phx3.secureserver.net 2.6.32-458.18.1.lve1.2.39.el6.x86_64 #1 SMP Mon Sep 16 12:10:28 EEST 2013 x86_64) MySQL: 5.5.32-cll-lve Server: Apache mod_fcgid/2.3.10-dev safe_mode: 0 max_execution_time: 900 memory_limit: 256M (used: 16.4M | 16.8M) multisite: N mcrypt: Y ZipArchive::addFile: Y W3TC/ObjectCache: N
    0000.166 (0) Free space on disk containing Updraft's temporary directory: 3042833 Mb
    0000.169 (0) Tasks: Backup files: 1 (schedule: manual) Backup DB: 1 (schedule: manual)
    0000.174 (0) Requesting semaphore lock (fd)
    0000.176 (0) Semaphore (fd) was stuck, set lock time to 2014-01-09 01:57:45
    0000.178 (0) Semaphore (fd) reset to 1
    0000.179 (0) Set semaphore last lock (fd) time to 2014-01-09 01:57:45
    0000.180 (0) Semaphore lock (fd) complete
    0000.185 (0) Backup run: resumption=0, nonce=bd1700b52684, begun at=1389232665 (0s ago), job type=backup
    0000.186 (0) Scheduling a resumption (1) after 300 seconds (1389232965) in case this run gets aborted
    0000.200 (0) Checking if we have a zip executable available
    0000.201 (0) Testing: /usr/bin/zip
    0000.209 (0) Output: zip warning: binziptest/test.zip not found or empty
    0000.210 (0) Output: adding: binziptest/subdir1/	(in=0) (out=0) (stored 0%)
    0000.212 (0) Output: adding: binziptest/subdir1/subdir2/	(in=0) (out=0) (stored 0%)
    0000.213 (0) Output: adding: binziptest/subdir1/subdir2/test.html	(in=127) (out=105) (deflated 17%)
    0000.214 (0) Output: total bytes=127, compressed=105 -> 17% savings
    0000.221 (0) Output: adding: binziptest/subdir1/subdir2/test2.html	(in=134) (out=109) (deflated 19%)
    0000.223 (0) Output: total bytes=261, compressed=214 -> 18% savings
    0000.238 (0) Working binary zip found: /usr/bin/zip
    0000.240 (0) Zip engine: found/will use a binary zip: /usr/bin/zip
    0000.240 (0) Creation of backups of directories: beginning
    0000.243 (0) Beginning creation of dump of plugins (split every: 800 Mb)
    0000.432 (0) Total entities for the zip file: 115 directories, 1001 files, 9.2 Mb
    0000.435 (0) Zip: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip.tmp: 100 files added (on-disk size: 0 Kb)
    0000.439 (0) Zip: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip.tmp: 200 files added (on-disk size: 0 Kb)
    0000.442 (0) Zip: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip.tmp: 300 files added (on-disk size: 0 Kb)
    0000.445 (0) Zip: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip.tmp: 400 files added (on-disk size: 0 Kb)
    0000.449 (0) Zip: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip.tmp: 500 files added (on-disk size: 0 Kb)
    0000.450 (0) Adding batch to zip file (UpdraftPlus_BinZip): over 500 files added on this batch (2.4 Mb, 1001 files batched, 501 (501) added so far); re-opening (prior size: 0 Kb)
    0001.272 (0) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip.tmp: size is now: 1.10 Mb
    0001.277 (0) Zip: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip.tmp: 600 files added (on-disk size: 1141.4 Kb)
    0001.280 (0) Zip: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip.tmp: 700 files added (on-disk size: 1141.4 Kb)
    0001.283 (0) Zip: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip.tmp: 800 files added (on-disk size: 1141.4 Kb)
    0001.287 (0) Zip: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip.tmp: 900 files added (on-disk size: 1141.4 Kb)
    0001.290 (0) Zip: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip.tmp: 1000 files added (on-disk size: 1141.4 Kb)
    0005.636 (0) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip.tmp: size is now: 4.30 Mb
    0005.673 (0) Created plugins zip (0) - 4364.4 Kb in 5.3 s (828.6 Kb/s) (SHA1 checksum: 5d569251aab82d9d3501e0b68073173e23b8da8d)
    0005.675 (0) Beginning creation of dump of themes (split every: 800 Mb)
    0005.876 (0) Total entities for the zip file: 43 directories, 331 files, 7.7 Mb
    0005.880 (0) Zip: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-themes.zip.tmp: 100 files added (on-disk size: 0 Kb)
    0005.883 (0) Zip: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-themes.zip.tmp: 200 files added (on-disk size: 0 Kb)
    0005.886 (0) Zip: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-themes.zip.tmp: 300 files added (on-disk size: 0 Kb)
    0006.310 (0) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-themes.zip.tmp: size is now: 2.90 Mb
    0006.343 (0) Created themes zip (0) - 2999.7 Kb in 0.5 s (6282.7 Kb/s) (SHA1 checksum: ccf82597805b994f8971dd849b5e348a605b9e5c)
    0006.345 (0) Looking for candidates to back up in: /home/patgarner/public_html/wp-content/uploads
    0006.346 (0) finding files: 2014: adding to list
    0006.347 (0) finding files: et_temp: adding to list
    0006.348 (0) Beginning creation of dump of uploads (split every: 800 Mb)
    0006.522 (0) Total entities for the zip file: 3 directories, 247 files, 77.3 Mb
    0006.527 (0) Adding batch to zip file (UpdraftPlus_BinZip): over 25 Mb added on this batch (25.4 Mb, 247 files batched, 78 (78) added so far); re-opening (prior size: 0 Kb)
    0032.704 (0) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-uploads.zip.tmp: size is now: 25.20 Mb
    0032.707 (0) A useful amount of data was added after this amount of zip processing: 26.9 s (normalised: 26.5 s, rate: 964.4 Kb/s)
    0032.710 (0) Zip: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-uploads.zip.tmp: 100 files added (on-disk size: 25828 Kb)
    0032.714 (0) Adding batch to zip file (UpdraftPlus_BinZip): over 25 Mb added on this batch (25.1 Mb, 247 files batched, 96 (174) added so far); re-opening (prior size: 25828 Kb)
    0082.918 (0) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-uploads.zip.tmp: size is now: 50.20 Mb
    0082.921 (0) A useful amount of data was added after this amount of zip processing: 51.1 s (normalised: 50.9 s, rate: 503.2 Kb/s)
    0082.924 (0) Zip: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-uploads.zip.tmp: 200 files added (on-disk size: 51378.2 Kb)
    0082.927 (0) Adding batch to zip file (UpdraftPlus_BinZip): over 25 Mb added on this batch (25.5 Mb, 247 files batched, 65 (239) added so far); re-opening (prior size: 51378.2 Kb)
    0158.554 (0) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-uploads.zip.tmp: size is now: 75.60 Mb
    0158.557 (0) A useful amount of data was added after this amount of zip processing: 75.8 s (normalised: 74.4 s, rate: 344.1 Kb/s)
    0236.032 (0) Created uploads zip (0) - 78746.2 Kb in 229.5 s (343.1 Kb/s) (SHA1 checksum: 35d9be895288013241c5090c268a13cea42a7cee)
    0236.034 (0) Beginning backup of other directories found in the content directory (index: 0)
    0236.034 (0) Looking for candidates to back up in: /home/patgarner/public_html/wp-content
    0236.035 (0) finding files: uploads: skipping: this is the uploads directory
    0236.035 (0) finding files: updraft: skipping: this is the updraft directory
    0236.038 (0) finding files: languages: adding to list
    0236.038 (0) finding files: upgrade: skipping: excluded by options
    0236.038 (0) finding files: index.php: adding to list
    0236.041 (0) finding files: plugins: skipping: this is the plugins directory
    0236.042 (0) finding files: themes: skipping: this is the themes directory
    0236.207 (0) Total entities for the zip file: 1 directories, 1 files, 0 Mb
    0236.216 (0) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-others.zip.tmp: size is now: 0.00 Mb
    0236.231 (0) Created others zip (0) - 0.3 Kb in 0 s (13.2 Kb/s) (SHA1 checksum: 348f4720d8238ac89392b0c36bd5dcade9dfa400)
    0236.235 (0) Saving backup status to database (elements: 8)
    0236.242 (0) Beginning creation of database dump
    0236.243 (0) Testing: /usr/bin/mysqldump
    0236.274 (0) Working binary mysqldump found: /usr/bin/mysqldump
    0236.276 (0) Error: No database tables found (SHOW TABLES returned nothing) - please wait for the rescheduled attempt
    0236.276 (0) An error condition has occurred for the first time during this job
    0301.699 (1) Opened log file at time: Thu, 09 Jan 2014 02:02:46 +0000
    0301.700 (1) UpdraftPlus WordPress backup plugin (http://updraftplus.com): 1.8.6 WP: 3.8 PHP: 5.4.23 (Linux p3plcpnl0103.prod.phx3.secureserver.net 2.6.32-458.18.1.lve1.2.39.el6.x86_64 #1 SMP Mon Sep 16 12:10:28 EEST 2013 x86_64) MySQL: 5.5.32-cll-lve Server: Apache mod_fcgid/2.3.10-dev safe_mode: 0 max_execution_time: 900 memory_limit: 256M (used: 3.3M | 3.5M) multisite: N mcrypt: Y ZipArchive::addFile: Y
    0301.846 (1) Free space on disk containing Updraft's temporary directory: 3042695.3 Mb
    0301.848 (1) Backup run: resumption=1, nonce=bd1700b52684, begun at=1389232665 (302s ago), job type=backup, previous check-in=158.6s
    0301.848 (1) Scheduling a resumption (2) after 300 seconds (1389233267) in case this run gets aborted
    0301.856 (1) Checking if we have a zip executable available
    0301.857 (1) Zip engine: found/will use a binary zip: /usr/bin/zip
    0301.858 (1) Creation of backups of directories: had begun; will resume
    0301.860 (1) Beginning creation of dump of plugins (split every: 800 Mb)
    0301.860 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip: this file has already been created (age: 297 s)
    0301.862 (1) Beginning creation of dump of themes (split every: 800 Mb)
    0301.862 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-themes.zip: this file has already been created (age: 296 s)
    0301.864 (1) Looking for candidates to back up in: /home/patgarner/public_html/wp-content/uploads
    0301.865 (1) finding files: 2014: adding to list
    0301.865 (1) finding files: et_temp: adding to list
    0301.865 (1) Beginning creation of dump of uploads (split every: 800 Mb)
    0301.866 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-uploads.zip: this file has already been created (age: 67 s)
    0301.868 (1) Beginning backup of other directories found in the content directory (index: 0)
    0301.868 (1) Looking for candidates to back up in: /home/patgarner/public_html/wp-content
    0301.869 (1) finding files: uploads: skipping: this is the uploads directory
    0301.869 (1) finding files: updraft: skipping: this is the updraft directory
    0301.870 (1) finding files: languages: adding to list
    0301.870 (1) finding files: upgrade: skipping: excluded by options
    0301.871 (1) finding files: index.php: adding to list
    0301.871 (1) finding files: plugins: skipping: this is the plugins directory
    0301.871 (1) finding files: themes: skipping: this is the themes directory
    0301.872 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-others.zip: this file has already been created (age: 66 s)
    0301.875 (1) Saving backup status to database (elements: 8)
    0301.876 (1) Resuming creation of database dump
    0301.877 (1) Testing: /usr/bin/mysqldump
    0301.894 (1) Working binary mysqldump found: /usr/bin/mysqldump
    0301.898 (1) Table wp_options: Total expected rows (approximate): 2209
    0301.921 (1) Table wp_options: Total rows added: 221 in 0.02 seconds
    0301.922 (1) Table wp_options: finishing file (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_options.table.gz - 48 Kb)
    0301.946 (1) Table wp_users: Total expected rows (approximate): 1
    0301.949 (1) Table wp_users: Total rows added: 1 in 0.00 seconds
    0301.950 (1) Table wp_users: finishing file (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_users.table.gz - 0 Kb)
    0301.969 (1) Table wp_usermeta: Total expected rows (approximate): 24
    0301.973 (1) Table wp_usermeta: Total rows added: 24 in 0.00 seconds
    0301.975 (1) Table wp_usermeta: finishing file (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_usermeta.table.gz - 0 Kb)
    0301.993 (1) Table wp_commentmeta: Total expected rows (approximate): 0
    0301.996 (1) Table wp_commentmeta: Total rows added: 0 in 0.00 seconds
    0301.997 (1) Table wp_commentmeta: finishing file (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_commentmeta.table.gz - 0 Kb)
    0302.013 (1) Table wp_comments: Total expected rows (approximate): 1
    0302.017 (1) Table wp_comments: Total rows added: 1 in 0.00 seconds
    0302.018 (1) Table wp_comments: finishing file (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_comments.table.gz - 0 Kb)
    0302.026 (1) Table wp_links: Total expected rows (approximate): 7
    0302.030 (1) Table wp_links: Total rows added: 7 in 0.00 seconds
    0302.031 (1) Table wp_links: finishing file (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_links.table.gz - 0 Kb)
    0302.037 (1) Table wp_postmeta: Total expected rows (approximate): 397
    0302.048 (1) Table wp_postmeta: Total rows added: 334 in 0.01 seconds
    0302.049 (1) Table wp_postmeta: finishing file (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_postmeta.table.gz - 0 Kb)
    0302.060 (1) Table wp_posts: Total expected rows (approximate): 214
    0302.093 (1) Table wp_posts: Total rows added: 182 in 0.03 seconds
    0302.094 (1) Table wp_posts: finishing file (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_posts.table.gz - 16 Kb)
    0302.104 (1) Table wp_term_relationships: Total expected rows (approximate): 22
    0302.108 (1) Table wp_term_relationships: Total rows added: 22 in 0.00 seconds
    0302.109 (1) Table wp_term_relationships: finishing file (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_term_relationships.table.gz - 0 Kb)
    0302.117 (1) Table wp_term_taxonomy: Total expected rows (approximate): 3
    0302.121 (1) Table wp_term_taxonomy: Total rows added: 3 in 0.00 seconds
    0302.121 (1) Table wp_term_taxonomy: finishing file (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_term_taxonomy.table.gz - 0 Kb)
    0302.139 (1) Table wp_terms: Total expected rows (approximate): 3
    0302.142 (1) Table wp_terms: Total rows added: 3 in 0.00 seconds
    0302.143 (1) Table wp_terms: finishing file (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_terms.table.gz - 0 Kb)
    0302.155 (1) Table wp_wpgmza: Total expected rows (approximate): 1
    0302.159 (1) Table wp_wpgmza: Total rows added: 1 in 0.00 seconds
    0302.159 (1) Table wp_wpgmza: finishing file (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_wpgmza.table.gz - 0 Kb)
    0302.175 (1) Table wp_wpgmza_categories: Total expected rows (approximate): 0
    0302.178 (1) Table wp_wpgmza_categories: Total rows added: 0 in 0.00 seconds
    0302.179 (1) Table wp_wpgmza_categories: finishing file (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_wpgmza_categories.table.gz - 0 Kb)
    0302.192 (1) Table wp_wpgmza_maps: Total expected rows (approximate): 1
    0302.196 (1) Table wp_wpgmza_maps: Total rows added: 1 in 0.00 seconds
    0302.197 (1) Table wp_wpgmza_maps: finishing file (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_wpgmza_maps.table.gz - 0 Kb)
    0302.201 (1) Table wp_wpgmza_polygon: Total expected rows (approximate): 0
    0302.204 (1) Table wp_wpgmza_polygon: Total rows added: 0 in 0.00 seconds
    0302.204 (1) Table wp_wpgmza_polygon: finishing file (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_wpgmza_polygon.table.gz - 0 Kb)
    0302.208 (1) Table wp_wpgmza_polylines: Total expected rows (approximate): 0
    0302.211 (1) Table wp_wpgmza_polylines: Total rows added: 0 in 0.00 seconds
    0302.211 (1) Table wp_wpgmza_polylines: finishing file (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_wpgmza_polylines.table.gz - 0 Kb)
    0302.214 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_options.table.gz (1/16): adding to final database dump
    0302.232 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_users.table.gz (2/16): adding to final database dump
    0302.233 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_usermeta.table.gz (3/16): adding to final database dump
    0302.234 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_commentmeta.table.gz (4/16): adding to final database dump
    0302.235 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_comments.table.gz (5/16): adding to final database dump
    0302.235 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_links.table.gz (6/16): adding to final database dump
    0302.236 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_postmeta.table.gz (7/16): adding to final database dump
    0302.239 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_posts.table.gz (8/16): adding to final database dump
    0302.250 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_term_relationships.table.gz (9/16): adding to final database dump
    0302.251 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_term_taxonomy.table.gz (10/16): adding to final database dump
    0302.252 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_terms.table.gz (11/16): adding to final database dump
    0302.252 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_wpgmza.table.gz (12/16): adding to final database dump
    0302.253 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_wpgmza_categories.table.gz (13/16): adding to final database dump
    0302.254 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_wpgmza_maps.table.gz (14/16): adding to final database dump
    0302.255 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_wpgmza_polygon.table.gz (15/16): adding to final database dump
    0302.255 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db-table-wp_wpgmza_polylines.table.gz (16/16): adding to final database dump
    0302.256 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db.gz: finished writing out complete database file (96 Kb)
    0302.259 (1) Total database tables backed up: 16 (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db.gz: checksum (SHA1): 0e1fb6bcfc847fcf54ec74ebd154016f6571efb8)
    0302.261 (1) Saving backup history
    0302.264 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip: plugins: This file has not yet been successfully uploaded: will queue
    0302.264 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-themes.zip: themes: This file has not yet been successfully uploaded: will queue
    0302.265 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-uploads.zip: uploads: This file has not yet been successfully uploaded: will queue
    0302.266 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-others.zip: others: This file has not yet been successfully uploaded: will queue
    0302.266 (1) backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db.gz: db: This file has not yet been successfully uploaded: will queue
    0302.268 (1) Requesting upload of the files that have not yet been successfully uploaded (5)
    0302.270 (1) Cloud backup selection: dropbox
    0302.272 (1) Beginning dispatch of backup to remote (dropbox)
    0302.273 (1) Dropbox: begin cloud upload
    0302.277 (1) Dropbox: access gained
    0302.491 (1) Dropbox quota usage: normal=168 Mb, shared=0 Mb, total=2048 Mb, available=1880 Mb
    0302.492 (1) Dropbox: Attempt to upload: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip to: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip
    0303.842 (1) Dropbox chunked upload: 11.7 % uploaded (3s5jLFJeDyWf6TzAPuR16g, 524288)
    0305.360 (1) Dropbox chunked upload: 23.5 % uploaded (3s5jLFJeDyWf6TzAPuR16g, 1048576)
    0307.199 (1) Dropbox chunked upload: 35.2 % uploaded (3s5jLFJeDyWf6TzAPuR16g, 1572864)
    0308.975 (1) Dropbox chunked upload: 46.9 % uploaded (3s5jLFJeDyWf6TzAPuR16g, 2097152)
    0310.855 (1) Dropbox chunked upload: 58.7 % uploaded (3s5jLFJeDyWf6TzAPuR16g, 2621440)
    0312.993 (1) Dropbox chunked upload: 70.4 % uploaded (3s5jLFJeDyWf6TzAPuR16g, 3145728)
    0315.697 (1) Dropbox chunked upload: 82.1 % uploaded (3s5jLFJeDyWf6TzAPuR16g, 3670016)
    0318.374 (1) Dropbox chunked upload: 93.9 % uploaded (3s5jLFJeDyWf6TzAPuR16g, 4194304)
    0319.503 (1) Dropbox chunked upload: 100 % uploaded (3s5jLFJeDyWf6TzAPuR16g, 4469097)
    0320.521 (1) Recording as successfully uploaded: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip (179f6c18f155047bafe161605260507d)
    0320.526 (1) Deleting local file: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip: OK
    0320.527 (1) Dropbox: File upload success (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-plugins.zip): 4364 Kb in 18s (241 Kb/s)
    0320.770 (1) Dropbox quota usage: normal=172.3 Mb, shared=0 Mb, total=2048 Mb, available=1875.7 Mb
    0320.771 (1) Dropbox: Attempt to upload: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-themes.zip to: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-themes.zip
    0322.193 (1) Dropbox chunked upload: 17.1 % uploaded (BrxOO_TetMAyvxoAaDyTHA, 524288)
    0323.667 (1) Dropbox chunked upload: 34.1 % uploaded (BrxOO_TetMAyvxoAaDyTHA, 1048576)
    0325.355 (1) Dropbox chunked upload: 51.2 % uploaded (BrxOO_TetMAyvxoAaDyTHA, 1572864)
    0327.102 (1) Dropbox chunked upload: 68.3 % uploaded (BrxOO_TetMAyvxoAaDyTHA, 2097152)
    0329.252 (1) Dropbox chunked upload: 85.3 % uploaded (BrxOO_TetMAyvxoAaDyTHA, 2621440)
    0331.213 (1) Dropbox chunked upload: 100 % uploaded (BrxOO_TetMAyvxoAaDyTHA, 3071667)
    0333.607 (1) Recording as successfully uploaded: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-themes.zip (651892d90495b5e1b53bc84fc5047218)
    0333.611 (1) Deleting local file: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-themes.zip: OK
    0333.612 (1) Dropbox: File upload success (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-themes.zip): 2999 Kb in 12s (233 Kb/s)
    0333.849 (1) Dropbox quota usage: normal=175.2 Mb, shared=0 Mb, total=2048 Mb, available=1872.8 Mb
    0333.850 (1) Dropbox: Attempt to upload: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-uploads.zip to: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-uploads.zip
    0335.229 (1) Dropbox chunked upload: 0.7 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 524288)
    0336.744 (1) Dropbox chunked upload: 1.3 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 1048576)
    0338.438 (1) Dropbox chunked upload: 2 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 1572864)
    0340.414 (1) Dropbox chunked upload: 2.6 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 2097152)
    0342.293 (1) Dropbox chunked upload: 3.3 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 2621440)
    0344.529 (1) Dropbox chunked upload: 3.9 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 3145728)
    0347.062 (1) Dropbox chunked upload: 4.6 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 3670016)
    0349.497 (1) Dropbox chunked upload: 5.2 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 4194304)
    0350.885 (1) Dropbox chunked upload: 5.9 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 4718592)
    0352.484 (1) Dropbox chunked upload: 6.5 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 5242880)
    0354.166 (1) Dropbox chunked upload: 7.2 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 5767168)
    0355.991 (1) Dropbox chunked upload: 7.8 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 6291456)
    0357.975 (1) Dropbox chunked upload: 8.5 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 6815744)
    0360.353 (1) Dropbox chunked upload: 9.1 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 7340032)
    0362.522 (1) Dropbox chunked upload: 9.8 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 7864320)
    0365.197 (1) Dropbox chunked upload: 10.4 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 8388608)
    0366.564 (1) Dropbox chunked upload: 11.1 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 8912896)
    0368.224 (1) Dropbox chunked upload: 11.7 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 9437184)
    0370.368 (1) Dropbox chunked upload: 12.4 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 9961472)
    0372.211 (1) Dropbox chunked upload: 13 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 10485760)
    0374.344 (1) Dropbox chunked upload: 13.7 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 11010048)
    0376.394 (1) Dropbox chunked upload: 14.3 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 11534336)
    0378.785 (1) Dropbox chunked upload: 15 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 12058624)
    0381.528 (1) Dropbox chunked upload: 15.6 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 12582912)
    0382.816 (1) Dropbox chunked upload: 16.3 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 13107200)
    0385.114 (1) Dropbox chunked upload: 16.9 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 13631488)
    0386.845 (1) Dropbox chunked upload: 17.6 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 14155776)
    0388.595 (1) Dropbox chunked upload: 18.2 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 14680064)
    0390.472 (1) Dropbox chunked upload: 18.9 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 15204352)
    0392.650 (1) Dropbox chunked upload: 19.5 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 15728640)
    0394.988 (1) Dropbox chunked upload: 20.2 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 16252928)
    0397.390 (1) Dropbox chunked upload: 20.8 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 16777216)
    0398.737 (1) Dropbox chunked upload: 21.5 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 17301504)
    0400.244 (1) Dropbox chunked upload: 22.1 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 17825792)
    0402.238 (1) Dropbox chunked upload: 22.8 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 18350080)
    0403.913 (1) Dropbox chunked upload: 23.4 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 18874368)
    0406.193 (1) Dropbox chunked upload: 24.1 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 19398656)
    0408.267 (1) Dropbox chunked upload: 24.7 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 19922944)
    0410.554 (1) Dropbox chunked upload: 25.4 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 20447232)
    0412.909 (1) Dropbox chunked upload: 26 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 20971520)
    0414.166 (1) Dropbox chunked upload: 26.7 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 21495808)
    0415.632 (1) Dropbox chunked upload: 27.3 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 22020096)
    0417.276 (1) Dropbox chunked upload: 28 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 22544384)
    0419.061 (1) Dropbox chunked upload: 28.6 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 23068672)
    0421.149 (1) Dropbox chunked upload: 29.3 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 23592960)
    0423.261 (1) Dropbox chunked upload: 29.9 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 24117248)
    0425.481 (1) Dropbox chunked upload: 30.6 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 24641536)
    0427.995 (1) Dropbox chunked upload: 31.2 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 25165824)
    0429.454 (1) Dropbox chunked upload: 31.9 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 25690112)
    0431.258 (1) Dropbox chunked upload: 32.5 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 26214400)
    0433.225 (1) Dropbox chunked upload: 33.2 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 26738688)
    0435.098 (1) Dropbox chunked upload: 33.8 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 27262976)
    0437.702 (1) Dropbox chunked upload: 34.5 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 27787264)
    0439.884 (1) Dropbox chunked upload: 35.1 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 28311552)
    0442.139 (1) Dropbox chunked upload: 35.8 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 28835840)
    0445.397 (1) Dropbox chunked upload: 36.4 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 29360128)
    0446.829 (1) Dropbox chunked upload: 37.1 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 29884416)
    0448.584 (1) Dropbox chunked upload: 37.7 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 30408704)
    0450.946 (1) Dropbox chunked upload: 38.4 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 30932992)
    0452.793 (1) Dropbox chunked upload: 39 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 31457280)
    0455.029 (1) Dropbox chunked upload: 39.7 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 31981568)
    0457.117 (1) Dropbox chunked upload: 40.3 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 32505856)
    0459.478 (1) Dropbox chunked upload: 41 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 33030144)
    0461.849 (1) Dropbox chunked upload: 41.6 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 33554432)
    0463.400 (1) Dropbox chunked upload: 42.3 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 34078720)
    0464.925 (1) Dropbox chunked upload: 42.9 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 34603008)
    0466.688 (1) Dropbox chunked upload: 43.6 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 35127296)
    0468.592 (1) Dropbox chunked upload: 44.2 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 35651584)
    0470.892 (1) Dropbox chunked upload: 44.9 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 36175872)
    0473.119 (1) Dropbox chunked upload: 45.5 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 36700160)
    0476.217 (1) Dropbox chunked upload: 46.2 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 37224448)
    0478.848 (1) Dropbox chunked upload: 46.8 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 37748736)
    0480.224 (1) Dropbox chunked upload: 47.5 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 38273024)
    0481.860 (1) Dropbox chunked upload: 48.1 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 38797312)
    0484.163 (1) Dropbox chunked upload: 48.8 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 39321600)
    0485.955 (1) Dropbox chunked upload: 49.4 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 39845888)
    0488.256 (1) Dropbox chunked upload: 50.1 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 40370176)
    0490.525 (1) Dropbox chunked upload: 50.7 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 40894464)
    0492.726 (1) Dropbox chunked upload: 51.4 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 41418752)
    0495.153 (1) Dropbox chunked upload: 52 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 41943040)
    0496.783 (1) Dropbox chunked upload: 52.7 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 42467328)
    0498.349 (1) Dropbox chunked upload: 53.3 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 42991616)
    0500.047 (1) Dropbox chunked upload: 54 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 43515904)
    0502.270 (1) Dropbox chunked upload: 54.6 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 44040192)
    0504.498 (1) Dropbox chunked upload: 55.3 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 44564480)
    0508.871 (1) Dropbox chunked upload: 55.9 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 45088768)
    0511.554 (1) Dropbox chunked upload: 56.6 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 45613056)
    0514.079 (1) Dropbox chunked upload: 57.2 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 46137344)
    0515.718 (1) Dropbox chunked upload: 57.9 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 46661632)
    0517.228 (1) Dropbox chunked upload: 58.5 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 47185920)
    0518.953 (1) Dropbox chunked upload: 59.2 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 47710208)
    0520.956 (1) Dropbox chunked upload: 59.8 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 48234496)
    0523.126 (1) Dropbox chunked upload: 60.5 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 48758784)
    0525.182 (1) Dropbox chunked upload: 61.1 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 49283072)
    0527.510 (1) Dropbox chunked upload: 61.8 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 49807360)
    0529.838 (1) Dropbox chunked upload: 62.4 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 50331648)
    0531.275 (1) Dropbox chunked upload: 63.1 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 50855936)
    0533.449 (1) Dropbox chunked upload: 63.7 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 51380224)
    0535.112 (1) Dropbox chunked upload: 64.4 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 51904512)
    0537.043 (1) Dropbox chunked upload: 65 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 52428800)
    0539.037 (1) Dropbox chunked upload: 65.7 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 52953088)
    0541.174 (1) Dropbox chunked upload: 66.3 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 53477376)
    0543.732 (1) Dropbox chunked upload: 67 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 54001664)
    0546.686 (1) Dropbox chunked upload: 67.6 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 54525952)
    0548.117 (1) Dropbox chunked upload: 68.3 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 55050240)
    0550.142 (1) Dropbox chunked upload: 68.9 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 55574528)
    0551.808 (1) Dropbox chunked upload: 69.6 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 56098816)
    0553.651 (1) Dropbox chunked upload: 70.2 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 56623104)
    0555.613 (1) Dropbox chunked upload: 70.9 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 57147392)
    0558.878 (1) The scheduled resumption is within 45 seconds - will reschedule
    0558.880 (1) Rescheduling resumption 2: moving to 345 seconds from now (1389233569)
    0558.882 (1) To decrease the likelihood of overlaps, increasing resumption interval to: 300 + 45 = 345
    0558.883 (1) Dropbox chunked upload: 71.5 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 57671680)
    0562.045 (1) Dropbox chunked upload: 72.2 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 58195968)
    0564.530 (1) Dropbox chunked upload: 72.8 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 58720256)
    0566.900 (1) Dropbox chunked upload: 73.5 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 59244544)
    0568.645 (1) Dropbox chunked upload: 74.1 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 59768832)
    0570.350 (1) Dropbox chunked upload: 74.8 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 60293120)
    0572.708 (1) Dropbox chunked upload: 75.4 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 60817408)
    0574.762 (1) Dropbox chunked upload: 76.1 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 61341696)
    0576.887 (1) Dropbox chunked upload: 76.7 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 61865984)
    0579.063 (1) Dropbox chunked upload: 77.4 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 62390272)
    0582.941 (1) Dropbox chunked upload: 78 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 62914560)
    0584.553 (1) Dropbox chunked upload: 78.7 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 63438848)
    0586.150 (1) Dropbox chunked upload: 79.3 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 63963136)
    0588.523 (1) Dropbox chunked upload: 80 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 64487424)
    0590.476 (1) Dropbox chunked upload: 80.6 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 65011712)
    0592.539 (1) Dropbox chunked upload: 81.3 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 65536000)
    0594.667 (1) Dropbox chunked upload: 81.9 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 66060288)
    0597.844 (1) Dropbox chunked upload: 82.6 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 66584576)
    0600.444 (1) Dropbox chunked upload: 83.2 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 67108864)
    0601.765 (1) Dropbox chunked upload: 83.9 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 67633152)
    0603.349 (1) Dropbox chunked upload: 84.5 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 68157440)
    0605.053 (1) Dropbox chunked upload: 85.2 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 68681728)
    0606.969 (1) Dropbox chunked upload: 85.8 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 69206016)
    0609.120 (1) Dropbox chunked upload: 86.5 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 69730304)
    0611.167 (1) Dropbox chunked upload: 87.1 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 70254592)
    0614.795 (1) Dropbox chunked upload: 87.8 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 70778880)
    0618.266 (1) The time we have been running (316.6) is approaching the resumption interval (345) - increasing resumption interval to 347
    0618.269 (1) Dropbox chunked upload: 88.4 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 71303168)
    0619.769 (1) The time we have been running (318.1) is approaching the resumption interval (347) - increasing resumption interval to 349
    0619.771 (1) Dropbox chunked upload: 89.1 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 71827456)
    0621.616 (1) The time we have been running (319.9) is approaching the resumption interval (349) - increasing resumption interval to 350
    0621.618 (1) Dropbox chunked upload: 89.7 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 72351744)
    0623.468 (1) The time we have been running (321.8) is approaching the resumption interval (350) - increasing resumption interval to 352
    0623.469 (1) Dropbox chunked upload: 90.4 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 72876032)
    0625.588 (1) The time we have been running (323.9) is approaching the resumption interval (352) - increasing resumption interval to 354
    0625.590 (1) Dropbox chunked upload: 91 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 73400320)
    0627.682 (1) The time we have been running (326) is approaching the resumption interval (354) - increasing resumption interval to 356
    0627.684 (1) Dropbox chunked upload: 91.7 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 73924608)
    0629.784 (1) The time we have been running (328.1) is approaching the resumption interval (356) - increasing resumption interval to 359
    0629.786 (1) Dropbox chunked upload: 92.3 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 74448896)
    0632.590 (1) The time we have been running (330.9) is approaching the resumption interval (359) - increasing resumption interval to 361
    0632.591 (1) Dropbox chunked upload: 93 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 74973184)
    0636.198 (1) The time we have been running (334.5) is approaching the resumption interval (361) - increasing resumption interval to 365
    0636.200 (1) Dropbox chunked upload: 93.6 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 75497472)
    0637.570 (1) The time we have been running (335.9) is approaching the resumption interval (365) - increasing resumption interval to 366
    0637.572 (1) Dropbox chunked upload: 94.3 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 76021760)
    0639.171 (1) The time we have been running (337.5) is approaching the resumption interval (366) - increasing resumption interval to 368
    0639.173 (1) Dropbox chunked upload: 94.9 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 76546048)
    0641.249 (1) The time we have been running (339.5) is approaching the resumption interval (368) - increasing resumption interval to 370
    0641.252 (1) Dropbox chunked upload: 95.6 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 77070336)
    0643.119 (1) The time we have been running (341.4) is approaching the resumption interval (370) - increasing resumption interval to 372
    0643.120 (1) Dropbox chunked upload: 96.2 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 77594624)
    0645.147 (1) The time we have been running (343.4) is approaching the resumption interval (372) - increasing resumption interval to 374
    0645.148 (1) Dropbox chunked upload: 96.9 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 78118912)
    0647.817 (1) The time we have been running (346.1) is approaching the resumption interval (374) - increasing resumption interval to 377
    0647.818 (1) Dropbox chunked upload: 97.5 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 78643200)
    0650.413 (1) The time we have been running (348.7) is approaching the resumption interval (377) - increasing resumption interval to 379
    0650.415 (1) Dropbox chunked upload: 98.2 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 79167488)
    0653.725 (1) The time we have been running (352) is approaching the resumption interval (379) - increasing resumption interval to 383
    0653.728 (1) Dropbox chunked upload: 98.8 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 79691776)
    0655.326 (1) The time we have been running (353.6) is approaching the resumption interval (383) - increasing resumption interval to 384
    0655.328 (1) Dropbox chunked upload: 99.5 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 80216064)
    0657.262 (1) The time we have been running (355.6) is approaching the resumption interval (384) - increasing resumption interval to 386
    0657.263 (1) Dropbox chunked upload: 100 % uploaded (xF2vnI3kGDnlJs1H16hCkA, 80636086)
    0659.386 (1) Recording as successfully uploaded: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-uploads.zip (5746b7075b327f0317d72b1ad8b495dd)
    0659.409 (1) Deleting local file: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-uploads.zip: OK
    0659.410 (1) Dropbox: File upload success (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-uploads.zip): 78746 Kb in 325s (241 Kb/s)
    0659.647 (1) Dropbox quota usage: normal=252.1 Mb, shared=0 Mb, total=2048 Mb, available=1795.9 Mb
    0659.648 (1) Dropbox: Attempt to upload: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-others.zip to: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-others.zip
    0660.410 (1) The time we have been running (358.7) is approaching the resumption interval (386) - increasing resumption interval to 389
    0660.411 (1) Dropbox chunked upload: 100 % uploaded (HrNeRl_e_WoH4jgt9dV-Pw, 344)
    0661.696 (1) Recording as successfully uploaded: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-others.zip (f8454fc689d6bf358639d2ed89baae89)
    0661.701 (1) Deleting local file: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-others.zip: OK
    0661.702 (1) Dropbox: File upload success (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-others.zip): 0 Kb in 2s (0 Kb/s)
    0661.915 (1) Dropbox quota usage: normal=252.1 Mb, shared=0 Mb, total=2048 Mb, available=1795.9 Mb
    0661.916 (1) Dropbox: Attempt to upload: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db.gz to: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db.gz
    0662.891 (1) The time we have been running (361.2) is approaching the resumption interval (389) - increasing resumption interval to 392
    0662.893 (1) Dropbox chunked upload: 100 % uploaded (kxlfXyA-AfnqrHJupQ6TjQ, 110579)
    0663.837 (1) Recording as successfully uploaded: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db.gz (31328e035f361ec309eaf78d84626300)
    0663.840 (1) Deleting local file: backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db.gz: OK
    0663.841 (1) Dropbox: File upload success (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db.gz): 107 Kb in 1s (56 Kb/s)
    0663.843 (1) Retain: beginning examination of existing backup sets; user setting: retain_files=2, retain_db=2
    0663.844 (1) Number of backup sets in history: 2
    0663.845 (1) Examining backup set with datestamp: 1389232665 (Jan 09 2014 01:57:45)
    0663.846 (1) 1389232665: db: this set includes a database (backup_2014-01-09-0157_Acme_Conservation_Cemetery_bd1700b52684-db.gz); db count is now 1
    0663.851 (1) The time we have been running (362.1) is approaching the resumption interval (392) - increasing resumption interval to 393
    0663.854 (1) 1389232665: this backup set remains non-empty (1/1); will retain in history
    0663.854 (1) Examining backup set with datestamp: 1389218131 (Jan 08 2014 21:55:31)
    0663.855 (1) 1389218131: db: this set includes a database (backup_2014-01-08-2155_Acme_Conservation_Cemetery_e2ff5b1dd315-db.gz); db count is now 2
    0663.857 (1) 1389218131: this backup set remains non-empty (1/1); will retain in history
    0663.858 (1) Retain: saving new backup history (sets now: 2) and finishing retain operation
    0663.859 (1) Resume backup (bd1700b52684, 1): finish run
    0663.861 (1) There were no errors in the uploads, so the 'resume' event (2) is being unscheduled
    0663.863 (1) No email will/can be sent - the user has not configured an email address.
    0663.863 (1) The backup apparently succeeded and is now complete

    Also, the web screen appears to be reporting properly now:

    Last backup job run: Thu, January 9, 2014 01:57
    Last log message: The backup apparently succeeded and is now complete (Jan 09 02:08:49)
    Backups, logs & restoring: 2 set(s) available

    So it appears that UpdraftPlus dev release is working properly. I am going to make some changes to my WP installation that will create changes in the db schema, run another backup, restore the backup and report back to you my results.

  14. Pat Garner
    Member
    Posted 7 months ago #

    I did the restore and it worked properly. Awesome! Here are the log results:

    Final checks
    Looking for db archive: file name: backup_2014-01-09-0329_Acme_Conservation_Cemetery_e9e2c8a9a7ee-db.gz
    Archive is expected to be size: 104.8 Kb: OK
    Looking for others archive: file name: backup_2014-01-09-0329_Acme_Conservation_Cemetery_e9e2c8a9a7ee-others.zip
    Archive is expected to be size: 0.3 Kb: OK
    Looking for plugins archive: file name: backup_2014-01-09-0329_Acme_Conservation_Cemetery_e9e2c8a9a7ee-plugins.zip
    Archive is expected to be size: 4364.4 Kb: OK
    Looking for themes archive: file name: backup_2014-01-09-0329_Acme_Conservation_Cemetery_e9e2c8a9a7ee-themes.zip
    Archive is expected to be size: 2999.7 Kb: OK
    Looking for uploads archive: file name: backup_2014-01-09-0329_Acme_Conservation_Cemetery_e9e2c8a9a7ee-uploads.zip
    Archive is expected to be size: 78746.2 Kb: OK
    Database
    Unpacking backup...
    Restoring the database (on a large site this can take a long time - if it times out (which can happen if your web hosting company has configured your hosting to limit resources) then you should use a different method, such as phpMyAdmin)...
    Backup of: http://www.versatrax.com
    Content URL: http://www.versatrax.com/wp-content
    Old table prefix: wp_
    Site information: multisite = 0
    New table prefix: wp_
    Restoring table (InnoDB): wp_options
    Restoring table (InnoDB): wp_users
    Restoring table (InnoDB): wp_usermeta
    Restoring table (InnoDB): wp_commentmeta
    Restoring table (InnoDB): wp_comments
    Restoring table (InnoDB): wp_links
    Restoring table (InnoDB): wp_postmeta
    Restoring table (InnoDB): wp_posts
    Restoring table (InnoDB): wp_term_relationships
    Restoring table (InnoDB): wp_term_taxonomy
    Restoring table (InnoDB): wp_terms
    Restoring table (InnoDB): wp_wpgmza
    Restoring table (InnoDB): wp_wpgmza_categories
    Restoring table (InnoDB): wp_wpgmza_maps
    Restoring table (InnoDB): wp_wpgmza_polygon
    Restoring table (InnoDB): wp_wpgmza_polylines
    Database queries processed: 50 in 0.44 seconds
    Finished: lines processed: 52 in 0.44 seconds
    Cleaning up rubbish...
    Others
    Unpacking backup...
    Cleaning up rubbish...
    Plugins
    Unpacking backup...
    Moving old data out of the way...
    Moving unpacked backup into place...
    Cleaning up rubbish...
    Themes
    Unpacking backup...
    Moving old data out of the way...
    Moving unpacked backup into place...
    Cleaning up rubbish...
    Uploads
    Unpacking backup...
    Moving old data out of the way...
    Moving unpacked backup into place...
    Cleaning up rubbish...
    
    Restore successful!

    When you push your bug fixes to production I'll rate your wonderful product and excellent customer service.

  15. David Anderson
    Member
    Plugin Author

    Posted 7 months ago #

    Hi Pat,

    Glad that it's working for you!

    So the database portion of the backup is no longer empty. Awesome! As I suspected, the db was not dropping connections.

    There was an error in the log:

    What you're seeing there in (0) is the fix. The "error" is where UpdraftPlus detects the dropped database connection ("SHOW TABLES" returns totally empty results - this is an example of WordPress's database layer returning empty results when the database connection has been dropped); it then recovers from it by re-starting.

    One more thing about interpreting the log files... in UD, an "error" is not necessarily fatal. Errors cause re-tries; and on the re-try, no error may occur. So, an error in the log file really means "an error this time - but we'll retry"; and if it doesn't occur on the retry, then it can be ignored. (Another example is when errors occur uploading to Dropbox - e.g. if the network connection to Dropbox gets broken; it'll later retry).

    David

  16. David Anderson
    Member
    Plugin Author

    Posted 7 months ago #

    P.S. I forgot to mention - the fix is already in 1.8.5 - I backported it from the development version yesterday. So anyone who installs 1.8.5 since then has the fix.

    David

  17. Pat Garner
    Member
    Posted 7 months ago #

    @David, got it:

    What you're seeing there in (0) is the fix. The "error" is where UpdraftPlus detects the dropped database connection ("SHOW TABLES" returns totally empty results - this is an example of WordPress's database layer returning empty results when the database connection has been dropped); it then recovers from it by re-starting.

    One more thing about interpreting the log files... in UD, an "error" is not necessarily fatal. Errors cause re-tries; and on the re-try, no error may occur. So, an error in the log file really means "an error this time - but we'll retry"; and if it doesn't occur on the retry, then it can be ignored. (Another example is when errors occur uploading to Dropbox - e.g. if the network connection to Dropbox gets broken; it'll later retry).

    Thanks for your patience with my uninformed diagnosis of the problem! And thank you for taking the time to explain what's going on under the hood. The application server logs I'm used to seeing in my world (JEE) work a bit differently. This has been an enlightening experience.

  18. David Anderson
    Member
    Plugin Author

    Posted 7 months ago #

    Hi Pat,

    You're welcome!

    David

Reply

You must log in to post.

About this Plugin

About this Topic