NETGEAR is aware of a growing number of phone and online scams. To learn how to stay safe click here.
Forum Discussion
Periodic (daily) Time Machine failures
I am running TM on an iMac with 10.5.8 to a Duo running 4.1.6. However I am getting a failure about once a day where TM pops up a dialog saying the network volume could not be mounted because of a problem with the username or password. It is very odd because it will do its hourly backup just fine for some period of time (a day or maybe a bit longer) before giving me this error. If I go to the TM Preferences I have to re-enter the username and password, then all is fine again for a day or so. This happened again just now and I snagged the Duo logs and also took a look at the Mac log. On there I see this series of possibly related messages:
Aug 16 17:07:40 Kobe kernel[0]: AFP_VFS afpfs_mount: /Volumes/ReadyNAS, pid 4993
Aug 16 17:07:40 Kobe Bonjour Mounter[267]: DiskAppearedCallback diskDescription = {\n DAVolumeKind = afpfs;\n DAVolumeMountable = 1;\n DAVolumeName = ReadyNAS;\n DAVolumeNetwork = 1;\n DAVolumePath = file://localhost/Volumes/ReadyNAS/;\n}
Aug 16 17:07:42 Kobe [0x0-0x14c14c].com.apple.systempreferences[4987]: chown: /Volumes/ReadyNAS/.001ec20ffc9e: Operation not permitted
Aug 16 17:07:43: --- last message repeated 1 time ---
Aug 16 17:07:43 Kobe kernel[0]: AFP_VFS afpfs_unmount: /Volumes/ReadyNAS, flags 0, pid 4998
Aug 16 17:07:43 Kobe Finder[161]: StatusMonitor::volumesChangedCallBack returned -47
Aug 16 17:07:43 Kobe kernel[0]: ASP_TCP do_thread_read: no reqInfo found for reqID 1
Aug 16 17:07:43 Kobe Bonjour Mounter[267]: DiskDisappearedCallback diskDescription = {\n DAVolumeKind = afpfs;\n DAVolumeMountable = 1;\n DAVolumeName = ReadyNAS;\n DAVolumeNetwork = 1;\n DAVolumePath = file://localhost/Volumes/ReadyNAS/;\n}
Aug 16 17:07:43 Kobe kernel[0]: ASP_TCP CancelOneRequest: cancelling slot 29 error 89 reqID 30 flags 0x9 afpCmd 0x14 so 0x7e7a330
In Duo afp.log
Aug 16 17:07:42 cnid_dbd[29190][logger.c:255]: I:Logger: doing log_setup, type 0, level 50, filename "/var/log/netatalk.log"
Aug 16 17:07:42 cnid_dbd[29190][logger.c:395]: D7:Logger: log_file_arr[0] now contains: {log_filename:/var/log/netatalk.log, log_file:(nil), log_level: 50}
Aug 16 17:07:42 cnid_dbd[29190][logger.c:398]: D5:Logger: log_setup[0] done
Aug 16 17:07:42 cnid_dbd[29190][main.c:210]: I:CNID: Setting uid/gid to 0/0
Aug 16 17:07:42 cnid_dbd[29190][main.c:309]: I:CNID: Startup, DB dir /c/.timemachine/.AppleDB
Any idea how I prevent this from happening? It means if I'm not in front of the Mac for awhile the TM backups stop.
Aug 16 17:07:40 Kobe kernel[0]: AFP_VFS afpfs_mount: /Volumes/ReadyNAS, pid 4993
Aug 16 17:07:40 Kobe Bonjour Mounter[267]: DiskAppearedCallback diskDescription = {\n DAVolumeKind = afpfs;\n DAVolumeMountable = 1;\n DAVolumeName = ReadyNAS;\n DAVolumeNetwork = 1;\n DAVolumePath = file://localhost/Volumes/ReadyNAS/;\n}
Aug 16 17:07:42 Kobe [0x0-0x14c14c].com.apple.systempreferences[4987]: chown: /Volumes/ReadyNAS/.001ec20ffc9e: Operation not permitted
Aug 16 17:07:43: --- last message repeated 1 time ---
Aug 16 17:07:43 Kobe kernel[0]: AFP_VFS afpfs_unmount: /Volumes/ReadyNAS, flags 0, pid 4998
Aug 16 17:07:43 Kobe Finder[161]: StatusMonitor::volumesChangedCallBack returned -47
Aug 16 17:07:43 Kobe kernel[0]: ASP_TCP do_thread_read: no reqInfo found for reqID 1
Aug 16 17:07:43 Kobe Bonjour Mounter[267]: DiskDisappearedCallback diskDescription = {\n DAVolumeKind = afpfs;\n DAVolumeMountable = 1;\n DAVolumeName = ReadyNAS;\n DAVolumeNetwork = 1;\n DAVolumePath = file://localhost/Volumes/ReadyNAS/;\n}
Aug 16 17:07:43 Kobe kernel[0]: ASP_TCP CancelOneRequest: cancelling slot 29 error 89 reqID 30 flags 0x9 afpCmd 0x14 so 0x7e7a330
In Duo afp.log
Aug 16 17:07:42 cnid_dbd[29190][logger.c:255]: I:Logger: doing log_setup, type 0, level 50, filename "/var/log/netatalk.log"
Aug 16 17:07:42 cnid_dbd[29190][logger.c:395]: D7:Logger: log_file_arr[0] now contains: {log_filename:/var/log/netatalk.log, log_file:(nil), log_level: 50}
Aug 16 17:07:42 cnid_dbd[29190][logger.c:398]: D5:Logger: log_setup[0] done
Aug 16 17:07:42 cnid_dbd[29190][main.c:210]: I:CNID: Setting uid/gid to 0/0
Aug 16 17:07:42 cnid_dbd[29190][main.c:309]: I:CNID: Startup, DB dir /c/.timemachine/.AppleDB
Any idea how I prevent this from happening? It means if I'm not in front of the Mac for awhile the TM backups stop.
82 Replies
Replies have been turned off for this discussion
roadfun wrote: I guess it may be some sort of time out but not sure. Like the others, mine will work the next time.
Just by way of update, this error happens to me about once every two days. Sometimes more, sometimes less.
I suppose it isn't terribly critical, but it sure would be nice to know that the ReadyNAS is at least as solid as my Time Capsule is (which never gives any errors on my other, smaller Macs that back up to it).
Any word from Netgear?
Thanks!
\marc- Same exact problem with Leopard/Snow Leopard.
I do notice that the problem at times happens right after waking from sleep. But there were certainly times when the system was on and it would happen randomly too. rezonat0r wrote: Same exact problem with Leopard/Snow Leopard.
I do notice that the problem at times happens right after waking from sleep. But there were certainly times when the system was on and it would happen randomly too.
Happens on my Mac Pro desktop, not set to sleep at all -- pretty much on 24x7.. Have not seen any particular pattern to the failures. It's really quite odd..
\marc- Just to add another data point -- one Leopard and one Snow Leopard machine, both with periodic failures of the sort described here. Any updates?
FromageTheDog wrote: Just to add another data point -- one Leopard and one Snow Leopard machine, both with periodic failures of the sort described here. Any updates?
I sure wish someone would comment.. I'm willing to provide any data they want to help resolve the issue.
It's not a fatal problem, to be sure, but it is unsettling when it has to do with my backup system... :-)
\marc- I have been unable to mount "ReadyNAS" share simultaneously with other shares such as "media" , "backup" or "user shares".
What I have done is to manually backup my 3 MBPs about once a week using the "ReadyNAS" share and then using TimeMachineEditor I use a NEW dual channel Apple Time Capsule to perform automatic daily backups. I got Apple to replace a first generation TC due to an overheating issue and the report of many TC power supply failures at the 18 month juncture.
The hourly backup deal sucks to much bandwidth for me. Lastly, I use Memeo to backup critical data to the time capsule, ReadyNAS backup share, an old Windoze computer on the local network and finally remotely to MobileMe account.
So far so good but a fix would be preferable. I would like to know others thoughts and views
BigBearf mnc042 wrote: FromageTheDog wrote: Just to add another data point -- one Leopard and one Snow Leopard machine, both with periodic failures of the sort described here. Any updates?
I sure wish someone would comment.. I'm willing to provide any data they want to help resolve the issue.
It's not a fatal problem, to be sure, but it is unsettling when it has to do with my backup system... :-)
\marc
Guys, please send your logs in (see my sig) if you're encountering time machine failures. We'll look into what's going on.yoh-dah wrote:
Guys, please send your logs in (see my sig) if you're encountering time machine failures. We'll look into what's going on.
I have just submitted my logs.. Please let me know if there's anything else I can do to help track this one down.
Standing by -- thanks for the help!
\marcmnc042 wrote: yoh-dah wrote:
Guys, please send your logs in (see my sig) if you're encountering time machine failures. We'll look into what's going on.
I have just submitted my logs.. Please let me know if there's anything else I can do to help track this one down.
Standing by -- thanks for the help!
\marc
Thanks -- we'll be taking a look. Others, please help out as well so we can get more logs from failure cases.- Attn: yoh-dah
Just sent email with my log files from my ReadyNAS.
I've also included the system.log from my mac (mac_system.log) which show the failures from the Mac side:
Oct 12 23:26:54 underdog com.apple.backupd[77961]: Starting standard backup
Oct 12 23:26:54 underdog com.apple.backupd[77961]: Attempting to mount network destination using URL: afp://[email protected]/ReadyNAS
Oct 12 23:26:55 underdog com.apple.backupd[77961]: NetAuthConnectToServerSync failed with error: -6600 for url: afp://[email protected]/ReadyNAS
Oct 12 23:26:55 underdog com.apple.backupd[77961]: Authentication error - the correct user or password info may not exist in the System.keychain or the server may no longer allow access for this user.
Oct 12 23:27:00: --- last message repeated 1 time ---
Oct 12 23:27:00 underdog com.apple.backupd[77961]: Backup failed with error: 29
It would be great to figure this out as I backup three Macs in my home to the ReadyNAs.
--mark
NETGEAR Academy
Boost your skills with the Netgear Academy - Get trained, certified and stay ahead with the latest Netgear technology!
Join Us!
