Schedule automatic network backup.

[zickzhou]

Hi there.
I have some problem with the scheduled auto backup. What i would like to do it, for every 3 days, I do a complete system back up, and each day between the complete system back up, I do incremental backup.

So yesterday i setup a testing environment, and I have the configuration as the following:
--Every day at 5:10 pm, I schedule a complete system backup, writing to a sparse image on the network storage. The sparse image is named as "backup". The backup type is "back up all files" with "erase and copy" option.

-Every day at 2:30 am, 4:30am and 6:30 am, I scheduled 3 different incremental backups. The settings are identical except starting time. They have the following settings: the destination is also "backup". Backup type is "backup all files " with "Copy different" option.

I left my office at 5 yesterday and allow my mac to do the back up testing scheme it self. When I came back this morning...

Only the initial full system backup was successful. All incremental backups were failed.

I will post the backup log. and please tell me why this is happening. Thanks.

[zickzhou]

Backup log for complete backup

| 05:10:16 PM | Info | SuperDuper!, 2.5 (84), path: /Applications/SuperDuper!.app, Mac OS 10.5.7 build 9J61 (ppc)
| 05:10:16 PM | Info | Started on Tue, Jul 28, 2009 at 5:10 PM
| 05:10:16 PM | Info | Source Volume: Leopard, mount: /, device: /dev/disk0s5, media: TOSHIBA MK6025GAS TOSHIBA MK6025GAS, interconnect: Internal ATA, file system: "Journaled HFS+", OS: 10.5.7 (9J61), capacity: 39.82 GB, used: 20.51 GB, directories: 145370, files: 682167, ejectable: NO, ACLs: Enabled
| 05:10:16 PM | Info | Target Image: /Volumes/Volume_1/LewisIBOOKG4/LEWISIbookG4.sparseimage, name: LEWISIbookG4
| 05:10:16 PM | Info | Copy Mode : Erase, then copy files
| 05:10:16 PM | Info | Copy Script : Backup - all files.dset
| 05:10:16 PM | Info | Transcript : BuildTranscript.plist
| 05:10:17 PM | Info | PHASE: 1. Prepare to Copy Files
| 05:10:17 PM | Info | ...ACTION: Preparing Leopard
| 05:10:17 PM | Info | ......COMMAND => Verifying the integrity of volinfo.database
| 05:10:17 PM | Info | volinfo.database OK
| 05:10:17 PM | Info | ......COMMAND => Enabling permissions on Leopard
| 05:10:18 PM | Info | Refreshing Disk Arbitration ...
| 05:10:18 PM | Info | ......COMMAND => Verifying that permissions are enabled for Leopard
| 05:10:18 PM | Info | Permissions on '/' are enabled.
| 05:10:18 PM | Info | ...ACTION: Mounting LEWISIbookG4
| 05:10:18 PM | Info | ......COMMAND => Preparing LEWISIbookG4
| 05:10:33 PM | Info | created: /Volumes/Volume_1/LewisIBOOKG4/LEWISIbookG4.sparseimage
| 05:10:33 PM | Info | ......COMMAND => Setting ownership and access modes for '/Volumes/Volume_1/LewisIBOOKG4/LEWISIbookG4.sparseimage'
| 05:10:33 PM | Info | ......COMMAND => Mounting LEWISIbookG4
| 05:10:38 PM | Info | /dev/disk1 Apple_partition_scheme /dev/disk1s1 Apple_partition_map /dev/disk1s2 Apple_HFS /Volumes/LEWISIbookG4
| 05:10:38 PM | Info | ......COMMAND => Mounting LEWISIbookG4
| 05:10:39 PM | Info | ...ACTION: Erasing LEWISIbookG4
| 05:10:39 PM | Info | ......COMMAND => Preserving LEWISIbookG4 UUID
| 05:10:39 PM | Info | D32FB3F93E866B8F
| 05:10:39 PM | Info | ......COMMAND => Preserving spotlight indexing state
| 05:10:39 PM | Info | ......COMMAND => Erasing LEWISIbookG4
| 05:11:00 PM | Info | ......COMMAND => Restoring LEWISIbookG4 UUID (SDUUID.hexString)
| 05:11:01 PM | Info | D32FB3F93E866B8F
| 05:11:01 PM | Info | ......COMMAND => Restoring Spotlight search indexing state on LEWISIbookG4
| 05:11:01 PM | Info | ...ACTION: Preparing LEWISIbookG4
| 05:11:01 PM | Info | ......COMMAND => Enabling permissions on LEWISIbookG4
| 05:11:01 PM | Info | Refreshing Disk Arbitration ...
| 05:11:01 PM | Info | ......COMMAND => Verifying that permissions are enabled for LEWISIbookG4
| 05:11:02 PM | Info | Permissions on '/Volumes/LEWISIbookG4' are enabled.
| 05:11:02 PM | Info | ......COMMAND => Verifying that LEWISIbookG4 ACL support matches Leopard
| 05:11:02 PM | Info | ...ACTION: Preserving Spotlight state on LEWISIbookG4
| 05:11:02 PM | Info | ......COMMAND => Disabling Spotlight search indexing on LEWISIbookG4
| 05:11:02 PM | Info | PHASE: 2. Copy Files
| 05:11:02 PM | Info | ...ACTION: Copying files from Leopard to LEWISIbookG4
| 05:11:02 PM | Info | ......COMMAND => Cloning Leopard to LEWISIbookG4
| 05:11:02 PM | Info | Copying copy files using script: /Users/lewiszhou/Library/Application Support/SuperDuper!/Copy Scripts/Standard Scripts/Backup - all files.dset
| 05:11:03 PM | Info | Loading 22 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude system temporary files.dset
| 05:11:03 PM | Info | Loading 6 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude system cache files.dset
| 05:11:03 PM | Info | Loading 1 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude Norton FileSaver files.dset
| 05:11:03 PM | Info | Loading 1 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude Google Desktop Index files.dset
| 05:11:03 PM | Info | Loading 1 commands from copy script /Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude iTunes Temporary files.dset
| 05:11:03 PM | Info | Loading 0 commands from copy script /Users/lewiszhou/Library/Application Support/SuperDuper!/Copy Scripts/Standard Scripts/Backup - all files.dset
| 05:11:03 PM | Info | /
| 05:11:07 PM | Info | /cores
| 05:11:07 PM | Info | /Network
| 05:11:07 PM | Info | /.vol
| 05:11:07 PM | Info | Ignoring /.vol
| 05:11:07 PM | Info | /.Trashes
| 05:11:07 PM | Info | /private
| 05:11:27 PM | Info | Ignoring /private/var/vm/swapfile0
| 05:11:59 PM | Info | Ignoring /private/var/db/BootCache.playlist
| 05:11:59 PM | Info | Ignoring /private/var/db/volinfo.database
| 05:11:59 PM | Info | Ignoring /private/var/run/syslog.pid
| 05:11:59 PM | Info | Ignoring /private/var/run/diskarbitrationd.pid
| 05:11:59 PM | Info | Ignoring /private/var/run/configd.pid
| 05:11:59 PM | Info | Ignoring /private/var/run/DirectoryService.pid
| 05:11:59 PM | Info | Ignoring /private/var/run/mds.pid
| 05:11:59 PM | Info | Ignoring /private/var/run/appfwd.pid
| 05:11:59 PM | Info | Ignoring /private/var/run/automount.initialized
| 05:11:59 PM | Info | Ignoring /private/var/run/utmpx
| 05:11:59 PM | Info | Ignoring /private/var/run/resolv.conf
| 05:11:59 PM | Info | Ignoring /private/var/run/mach.sym
| 05:11:59 PM | Info | Ignoring /private/var/run/ntpd.pid
| 05:11:59 PM | Info | Ignoring /private/var/run/cron.pid
| 05:11:59 PM | Info | Ignoring /private/var/run/com.sophos.sav.pid
| 05:11:59 PM | Info | Ignoring /private/var/run/com.sophos.sav.ic.pid
| 05:11:59 PM | Info | Ignoring /private/var/run/com.sophos.sau.pid
| 05:11:59 PM | Info | Ignoring /private/var/run/mds
| 05:11:59 PM | Info | Ignoring /private/var/run/hdiejectd.pid
| 05:11:59 PM | Info | Ignoring /private/var/tmp/com.apple.speech.synthesis.globals
| 05:11:59 PM | Info | Ignoring /private/var/tmp/launchd
| 05:11:59 PM | Info | Ignoring /private/var/tmp/mds
| 05:12:02 PM | Info | Ignoring /private/tmp/launchd-72.4hy7t2
| 05:12:02 PM | Info | Ignoring /private/tmp/launch-l3M7oF
| 05:12:02 PM | Info | Ignoring /private/tmp/launch-dKwwTT
| 05:12:02 PM | Info | Ignoring /private/tmp/launch-WD32du
| 05:12:02 PM | Info | Ignoring /private/tmp/SDTargetImageDevice
| 05:12:02 PM | Info | Ignoring /private/tmp/target.indexState
| 05:12:02 PM | Info | /.Spotlight-V100
| 05:12:02 PM | Info | Preserving /.Spotlight-V100
| 05:12:02 PM | Info | /Users
| 05:17:56 PM | Info | /Developer
| 06:08:16 PM | Info | Ignoring /Desktop DF
| 06:08:16 PM | Info | Ignoring /Desktop DB
| 06:08:16 PM | Info | Ignoring /.hotfiles.btree
| 06:08:21 PM | Info | /usr
| 06:24:44 PM | Info | /bin
| 06:24:44 PM | Info | /sbin
| 06:24:47 PM | Info | /System
| 07:53:24 PM | Info | /Library
| 09:11:28 PM | Info | /dev
| 09:11:28 PM | Info | /net
| 09:11:28 PM | Info | /home
| 09:11:28 PM | Info | /Applications
| 11:00:20 PM | Info | /.fseventsd
| 11:00:20 PM | Info | Preserving /.fseventsd
| 11:00:20 PM | Info | /Volumes
| 11:00:20 PM | Info | Ignoring /Volumes/Volume_1
| 11:00:20 PM | Info | Ignoring /Volumes/Data-Restore Disk
| 11:00:20 PM | Info | Ignoring /Volumes/.DS_Store
| 11:00:20 PM | Info | Ignoring /Volumes/Leopard
| 11:00:20 PM | Info | Ignoring /Volumes/LEWISIbookG4
| 11:00:20 PM | Info | Evaluated 810988 items occupying 19.63 GB (145371 directories, 642300 files, 23317 symlinks)
| 11:00:20 PM | Info | Copied 810476 items totaling 19.37 GB (145352 directories, 641808 files, 23316 symlinks)
| 11:00:20 PM | Info | Cloned 19.37 GB of data in 20957 seconds at an effective transfer rate of 0.95 MB/s
| 11:00:20 PM | Info | ......COMMAND => Resetting Custom Icon bit on LEWISIbookG4
| 11:00:20 PM | Info | PHASE: 3. After Successful Copy
| 11:00:20 PM | Info | ...ACTION: Making LEWISIbookG4 bootable
| 11:00:20 PM | Info | ......COMMAND => Blessing OS X System Folder
| 11:00:22 PM | Info | Successfully blessed Mac OS X folder on LEWISIbookG4
| 11:00:22 PM | Info | ......COMMAND => Blessing OS 9 System Folder
| 11:00:22 PM | Info | Did not bless Mac OS 9 System Folder on LEWISIbookG4 because it does not exist.
| 11:00:22 PM | Info | ...ACTION: Restoring Spotlight state on LEWISIbookG4
| 11:00:22 PM | Info | ......COMMAND => Restoring Spotlight search indexing state on LEWISIbookG4
| 11:00:22 PM | Info | ...ACTION: Unmounting LEWISIbookG4
| 11:00:22 PM | Info | ......COMMAND => Unmounting '/Volumes/Volume_1/LewisIBOOKG4/LEWISIbookG4.sparseimage'
| 11:00:24 PM | Info | hdiutil: detach failed - No such file or directory
| 11:00:24 PM | Info | PHASE: 4. And Finally...
| 11:00:24 PM | Info | ...ACTION: Quitting SuperDuper!
| 11:00:24 PM | Info | ......COMMAND => Quitting SuperDuper!
| 11:00:24 PM | Info | Copy complete.

