Joplin (macOS) stops synchronising with filesystem target

[AlbertBaubleDeem]

Environment

Joplin version: Joplin 2.8.8 (prod, darwin) Revision: c2a6a13 Platform: macOS OS specifics: Big Sur 11.6.5

Steps to reproduce

1. In 'Synchronisation' set 'Synchronisation target' to 'File system'.

2. Set 'Directory to synchronise with' to /Users/<user>/Joplin.

3. Add /Users/<user>/Joplin to Syncthing to sync it with other Joplin clients (Android).

4. After a random time period, Joplin stops showing changes to notes and adding new notes after both automatic and manual synchronisation.

5. The current fix is 'Synchronisation', open 'Show Advanced Settings' and choose 'Delete local data and re-download from sync target'.

Describe what you expected to happen

Joplin synchronises its internal database with /Users/<user>/Joplin and any changes recorded in/Users/<user>/Joplin show in notes.

Logfile

Log file from the last occurence

[tomasz1986]

Just a guess, but it's likely a duplicate of https://github.com/laurent22/joplin/issues/6517.

[personalizedrefrigerator]

Interestingly, the log (sometimes) states that it found new notes:

2022-07-25 10:03:32: Synchronizer: 
                     "BasicDelta: Report: {"timestamp":1658319491033,"older":2405,"newer":3,"equal":1}"
2022-07-25 10:03:32: Synchronizer: "Sync: fetchingTotal: Fetching delta items from sync target"
2022-07-25 10:03:32: Synchronizer: "Sync: fetchingProcessed: Processing fetched item"
2022-07-25 10:03:32: Synchronizer: "Sync: fetchingProcessed: Processing fetched item"
2022-07-25 10:03:32: Synchronizer: "Sync: fetchingProcessed: Processing fetched item"
2022-07-25 10:03:32: Synchronizer: "Sync: finished: Synchronisation finished [1658736211657]"
2022-07-25 10:03:32: Synchronizer: "Operations completed: "
2022-07-25 10:03:32: Synchronizer: "fetchingTotal: 3"
2022-07-25 10:03:32: Synchronizer: "fetchingProcessed: 3"
2022-07-25 10:03:32: Synchronizer: "Total folders: 89"
2022-07-25 10:03:32: Synchronizer: "Total notes: 396"
2022-07-25 10:03:32: Synchronizer: "Total resources: 576"
2022-07-25 10:03:33: "DecryptionWorker: cannot start because no master key is currently loaded."
2022-07-25 10:07:44: "Garbage collecting alarms..."
2022-07-25 10:07:44: RevisionService: "maintenance: Starting..."
2022-07-25 10:07:44: RevisionService: "maintenance: Service is enabled"
2022-07-25 10:07:44: RevisionService: "collectRevisions: Created revisions for 1 notes"
2022-07-25 10:07:44: RevisionService: "maintenance: Done in 139ms"
2022-07-25 10:08:32: "Running background sync on timer..."
2022-07-25 10:08:32: "Preparing scheduled sync"
2022-07-25 10:08:32: "Starting scheduled sync"
2022-07-25 10:08:32: Synchronizer: "Sync: starting: Starting synchronisation to target 2... supportsAccurateTimestamp = false; supportsMultiPut = false [1658736512314]"
2022-07-25 10:08:32: Synchronizer: "Indexing resources..."
2022-07-25 10:08:32: "ResourceService::indexNoteResources: Start"
2022-07-25 10:08:32: "ResourceService::indexNoteResources: Completed"
2022-07-25 10:08:32: Synchronizer: "Sync target remote info:", "{"version_":3,"masterKeys_":[],"e2ee_":{"value":false,"updatedTime":0},"activeMasterKeyId_":{"value":"","updatedTime":0},"ppk_":{"value":null,"updatedTime":0}}"
2022-07-25 10:08:32: Synchronizer: "Sync target is already setup - checking it..."
2022-07-25 10:08:32: Synchronizer: "Sync target local info:", "{"version_":3,"masterKeys_":[],"e2ee_":{"value":false,"updatedTime":0},"activeMasterKeyId_":{"value":"","updatedTime":0},"ppk_":{"value":null,"updatedTime":0}}"
2022-07-25 10:08:32: Synchronizer:
                         "Sync: createRemote: remote does not exist, and local is new and has never been synced: Revision: (Local 1c2db1ff64ff46089f0edda3f3de5d99)"
2022-07-25 10:08:32: Synchronizer: "TaskQueue.stop: syncDownload: waiting for tasks to complete: 0"
2022-07-25 10:08:32: Synchronizer: "TaskQueue.stop: syncDownload: Done, waited for 0"
2022-07-25 10:08:32: Synchronizer: "BasicDelta:
                               Report: {"timestamp":1658735911607,"older":2408,"newer":1,"equal":1}"
2022-07-25 10:08:32: Synchronizer: "Sync: fetchingTotal: Fetching delta items from sync target"
2022-07-25 10:08:32: Synchronizer: "Sync: fetchingProcessed: Processing fetched item"
2022-07-25 10:08:32: Synchronizer: "Sync: finished: Synchronisation finished [1658736512314]"
2022-07-25 10:08:32: Synchronizer: "Operations completed: "
2022-07-25 10:08:32: Synchronizer: "createRemote: 1"
2022-07-25 10:08:32: Synchronizer: "fetchingTotal: 1"
2022-07-25 10:08:32: Synchronizer: "fetchingProcessed: 1"
2022-07-25 10:08:32: Synchronizer: "Total folders: 89"
2022-07-25 10:08:32: Synchronizer: "Total notes: 396"
2022-07-25 10:08:32: Synchronizer: "Total resources: 576"

There are also several lines that suggest new notes were identified successfully. For example,

