Shirt Pocket Discussions  
    Home netTunes launchTunes SuperDuper! Buy Now Support Discussions About Shirt Pocket    

Go Back   Shirt Pocket Discussions > SuperDuper! > General

 
 
Thread Tools Rate Thread Display Modes
Prev Previous Post   Next Post Next
  #3  
Old 09-09-2008, 03:34 PM
acronce acronce is offline
Registered User
 
Join Date: Aug 2006
Posts: 10
Quote:
Originally Posted by dnanian View Post
We're just asking the image to mount with hdiutil; I don't know why it would hang the diskimages-helper unless there was something pretty wrong.

Maybe try pointing at the mounted image rather than the file?
Sorry, I wasn't clear enough. In all the failure cases, I specified the mounted image as the Super Duper destination. That means that Super Duper didn't even mount the image, the Finder did.

BTW, I created the sparse image manually using Disk Utility rather than let Super Duper create it. The image file is also AES encrypted, which I think I mentioned.

I've looked at this problem a little more. In both failure cases, the backup seems to get to around the same place before the bad stuff happens. The file that Super Duper is trying to copy, ironically, is an archived Super Duper backup sparse image file that's 160 GB.

Also, I've looked at the logs on the system. If I'm reading this right, it looks like the underlying AFP volume is disappearing right around the time that the backup fails. Here's the log entries (with volume names changed to protect the innocent):

Sep 9 00:00:01 my_server newsyslog[4773]: logfile turned over
Sep 9 00:14:33 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: doing reconnect on /Volumes/afp_share
Sep 9 00:14:33 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: connect to the server /Volumes/afp_share
Sep 9 00:14:38 my_server kernel[0]: AFP_VFS afpfs_Reconnect: posting to KEA EINPROGRESS for /Volumes/afp_share
Sep 9 00:14:38 my_server KernelEventAgent[60]: tid 00000000 received VQ_NOTRESP event (1)
Sep 9 00:14:38 my_server KernelEventAgent[60]: tid 00000000 type 'afpfs', mounted on '/Volumes/afp_share', from 'afp_00btPO00btPO00btPO00btPO-2.2d000005', not responding
Sep 9 00:14:38 my_server loginwindow[59]: 1 server now unresponsive
Sep 9 00:14:38 my_server KernelEventAgent[60]: tid 00000000 found 1 filesystem(s) with problem(s)
Sep 9 00:15:08 my_server loginwindow[59]: 1 server now unresponsive
Sep 9 00:15:33 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: Open Session failed 32 /Volumes/afp_share
Sep 9 00:15:33 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: sleep for 1 seconds and then try again
Sep 9 00:15:34 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: connect to the server /Volumes/afp_share
Sep 9 00:16:35 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: Open Session failed 32 /Volumes/afp_share
Sep 9 00:16:35 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: sleep for 2 seconds and then try again
Sep 9 00:16:37 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: connect to the server /Volumes/afp_share
Sep 9 00:17:37 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: Open Session failed 32 /Volumes/afp_share
Sep 9 00:17:37 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: sleep for 4 seconds and then try again
Sep 9 00:17:41 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: connect to the server /Volumes/afp_share
Sep 9 00:18:41 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: Open Session failed 32 /Volumes/afp_share
Sep 9 00:18:41 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: sleep for 8 seconds and then try again
Sep 9 00:18:49 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: connect to the server /Volumes/afp_share
Sep 9 00:19:49 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: Open Session failed 32 /Volumes/afp_share
Sep 9 00:19:49 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: sleep for 10 seconds and then try again
Sep 9 00:19:59 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: connect to the server /Volumes/afp_share
Sep 9 00:20:22 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: Logging in with uam 7 /Volumes/afp_share
Sep 9 00:20:23 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: Restoring session /Volumes/afp_share
Sep 9 00:20:24 my_server kernel[0]: AFP_VFS afpfs_DoReconnect: primary reconnect failed 5, trying secondary /Volumes/afp_share
Sep 9 00:20:24 my_server kernel[0]: AFP_VFS afpfsCheckOpenFiles: File <my_server backup.sparseimage> can not be reopened due to deny modes or byte range locks (file has exclusive access).
Sep 9 00:20:24 my_server kernel[0]: disk2s2: 0x5 (UNDEFINED).
Sep 9 00:20:24 my_server kernel[0]:
Sep 9 00:20:24: --- last message repeated 1 time ---
Sep 9 00:20:24 my_server kernel[0]: disk2s2: 0x5 (UNDEFINED).
Sep 9 00:20:24 my_server kernel[0]:
Sep 9 00:20:24: --- last message repeated 1 time ---
Sep 9 00:20:24 my_server KernelEventAgent[60]: tid 00000000 received VQ_NOTRESP event (1)
Sep 9 00:20:24 my_server kernel[0]: disk2s2: 0x5 (UNDEFINED).
Sep 9 00:20:24 my_server loginwindow[59]: No servers unresponsive
Sep 9 00:20:24 my_server kernel[0]:
Sep 9 00:20:24: --- last message repeated 1 time ---
Sep 9 00:20:24 my_server kernel[0]: disk2s2: 0x5 (UNDEFINED).
Sep 9 00:20:24 my_server /Applications/SuperDuper!.app/Contents/MacOS/SDCopy[2148]: open on /Volumes/my_server backup/backups/Treasury/image/Treasury Backup.sparseimage: Invalid argument
Sep 9 00:20:24 my_server kernel[0]:
Sep 9 00:20:24: --- last message repeated 1 time ---
Sep 9 00:20:24 my_server kernel[0]: disk2s2: 0x5 (UNDEFINED).
Sep 9 00:20:24 my_server kernel[0]:
Sep 9 00:20:24: --- last message repeated 1 time ---
Sep 9 00:20:24 my_server kernel[0]: disk2s2: 0x5 (UNDEFINED).
Sep 9 00:20:24 my_server kernel[0]:
Sep 9 00:20:24: --- last message repeated 1 time ---
Sep 9 00:20:24 my_server kernel[0]: disk2s2: 0x5 (UNDEFINED).
Sep 9 00:20:24 my_server kernel[0]:
Sep 9 00:20:24: --- last message repeated 1 time ---
Sep 9 00:20:24 my_server kernel[0]: disk2s2: 0x5 (UNDEFINED).
Sep 9 00:20:24 my_server kernel[0]:
Sep 9 00:20:24: --- last message repeated 1 time ---
Sep 9 00:20:24 my_server kernel[0]: disk2s2: 0x5 (UNDEFINED).
Sep 9 00:20:24 my_server kernel[0]:
Sep 9 00:20:24: --- last message repeated 1 time ---
Sep 9 00:20:24 my_server kernel[0]: jnl: disk2s2: do_jnl_io: strategy err 0x5
Sep 9 00:20:24 my_server SuperDuper![533]: ***ERROR OCCURRED: ditto: /Volumes/my_server backup/backups/Treasury/image/Treasury Backup.sparseimage: Input/output error
Sep 9 00:20:24 my_server kernel[0]: jnl: disk2s2: write_journal_header: error writing the journal header!
Sep 9 00:20:24 my_server kernel[0]: disk2s2: 0x5 (UNDEFINED).
Sep 9 00:20:24 my_server kernel[0]:
Sep 9 00:20:24: --- last message repeated 1 time ---
Sep 9 00:20:24 my_server kernel[0]: jnl: disk2s2: do_jnl_io: strategy err 0x5
Sep 9 00:20:24 my_server kernel[0]: jnl: disk2s2: end_transaction: only wrote 0 of 25088 bytes to the journal!
Sep 9 00:20:24 my_server kernel[0]: disk2s2: 0x5 (UNDEFINED).


