Sync stopped working (FIXED w/ beta 1.18.6.2348)

rmccullough · January 31, 2020, 4:34pm

Server Version#: 1.18.4.2171 (FreeNAS/FreeBSD)
Player Version#: 6.7.1 (iOS)

Not sure if it is safe to attach logs here.

I noticed about 3 or 4 weeks ago that the Sync functionality stopped working to my iOS tablet. My cousin who also uses my Plex to download content to watch off her tablet also said it stopped working for her recently.

I have a copy of the player logs and server logs downloaded, just not sure if there is private information in the logs or if I can safely post them here.

Is this a known issue? I am reluctant to delete the app from my tablet and reload it. I am worried it will not work and then I will be left with a tablet that has no content on it.

Recommendations? What are my next investigation steps?

rmccullough · January 31, 2020, 4:35pm

I forgot to add, when I request to download a video item, it will transcode it and get to 50% in the download manager. However, it fails to start downloading it and the status of the item simply says “Waiting”.

rmccullough · January 31, 2020, 4:41pm

Here is a server log I sanitized:

Jan 31, 2020 09:37:46.337 [0x80c3b4c00] DEBUG - Auth: authenticated user 1 as [REMOVED_USER]
Jan 31, 2020 09:37:46.340 [0x80c47c800] DEBUG - Request: [[REMOVED_IP]:52224 (WAN)] GET /sync/CD41B0D8-4CBA-4AC8-9F28-23CD29A8AF3C/status (5 live) TLS GZIP Signed-in Token ([REMOVED_USER])
Jan 31, 2020 09:37:46.346 [0x80c3b4c00] DEBUG - Completed: [[REMOVED_IP]:52224] 200 GET /sync/CD41B0D8-4CBA-4AC8-9F28-23CD29A8AF3C/status (5 live) TLS GZIP 8ms 1910 bytes (pipelined: 1)
Jan 31, 2020 09:37:46.509 [0x80c3b4c00] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Jan 31, 2020 09:37:46.510 [0x80c3b4c00] DEBUG - HTTP requesting GET https://plex.tv/api/v2/server/access_tokens?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1&unknownToken=xxxxxxxxxxxxxxxxxxxx
Jan 31, 2020 09:37:47.317 [0x80c3b4c00] DEBUG - HTTP 200 response from GET https://plex.tv/api/v2/server/access_tokens?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1&unknownToken=xxxxxxxxxxxxxxxxxxxx
Jan 31, 2020 09:37:47.318 [0x80c3b4c00] DEBUG - MyPlex: updating with 13 access tokens
Jan 31, 2020 09:37:47.320 [0x80c3b4c00] DEBUG - Auth: authenticating user as guest
Jan 31, 2020 09:37:47.320 [0x80c47a000] DEBUG - Auth: authenticating user as guest
Jan 31, 2020 09:37:47.320 [0x80c3b4c00] DEBUG - Request: [[REMOVED_IP]:52224 (WAN)] GET /:/timeline?state=playing&updatedAt=1555903936 (8 live) TLS GZIP Signed-in Token ()
Jan 31, 2020 09:37:47.320 [0x80c47a000] DEBUG - Request: [[REMOVED_IP]:52225 (WAN)] GET /:/timeline?state=playing&updatedAt=1564973755 (8 live) TLS GZIP Signed-in Token ()
Jan 31, 2020 09:37:47.321 [0x80c3b4c00] DEBUG - Completed: [[REMOVED_IP]:52224] 401 GET /:/timeline?state=playing&updatedAt=1555903936 (8 live) TLS GZIP 0ms 357 bytes
Jan 31, 2020 09:37:47.321 [0x80c3b4c00] DEBUG - Completed: [[REMOVED_IP]:52225] 401 GET /:/timeline?state=playing&updatedAt=1564973755 (8 live) TLS GZIP 0ms 357 bytes
Jan 31, 2020 09:37:47.321 [0x80c47eb00] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 31, 2020 09:37:47.486 [0x80c47a000] DEBUG - Auth: authenticating user as guest
Jan 31, 2020 09:37:47.486 [0x80c47a000] DEBUG - Request: [[REMOVED_IP]:52227 (WAN)] GET /:/timeline?state=stopped (8 live) TLS GZIP Signed-in Token ()
Jan 31, 2020 09:37:47.486 [0x80c47a000] DEBUG - Completed: [[REMOVED_IP]:52227] 401 GET /:/timeline?state=stopped (8 live) TLS GZIP 0ms 357 bytes
Jan 31, 2020 09:37:47.500 [0x80c47a000] DEBUG - Auth: authenticating user as guest
Jan 31, 2020 09:37:47.500 [0x80c47a000] DEBUG - Request: [[REMOVED_IP]:52226 (WAN)] GET /:/timeline?state=playing&updatedAt=1555901387 (7 live) TLS GZIP Signed-in Token ()
Jan 31, 2020 09:37:47.500 [0x80c47a000] DEBUG - Completed: [[REMOVED_IP]:52226] 401 GET /:/timeline?state=playing&updatedAt=1555901387 (7 live) TLS GZIP 0ms 357 bytes
Jan 31, 2020 09:37:47.698 [0x80c3b4c00] DEBUG - Auth: authenticating user as guest
Jan 31, 2020 09:37:47.698 [0x80c3b4c00] DEBUG - Request: [[REMOVED_IP]:52228 (WAN)] GET /:/timeline?state=stopped (9 live) TLS GZIP Signed-in Token ()
Jan 31, 2020 09:37:47.698 [0x80c3b4c00] DEBUG - Completed: [[REMOVED_IP]:52228] 401 GET /:/timeline?state=stopped (9 live) TLS GZIP 0ms 357 bytes
Jan 31, 2020 09:37:47.808 [0x80c47a000] DEBUG - Auth: authenticating user as guest
Jan 31, 2020 09:37:47.808 [0x80c47a000] DEBUG - Request: [[REMOVED_IP]:52229 (WAN)] GET /:/timeline?state=stopped (8 live) TLS GZIP Signed-in Token ()
Jan 31, 2020 09:37:47.808 [0x80c47a000] DEBUG - Completed: [[REMOVED_IP]:52229] 401 GET /:/timeline?state=stopped (8 live) TLS GZIP 0ms 357 bytes
Jan 31, 2020 09:37:47.898 [0x80c47a000] DEBUG - Auth: authenticating user as guest
Jan 31, 2020 09:37:47.898 [0x80c47a000] DEBUG - Request: [[REMOVED_IP]:52230 (WAN)] GET /:/timeline?state=playing&updatedAt=1555898743 (9 live) TLS GZIP Signed-in Token ()
Jan 31, 2020 09:37:47.898 [0x80c47a000] DEBUG - Completed: [[REMOVED_IP]:52230] 401 GET /:/timeline?state=playing&updatedAt=1555898743 (9 live) TLS GZIP 0ms 357 bytes
Jan 31, 2020 09:37:47.912 [0x80c47a000] DEBUG - Auth: authenticating user as guest
Jan 31, 2020 09:37:47.912 [0x80c47a000] DEBUG - Request: [[REMOVED_IP]:52231 (WAN)] GET /:/timeline?state=stopped (8 live) TLS GZIP Signed-in Token ()
Jan 31, 2020 09:37:47.912 [0x80c3b4c00] DEBUG - Completed: [[REMOVED_IP]:52231] 401 GET /:/timeline?state=stopped (8 live) TLS GZIP 0ms 357 bytes
Jan 31, 2020 09:37:47.951 [0x80c47eb00] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 31, 2020 09:37:47.952 [0x80c47eb00] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 31, 2020 09:37:47.999 [0x80c47a000] DEBUG - Auth: authenticating user as guest
Jan 31, 2020 09:37:48.000 [0x80c47a000] DEBUG - Request: [[REMOVED_IP]:52232 (WAN)] GET /:/timeline?state=stopped (7 live) TLS GZIP Signed-in Token ()
Jan 31, 2020 09:37:48.000 [0x80c3b4c00] DEBUG - Completed: [[REMOVED_IP]:52232] 401 GET /:/timeline?state=stopped (7 live) TLS GZIP 0ms 357 bytes
Jan 31, 2020 09:37:48.114 [0x80c47a000] DEBUG - Auth: authenticating user as guest
Jan 31, 2020 09:37:48.114 [0x80c47a000] DEBUG - Request: [[REMOVED_IP]:52233 (WAN)] GET /:/timeline?state=playing&updatedAt=1555908408 (8 live) TLS GZIP Signed-in Token ()
Jan 31, 2020 09:37:48.114 [0x80c47a000] DEBUG - Completed: [[REMOVED_IP]:52233] 401 GET /:/timeline?state=playing&updatedAt=1555908408 (8 live) TLS GZIP 0ms 357 bytes
Jan 31, 2020 09:37:48.317 [0x80c3b4c00] DEBUG - Auth: authenticating user as guest
Jan 31, 2020 09:37:48.317 [0x80c3b4c00] DEBUG - Request: [[REMOVED_IP]:52234 (WAN)] GET /:/timeline?state=stopped (9 live) TLS GZIP Signed-in Token ()
Jan 31, 2020 09:37:48.317 [0x80c3b4c00] DEBUG - Completed: [[REMOVED_IP]:52234] 401 GET /:/timeline?state=stopped (9 live) TLS GZIP 0ms 357 bytes
Jan 31, 2020 09:37:48.322 [0x80c47a000] DEBUG - Auth: authenticating user as guest
Jan 31, 2020 09:37:48.322 [0x80c47a000] DEBUG - Request: [[REMOVED_IP]:52235 (WAN)] GET /:/timeline?state=playing&updatedAt=1562847544 (9 live) TLS GZIP Signed-in Token ()
Jan 31, 2020 09:37:48.322 [0x80c47a000] DEBUG - Completed: [[REMOVED_IP]:52235] 401 GET /:/timeline?state=playing&updatedAt=1562847544 (9 live) TLS GZIP 0ms 357 bytes
Jan 31, 2020 09:37:48.430 [0x80c47a000] DEBUG - Auth: authenticating user as guest
Jan 31, 2020 09:37:48.430 [0x80c47a000] DEBUG - Request: [[REMOVED_IP]:52236 (WAN)] GET /:/timeline?state=playing&updatedAt=1555898826 (7 live) TLS GZIP Signed-in Token ()
Jan 31, 2020 09:37:48.430 [0x80c47a000] DEBUG - Completed: [[REMOVED_IP]:52236] 401 GET /:/timeline?state=playing&updatedAt=1555898826 (7 live) TLS GZIP 0ms 357 bytes
Jan 31, 2020 09:37:48.534 [0x80c3b4c00] DEBUG - Auth: authenticating user as guest
Jan 31, 2020 09:37:48.534 [0x80c3b4c00] DEBUG - Request: [[REMOVED_IP]:52237 (WAN)] GET /:/timeline?state=stopped (8 live) TLS GZIP Signed-in Token ()
Jan 31, 2020 09:37:48.534 [0x80c3b4c00] DEBUG - Completed: [[REMOVED_IP]:52237] 401 GET /:/timeline?state=stopped (8 live) TLS GZIP 0ms 357 bytes
Jan 31, 2020 09:37:48.571 [0x80c47eb00] DEBUG - HTTP 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Jan 31, 2020 09:37:48.606 [0x80c47a000] DEBUG - Auth: authenticating user as guest
Jan 31, 2020 09:37:48.606 [0x80c47a000] DEBUG - Request: [[REMOVED_IP]:52238 (WAN)] GET /:/timeline?state=playing&updatedAt=1564973773 (8 live) TLS GZIP Signed-in Token ()
Jan 31, 2020 09:37:48.606 [0x80c47a000] DEBUG - Completed: [[REMOVED_IP]:52238] 401 GET /:/timeline?state=playing&updatedAt=1564973773 (8 live) TLS GZIP 0ms 357 bytes
Jan 31, 2020 09:37:48.636 [0x80c47a000] DEBUG - Auth: authenticating user as guest
Jan 31, 2020 09:37:48.636 [0x80c47a000] DEBUG - Request: [[REMOVED_IP]:52239 (WAN)] GET /:/timeline?state=stopped (8 live) TLS GZIP Signed-in Token ()
Jan 31, 2020 09:37:48.636 [0x80c3b4c00] DEBUG - Completed: [[REMOVED_IP]:52239] 401 GET /:/timeline?state=stopped (8 live) TLS GZIP 0ms 357 bytes
Jan 31, 2020 09:37:48.838 [0x80c47a000] DEBUG - Auth: authenticated user 1 as [REMOVED_USER]
Jan 31, 2020 09:37:48.838 [0x80c47d200] DEBUG - Request: [[REMOVED_IP]:52240 (WAN)] GET /sync/CD41B0D8-4CBA-4AC8-9F28-23CD29A8AF3C/package?changestamp=672896&limit=25&onlyDeletes=1 (5 live) TLS GZIP Signed-in Token ([REMOVED_USER])
Jan 31, 2020 09:37:48.840 [0x80c47d200] DEBUG - Sync: packaging delete at 676559 for sync list 33092862, metadata item 48
Jan 31, 2020 09:37:48.840 [0x80c3b4c00] DEBUG - Completed: [[REMOVED_IP]:52240] 200 GET /sync/CD41B0D8-4CBA-4AC8-9F28-23CD29A8AF3C/package?changestamp=672896&limit=25&onlyDeletes=1 (5 live) TLS GZIP 2ms 507 bytes (pipelined: 1)
Jan 31, 2020 09:37:48.962 [0x80c3b4c00] DEBUG - Auth: authenticated user 1 as [REMOVED_USER]
Jan 31, 2020 09:37:48.962 [0x80c47c800] DEBUG - Request: [[REMOVED_IP]:52240 (WAN)] PUT /sync/refreshSynclists (5 live) TLS GZIP Signed-in Token ([REMOVED_USER])
Jan 31, 2020 09:37:48.962 [0x80c47c800] DEBUG - Sync: refreshSyncLists
Jan 31, 2020 09:37:48.962 [0x80c47c800] DEBUG - HTTP requesting GET https://plex.tv/servers/5ba184f7e744d32734ea67ee7394ea369c50aa5f/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Jan 31, 2020 09:37:49.651 [0x80c47c800] DEBUG - HTTP 200 response from GET https://plex.tv/servers/5ba184f7e744d32734ea67ee7394ea369c50aa5f/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Jan 31, 2020 09:37:49.654 [0x80c47c800] DEBUG - Sync: downloaded 2 sync lists, analyzing now...
Jan 31, 2020 09:37:49.654 [0x80c47c800] DEBUG - Sync: sync list 33092862: user 1 ([REMOVED_USER]), device iPad, profile iOS, id CD41B0D8-4CBA-4AC8-9F28-23CD29A8AF3C, sync item(s): 34880106,34880137,34880144,34880149,34880171,34880206,34880228,34880233,34880239,34880248,34880252,34880267,34880281,34880298,34880300,34893833,34893849,35088453,37790126,39387312,40092636,41612300,41612311,41612337,41612340,41612348,42046764,42046767
Jan 31, 2020 09:37:49.654 [0x80c47c800] DEBUG - Sync: sync list 27732537: user 20030382 ([REMOVED_USER2]), device [REMOVED_DEVICE2], profile iOS, id 86F09C15-64F9-4D15-842D-B3DF5B1E3F08, sync item(s): 38412175,38412374,38412382,38419580,38419615,38419630,38462130,38462135,38495432,38570977,39276467
Jan 31, 2020 09:37:49.654 [0x80c47c800] DEBUG - Sync: synchronizing 2 sync lists to generators
Jan 31, 2020 09:37:49.662 [0x80c47c800] DEBUG - Sync: synchronized 2 sync list(s) to 39 generators
Jan 31, 2020 09:37:49.663 [0x80c47c800] INFO - Sync: downloaded 2 sync list(s) with 39 sync items(s): 0 new, 0 updated, 0 deleted
Jan 31, 2020 09:37:49.663 [0x814c43900] DEBUG - BPQ: garbageCollect - sync - forced - 10
Jan 31, 2020 09:37:49.663 [0x814c43900] DEBUG - Sync: starting Sync garbage collection: 10, forced
Jan 31, 2020 09:37:49.663 [0x814c43900] DEBUG - Sync: garbage collecting filesystem
Jan 31, 2020 09:37:49.664 [0x80c47a000] DEBUG - Completed: [[REMOVED_IP]:52240] 200 PUT /sync/refreshSynclists (5 live) TLS GZIP 701ms 195 bytes (pipelined: 2)
Jan 31, 2020 09:37:49.665 [0x814c43900] DEBUG - Sync: garbage collected filesystem
Jan 31, 2020 09:37:49.665 [0x814c43900] DEBUG - Sync: scanning for items to reprocess in sync list 33092862
Jan 31, 2020 09:37:49.666 [0x814c43900] DEBUG - Sync: scanned for items to reprocess in sync list 33092862
Jan 31, 2020 09:37:49.666 [0x814c43900] DEBUG - Sync: scanning for items to reprocess in sync list 27732537
Jan 31, 2020 09:37:49.666 [0x814c43900] DEBUG - Sync: scanned for items to reprocess in sync list 27732537
Jan 31, 2020 09:37:49.666 [0x814c43900] DEBUG - Sync: finished Sync garbage collection: 10
Jan 31, 2020 09:37:49.786 [0x80c3b4c00] DEBUG - Auth: authenticated user 1 as [REMOVED_USER]
Jan 31, 2020 09:37:49.786 [0x80c47c800] DEBUG - Request: [[REMOVED_IP]:52240 (WAN)] PUT /sync/refreshContent (5 live) TLS GZIP Signed-in Token ([REMOVED_USER])
Jan 31, 2020 09:37:49.786 [0x80c47c800] DEBUG - Sync: refreshContent
Jan 31, 2020 09:37:49.786 [0x80c47c800] DEBUG - HTTP requesting GET https://plex.tv/servers/5ba184f7e744d32734ea67ee7394ea369c50aa5f/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Jan 31, 2020 09:37:53.256 [0x80c47c800] DEBUG - HTTP 200 response from GET https://plex.tv/servers/5ba184f7e744d32734ea67ee7394ea369c50aa5f/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Jan 31, 2020 09:37:53.259 [0x80c47c800] DEBUG - Sync: downloaded 2 sync lists, analyzing now...
Jan 31, 2020 09:37:53.259 [0x80c47c800] DEBUG - Sync: sync list 33092862: user 1 ([REMOVED_USER]), device iPad, profile iOS, id CD41B0D8-4CBA-4AC8-9F28-23CD29A8AF3C, sync item(s): 34880106,34880137,34880144,34880149,34880171,34880206,34880228,34880233,34880239,34880248,34880252,34880267,34880281,34880298,34880300,34893833,34893849,35088453,37790126,39387312,40092636,41612300,41612311,41612337,41612340,41612348,42046764,42046767
Jan 31, 2020 09:37:53.259 [0x80c47c800] DEBUG - Sync: sync list 27732537: user 20030382 ([REMOVED_USER2]), device [REMOVED_DEVICE2], profile iOS, id 86F09C15-64F9-4D15-842D-B3DF5B1E3F08, sync item(s): 38412175,38412374,38412382,38419580,38419615,38419630,38462130,38462135,38495432,38570977,39276467
Jan 31, 2020 09:37:53.259 [0x80c47c800] DEBUG - Sync: synchronizing 2 sync lists to generators
Jan 31, 2020 09:37:53.267 [0x80c47c800] DEBUG - Sync: synchronized 2 sync list(s) to 39 generators
Jan 31, 2020 09:37:53.267 [0x80c47c800] INFO - Sync: downloaded 2 sync list(s) with 39 sync items(s): 0 new, 0 updated, 0 deleted
Jan 31, 2020 09:37:53.267 [0x814c43900] DEBUG - BPQ: garbageCollect - sync - forced - 10
Jan 31, 2020 09:37:53.267 [0x814c43900] DEBUG - Sync: starting Sync garbage collection: 10, forced
Jan 31, 2020 09:37:53.267 [0x814c43900] DEBUG - Sync: garbage collecting filesystem
Jan 31, 2020 09:37:53.267 [0x80c47a000] DEBUG - Completed: [[REMOVED_IP]:52240] 200 PUT /sync/refreshContent (5 live) TLS GZIP 3481ms 195 bytes (pipelined: 3)
Jan 31, 2020 09:37:53.269 [0x814c43900] DEBUG - Sync: garbage collected filesystem
Jan 31, 2020 09:37:53.269 [0x814c43900] DEBUG - Sync: scanning for items to reprocess in sync list 33092862
Jan 31, 2020 09:37:53.270 [0x814c43900] DEBUG - Sync: scanned for items to reprocess in sync list 33092862
Jan 31, 2020 09:37:53.270 [0x814c43900] DEBUG - Sync: scanning for items to reprocess in sync list 27732537
Jan 31, 2020 09:37:53.270 [0x814c43900] DEBUG - Sync: scanned for items to reprocess in sync list 27732537
Jan 31, 2020 09:37:53.270 [0x814c43900] DEBUG - Sync: finished Sync garbage collection: 10
Jan 31, 2020 09:37:53.450 [0x80c47a000] DEBUG - Auth: authenticated user 1 as [REMOVED_USER]
Jan 31, 2020 09:37:53.451 [0x80c47c800] DEBUG - Request: [[REMOVED_IP]:52240 (WAN)] GET /sync/CD41B0D8-4CBA-4AC8-9F28-23CD29A8AF3C/status (5 live) TLS GZIP Signed-in Token ([REMOVED_USER])
Jan 31, 2020 09:37:53.457 [0x80c47a000] DEBUG - Completed: [[REMOVED_IP]:52240] 200 GET /sync/CD41B0D8-4CBA-4AC8-9F28-23CD29A8AF3C/status (5 live) TLS GZIP 6ms 1910 bytes (pipelined: 4)
Jan 31, 2020 09:37:53.600 [0x80c3b4c00] DEBUG - Auth: authenticated user 1 as [REMOVED_USER]
Jan 31, 2020 09:37:53.601 [0x80c47c800] DEBUG - Request: [[REMOVED_IP]:52240 (WAN)] GET /sync/CD41B0D8-4CBA-4AC8-9F28-23CD29A8AF3C/package?changestamp=672896&limit=25 (5 live) TLS GZIP Signed-in Token ([REMOVED_USER])
Jan 31, 2020 09:37:53.602 [0x80c47c800] DEBUG - Sync: packaging add at 672917 for sync list 33092862, metadata item 7782: 672917 > 672896
Jan 31, 2020 09:37:53.602 [0x80c47c800] DEBUG - Sync: packaging add at 672917 for sync list 33092862, metadata item 5127: 672917 > 672896
Jan 31, 2020 09:37:53.605 [0x80c47c800] DEBUG - Sync: packaging add at 672917 for sync list 33092862, metadata item 8453: 672917 > 672896
Jan 31, 2020 09:37:53.609 [0x80c47c800] ERROR - Soci Exception handled: Value at position 7 was set using a different type than the one passed to get()
Jan 31, 2020 09:37:53.609 [0x80c3b4c00] DEBUG - Completed: [[REMOVED_IP]:52240] 500 GET /sync/CD41B0D8-4CBA-4AC8-9F28-23CD29A8AF3C/package?changestamp=672896&limit=25 (5 live) TLS GZIP 9ms 405 bytes (pipelined: 5)
Jan 31, 2020 09:37:54.018 [0x80c3b4c00] DEBUG - Auth: authenticated user 1 as [REMOVED_USER]
Jan 31, 2020 09:37:54.019 [0x80c47c800] DEBUG - Request: [[REMOVED_IP]:52240 (WAN)] GET /sync/CD41B0D8-4CBA-4AC8-9F28-23CD29A8AF3C/status (5 live) TLS GZIP Signed-in Token ([REMOVED_USER])
Jan 31, 2020 09:37:54.025 [0x80c47a000] DEBUG - Completed: [[REMOVED_IP]:52240] 200 GET /sync/CD41B0D8-4CBA-4AC8-9F28-23CD29A8AF3C/status (5 live) TLS GZIP 6ms 1910 bytes (pipelined: 6)
Jan 31, 2020 09:37:54.253 [0x80c47a000] DEBUG - Auth: authenticated user 1 as [REMOVED_USER]
Jan 31, 2020 09:37:54.254 [0x80c47c800] DEBUG - Request: [[REMOVED_IP]:52240 (WAN)] PUT /sync/refreshSynclists (5 live) TLS GZIP Signed-in Token ([REMOVED_USER])
Jan 31, 2020 09:37:54.254 [0x80c47c800] DEBUG - Sync: refreshSyncLists
Jan 31, 2020 09:37:54.254 [0x80c47c800] DEBUG - HTTP requesting GET https://plex.tv/servers/5ba184f7e744d32734ea67ee7394ea369c50aa5f/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Jan 31, 2020 09:37:55.002 [0x80c47c800] DEBUG - HTTP 200 response from GET https://plex.tv/servers/5ba184f7e744d32734ea67ee7394ea369c50aa5f/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Jan 31, 2020 09:37:55.005 [0x80c47c800] DEBUG - Sync: downloaded 2 sync lists, analyzing now...
Jan 31, 2020 09:37:55.005 [0x80c47c800] DEBUG - Sync: sync list 33092862: user 1 ([REMOVED_USER]), device iPad, profile iOS, id CD41B0D8-4CBA-4AC8-9F28-23CD29A8AF3C, sync item(s): 34880106,34880137,34880144,34880149,34880171,34880206,34880228,34880233,34880239,34880248,34880252,34880267,34880281,34880298,34880300,34893833,34893849,35088453,37790126,39387312,40092636,41612300,41612311,41612337,41612340,41612348,42046764,42046767
Jan 31, 2020 09:37:55.005 [0x80c47c800] DEBUG - Sync: sync list 27732537: user 20030382 ([REMOVED_USER2]), device [REMOVED_DEVICE2], profile iOS, id 86F09C15-64F9-4D15-842D-B3DF5B1E3F08, sync item(s): 38412175,38412374,38412382,38419580,38419615,38419630,38462130,38462135,38495432,38570977,39276467
Jan 31, 2020 09:37:55.005 [0x80c47c800] DEBUG - Sync: synchronizing 2 sync lists to generators
Jan 31, 2020 09:37:55.013 [0x80c47c800] DEBUG - Sync: synchronized 2 sync list(s) to 39 generators
Jan 31, 2020 09:37:55.013 [0x80c47c800] INFO - Sync: downloaded 2 sync list(s) with 39 sync items(s): 0 new, 0 updated, 0 deleted
Jan 31, 2020 09:37:55.013 [0x814c43900] DEBUG - BPQ: garbageCollect - sync - forced - 10
Jan 31, 2020 09:37:55.014 [0x814c43900] DEBUG - Sync: starting Sync garbage collection: 10, forced
Jan 31, 2020 09:37:55.014 [0x814c43900] DEBUG - Sync: garbage collecting filesystem
Jan 31, 2020 09:37:55.014 [0x80c47a000] DEBUG - Completed: [[REMOVED_IP]:52240] 200 PUT /sync/refreshSynclists (5 live) TLS GZIP 760ms 195 bytes (pipelined: 7)
Jan 31, 2020 09:37:55.015 [0x814c43900] DEBUG - Sync: garbage collected filesystem
Jan 31, 2020 09:37:55.015 [0x814c43900] DEBUG - Sync: scanning for items to reprocess in sync list 33092862
Jan 31, 2020 09:37:55.016 [0x814c43900] DEBUG - Sync: scanned for items to reprocess in sync list 33092862
Jan 31, 2020 09:37:55.016 [0x814c43900] DEBUG - Sync: scanning for items to reprocess in sync list 27732537
Jan 31, 2020 09:37:55.016 [0x814c43900] DEBUG - Sync: scanned for items to reprocess in sync list 27732537
Jan 31, 2020 09:37:55.016 [0x814c43900] DEBUG - Sync: finished Sync garbage collection: 10
Jan 31, 2020 09:37:55.194 [0x80c47a000] DEBUG - Auth: authenticated user 1 as [REMOVED_USER]
Jan 31, 2020 09:37:55.195 [0x80c47c800] DEBUG - Request: [[REMOVED_IP]:52240 (WAN)] PUT /sync/refreshContent (5 live) TLS GZIP Signed-in Token ([REMOVED_USER])
Jan 31, 2020 09:37:55.195 [0x80c47c800] DEBUG - Sync: refreshContent
Jan 31, 2020 09:37:55.195 [0x80c47c800] DEBUG - HTTP requesting GET https://plex.tv/servers/5ba184f7e744d32734ea67ee7394ea369c50aa5f/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Jan 31, 2020 09:37:56.029 [0x80c47c800] DEBUG - HTTP 200 response from GET https://plex.tv/servers/5ba184f7e744d32734ea67ee7394ea369c50aa5f/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx
Jan 31, 2020 09:37:56.032 [0x80c47c800] DEBUG - Sync: downloaded 2 sync lists, analyzing now...
Jan 31, 2020 09:37:56.032 [0x80c47c800] DEBUG - Sync: sync list 33092862: user 1 ([REMOVED_USER]), device iPad, profile iOS, id CD41B0D8-4CBA-4AC8-9F28-23CD29A8AF3C, sync item(s): 34880106,34880137,34880144,34880149,34880171,34880206,34880228,34880233,34880239,34880248,34880252,34880267,34880281,34880298,34880300,34893833,34893849,35088453,37790126,39387312,40092636,41612300,41612311,41612337,41612340,41612348,42046764,42046767
Jan 31, 2020 09:37:56.032 [0x80c47c800] DEBUG - Sync: sync list 27732537: user 20030382 ([REMOVED_USER2]), device [REMOVED_DEVICE2], profile iOS, id 86F09C15-64F9-4D15-842D-B3DF5B1E3F08, sync item(s): 38412175,38412374,38412382,38419580,38419615,38419630,38462130,38462135,38495432,38570977,39276467
Jan 31, 2020 09:37:56.032 [0x80c47c800] DEBUG - Sync: synchronizing 2 sync lists to generators
Jan 31, 2020 09:37:56.040 [0x80c47c800] DEBUG - Sync: synchronized 2 sync list(s) to 39 generators
Jan 31, 2020 09:37:56.040 [0x80c47c800] INFO - Sync: downloaded 2 sync list(s) with 39 sync items(s): 0 new, 0 updated, 0 deleted
Jan 31, 2020 09:37:56.040 [0x814c43900] DEBUG - BPQ: garbageCollect - sync - forced - 10
Jan 31, 2020 09:37:56.040 [0x814c43900] DEBUG - Sync: starting Sync garbage collection: 10, forced
Jan 31, 2020 09:37:56.040 [0x814c43900] DEBUG - Sync: garbage collecting filesystem
Jan 31, 2020 09:37:56.041 [0x80c47a000] DEBUG - Completed: [[REMOVED_IP]:52240] 200 PUT /sync/refreshContent (5 live) TLS GZIP 845ms 195 bytes (pipelined: 8)
Jan 31, 2020 09:37:56.042 [0x814c43900] DEBUG - Sync: garbage collected filesystem
Jan 31, 2020 09:37:56.042 [0x814c43900] DEBUG - Sync: scanning for items to reprocess in sync list 33092862
Jan 31, 2020 09:37:56.043 [0x814c43900] DEBUG - Sync: scanned for items to reprocess in sync list 33092862
Jan 31, 2020 09:37:56.043 [0x814c43900] DEBUG - Sync: scanning for items to reprocess in sync list 27732537
Jan 31, 2020 09:37:56.043 [0x814c43900] DEBUG - Sync: scanned for items to reprocess in sync list 27732537
Jan 31, 2020 09:37:56.043 [0x814c43900] DEBUG - Sync: finished Sync garbage collection: 10
Jan 31, 2020 09:37:56.563 [0x80c3b4c00] DEBUG - Auth: authenticated user 1 as [REMOVED_USER]
Jan 31, 2020 09:37:56.563 [0x80c47c800] DEBUG - Request: [[REMOVED_IP]:52240 (WAN)] GET /sync/CD41B0D8-4CBA-4AC8-9F28-23CD29A8AF3C/status (5 live) TLS GZIP Signed-in Token ([REMOVED_USER])
Jan 31, 2020 09:37:56.570 [0x80c3b4c00] DEBUG - Completed: [[REMOVED_IP]:52240] 200 GET /sync/CD41B0D8-4CBA-4AC8-9F28-23CD29A8AF3C/status (5 live) TLS GZIP 6ms 1910 bytes (pipelined: 9)