[zickzhou]

| 02:30:26 AM | Info | SuperDuper!, 2.5 (84), path: /Applications/SuperDuper!.app, Mac OS 10.5.7 build 9J61 (ppc)
| 02:30:26 AM | Info | Started on Wed, Jul 29, 2009 at 2:30 AM
| 02:30:26 AM | Info | Source Volume: Leopard, mount: /, device: /dev/disk0s5, media: TOSHIBA MK6025GAS TOSHIBA MK6025GAS, interconnect: Internal ATA, file system: "Journaled HFS+", OS: 10.5.7 (9J61), capacity: 39.82 GB, used: 20.60 GB, directories: 145371, files: 682182, ejectable: NO, ACLs: Enabled
| 02:30:26 AM | Info | Target Image: /Volumes/Volume_1/LewisIBOOKG4/LEWISIbookG4.sparseimage, name: LEWISIbookG4
| 02:30:26 AM | Info | Copy Mode : Copy Newer files
| 02:30:26 AM | Info | Copy Script : Backup - all files.dset
| 02:30:26 AM | Info | Transcript : BuildTranscript.plist
| 02:30:28 AM | Info | PHASE: 1. Prepare to Copy Files
| 02:30:28 AM | Info | ...ACTION: Preparing Leopard
| 02:30:28 AM | Info | ......COMMAND => Verifying the integrity of volinfo.database
| 02:30:33 AM | Info | volinfo.database OK
| 02:30:33 AM | Info | ......COMMAND => Enabling permissions on Leopard
| 02:30:33 AM | Info | Refreshing Disk Arbitration ...
| 02:30:33 AM | Info | ......COMMAND => Verifying that permissions are enabled for Leopard
| 02:30:33 AM | Info | Permissions on '/' are enabled.
| 02:30:33 AM | Info | ...ACTION: Mounting LEWISIbookG4
| 02:30:33 AM | Info | ......COMMAND => Preparing LEWISIbookG4
| 02:30:34 AM | Info | ......COMMAND => Setting ownership and access modes for '/Volumes/Volume_1/LewisIBOOKG4/LEWISIbookG4.sparseimage'
| 02:30:34 AM | Info | ......COMMAND => Mounting LEWISIbookG4
| 02:30:36 AM | Info | /dev/disk1 Apple_partition_scheme /dev/disk1s1 Apple_partition_map /dev/disk1s3 Apple_HFS /Volumes/LEWISIbookG4
| 02:30:36 AM | Info | ......COMMAND => Mounting LEWISIbookG4
| 02:30:36 AM | Info | Waiting for LEWISIbookG4 to mount.
| 02:30:38 AM | Info | Waiting for LEWISIbookG4 to mount.
| 02:30:40 AM | Info | Waiting for LEWISIbookG4 to mount.
| 02:30:42 AM | Info | Waiting for LEWISIbookG4 to mount.
| 02:30:44 AM | Error | Failed to mount 'LEWISIbookG4'

