Runs indefinitely w/out syncing; no new logs created. #1196

akwala · 2022-06-02T22:50:34Z

akwala
Jun 2, 2022

Floccus Addon 4.15.0
Firefox 100.0.2
Nextcloud Bookmarks 10.3.1
Nextcloud 24.0.1

For some time now, when I launch syncing in Floccus, the addon shows "Syncing" indefinitely but doesn't seem to be doing anything.

Also, clicking "Cancel sync" does not stop the syncing.

I've disabled Auto-sync and only manually launch a Push (upload local changes) from a single browser profile. Below are screenshots showing my settings. I've set up a single account/folder to sync.

I can no longer see logs of recent actions because the buttons to download logs always show the following log from 5/23/22:

2022-05-23T02:31:56.594Z Starting sync process for account user@server
2022-05-23T02:31:58.911Z Resource is still locked, trying again in 1s
2022-05-23T02:32:00.297Z Resource is still locked, trying again in 1.25s
2022-05-23T02:32:02.021Z Resource is still locked, trying again in 1.5625s
2022-05-23T02:32:04.051Z Resource is still locked, trying again in 1.953125s
2022-05-23T02:32:06.328Z Resource is still locked, trying again in 2.44140625s
2022-05-23T02:32:09.142Z Resource is still locked, trying again in 3.0517578125s
2022-05-23T02:32:12.625Z Resource is still locked, trying again in 3.814697265625s
2022-05-23T02:32:16.761Z Resource is still locked, trying again in 4.76837158203125s
2022-05-23T02:32:21.950Z Resource is still locked, trying again in 5.9604644775390625s
2022-05-23T02:32:28.501Z Resource is still locked, trying again in 7.450580596923828s
2022-05-23T02:32:36.510Z Resource is still locked, trying again in 9.313225746154785s
2022-05-23T02:32:46.362Z Resource is still locked, trying again in 11.641532182693481s
2022-05-23T02:32:58.594Z Resource is still locked, trying again in 14.551915228366852s
2022-05-23T02:33:13.817Z Resource is still locked, trying again in 18.189894035458565s
2022-05-23T02:33:32.524Z Resource is still locked, trying again in 22.737367544323206s
2022-05-23T02:33:55.743Z Resource is still locked, trying again in 28.421709430404007s
2022-05-23T02:34:24.849Z Resource is still locked, trying again in 35.52713678800501s
2022-05-23T02:35:01.147Z Resource is still locked, trying again in 44.40892098500626s
2022-05-23T02:35:46.091Z Resource is still locked, trying again in 55.51115123125783s
2022-05-23T02:36:42.237Z Resource is still locked, trying again in 69.38893903907228s
2022-05-23T02:37:52.787Z Resource is still locked, trying again in 86.73617379884035s
2022-05-23T02:39:20.136Z Resource is still locked, trying again in 108.42021724855044s
2022-05-23T02:41:09.395Z Resource is still locked, trying again in 135.52527156068805s
2022-05-23T02:43:26.073Z Resource is still locked, trying again in 169.40658945086005s
2022-05-23T02:46:16.102Z Resource is still locked, trying again in 211.75823681357508s
2022-05-23T02:46:53.899Z Syncing failed with E027: Sync process was interrupted

Nextcloud does not show any related errors. I figured it would help to troubleshoot before reporting these as issues.

Answered by marcelklehr

Jun 26, 2022

I've had time to try and reproduce this recently and could indeed replicate the bug. v4.16.0 should have the fix. Let me know if that works for you ♥️

View full answer

pinpontitit · 2022-06-02T23:47:20Z

pinpontitit
Jun 2, 2022

You should open an "issue" instead of a "discussion". I think it would be easier to follow the updates, etc.. 😃
I figured it would help to troubleshoot before reporting these as issues.
Ah ok, for not letting you without an anwser personally I don't know what causes this 🤔

1 reply

marcelklehr Jun 3, 2022
Maintainer

Both are fine, imo.

marcelklehr · 2022-06-03T12:43:48Z

marcelklehr
Jun 3, 2022
Maintainer

Does it help to disable and re-enable floccus? Usually, it should work again after 15min or so, if the account is locked on the server.