This has me thinking that either the ReadyNAS has an AFP bug, or that backing up large amounts of data to a volume that's actually a sparse image file on an AFP share is maybe not a good idea in general. It's one thing when the backing store is a local hard drive. The diskimages-helper, which I think "fronts" the volume for the image file, can rely on solid reliability.

But if the backing store is over the network, then there could be big problems if diskimages-helper can't reliably read or write the underlying file data. I can totally see low level file operations get blocked by an incomplete AFP transfer. Especially when we're transferring huge files (like image backups of other systems) to the sparse image volume.

I think I'm going to try backing up to just a simple share point on the ReadyNAS, rather than a mounted sparse image, to see if that makes a difference. Maybe this all comes down to a reliability problem with the ReadyNAS.

If this ends up being a ReadyNAS problem, then I apologize for the diversion.

Best regards,
--
Allen Cronce
Reply With Quote
 


Currently Active Users Viewing This Thread: 1 (0 members and 1 guests)
 
Thread Tools
Display Modes Rate This Thread
Rate This Thread:

Posting Rules
You may not post new threads
You may not post replies
You may not post attachments
You may not edit your posts

BB code is On
Smilies are On
[IMG] code is On
HTML code is Off

Forum Jump

Similar Threads
Thread Thread Starter Forum Replies Last Post
network sparse image question subrama6 General 2 02-21-2008 11:35 AM
Sparse image from aborted DMG backup? Winston General 9 10-22-2005 12:28 PM
Sparse image mounted over network is grayed out in backup destination list therevolution General 5 06-25-2005 07:49 PM
Question regarding backing up to Image giba General 3 05-20-2005 05:54 PM
Backup to Sparse Image on Linux Box Through Network sukhjeet General 1 12-31-2004 08:08 PM


All times are GMT -4. The time now is 05:27 PM.


Powered by vBulletin® Version 3.8.9
Copyright ©2000 - 2022, vBulletin Solutions, Inc.