[zickzhou]

| 06:30:17 AM | Info | SuperDuper!, 2.5 (84), path: /Applications/SuperDuper!.app, Mac OS 10.5.7 build 9J61 (ppc)
| 06:30:17 AM | Info | Started on Wed, Jul 29, 2009 at 6:30 AM
| 06:30:17 AM | Info | Source Volume: Leopard, mount: /, device: /dev/disk0s5, media: TOSHIBA MK6025GAS TOSHIBA MK6025GAS, interconnect: Internal ATA, file system: "Journaled HFS+", OS: 10.5.7 (9J61), capacity: 39.82 GB, used: 20.61 GB, directories: 145371, files: 682186, ejectable: NO, ACLs: Enabled
| 06:30:17 AM | Info | Target Image: /Volumes/Volume_1/LewisIBOOKG4/LEWISIbookG4.sparseimage, name: LEWISIbookG4
| 06:30:17 AM | Info | Copy Mode : Copy Newer files
| 06:30:17 AM | Info | Copy Script : Backup - all files.dset
| 06:30:17 AM | Info | Transcript : BuildTranscript.plist
| 06:30:17 AM | Info | PHASE: 1. Prepare to Copy Files
| 06:30:17 AM | Info | ...ACTION: Preparing Leopard
| 06:30:17 AM | Info | ......COMMAND => Verifying the integrity of volinfo.database
| 06:30:18 AM | Info | volinfo.database OK
| 06:30:18 AM | Info | ......COMMAND => Enabling permissions on Leopard
| 06:30:18 AM | Info | Refreshing Disk Arbitration ...
| 06:30:18 AM | Info | ......COMMAND => Verifying that permissions are enabled for Leopard
| 06:30:18 AM | Info | Permissions on '/' are enabled.
| 06:30:18 AM | Info | ...ACTION: Mounting LEWISIbookG4
| 06:30:18 AM | Info | ......COMMAND => Preparing LEWISIbookG4
| 06:30:18 AM | Info | ......COMMAND => Setting ownership and access modes for '/Volumes/Volume_1/LewisIBOOKG4/LEWISIbookG4.sparseimage'
| 06:30:18 AM | Info | ......COMMAND => Mounting LEWISIbookG4
| 06:30:19 AM | Info | /dev/disk1 Apple_partition_scheme /dev/disk1s1 Apple_partition_map /dev/disk1s3 Apple_HFS /Volumes/LEWISIbookG4
| 06:30:19 AM | Info | ......COMMAND => Mounting LEWISIbookG4
| 06:30:19 AM | Info | Waiting for LEWISIbookG4 to mount.
| 06:30:21 AM | Info | Waiting for LEWISIbookG4 to mount.
| 06:30:23 AM | Info | Waiting for LEWISIbookG4 to mount.
| 06:30:25 AM | Info | Waiting for LEWISIbookG4 to mount.
| 06:30:27 AM | Info | Waiting for LEWISIbookG4 to mount.
| 06:30:27 AM | Error | Failed to mount 'LEWISIbookG4'