See the faq on the topic:

Floccus employs a locking mechanism to avoid manipulating data on the server from two browsers at the same time. Sometimes, when closing a browser while floccus is syncing,
this lock is not removed. On the next browser start floccus will then wait for the lock to be released and appear to be syncing for a long time without doing anything.
After half an hour of trying floccus will override the lock and start its own sync run. So, after half an hour, things should be fine without you having to intervene.

0 replies

akwala · 2022-06-03T20:36:57Z

akwala
Jun 3, 2022
Author

Does it help to disable and re-enable floccus? Usually, it should work again after 15min or so, if the account is locked on the server.

I did the following:

disabled Floccus addon;
shut down Firefox, then launched it;
enabled Floccus addon;
entered passphrase to unlock creds;
clicked Push Once button*; and
left the browser running for over an hour.

*Immediately after the sync started, Firefox complained that a running script was slowing it down. I had done nothing else in the browser. This didn't seem to affect my browsing during the hour.

No change in behavior:

Floccus was still syncing an hour later, and the number of bookmarks remained unchanged on Nextcloud.
'Cancel sync' button did not stop the syncing.
'Full logs' button showed the same log from 5/23/22.

Before posting here, I even removed the account and added it anew. This made no difference.

0 replies

marcelklehr · 2022-06-09T13:38:45Z

marcelklehr
Jun 9, 2022
Maintainer

I assume you know curl, based on your github profile. Using curl you can unlock nextcloud bookmarks manually:

curl -v -X DELETE --user "username:password" https://nextcloud/index.php/apps/bookmarks/public/rest/v2/lock

10 replies

marcelklehr Jun 14, 2022
Maintainer

Fetching single bookmark

mmh. Can you observe the Network requests in the addon inspector and check what happens when Fetching single bookmark is logged?

akwala Jun 14, 2022
Author

I deleted the root bookmark folder on the server and created it anew. Push Once in the Floccus addon then ran and completed as expected... 🎉 – not a big deal in my case since I only do uploads to the server.

Before doing that, I did do sync again and looked at the network log. This time there were 5 instances of "Fetching single bookmark" after the CREATEFOLDER actions – Firefox updated recently to 101.0.1. The last 2 corresponding requests were a GET followed by a PUT, both successful (stack traces: floccus_st-GET.txt, floccus_st-PUT.txt). After that, the only requests were POSTs of the lock:

16:42:03.212 2022-06-14T20:42:03.212Z (nextcloud-folders)CREATEFOLDER { folder:
     + #undefined[semanticWeb] parentId: 1375, hash: undefined
    } background-script.js:2:14224
16:42:03.213 2022-06-14T20:42:03.213Z (nextcloud-folders)CREATEFOLDER { folder:
     + #undefined[Jitsi] parentId: 1379, hash: undefined
    } background-script.js:2:14224
16:42:03.214 2022-06-14T20:42:03.214Z (nextcloud-folders)CREATEFOLDER { folder:
     + #undefined[Ubuntu and Free Software links] parentId: 1350, hash: undefined
    } background-script.js:2:14224
16:42:03.215 2022-06-14T20:42:03.215Z (nextcloud-folders)CREATEFOLDER { folder:
     + #undefined[KB] parentId: 1351, hash: undefined
    } background-script.js:2:14224
16:42:03.215 2022-06-14T20:42:03.215Z (nextcloud-folders)CREATEFOLDER { folder:
     + #undefined[python pkgs] parentId: 1434, hash: undefined
    } background-script.js:2:14224
16:42:03.216 2022-06-14T20:42:03.216Z (nextcloud-folders)CREATEFOLDER { folder:
     + #undefined[OER] parentId: 1371, hash: undefined
    } background-script.js:2:14224
16:42:03.217 2022-06-14T20:42:03.217Z (nextcloud-folders)CREATEFOLDER { folder:
     + #undefined[python] parentId: 1375, hash: undefined
    } background-script.js:2:14224
16:42:03.218 2022-06-14T20:42:03.218Z (nextcloud-folders)CREATEFOLDER { folder:
     + #undefined[pcp] parentId: 1373, hash: undefined
    } background-script.js:2:14224
