Syncing stuck at 97%

Support
1

Hello,

I have syncing stuck at 97%. The reported error is cryptic: “Generic error”. If I go to server logs, and turn on “model” like was requested in some other threads, I see this for every file in that list:

2023-03-26 19:21:08 receiveonly/abcde-ghijk@0xc0001c5400 parent not missing file.jpg
2023-03-26 19:21:08 receiveonly/abcde-ghijk@0xc0001c5400 need file file.jpg; copy 17, reused 0
2023-03-26 19:21:08 progress emitter: registering abcde-ghijk file.jpg
2023-03-26 19:21:08 weak hasher open /media/xxx/file.jpg: no such file or directory
2023-03-26 19:21:08 sharedPullerState abcde-ghijk file.jpg pullNeeded start -> 1
2023-03-26 19:21:08 sharedPullerState abcde-ghijk file.jpg pullNeeded start -> 2
2023-03-26 19:21:08 sharedPullerState abcde-ghijk file.jpg pullNeeded start -> 3
2023-03-26 19:21:08 model@0xc0002b8340 REQ(out): EXGKGUR-YF24ZXC-RIKH3GX-EBLW7UB-7BN5VLZ-CBOHKLM-DPORM75-E6M4TQV: "abcde-ghijk" / "file.jpg" b=6 o=786432 s=131072 h=eeec81bc263e5121de71e82ed1694c400e26a2d343c3e72438098d6e4007cc88 wh=e54e8358 ft=false
2023-03-26 19:21:08 sharedPullerState abcde-ghijk file.jpg pullNeeded start -> 4
2023-03-26 19:21:08 model@0xc0002b8340 REQ(out): EXGKGUR-YF24ZXC-RIKH3GX-EBLW7UB-7BN5VLZ-CBOHKLM-DPORM75-E6M4TQV: "abcde-ghijk" / "file.jpg" b=7 o=917504 s=131072 h=a515e4c8dd768d7dea77a36f06263b3207e729cf686cdd4e89ccd6418fc40074 wh=439fbcf8 ft=false
2023-03-26 19:21:08 sharedPullerState abcde-ghijk file.jpg pullNeeded start -> 5
2023-03-26 19:21:08 model@0xc0002b8340 REQ(out): EXGKGUR-YF24ZXC-RIKH3GX-EBLW7UB-7BN5VLZ-CBOHKLM-DPORM75-E6M4TQV: "abcde-ghijk" / "file.jpg" b=10 o=1310720 s=131072 h=ebf53aa85d0cd22480c969e011274769948fd10a3d68127f5c78cb0febac8e5b wh=c5aaa56 ft=false
2023-03-26 19:21:08 model@0xc0002b8340 REQ(out): EXGKGUR-YF24ZXC-RIKH3GX-EBLW7UB-7BN5VLZ-CBOHKLM-DPORM75-E6M4TQV: "abcde-ghijk" / "file.jpg" b=9 o=1179648 s=131072 h=d9d0af6cb1599a826c7ec9f876e85714661b84404f7e17be94d7f2a56ecbbd90 wh=c1e8a965 ft=false
2023-03-26 19:21:08 sharedPullerState abcde-ghijk file.jpg pullNeeded start -> 6
2023-03-26 19:21:08 model@0xc0002b8340 REQ(out): EXGKGUR-YF24ZXC-RIKH3GX-EBLW7UB-7BN5VLZ-CBOHKLM-DPORM75-E6M4TQV: "abcde-ghijk" / "file.jpg" b=11 o=1441792 s=131072 h=56ca68bef80ef2a7a9a2c3f36751154e33cd21ea574529473cbf975ff37b078b wh=9fc2ac16 ft=false
2023-03-26 19:21:08 sharedPullerState abcde-ghijk file.jpg pullNeeded start -> 7
2023-03-26 19:21:08 sharedPullerState abcde-ghijk file.jpg pullNeeded start -> 8
2023-03-26 19:21:08 request: abcde-ghijk file.jpg 786432 131072 EXGKGUR returned error: generic error
2023-03-26 19:21:08 model@0xc0002b8340 REQ(out): EXGKGUR-YF24ZXC-RIKH3GX-EBLW7UB-7BN5VLZ-CBOHKLM-DPORM75-E6M4TQV: "abcde-ghijk" / "file.jpg" b=8 o=1048576 s=131072 h=3fdcb9ca62cf0ad700efda3d5fcfca9418b3cde8f8c997b2a62ef508f0f53e73 wh=675ef446 ft=false
2023-03-26 19:21:08 request: abcde-ghijk file.jpg 1310720 131072 EXGKGUR returned error: generic error
2023-03-26 19:21:08 request: abcde-ghijk file.jpg 917504 131072 EXGKGUR returned error: generic error
2023-03-26 19:21:08 request: abcde-ghijk file.jpg 1179648 131072 EXGKGUR returned error: generic error
2023-03-26 19:21:08 model@0xc0002b8340 REQ(out): EXGKGUR-YF24ZXC-RIKH3GX-EBLW7UB-7BN5VLZ-CBOHKLM-DPORM75-E6M4TQV: "abcde-ghijk" / "file.jpg" b=13 o=1703936 s=131072 h=a05a6dad14c04297dafa9a9bea747bd850d60ffefc6329a749f951fde934a9a2 wh=e5c3b5b4 ft=false
2023-03-26 19:21:08 request: abcde-ghijk file.jpg 1441792 131072 EXGKGUR returned error: generic error
2023-03-26 19:21:08 request: abcde-ghijk file.jpg 1048576 131072 EXGKGUR returned error: generic error
2023-03-26 19:23:10 Puller (folder "xxx image sync" (abcde-ghijk), item "file.jpg"): syncing: finishing: pull: generic error