rmccullough · January 31, 2020, 4:42pm

I also have the zip files from the server and client if they are safe to post.

tonyp1983 · February 2, 2020, 11:09pm

You are not alone. There are a number of us reporting the same issue, another user spotted that it seemed to happen after server version 1.18.3.2129, having downgraded back to that, everything is working again for me. Hopefully someone at Plex will see all these posts/logs and be able to track down the issue.

My post (including logs): Plex sync stuck at 50% (waiting) on all devices

Post identifying last working version: Sync stuck on "Waiting to download"

rmccullough · February 3, 2020, 4:24pm

Is there a reasonably easy method to downgrade to 1.18.3.2129? And then is there a bug record I can subscribe to so I know when I can upgrade?

pl_5309 · February 3, 2020, 4:33pm

Or load the latest Beta: 1.18.6.2348

(FreeBSD) Fixes for database problems when syncing

tonyp1983 · February 3, 2020, 4:36pm

This… just come out today and can confirm it fixes the issue for me! Happy days!

rmccullough · February 3, 2020, 5:18pm

Thanks all. I stopped the Plex service, extracted the latest beta (1.18.6.2348) over it, and started the service again. I then opened the Plex app on my iPad and my “Waiting” items started to go to Queue and Download. Thanks!

surroundedbytrees · February 10, 2020, 7:24pm