16:42:03.218 2022-06-14T20:42:03.218Z (nextcloud-folders)CREATEFOLDER { folder:
     + #undefined[mtgoxnft] parentId: 1373, hash: undefined
    } background-script.js:2:14224
16:42:06.587 2022-06-14T20:42:06.587Z Fetching single bookmark background-script.js:2:14224
16:42:06.667 2022-06-14T20:42:06.668Z Fetching single bookmark background-script.js:2:14224
16:42:07.833 2022-06-14T20:42:07.833Z Fetching single bookmark background-script.js:2:14224
16:42:08.663 2022-06-14T20:42:08.664Z Fetching single bookmark background-script.js:2:14224
16:42:10.181 2022-06-14T20:42:10.182Z Fetching single bookmark background-script.js:2:14224
16:42:38.648 XHRPOSThttps://my.nextcloud.server/index.php/apps/bookmarks/public/rest/v2/lock
[HTTP/2 423 Locked 813ms]

16:44:38.646 XHRPOSThttps://my.nextcloud.server/index.php/apps/bookmarks/public/rest/v2/lock
[HTTP/2 423 Locked 513ms]

16:46:38.654 XHRPOSThttps://my.nextcloud.server/index.php/apps/bookmarks/public/rest/v2/lock
[HTTP/2 423 Locked 1064ms]

16:48:38.864 XHRPOSThttps://my.nextcloud.server/index.php/apps/bookmarks/public/rest/v2/lock
[HTTP/2 423 Locked 744ms]

16:50:38.646 XHRPOSThttps://my.nextcloud.server/index.php/apps/bookmarks/public/rest/v2/lock
[HTTP/2 423 Locked 548ms]

16:52:38.649 XHRPOSThttps://my.nextcloud.server/index.php/apps/bookmarks/public/rest/v2/lock
[HTTP/2 423 Locked 1084ms]

16:54:38.645 XHRPOSThttps://my.nextcloud.server/index.php/apps/bookmarks/public/rest/v2/lock
[HTTP/2 423 Locked 574ms]

16:56:38.652 XHRPOSThttps://my.nextcloud.server/index.php/apps/bookmarks/public/rest/v2/lock
[HTTP/2 423 Locked 532ms]

16:58:38.647 XHRPOSThttps://my.nextcloud.server/index.php/apps/bookmarks/public/rest/v2/lock
[HTTP/2 423 Locked 1162ms]

17:00:38.647 XHRPOSThttps://my.nextcloud.server/index.php/apps/bookmarks/public/rest/v2/lock
[HTTP/2 423 Locked 644ms]

17:02:38.663 XHRPOSThttps://my.nextcloud.server/index.php/apps/bookmarks/public/rest/v2/lock
[HTTP/2 423 Locked 662ms]

17:04:38.668 XHRPOSThttps://my.nextcloud.server/index.php/apps/bookmarks/public/rest/v2/lock
[HTTP/2 423 Locked 1100ms]

17:06:38.653 XHRPOSThttps://my.nextcloud.server/index.php/apps/bookmarks/public/rest/v2/lock
[HTTP/2 423 Locked 518ms]

17:08:38.653 XHRPOSThttps://my.nextcloud.server/index.php/apps/bookmarks/public/rest/v2/lock
[HTTP/2 423 Locked 757ms]

akwala Jun 15, 2022
Author

Spoke too soon. After the initial successful Push, the issue has returned 😭

marcelklehr Jun 17, 2022
Maintainer

In the logs there should also be a plan being logged before anything is done. Can you check if there is anything left in the plan that hasn't been executed by the time it goes into the lock loop?

akwala Jun 21, 2022
Author

In the logs there should also be a plan being logged before anything is done. Can you check if there is anything left in the plan that hasn't been executed by the time it goes into the lock loop?

I found the bookmark(s) that were being synced just before the lock loop. They were duplicates of the same bookmark. Only one of the duplicates was intended so I deleted the two I didn't need. Upon rerunning the Push after that, the same thing happened but with a different bookmark that was also a duplicate.

Couldn't look further because I seem to have broken/disconnected some hardware while cleaning out accumulated dust, and am without computer until it gets fixed.

marcelklehr · 2022-06-26T13:36:49Z

