PDA

View Full Version : Long Hang While Copying


BackerUpper
06-06-2006, 06:06 PM
When attempting to copy an external drive to a disk image (parse or read only) it seems SD hangs right away when it starts copying (info about how much has been copied stops increasing but elapsed time continues to go) and then continues once it gets to about 4GB or so. What causes this to hang? I have tried it on several systems and tried a couple external drives. Below is the log file from one operation. You can see where it jumps from 03:16:14 PM to 03:35:13 PM after copying Chrismas Hits 60 CD2. Chrismas Hits 60 CD2 is only about 100MB but DV File is 4GB or so.

| 03:15:42 PM | Info | SuperDuper!, 2.1.1 (78), path: /Users/TestMachine/Applications/SuperDuper!.app, Mac OS 10.4.6 build 8I127 (ppc)
| 03:15:42 PM | Info | Started on Tue, Jun 6, 2006 at 3:15 PM
| 03:15:42 PM | Info | Source Drive: Travel Drive 9GB, mount: /Volumes/Travel Drive 9GB, device: ST310211 A, interconnect: External FireWire, file system: "Journaled HFS+", OS: N/A, capacity: 9.19 GB, used: 4.82 GB, directories: 20, files: 156, ejectable: NO, ACLs: Disabled
| 03:15:42 PM | Info | Target Image: /Users/TestMachine/Documents/Test Test.sparseimage, name: Test Test
| 03:15:42 PM | Info | Copy Mode : Erase, then copy files
| 03:15:42 PM | Info | Copy Script : Backup - all files.dset
| 03:15:42 PM | Info | Transcript : BuildTranscript.plist
| 03:15:42 PM | Info | PHASE: 1. Prepare to Copy Files
| 03:15:42 PM | Info | ...ACTION: Preparing Travel Drive 9GB
| 03:15:42 PM | Info | ......COMMAND => Verifying the integrity of volinfo.database
| 03:15:43 PM | Info | volinfo.database OK
| 03:15:43 PM | Info | ......COMMAND => Enabling permissions on Travel Drive 9GB
| 03:15:43 PM | Info | Refreshing Disk Arbitration ...
| 03:15:43 PM | Info | ......COMMAND => Verifying that permissions are enabled for Travel Drive 9GB
| 03:15:43 PM | Info | Permissions on '/Volumes/Travel Drive 9GB' are enabled.
| 03:15:43 PM | Info | ...ACTION: Mounting Test Test
| 03:15:43 PM | Info | ......COMMAND => Preparing Test Test
| 03:15:51 PM | Info | created: /Users/TestMachine/Documents/Test Test.sparseimage
| 03:15:51 PM | Info | ......COMMAND => Setting ownership and access modes for '/Users/TestMachine/Documents/Test Test.sparseimage'
| 03:15:51 PM | Info | ......COMMAND => Mounting Test Test
| 03:15:56 PM | Info | ...ACTION: Erasing Test Test
| 03:15:56 PM | Info | ......COMMAND => Preserving Test Test UUID
| 03:15:57 PM | Info | 29F447529C2CA241
| 03:15:57 PM | Info | ......COMMAND => Preserving spotlight indexing state
| 03:15:57 PM | Info | ......COMMAND => Erasing Test Test
| 03:16:09 PM | Info | ......COMMAND => Restoring Test Test UUID (SDUUID.hexString)
| 03:16:10 PM | Info | 29F447529C2CA241
| 03:16:10 PM | Info | ......COMMAND => Restoring Spotlight search indexing state on Test Test
| 03:16:10 PM | Info | ...ACTION: Preparing Test Test
| 03:16:10 PM | Info | ......COMMAND => Enabling permissions on Test Test
| 03:16:10 PM | Info | Refreshing Disk Arbitration ...
| 03:16:10 PM | Info | ......COMMAND => Verifying that permissions are enabled for Test Test
| 03:16:10 PM | Info | Permissions on '/Volumes/Test Test' are enabled.
| 03:16:10 PM | Info | ......COMMAND => Verifying that Test Test ACL support matches Travel Drive 9GB
| 03:16:10 PM | Info | ......COMMAND => Disabling Spotlight search indexing on Test Test
| 03:16:10 PM | Info | PHASE: 2. Copy Files
| 03:16:10 PM | Info | ...ACTION: Copying files from Travel Drive 9GB to Test Test
| 03:16:10 PM | Info | ......COMMAND => Cloning Travel Drive 9GB to Test Test
| 03:16:11 PM | Info | Copying all files using script: /Users/TestMachine/Library/Application Support/SuperDuper!/Copy Scripts/Standard Scripts/Backup - all files.dset
| 03:16:11 PM | Info | Loading 19 commands from copy script /Users/TestMachine/Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude system temporary files.dset
| 03:16:11 PM | Info | Loading 6 commands from copy script /Users/TestMachine/Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude system cache files.dset
| 03:16:11 PM | Info | Loading 1 commands from copy script /Users/TestMachine/Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude Norton FileSaver files.dset
| 03:16:11 PM | Info | Loading 2 commands from copy script /Users/TestMachine/Applications/SuperDuper!.app/Contents/Resources/Copy Scripts/Exclude Spotlight search index.dset
| 03:16:11 PM | Info | Loading 0 commands from copy script /Users/TestMachine/Library/Application Support/SuperDuper!/Copy Scripts/Standard Scripts/Backup - all files.dset
| 03:16:11 PM | Info | /Volumes/Travel Drive 9GB
| 03:16:14 PM | Info | /Volumes/Travel Drive 9GB/Chrismas Hits 60 CD2
| 03:35:13 PM | Info | /Volumes/Travel Drive 9GB/Recycled
| 03:35:13 PM | Info | /Volumes/Travel Drive 9GB/DV File
| 03:35:16 PM | Info | /Volumes/Travel Drive 9GB/TheVolumeSettingsFolder
| 03:35:16 PM | Info | /Volumes/Travel Drive 9GB/Trash
| 03:35:16 PM | Info | /Volumes/Travel Drive 9GB/Barry Manilow - A Christmas Gift Of Love
| 03:35:26 PM | Info | /Volumes/Travel Drive 9GB/Chrismas Hits 60 CD1
| 03:35:48 PM | Info | /Volumes/Travel Drive 9GB/A Christmas Together
| 03:36:08 PM | Info | /Volumes/Travel Drive 9GB/Chrismas Hits 60 CD3
| 03:36:26 PM | Info | /Volumes/Travel Drive 9GB/Denis Leary - Merry F#%$in Christmas
| 03:36:34 PM | Info | /Volumes/Travel Drive 9GB/.Spotlight-V100
| 03:36:34 PM | Info | Ignoring /Volumes/Travel Drive 9GB/.Spotlight-V100/_rules.plist
| 03:36:34 PM | Info | Ignoring /Volumes/Travel Drive 9GB/.Spotlight-V100/ContentIndex.db
| 03:36:34 PM | Info | Ignoring /Volumes/Travel Drive 9GB/.Spotlight-V100/master-change-id
| 03:36:34 PM | Info | Ignoring /Volumes/Travel Drive 9GB/.Spotlight-V100/master-change-list-00000001
| 03:36:34 PM | Info | Ignoring /Volumes/Travel Drive 9GB/.Spotlight-V100/.journalHistoryLog
| 03:36:34 PM | Info | Ignoring /Volumes/Travel Drive 9GB/.Spotlight-V100/store.db
| 03:36:34 PM | Info | Ignoring /Volumes/Travel Drive 9GB/.Spotlight-V100/.store.db
| 03:36:34 PM | Info | /Volumes/Travel Drive 9GB/.TemporaryItems
| 03:36:34 PM | Info | /Volumes/Travel Drive 9GB/Euro Trip - Original Soundtrack
| 03:36:53 PM | Info | /Volumes/Travel Drive 9GB/.Trashes
| 03:36:53 PM | Info | Ignoring /Volumes/Travel Drive 9GB/Desktop DB
| 03:36:53 PM | Info | Ignoring /Volumes/Travel Drive 9GB/Desktop DF
| 03:36:54 PM | Info | Evaluated 174 items occupying 4.80 GB (20 directories, 154 files, 0 symlinks)
| 03:36:54 PM | Info | Copied 180 items totaling 4.80 GB (18 directories, 144 files, 18 symlinks)
| 03:36:54 PM | Info | Cloned 4.80 GB of data in 1243 seconds at an effective transfer rate of 3.95 MB/s
| 03:36:54 PM | Info | PHASE: 3. After Successful Copy
| 03:36:54 PM | Info | ...ACTION: Making Test Test bootable
| 03:36:54 PM | Info | ......COMMAND => Blessing OS X System Folder
| 03:36:55 PM | Info | Did not bless Mac OS X System Folder on Test Test because it does not exist.
| 03:36:55 PM | Info | ......COMMAND => Blessing OS 9 System Folder
| 03:36:55 PM | Info | Did not bless Mac OS 9 System Folder on Test Test because it does not exist.
| 03:36:55 PM | Info | ......COMMAND => Restoring Spotlight search indexing state on Test Test
| 03:36:56 PM | Info | ...ACTION: Unmounting Test Test
| 03:36:56 PM | Info | ......COMMAND => Unmounting '/Users/TestMachineTestMachine/Documents/Test Test.sparseimage'
| 03:36:57 PM | Info | "disk3" unmounted.
| 03:36:58 PM | Info | "disk3" ejected.
| 03:36:58 PM | Info | Copy complete.