022-07-25 10:08:32: Synchronizer: "Sync: createRemote: remote does not exist, and local is new and has never been synced: Revision: (Local 1c2db1ff64ff46089f0edda3f3de5d99)"

Does this log include a period in which the synchronizer was working?

[AlbertBaubleDeem]

Interestingly, the log (sometimes) states that it found new notes:

2022-07-25 10:03:32: Synchronizer: 
                     "BasicDelta: Report: {"timestamp":1658319491033,"older":2405,"newer":3,"equal":1}"
2022-07-25 10:03:32: Synchronizer: "Sync: fetchingTotal: Fetching delta items from sync target"
2022-07-25 10:03:32: Synchronizer: "Sync: fetchingProcessed: Processing fetched item"
2022-07-25 10:03:32: Synchronizer: "Sync: fetchingProcessed: Processing fetched item"
2022-07-25 10:03:32: Synchronizer: "Sync: fetchingProcessed: Processing fetched item"
2022-07-25 10:03:32: Synchronizer: "Sync: finished: Synchronisation finished [1658736211657]"
2022-07-25 10:03:32: Synchronizer: "Operations completed: "
2022-07-25 10:03:32: Synchronizer: "fetchingTotal: 3"
2022-07-25 10:03:32: Synchronizer: "fetchingProcessed: 3"
2022-07-25 10:03:32: Synchronizer: "Total folders: 89"
2022-07-25 10:03:32: Synchronizer: "Total notes: 396"
2022-07-25 10:03:32: Synchronizer: "Total resources: 576"
2022-07-25 10:03:33: "DecryptionWorker: cannot start because no master key is currently loaded."
2022-07-25 10:07:44: "Garbage collecting alarms..."
2022-07-25 10:07:44: RevisionService: "maintenance: Starting..."
2022-07-25 10:07:44: RevisionService: "maintenance: Service is enabled"
2022-07-25 10:07:44: RevisionService: "collectRevisions: Created revisions for 1 notes"
2022-07-25 10:07:44: RevisionService: "maintenance: Done in 139ms"
2022-07-25 10:08:32: "Running background sync on timer..."
2022-07-25 10:08:32: "Preparing scheduled sync"
2022-07-25 10:08:32: "Starting scheduled sync"
2022-07-25 10:08:32: Synchronizer: "Sync: starting: Starting synchronisation to target 2... supportsAccurateTimestamp = false; supportsMultiPut = false [1658736512314]"
2022-07-25 10:08:32: Synchronizer: "Indexing resources..."
2022-07-25 10:08:32: "ResourceService::indexNoteResources: Start"
2022-07-25 10:08:32: "ResourceService::indexNoteResources: Completed"
2022-07-25 10:08:32: Synchronizer: "Sync target remote info:", "{"version_":3,"masterKeys_":[],"e2ee_":{"value":false,"updatedTime":0},"activeMasterKeyId_":{"value":"","updatedTime":0},"ppk_":{"value":null,"updatedTime":0}}"
2022-07-25 10:08:32: Synchronizer: "Sync target is already setup - checking it..."
2022-07-25 10:08:32: Synchronizer: "Sync target local info:", "{"version_":3,"masterKeys_":[],"e2ee_":{"value":false,"updatedTime":0},"activeMasterKeyId_":{"value":"","updatedTime":0},"ppk_":{"value":null,"updatedTime":0}}"
2022-07-25 10:08:32: Synchronizer:
                         "Sync: createRemote: remote does not exist, and local is new and has never been synced: Revision: (Local 1c2db1ff64ff46089f0edda3f3de5d99)"
2022-07-25 10:08:32: Synchronizer: "TaskQueue.stop: syncDownload: waiting for tasks to complete: 0"
2022-07-25 10:08:32: Synchronizer: "TaskQueue.stop: syncDownload: Done, waited for 0"
2022-07-25 10:08:32: Synchronizer: "BasicDelta:
                               Report: {"timestamp":1658735911607,"older":2408,"newer":1,"equal":1}"
2022-07-25 10:08:32: Synchronizer: "Sync: fetchingTotal: Fetching delta items from sync target"
2022-07-25 10:08:32: Synchronizer: "Sync: fetchingProcessed: Processing fetched item"
2022-07-25 10:08:32: Synchronizer: "Sync: finished: Synchronisation finished [1658736512314]"
2022-07-25 10:08:32: Synchronizer: "Operations completed: "
2022-07-25 10:08:32: Synchronizer: "createRemote: 1"
2022-07-25 10:08:32: Synchronizer: "fetchingTotal: 1"
2022-07-25 10:08:32: Synchronizer: "fetchingProcessed: 1"
2022-07-25 10:08:32: Synchronizer: "Total folders: 89"
2022-07-25 10:08:32: Synchronizer: "Total notes: 396"
2022-07-25 10:08:32: Synchronizer: "Total resources: 576"

There are also several lines that suggest new notes were identified successfully. For example,

022-07-25 10:08:32: Synchronizer: "Sync: createRemote: remote does not exist, and local is new and has never been synced: Revision: (Local 1c2db1ff64ff46089f0edda3f3de5d99)"

Does this log include a period in which the synchronizer was working?

To the best of my knowledge the log covers the period when the synchronizer wasn't working. I turned the computer on that day and found out my android notes weren't syncing. Then proceeded to debug.

[github-actions[bot]]

Hey there, it looks like there has been no activity on this issue recently. Has the issue been fixed, or does it still require the community's attention? If you require support or are requesting an enhancement or feature then please create a topic on the Joplin forum. This issue may be closed if no further activity occurs. You may comment on the issue and I will leave it open. Thank you for your contributions.

[github-actions[bot]]

Closing this issue after a prolonged period of inactivity. If this issue is still present in the latest release, feel free to create a new issue with up-to-date information.