Eventually it ends with this:

2023-03-26 19:28:48 "xxx image sync" (abcde-ghijk): Failed to sync 545 items
2023-03-26 19:28:48 Folder "xxx image sync" (abcde-ghijk) isn't making sync progress - retrying in 3m17s.
2023-03-26 19:28:48 progress emitter: bytes completed for abcde-ghijk: 0
2023-03-26 19:28:48 model@0xc0002b8340 Completion(EXGKGUR-YF24ZXC-RIKH3GX-EBLW7UB-7BN5VLZ-CBOHKLM-DPORM75-E6M4TQV, "abcde-ghijk"): map[completion:100 globalBytes:461400279850 globalItems:53027 needBytes:0 needDeletes:0 needItems:0 remoteState:valid sequence:138548]

Any ideas what might be happening?

2

“Generic error” from the other side typically means the file doesn’t match expected checksums – which should correct automatically when detecting it like this… So I would start by 1) capturing the same debug output from the other side, to see if there’s something enlightening that we can fix, 2) touching that file to force a rescan, which should resolve the issue…

3
  1. capturing the same debug output from the other side,

The other side (the client) does not have any errors. The only output is just this “Completion”:

2023-03-26 23:24:37 model@0xc000105520 Completion(...): map[completion:97.1828967283884 globalBytes:461404721296 globalItems:53028 needBytes:12998247499 needDeletes:0 needItems:546 remoteState:valid sequence:157450]
  1. touching that file to force a rescan, which should resolve the issue…

It doesn’t work for new files either. In this case I just added another file, and I’m now at 545+1 items

4

Fascinating, I have no idea what would cause that. It’s quite surprising that there are no errors in the debug logs on the sending side.

1 Like
5

I get this every now and then where something goes wrong with the index exchange and a device gets stuck either thinking its peers are not up to date (they are) or the peers thinking the new device is not up to date (when it is). Only way to fix it is to reset deltas and run the exchange again.

6

This topic was automatically closed 30 days after the last reply. New replies are no longer allowed.