dnanian
06-06-2006, 06:40 PM
It doesn't really sound like it's hanging, it sounds like it's copying a large file. We don't update the status mid-file, so you won't get much feedback if something big is going across the wire...

geekboy2000
06-12-2006, 01:14 AM
When attempting to copy an external drive to a disk image (parse or read only) it seems SD hangs right away when it starts copying (info about how much has been copied stops increasing but elapsed time continues to go) and then continues once it gets to about 4GB or so. What causes this to hang? I have tried it on several systems and tried a couple external drives. Below is the log file from one operation. You can see where it jumps from 03:16:14 PM to 03:35:13 PM after copying Chrismas Hits 60 CD2. Chrismas Hits 60 CD2 is only about 100MB but DV File is 4GB or so.


After much head scratching, hair pulling, and fist pounding, I discovered the following tonight. This may or may not help, but it can't hurt. :)

Scenario:
Intel 20" Core Duo iMac
Two External firewire drives. One is a Lacie 160GB, the other, a 250GB Samsung PATA drive in a Mad Dog Multimedia Firewire/USB enclosure.

SuperDuper! works flawlessly with the Lacie, but the Samsung "falls asleep" during the copying process. During this time, there is no progress displayed in SuperDuper!, and the drive is completely silent with the exception of what sounds like one "burst" of activity about every 2 minutes. The copy never finishes.
Tried CCC, same results.