I just bought a Plex pass sub for Sync. Friends going on a trip so figured this was an easy way for them to load up the iPad. Sync isn’t working on my WD PR4100 PMS Version 1.18.6.2368.

Time to head to the App Store and get a refund.

TAC57 · February 12, 2020, 2:32pm

I just donwloaded PMS version 1.18.6.2368 and now syncing to my phone works again!

I can’t seem to delete synced items from my phone but I can deleted them from web Status > Sync menu. If I try to delete a synced item from my phone Plex resets and I have to relog in by entering my user PIN.

I did DL the latest Plex app to my Android phone, 7.27.1.15203.

pl_5309 · February 12, 2020, 2:57pm

If you have an SD card in addition to internal memory you can change the storage location and back, it will delete all synced files.

RuneMason1 · February 14, 2020, 4:52am

Same problem. On beta 2368. Sync confirmed working from friend’s remote server.

system · May 14, 2020, 4:52am

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

Topic		Replies	Views
PMS Sync failing on FreeBSD & nVidia, possibly related to a soci expection Desktops & Laptops server-truenas	44	1554	May 27, 2020
Sync stuck on "Waiting to download" Mobile Devices android-mobile-legacy , downloads	387	32392	March 16, 2021
Plex Pass Sync - Only syncing to 50%/Waiting for Server - Mac/IPhone General Discussions	24	362	January 24, 2020
Android Sync Problems General Discussions	64	473	January 7, 2020
Slow Syncing after migration Desktops & Laptops server-windows , ios-legacy	33	653	April 21, 2019

Sync stopped working (FIXED w/ beta 1.18.6.2348)

Related topics