[zickzhou]

| 04:30:16 AM | Info | SuperDuper!, 2.5 (84), path: /Applications/SuperDuper!.app, Mac OS 10.5.7 build 9J61 (ppc)
| 04:30:16 AM | Info | Started on Wed, Jul 29, 2009 at 4:30 AM
| 04:30:16 AM | Info | Source Volume: Leopard, mount: /, device: /dev/disk0s5, media: TOSHIBA MK6025GAS TOSHIBA MK6025GAS, interconnect: Internal ATA, file system: "Journaled HFS+", OS: 10.5.7 (9J61), capacity: 39.82 GB, used: 20.61 GB, directories: 145371, files: 682184, ejectable: NO, ACLs: Enabled
| 04:30:16 AM | Info | Target Image: /Volumes/Volume_1/LewisIBOOKG4/LEWISIbookG4.sparseimage, name: LEWISIbookG4
| 04:30:16 AM | Info | Copy Mode : Copy Newer files
| 04:30:16 AM | Info | Copy Script : Backup - all files.dset
| 04:30:16 AM | Info | Transcript : BuildTranscript.plist
| 04:30:17 AM | Info | PHASE: 1. Prepare to Copy Files
| 04:30:17 AM | Info | ...ACTION: Preparing Leopard
| 04:30:17 AM | Info | ......COMMAND => Verifying the integrity of volinfo.database
| 04:30:18 AM | Info | volinfo.database OK
| 04:30:18 AM | Info | ......COMMAND => Enabling permissions on Leopard
| 04:30:18 AM | Info | Refreshing Disk Arbitration ...
| 04:30:24 AM | Info | ......COMMAND => Verifying that permissions are enabled for Leopard
| 04:30:24 AM | Info | Permissions on '/' are enabled.
| 04:30:24 AM | Info | ...ACTION: Mounting LEWISIbookG4
| 04:30:24 AM | Info | ......COMMAND => Preparing LEWISIbookG4
| 04:30:24 AM | Info | ......COMMAND => Setting ownership and access modes for '/Volumes/Volume_1/LewisIBOOKG4/LEWISIbookG4.sparseimage'
| 04:30:24 AM | Info | ......COMMAND => Mounting LEWISIbookG4
| 04:30:25 AM | Info | /dev/disk1 Apple_partition_scheme /dev/disk1s1 Apple_partition_map /dev/disk1s3 Apple_HFS /Volumes/LEWISIbookG4
| 04:30:25 AM | Info | ......COMMAND => Mounting LEWISIbookG4
| 04:30:25 AM | Info | Waiting for LEWISIbookG4 to mount.
| 04:30:27 AM | Info | Waiting for LEWISIbookG4 to mount.
| 04:30:29 AM | Info | Waiting for LEWISIbookG4 to mount.
| 04:30:31 AM | Info | Waiting for LEWISIbookG4 to mount.
| 04:30:33 AM | Error | Failed to mount 'LEWISIbookG4'

[zickzhou]

I checked my system-energy saving settings.
If the laptop is on AC, then it never sleep. the screen will turn off after 15 min. So i do not think it is a weekup issue.

And from the log, I can see it is determined to be a mounting issue. My question is, why this is happening? I used exactly the same mounting configuration as the full backup. And when I came back this morning, I actually noticed that the sparse image is mounted on my mac (very much like mounting a dmg file). So how can I actually configure the SD to acheive the back up scheme I proposed in my initial post?

[dnanian]

The problem in all of these backups is that the image is already mounted when the backup starts. If you eject the image, they should work fine.