Q: PHD will not sync on log out

Question

Frando

Level 2 (153 points)

Mac OS X

Q: PHD will not sync on log out

Hi,

I have two 10.7.4 clients running as PHD on a 10.7.4 server. I have noticed rec ently that neither of them sync themselves when they log out. All other syncing works (i.e log in, maually, and every 5 minutes). I have an exception errro in my FileSyncAgent-Verbose.log file that says that it couldn't write a .FileSyncHistory file to my server side ~/.FileSync folder:

From FileSynce Agent.log

EXCEPTION: [data writeToFile:'/Volumes/HomeDirectories/francis.hampden/.FileSync/HomeSync_Mirror_Peer-eu96pQJN794-francis.hampden.FileSyncHistory' options:0 error:&error] (Cocoa error 512)

From FileSynce Agent-verbose.log

Marker - 6 Jul 2012 08:06:59
1:: [12/07/06 08:07:00.461] Setting PHD remote home path to "/Volumes/HomeDirectories/francis.hampden".
1:: [12/07/06 08:07:01.501] ==========================================================
0:: [12/07/06 08:07:01.501] Starting manual sync of "HomeSync_Mirror".
1:: [12/07/06 08:07:01.502] _incomingIPC: SFObjcMessageMsgID (2000) took 1.041332 seconds.
1:: [12/07/06 08:07:01.505] -[SPeer_FS_PHD mountPeerVolume]: We've been given remote home path "/Volumes/HomeDirectories/francis.hampden".
1:: [12/07/06 08:07:01.505] -[SPeer_FS_PHD mountPeerVolume]: Remote home path exists.
1:: [12/07/06 08:07:01.505] -[SPeer_FS_PHD mountPeerVolume]: Final path to PHD remote home root = "/Volumes/HomeDirectories/francis.hampden"
1:: [12/07/06 08:07:01.507] [0x7f8feaa04bb0/<SStore_FS:0x10d7f2420>] +[SStore_FS newStore_FSForPeer:alias:]: isRemote = NO
1:: [12/07/06 08:07:01.507] [0x7f8feaa04bb0/<SStore_FS:0x10d7f2420>] +[SStore_FS newStore_FSForPeer:rootPath:rootAlias:rootRef:storePath:optionalStoreID:]: peer = <SPeer_FS:0x7f8fea80b440> = local, optionalStoreID = (null), peer.storeIDString = *
1:: [12/07/06 08:07:01.507] [0x7f8feaa04bb0/<SStore_FS:0x10d7f2420>] +[SStore_FS newStore_FSForPeer:rootPath:rootAlias:rootRef:storePath:optionalStoreID:]: rootPath        = /Users/francis.hampden
1:: [12/07/06 08:07:01.507] [0x7f8feaa04bb0/<SStore_FS:0x10d7f2420>] +[SStore_FS newStore_FSForPeer:rootPath:rootAlias:rootRef:storePath:optionalStoreID:]: storePath       = /Users/francis.hampden/.FileSync/store.filesyncstatetree
1:: [12/07/06 08:07:01.509] [0x7f8feaa04bb0/<SStore_FS:0x10d7f2420>] +[SStore_FS newStore_FSForPeer:rootPath:rootAlias:rootRef:storePath:optionalStoreID:]: rootAlias       = {path='/Users/francis.hampden', targetName='francis.hampden', volumeName='Macintosh HD', type=DIR, volumeCreateDate=2011-10-08-06:16:08, targetCreateDate=2012-06-29-17:43:11, parentDirID=49187, nodeID=7693575, filesystemID=0000 ('0000'), signature=0x482b ('H+'), isBootVolume=YES, isAutomounted=NO, isEjectable=NO, hasPersistentFileIDs=YES, mounted=YES, url='file://localhost/'}
1:: [12/07/06 08:07:01.515] -[SStore_FS setupWithAlias:andRef:] (Store-FS.m:447): unlink('/Users/francis.hampden/.FileSync/.fstemp.Optt41-cRGLKUN.HK3.noindex')
1:: [12/07/06 08:07:01.524] X::...PHD-L-rRaw7zntXN5-francis.hampden Exclusion/Inclusion pair #1 of 1
1:: [12/07/06 08:07:01.524] X::Exclusions                                                       | Inclusions      
1:: [12/07/06 08:07:01.524] X::------------------------------------------------------------------------------------
1:: [12/07/06 08:07:01.524] X::path BEGINSWITH "."                                              |                 
1:: [12/07/06 08:07:01.524] X::name BEGINSWITH[c] ".DocumentRevisions"                          |                 
1:: [12/07/06 08:07:01.524] X::name BEGINSWITH[c] ".afpDeleted"                                 |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] "Library/Logs/FileSyncAgent.log"                    |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] "Documents/Microsoft User Data"                     |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] ".dropbox"                                          |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] "Library/Logs/MirrorAgent.log"                      |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] "Library/Preferences/com.apple.homeSync.plist"      |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] "NAVMac800QSFile"                                   |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] ".FileSync"                                         |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] "Library/Preferences/com.apple.FileSyncUI.plist"    |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] "Library/Mirrors"                                   |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] "Library/FileSync"                                  |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] "Library/Preferences/com.apple.mcxMenuExtras.plist" |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] "Dropbox"                                           |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] ".Spotlight-V100"                                   |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] ".account"                                          |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] ".TemporaryItems"                                   |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] ".fseventsd"                                        |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] ".mcxlc"                                            |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] "Library"                                           |                 
1:: [12/07/06 08:07:01.524] X::name LIKE[c] ".com.apple.timemachine.supported"                  |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] ".Cleanup At Startup"                               |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] "Parallels"                                         |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] "Applications (Parallels)"                          |                 
1:: [12/07/06 08:07:01.524] X::path LIKE[c] ".SymAVQSFile"                                      |                 
1:: [12/07/06 08:07:01.524] X::---not changed---                                                | ---not changed---
1:: [12/07/06 08:07:01.524] X::------------------------------------------------------------------------------------
1:: [12/07/06 08:07:01.524] [0x7f8feaa04bb0/<SStore_FS:0x7f8fea818520>] -[SStore_FS prepareForSync]: PHD-L-rRaw7zntXN5-francis.hampden: self.wasPrepareForSyncDoneRemotely => NO
1:: [12/07/06 08:07:01.525] Store "PHD-L-rRaw7zntXN5-francis.hampden" started prepareForSync with root version HomeSync_Mirror_Peer-eu96pQJN794-francis.hampden1340988492PHD-L-rRaw7zntXN5-francis.hampden1340988517[]
1:: [12/07/06 08:07:01.527] PHD-L-rRaw7zntXN5-francis.hampden FSEvents scan
1:: [12/07/06 08:07:01.724] PHD-L-rRaw7zntXN5-francis.hampden FSEvents scan took 0.20 seconds
1:: [12/07/06 08:07:02.077] PHD-L-rRaw7zntXN5-francis.hampden Scanning flagged directory "/"
1:: [12/07/06 08:07:02.436] Store "PHD-L-rRaw7zntXN5-francis.hampden" finished prepareForSync with root version HomeSync_Mirror_Peer-eu96pQJN794-francis.hampden1340988492PHD-L-rRaw7zntXN5-francis.hampden1340988517[]
1:: [12/07/06 08:07:02.437] -[SPeer_FS_PHD mountPeerVolume]: Remote home located at "/Volumes/HomeDirectories/francis.hampden"
1:: [12/07/06 08:07:02.438] [0x7f8feaa03110/<SStore_FS:0x10d7f2420>] +[SStore_FS newStore_FSForPeer:alias:]: isRemote = YES
1:: [12/07/06 08:07:02.438] [0x7f8feaa03110/<SStore_FS:0x10d7f2420>] +[SStore_FS newStore_FSForPeer:alias:]: gAttemptRemotePrepareForSync = YES
1:: [12/07/06 08:07:02.451] ssh subtask PID:509
1:: [12/07/06 08:07:02.461] -[SSHIPCClient handleStderrLineOrEOF:]: [2012-07-06 07:07:02 +0000] 'Pseudo-terminal will not be allocated because stdin is not a terminal.
1:: [12/07/06 08:07:02.461] '
1:: [12/07/06 08:07:02.512] -[SSHIPCClient handleStderrLineOrEOF:]: [2012-07-06 07:07:02 +0000] 'Warning: Permanently added '[leopard.glyndeestates.co.uk]:2336' (RSA) to the list of known hosts.
1:: [12/07/06 08:07:02.512] '
1:: [12/07/06 08:07:02.845] Remote peer greeting: 'com.apple.FileSync-2.0.0 FileSyncAgent-502.2 r? OKAY pid=28624'
1:: [12/07/06 08:07:02.846] Remote host 'leopard.glyndeestates.co.uk' reported status as 'OKAY'
1:: [12/07/06 08:07:18.601] Peer 'network' successfully performed remote prepareForSync, remoteStoreID = HomeSync_Mirror_Peer-eu96pQJN794-francis.hampden, remoteDBName = Store-FS-PHD-network-home.filesyncstatetree, took 16.16 seconds
1:: [12/07/06 08:07:18.606] +[SStore_FS newStore_FSForPeer:alias:] (Store-FS.m:325): unlink('/Users/francis.hampden/Library/Caches/Cleanup At Startup/FileSyncAgent-1025/.FileSync/Store-FS-PHD-network-home.filesyncstatetree')
1:: [12/07/06 08:07:18.606] unlink('/Users/francis.hampden/Library/Caches/Cleanup At Startup/FileSyncAgent-1025/.FileSync/Store-FS-PHD-network-home.filesyncstatetree') failed (2)
1:: [12/07/06 08:07:21.933] Download/expand of "/Volumes/HomeDirectories/francis.hampden/.FileSync/Store-FS-PHD-network-home.filesyncstatetree.bz2" took 3.33 seconds
1:: [12/07/06 08:07:21.933] [0x7f8feaa03110/<SStore_FS:0x10d7f2420>] +[SStore_FS newStore_FSForPeer:rootPath:rootAlias:rootRef:storePath:optionalStoreID:]: peer = <SPeer_FS_PHD:0x7f8fe960ca10> = network, optionalStoreID = HomeSync_Mirror_Peer-eu96pQJN794-francis.hampden, peer.storeIDString = *
1:: [12/07/06 08:07:21.933] [0x7f8feaa03110/<SStore_FS:0x10d7f2420>] +[SStore_FS newStore_FSForPeer:rootPath:rootAlias:rootRef:storePath:optionalStoreID:]: rootPath        = /Volumes/HomeDirectories/francis.hampden
1:: [12/07/06 08:07:21.933] [0x7f8feaa03110/<SStore_FS:0x10d7f2420>] +[SStore_FS newStore_FSForPeer:rootPath:rootAlias:rootRef:storePath:optionalStoreID:]: storePath       = /Users/francis.hampden/Library/Caches/Cleanup At Startup/FileSyncAgent-1025/.FileSync/Store-FS-PHD-network-home.filesyncstatetree
1:: [12/07/06 08:07:21.934] [0x7f8feaa03110/<SStore_FS:0x10d7f2420>] +[SStore_FS newStore_FSForPeer:rootPath:rootAlias:rootRef:storePath:optionalStoreID:]: rootAlias       = {path='/Volumes/HomeDirectories/francis.hampden', targetName='francis.hampden', volumeName='HomeDirectories', type=DIR, volumeCreateDate=2011-08-26-18:22:47, targetCreateDate=2012-03-05-18:26:39, parentDirID=2, nodeID=3851835, filesystemID=0x6173 ('as'), signature=0x482b ('H+'), isBootVolume=NO, isAutomounted=NO, isEjectable=NO, hasPersistentFileIDs=YES, mounted=YES, url='afp://francis.hampden@leopard.glyndeestates.co.uk/HomeDirectories'}
1:: [12/07/06 08:07:21.942] -[SStore_FS setupWithAlias:andRef:] (Store-FS.m:447): unlink('/Users/francis.hampden/Library/Caches/Cleanup At Startup/FileSyncAgent-1025/.FileSync/.fstemp.Optt41-CwkuIb9eL_.noindex')
1:: [12/07/06 08:07:22.072] -[SStore(protected) cleanupTree]: HomeSync_Mirror_Peer-eu96pQJN794-francis.hampden: required 0.1 seconds to execute
1:: [12/07/06 08:07:22.078] X::...HomeSync_Mirror_Peer-eu96pQJN794-francis.hampden Exclusion/Inclusion pair #1 of 1
1:: [12/07/06 08:07:22.078] X::Exclusions                                                       | Inclusions      
1:: [12/07/06 08:07:22.078] X::------------------------------------------------------------------------------------
1:: [12/07/06 08:07:22.078] X::path BEGINSWITH "."                                              |                 
1:: [12/07/06 08:07:22.078] X::name BEGINSWITH[c] ".DocumentRevisions"                          |                 
1:: [12/07/06 08:07:22.078] X::name BEGINSWITH[c] ".afpDeleted"                                 |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] "Library/Logs/FileSyncAgent.log"                    |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] "Documents/Microsoft User Data"                     |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] ".dropbox"                                          |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] "Library/Logs/MirrorAgent.log"                      |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] "Library/Preferences/com.apple.homeSync.plist"      |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] "NAVMac800QSFile"                                   |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] ".FileSync"                                         |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] "Library/Preferences/com.apple.FileSyncUI.plist"    |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] "Library/Mirrors"                                   |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] "Library/FileSync"                                  |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] "Library/Preferences/com.apple.mcxMenuExtras.plist" |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] "Dropbox"                                           |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] ".Spotlight-V100"                                   |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] ".account"                                          |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] ".TemporaryItems"                                   |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] ".fseventsd"                                        |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] ".mcxlc"                                            |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] "Library"                                           |                 
1:: [12/07/06 08:07:22.078] X::name LIKE[c] ".com.apple.timemachine.supported"                  |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] ".Cleanup At Startup"                               |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] "Parallels"                                         |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] "Applications (Parallels)"                          |                 
1:: [12/07/06 08:07:22.078] X::path LIKE[c] ".SymAVQSFile"                                      |                 
1:: [12/07/06 08:07:22.078] X::---not changed---                                                | ---not changed---
1:: [12/07/06 08:07:22.078] X::------------------------------------------------------------------------------------
1:: [12/07/06 08:07:22.078] [0x7f8feaa03110/<SStore_FS:0x7f8feaa12680>] -[SStore_FS prepareForSync]: HomeSync_Mirror_Peer-eu96pQJN794-francis.hampden: self.wasPrepareForSyncDoneRemotely => YES
1:: [12/07/06 08:07:22.080] Sync history for "HomeSync_Mirror": last sync peer count = 2, all syncs peer count = 2, last sync included this machine: YES
1:: [12/07/06 08:07:22.080] Sync job generation pass 1 of 1 for "HomeSync_Mirror" took 0.00 seconds
0:: [12/07/06 08:07:22.088] EXCEPTION: [data writeToFile:'/Volumes/HomeDirectories/francis.hampden/.FileSync/HomeSync_Mirror_Peer-eu96pQJN794-francis.hampden.FileSyncHistory' options:0 error:&error] (Cocoa error 512) <-[SStoreFileOperator_FS writeFileData:] (StoreFileOperator-FS.m:1120): "(Error Domain=NSCocoaErrorDomain Code=512 "The file “HomeSync_Mirror_Peer-eu96pQJN794-francis.hampden.FileSyncHistory” couldn’t be saved in the folder “.FileSync”." UserInfo=0x7f8fe9635260 {NSFilePath=/Volumes/HomeDirectories/francis.hampden/.FileSync/HomeSync_Mirror_Peer-eu96pQJN794-francis.hampden.FileSyncHistory, NSUnderlyingError=0x7f8fe9635490 "The operation couldn’t be completed. Resource temporarily unavailable"})">
0:: [12/07/06 08:07:22.088] USERINFO: {
0:: [12/07/06 08:07:22.088]     NSFilePath = "/Volumes/HomeDirectories/francis.hampden/.FileSync/HomeSync_Mirror_Peer-eu96pQJN794-francis.hampden.FileSyncHistory";
0:: [12/07/06 08:07:22.088]     NSUnderlyingError = "Error Domain=NSPOSIXErrorDomain Code=35 \"The operation couldn\U2019t be completed. Resource temporarily unavailable\"";
0:: [12/07/06 08:07:22.088] }
0:: [12/07/06 08:07:22.088] BACKTRACE: {
0:: [12/07/06 08:07:22.088] ? | 0x10d6a1113  
0:: [12/07/06 08:07:22.088] ? | 0x10d6a0f24  
0:: [12/07/06 08:07:22.088] ? | 0x10d69ecdb  
0:: [12/07/06 08:07:22.088] ? | 0x10d69df88  
0:: [12/07/06 08:07:22.088] ? | 0x10d666863  
0:: [12/07/06 08:07:22.088] ? | 0x7fff8d89c72a
0:: [12/07/06 08:07:22.088] ? | 0x7fff8d89c6a2
0:: [12/07/06 08:07:22.088] ? | 0x7fff81bb78bf
0:: [12/07/06 08:07:22.088] ? | 0x7fff81bbab75
0:: [12/07/06 08:07:22.088] }
1:: [12/07/06 08:07:22.670] -[SStore(protected) cleanupTree]: PHD-L-rRaw7zntXN5-francis.hampden: required 0.0 seconds to execute
1:: [12/07/06 08:07:22.793] Store "PHD-L-rRaw7zntXN5-francis.hampden" finished syncing with root version HomeSync_Mirror_Peer-eu96pQJN794-francis.hampden1340988492PHD-L-rRaw7zntXN5-francis.hampden1340988517[]
1:: [12/07/06 08:07:22.794] -[SStore_FS deleteStateTreeTurdFile] (Store-FS.m:476): unlink('/Users/francis.hampden/.FileSync/store.filesyncstatetree.statetree_dirty')
1:: [12/07/06 08:07:23.069] Store "HomeSync_Mirror_Peer-eu96pQJN794-francis.hampden" finished syncing with root version HomeSync_Mirror_Peer-eu96pQJN794-francis.hampden1340988492PHD-L-rRaw7zntXN5-francis.hampden1340988517[]
1:: [12/07/06 08:07:23.070] Database not uploaded because it has no changes.
1:: [12/07/06 08:07:23.070] -[SStore_FS deleteStateTreeTurdFile] (Store-FS.m:476): unlink('/Volumes/HomeDirectories/francis.hampden/.FileSync/Store-FS-PHD-network-home.filesyncstatetree.bz2.statetree_dirty')
1:: [12/07/06 08:07:23.071] -[SStore_FS disconnectFromStore] (Store-FS.m:698): unlink('/Users/francis.hampden/Library/Caches/Cleanup At Startup/FileSyncAgent-1025/.FileSync/Store-FS-PHD-network-home.filesyncstatetree')
0:: [12/07/06 08:07:23.996] Finished sync of "HomeSync_Mirror".
1:: [12/07/06 08:07:23.997] 1-pass sync of "HomeSync_Mirror" took 20.58 seconds
1:: [12/07/06 08:07:24.614] Scheduling next sync of "HomeSync_Mirror" at 2012-07-06 07:27:24 +0000