Had an iRock (generic) firewire enclosure being used elsewhere. Swapped the Samsung drive into it, and behold, no problems whatever.

Here's where it gets interesting. Both drive enclosures are close cousins. The Mad Dog has a fancy illuminated front activity light, the generic iRock does not. They both show as an initio (If I recall correctly) interface in Disk Utility, so I'm guessing the chipset is similar, if not the same. The PCB inside both enclosures is very similar.

The Mad Dog enclosure works fine on my PPC Mac Mini. The iRock generic on either. So, I have concluded that there's something about the compatibility between the Mad Dog enclosure, it's firewire interface, and the Intel Core Duo iMac.

Mark

dnanian
06-12-2006, 08:15 AM
In general, non-Oxford bridge chipsets have a lot of trouble with OS X (it's not so much SuperDuper!, since we're not operating at the hardware level, but rather the FireWire kernel extension inside OSX itself).

These problems can manifest themselves in lots of annoying ways, including hung copies, failed boots, I/O errors, etc.

One of the differences between your two drives could be the firmware installed: it's possible that Initio has improved their compatibility in later releases.

But, if at all possible, get a drive with an Oxford chipset... in my experience, they work a lot better.

geekboy2000
06-12-2006, 09:26 AM
In general, non-Oxford bridge chipsets have a lot of trouble with OS X (it's not so much SuperDuper!, since we're not operating at the hardware level, but rather the FireWire kernel extension inside OSX itself).

These problems can manifest themselves in lots of annoying ways, including hung copies, failed boots, I/O errors, etc.

One of the differences between your two drives could be the firmware installed: it's possible that Initio has improved their compatibility in later releases.

But, if at all possible, get a drive with an Oxford chipset... in my experience, they work a lot better.

Thanks! I'm not sure it'll be easy to determine the chipset in advance of purchase (particularly when buying an enclosure only), but it's good information. Thanks again.

Mark