marcelklehr
Jun 26, 2022
Maintainer

I've had time to try and reproduce this recently and could indeed replicate the bug. v4.16.0 should have the fix. Let me know if that works for you ♥️

2 replies

akwala Jun 26, 2022
Author

Yay!

Will check when I'm rejoined with a working computer.

akwala Aug 13, 2022
Author

I've had time to try and reproduce this recently and could indeed replicate the bug. v4.16.0 should have the fix. Let me know if that works for you hearts

Yes, it works! Thx!

serious-angel · 2022-10-13T21:05:42Z

serious-angel
Oct 13, 2022

Dear Developers and Community,

Thank you very much for the project.

Apparently, here's a similar issue (related to #972, probably):

2022-10-13T18:59:28.246Z Starting sync process for account [email protected]
2022-10-13T18:59:29.143Z Resource is still locked, trying again in 1s
2022-10-13T18:59:30.324Z Resource is still locked, trying again in 1.25s
2022-10-13T18:59:31.779Z Resource is still locked, trying again in 1.5625s
2022-10-13T18:59:33.529Z Resource is still locked, trying again in 1.953125s
2022-10-13T18:59:35.671Z Resource is still locked, trying again in 2.44140625s
2022-10-13T18:59:38.314Z Resource is still locked, trying again in 3.0517578125s
2022-10-13T18:59:41.659Z Resource is still locked, trying again in 3.814697265625s
2022-10-13T18:59:45.710Z Resource is still locked, trying again in 4.76837158203125s
2022-10-13T18:59:50.677Z Resource is still locked, trying again in 5.9604644775390625s
2022-10-13T18:59:53.014Z Syncing failed with E027: Sync process was interrupted

This is the only one and single device which is being synced at this moment, but 3 devices are added in total;
Clicking on "Cancel sync" button does not stop the action, but it is possible to restart the extension by re-enabling it forcefully in Google Chrome extension settings;
Nextcloud Bookmarks is the storage;
All settings are on default.

On the client side:

Table headers, respectively: #, ClientBeginRequest, GotResponseHeaders, ServerThinkTime, Protocol, RequestMethod, RequestBodySize, RequestSize, ResponseHTTPStatus, ResponseBodySize, ContentType, Host, URL, Caching, Process.

What might it be?

Best and kind regards

0 replies

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

Runs indefinitely w/out syncing; no new logs created. #1196

{{title}}

{{editor}}'s edit

{{editor}}'s edit

Replies: 6 comments 13 replies

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{title}}

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{editor}}'s edit

{{editor}}'s edit

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{title}}

{{editor}}'s edit

{{editor}}'s edit

Select a reply

Runs indefinitely w/out syncing; no new logs created. #1196

akwala Jun 2, 2022

Replies: 6 comments · 13 replies

pinpontitit Jun 2, 2022

marcelklehr Jun 3, 2022 Maintainer

marcelklehr Jun 3, 2022 Maintainer

akwala Jun 3, 2022 Author

marcelklehr Jun 9, 2022 Maintainer

marcelklehr Jun 14, 2022 Maintainer

akwala Jun 14, 2022 Author

akwala Jun 15, 2022 Author

marcelklehr Jun 17, 2022 Maintainer

akwala Jun 21, 2022 Author

marcelklehr Jun 26, 2022 Maintainer

akwala Jun 26, 2022 Author

akwala Aug 13, 2022 Author

serious-angel Oct 13, 2022

akwala
Jun 2, 2022

Replies: 6 comments 13 replies

pinpontitit
Jun 2, 2022

marcelklehr Jun 3, 2022
Maintainer

marcelklehr
Jun 3, 2022
Maintainer

akwala
Jun 3, 2022
Author

marcelklehr
Jun 9, 2022
Maintainer

marcelklehr Jun 14, 2022
Maintainer

akwala Jun 14, 2022
Author

akwala Jun 15, 2022
Author

marcelklehr Jun 17, 2022
Maintainer

akwala Jun 21, 2022
Author

marcelklehr
Jun 26, 2022
Maintainer

akwala Jun 26, 2022
Author

akwala Aug 13, 2022
Author

serious-angel
Oct 13, 2022