I've looked at other postings and can't find any solutions that work for me. I have delted .FileSync on both client and server. I've even deleted the PHD and recreated it (which did clear up some anomolies to do with with files and folder ought not to have been synced).

Any ideas?

Many thanks,

Francis.

Mac Pro, Mac OS X (10.7.4), Server

Posted on Jul 6, 2012 12:36 AM

I have this question too

Q: PHD will not sync on log out

All replies

Helpful answers

by Drizzt,

Drizzt Nov 22, 2013 5:42 AM in response to Frando
Level 1 (19 points)

Servers Enterprise

Nov 22, 2013 5:42 AM in response to Frando

Have you resolved this issue? If yes, how?

I'm having the same problem on 10.9
Reply options

Link to this post

by infinite vortex,

infinite vortex Nov 24, 2013 5:55 AM in response to Drizzt
Level 7 (21,405 points)

Nov 24, 2013 5:55 AM in response to Drizzt

You can try resolving it by trashing the…

~/.FileSync

… directory for affected accounts (on both the local system and the server sides). This will trigger a full account scan for differences on the next sync but may hopefully fix it.
Reply options

Link to this post

by Drizzt,

Drizzt Nov 24, 2013 7:32 AM in response to infinite vortex
Level 1 (19 points)

Servers Enterprise

Nov 24, 2013 7:32 AM in response to infinite vortex

Didn't work for me
Reply options

Link to this post

Q: PHD will not sync on log out

All replies Helpful answers

by Drizzt,

by infinite vortex,

by Drizzt,

All replies

Helpful answers