Plex takes too long to download metadata or does not at all (Argentina issue)

Maximiliano_Fernandez · April 12, 2018, 12:08am

Hi, long time Plex user here!
I’ve been having issues with metadata download on movies only lately (last month or so), it was taking longer than usual to donwload all info, or it didn’t download posters, backgrounds or cast pictures. Sometimes it didn’t work at all.
So I’ve decided to format my server and start over, but I’m having the same issues. I’ve put only one movie on the library and
tried to fetch its metadata to have a clear log to look at, and I’m seeing this kind of errors:

Apr 11, 2018 20:32:46.319 [0x7fdb3a3fe700] ERROR - Error bringing media local (metadata://art/com.plexapp.agents.imdb_ea83a7b0e4c34c194303166a5fb173d32ef2000f).
Apr 11, 2018 20:33:01.092 [0x7fdb35ffb700] ERROR - Error issuing curl_easy_perform(handle): 28

This is the full path and file names for the movie:
/storage2/movies/The.Avengers.2012/
-The.Avengers.2012.1080p.BluRay.H264.AAC-RARBG.en.srt
-The.Avengers.2012.1080p.BluRay.H264.AAC-RARBG.es.srt
-The.Avengers.2012.1080p.BluRay.H264.AAC-RARBG.mp4

I’m attaching the logs. Can someone help me? I don’t know what else I can do.

sa2000 · April 12, 2018, 1:58am

The logs do show timeouts and at least 20 second delays in getting images back from tmdb

Example of timeout

2018-04-11 20:29:23,178 (7f746e7fc700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/original/cezWGskPY5x7GaglTTRN4Fugfb8.jpg'

2018-04-11 20:29:43,357 (7f746e7fc700) :  CRITICAL (runtime:889) - Exception (most recent call last):

timeout: timed out

2018-04-11 20:29:43,359 (7f746e7fc700) :  DEBUG (runtime:924) - Response: [500] 1599 bytes

The request that timed out was http://image.tmdb.org/t/p/original/cezWGskPY5x7GaglTTRN4Fugfb8.jpg

and

2018-04-11 20:30:20,646 (7f745f7fe700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/original/ywb9dKBMdu3DEiTFpXrLofYizVC.jpg'
2018-04-11 20:30:40,941 (7f745f7fe700) :  CRITICAL (runtime:889) - Exception (most recent call last):
timeout: timed out

2018-04-11 20:30:40,944 (7f745f7fe700) :  DEBUG (runtime:924) - Response: [500] 1599 bytes

The agent log is also showing 20 second delays between requests which is indicative of timeouts

2018-04-11 20:27:18,157 (7f7ecbfff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/ktCmXrccBdB49ukGY2EqmPu8TdS.jpg'
2018-04-11 20:27:38,234 (7f7ecbfff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/qOzdBkf3UkHttlcCmy69ZKQKHmQ.jpg'

2018-04-11 20:27:38,322 (7f7ecbfff700) :  DEBUG (networking:198) - Not caching 'http://image.tmdb.org/t/p/w300/qOzdBkf3UkHttlcCmy69ZKQKHmQ.jpg' (content type 'image/jpeg' not cacheable in Agent plug-ins)

2018-04-11 20:27:38,323 (7f7ecbfff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/sESRB3O94GHSrin7lOCD8Asc4bN.jpg'
2018-04-11 20:27:58,385 (7f7ecbfff700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/w300/b6zYV69JleLL19gl2U49FmvD5eb.jpg'

The image.tmdb.org resolves to a number of ipv6 and ipv4 IP addresses. It is possible there may be an issue with one and leading to a connection timeout and a second IP address is used.

There was a reported issue with the ipv6 routes but that was leading to 100 second delay and not 20 since it was the 5 ipv6 connection attempts that were each timing out. Also it was on Windows. See http://forums.plex.tv/discussion/297868/metadata-no-longer-being-applied-to-movies-confirmed-as-ipv6-timeout-delays

If you are familiar with curl, you could test out one of these requests eg
http://image.tmdb.org/t/p/original/cezWGskPY5x7GaglTTRN4Fugfb8.jpg using curl and test it with ipv6 and ipv4 and also you could try each of the ip addresses that the nslookup image.tmdb.org returns

The forum topic I gave has examples of curl tests - which you can adapt

Please do the tests on the server itself in a shell session

Maximiliano_Fernandez · April 13, 2018, 1:14am

Thanks for the help, you were right, apparently it’s an ipv6 issue. This is the result of using curl in shell:

curl -sv6o junk.jpeg http://image.tmdb.org/t/p/original/cezWGskPY5x7GaglTTRN4Fugfb8.jpg

Trying 2400:cb00:2048:1::6810:3d9b…
TCP_NODELAY set
Immediate connect fail for 2400:cb00:2048:1::6810:3d9b: Network is unreachable
Trying 2400:cb00:2048:1::6810:399b…
TCP_NODELAY set
Immediate connect fail for 2400:cb00:2048:1::6810:399b: Network is unreachable
Trying 2400:cb00:2048:1::6810:3c9b…
TCP_NODELAY set
Immediate connect fail for 2400:cb00:2048:1::6810:3c9b: Network is unreachable
Trying 2400:cb00:2048:1::6810:3b9b…
TCP_NODELAY set
Immediate connect fail for 2400:cb00:2048:1::6810:3b9b: Network is unreachable
Trying 2400:cb00:2048:1::6810:3a9b…
TCP_NODELAY set
Immediate connect fail for 2400:cb00:2048:1::6810:3a9b: Network is unreachable
Closing connection 0

Using ipv4 works fine:

curl -svo junk.jpeg http://image.tmdb.org/t/p/original/cezWGskPY5x7GaglTTRN4Fugfb8.jpg

Trying 104.16.60.155…
TCP_NODELAY set
Connected to image.tmdb.org (104.16.60.155) port 80 (#0)

GET /t/p/original/cezWGskPY5x7GaglTTRN4Fugfb8.jpg HTTP/1.1
Host: image.tmdb.org
User-Agent: curl/7.58.0
Accept: /

< HTTP/1.1 200 OK
< Date: Thu, 12 Apr 2018 11:43:54 GMT
< Content-Type: image/jpeg
< Content-Length: 323404
< Connection: keep-alive
< Set-Cookie: __cfduid=d98dc72fb74a6b05594177fe9d92ea6811523533434; expires=Fri, 12-Apr-19 11:43:54 GMT; path=/; domain=.tmdb.org; HttpOnly
< Access-Control-Allow-Origin: *
< Cache-Control: max-age=31449600
< ETag: “cc207791f38ccd6611409f36062956a1”
< Expires: Fri, 01 Feb 2019 12:04:28 GMT
< Last-Modified: Mon, 29 Jan 2018 09:25:31 GMT
< X-Content-Digest: 0389338db0915b92aace654d7ff4d0b81414991b
< X-Rack-Cache: fresh
< CF-Cache-Status: HIT
< Accept-Ranges: bytes
< Server: cloudflare
< CF-RAY: 40a5701da0436845-EZE
<
{ [3743 bytes data]

Connection #0 to host image.tmdb.org left intact

I’ve disabled ipv6 like people mentioned on the link you posted, but I’m still having problems downloading media, some will download, others don’t. And also I can’t change posters or backgrounds, I get a grey poster instead:

I’m attaching the logs, maybe you can find something different this time.
Thanks again.

sa2000 · April 13, 2018, 3:01pm

@“Maximiliano Fernandez” said:
Thanks for the help, you were right, apparently it’s an ipv6 issue. This is the result of using curl in shell:

curl -sv6o junk.jpeg http://image.tmdb.org/t/p/original/cezWGskPY5x7GaglTTRN4Fugfb8.jpg

Trying 2400:cb00:2048:1::6810:3d9b…

TCP_NODELAY set

Immediate connect fail for 2400:cb00:2048:1::6810:3d9b: Network is unreachable

Trying 2400:cb00:2048:1::6810:399b…

TCP_NODELAY set

Immediate connect fail for 2400:cb00:2048:1::6810:399b: Network is unreachable

Trying 2400:cb00:2048:1::6810:3c9b…

TCP_NODELAY set

Immediate connect fail for 2400:cb00:2048:1::6810:3c9b: Network is unreachable

Trying 2400:cb00:2048:1::6810:3b9b…

TCP_NODELAY set

Immediate connect fail for 2400:cb00:2048:1::6810:3b9b: Network is unreachable

Trying 2400:cb00:2048:1::6810:3a9b…

TCP_NODELAY set

Immediate connect fail for 2400:cb00:2048:1::6810:3a9b: Network is unreachable

Closing connection 0

Using ipv4 works fine:

curl -svo junk.jpeg http://image.tmdb.org/t/p/original/cezWGskPY5x7GaglTTRN4Fugfb8.jpg

Trying 104.16.60.155…

TCP_NODELAY set

Connected to image.tmdb.org (104.16.60.155) port 80 (#0)

GET /t/p/original/cezWGskPY5x7GaglTTRN4Fugfb8.jpg HTTP/1.1
Host: image.tmdb.org
User-Agent: curl/7.58.0
Accept: /

< HTTP/1.1 200 OK
< Date: Thu, 12 Apr 2018 11:43:54 GMT
< Content-Type: image/jpeg
< Content-Length: 323404
< Connection: keep-alive
< Set-Cookie: __cfduid=d98dc72fb74a6b05594177fe9d92ea6811523533434; expires=Fri, 12-Apr-19 11:43:54 GMT; path=/; domain=.tmdb.org; HttpOnly
< Access-Control-Allow-Origin: *
< Cache-Control: max-age=31449600
< ETag: “cc207791f38ccd6611409f36062956a1”
< Expires: Fri, 01 Feb 2019 12:04:28 GMT
< Last-Modified: Mon, 29 Jan 2018 09:25:31 GMT
< X-Content-Digest: 0389338db0915b92aace654d7ff4d0b81414991b
< X-Rack-Cache: fresh
< CF-Cache-Status: HIT
< Accept-Ranges: bytes
< Server: cloudflare
< CF-RAY: 40a5701da0436845-EZE
<
{ [3743 bytes data]

Connection #0 to host image.tmdb.org left intact

I’ve disabled ipv6 like people mentioned on the link you posted, but I’m still having problems downloading media, some will download, others don’t. And also I can’t change posters or backgrounds, I get a grey poster instead:

I’m attaching the logs, maybe you can find something different this time.
Thanks again.

I can see improvements. Requests for images from image.tmdb.org do not take a long time anymore - with longest time showing as 657 ms.

However, occasional timeouts are occurring

There were two failures at 22:00 and 22:01 on 12th April
image for 10 Coverfield Lane and image for Star Wars - Episde II Attack of the Clones

10 Coverfield Lane

2018-04-12 22:00:10,351 (7fd9d14ba700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/original/fGrYo9I3Rde83wGPRAmzdjUYGQo.jpg'
2018-04-12 22:00:30,586 (7fd9d14ba700) :  CRITICAL (runtime:889) - Exception (most recent call last):
. . .
timeout: timed out
2018-04-12 22:00:30,588 (7fd9d14ba700) :  DEBUG (runtime:924) - Response: [500] 1599 bytes

Star Wars - Episde II Attack of the Clones

2018-04-12 22:01:15,906 (7fd9d14ba700) :  DEBUG (networking:166) - Requesting 'http://image.tmdb.org/t/p/original/2vcNFtrZXNwIcBgH5e2xXCmVR8t.jpg'
2018-04-12 22:01:36,082 (7fd9d14ba700) :  CRITICAL (runtime:889) - Exception (most recent call last):
. . .
timeout: timed out
2018-04-12 22:01:36,085 (7fd9d14ba700) :  DEBUG (runtime:924) - Response: [500] 1599 bytes

May have been a transient issue

Could you try these requests in curl defaulting the protocol

curl "http://image.tmdb.org/t/p/original/fGrYo9I3Rde83wGPRAmzdjUYGQo.jpg" -o 10cfl.jpg
curl "http://image.tmdb.org/t/p/original/2vcNFtrZXNwIcBgH5e2xXCmVR8t.jpg" -o swe2aoc.jpg

Was there any more that failed to get posters?

sa2000 · April 13, 2018, 3:09pm

Actually you were having network issues round that time

Internet outage / DNS outage during most of this period

Apr 12, 2018 21:53:31.705 [0x7f8ce3bfe700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:31.706 [0x7f8ce3bfe700] WARN - HTTP error requesting GET https://plex.tv/servers/201d6d938eb240acb4c83f6c6099580e12f5afcc/access_tokens.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&includeProfiles=1&includeProviders=1 (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:33.393 [0x7f8ce3bfe700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:33.393 [0x7f8ce3bfe700] WARN - HTTP error requesting GET http://plex.tv/pms/:/ip (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:33.395 [0x7f8ce53fd700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:33.395 [0x7f8ce53fd700] WARN - HTTP error requesting PUT https://plex.tv/devices/201d6d938eb240acb4c83f6c6099580e12f5afcc?httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:33.396 [0x7f8ce3bfe700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:33.396 [0x7f8ce3bfe700] WARN - HTTP error requesting PUT https://plex.tv/devices/201d6d938eb240acb4c83f6c6099580e12f5afcc?httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:33.426 [0x7f8ce01ea700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:33.426 [0x7f8ce01ea700] WARN - HTTP error requesting GET https://plex.tv/users/account.xml?auth_token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:33.434 [0x7f8ce43ff700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:33.434 [0x7f8ce43ff700] WARN - HTTP error requesting GET https://plex.tv/api/v2/server/webhooks?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:33.440 [0x7f8ce01ea700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:33.440 [0x7f8ce01ea700] WARN - HTTP error requesting GET https://plex.tv/servers/201d6d938eb240acb4c83f6c6099580e12f5afcc/sync_lists?auth_token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:33.443 [0x7f8ce01ea700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:33.443 [0x7f8ce01ea700] WARN - HTTP error requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:33.444 [0x7f8ce01ea700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:33.444 [0x7f8ce01ea700] WARN - HTTP error requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:33.447 [0x7f8ce01ea700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:33.447 [0x7f8ce01ea700] WARN - HTTP error requesting PUT https://plex.tv/devices/201d6d938eb240acb4c83f6c6099580e12f5afcc?httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:33.465 [0x7f8cddbfd700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:33.465 [0x7f8cddbfd700] WARN - HTTP error requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx&async=1&asyncIdentifier=2644b22f-f397-40c2-aef3-6dd862fbbd0a (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:33.502 [0x7f8ce63ff700] DEBUG - EventSource: Resolved 45.33.73.209 to 45.33.73.209
Apr 12, 2018 21:53:33.505 [0x7f8ce3bfe700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:33.505 [0x7f8ce3bfe700] WARN - HTTP error requesting GET http://plex.tv/pms/:/ip (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:33.506 [0x7f8ce73ff700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:33.506 [0x7f8ce73ff700] WARN - HTTP error requesting PUT https://plex.tv/devices/201d6d938eb240acb4c83f6c6099580e12f5afcc?httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:33.506 [0x7f8ce3bfe700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:33.506 [0x7f8ce3bfe700] WARN - HTTP error requesting PUT https://plex.tv/devices/201d6d938eb240acb4c83f6c6099580e12f5afcc?httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:33.512 [0x7f8ce01ea700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:33.512 [0x7f8ce01ea700] WARN - HTTP error requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:33.567 [0x7f8ce01ea700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:33.567 [0x7f8ce01ea700] WARN - HTTP error requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:34.169 [0x7f8ce73ff700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:34.169 [0x7f8ce73ff700] WARN - HTTP error requesting GET http://plex.tv/pms/:/ip (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:34.171 [0x7f8ce73ff700] ERROR - Error issuing curl_easy_perform(handle): 6
Apr 12, 2018 21:53:34.171 [0x7f8ce73ff700] WARN - HTTP error requesting PUT https://plex.tv/devices/201d6d938eb240acb4c83f6c6099580e12f5afcc?Connection[][uri]=http://10.0.0.50:32400&httpsEnabled=1&httpsRequired=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (0, No error) (Couldn't resolve host 'plex.tv')
Apr 12, 2018 21:53:48.502 [0x7f8ce5bfe700] DEBUG - EventSource: Resolved 45.33.73.209 to 45.33.73.209

and network card going off / on

Apr 12, 2018 21:53:33.394 [0x7f8cf52e2840] DEBUG - Network change.
Apr 12, 2018 21:53:33.394 [0x7f8cf52e2840] DEBUG - NetworkInterface: Notified of network changed (force=0)
Apr 12, 2018 21:53:33.397 [0x7f8cf52e2840] DEBUG -  * 1 lo (127.0.0.1) (loopback: 1)
Apr 12, 2018 21:53:33.397 [0x7f8cf52e2840] DEBUG - NetworkService: Quick dispatch of network change.
Apr 12, 2018 21:53:33.415 [0x7f8cdebff700] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.

Apr 12, 2018 21:53:34.154 [0x7f8cdf9e9700] DEBUG - Network change.
Apr 12, 2018 21:53:34.154 [0x7f8cdf9e9700] DEBUG - NetworkInterface: Notified of network changed (force=0)
Apr 12, 2018 21:53:34.154 [0x7f8cdf9e9700] DEBUG - Network change notification but nothing changed.

Apr 12, 2018 21:53:34.168 [0x7f8cdf9e9700] DEBUG - Network change.
Apr 12, 2018 21:53:34.168 [0x7f8cdf9e9700] DEBUG - NetworkInterface: Notified of network changed (force=0)
Apr 12, 2018 21:53:34.168 [0x7f8cdf9e9700] DEBUG -  * 1 lo (127.0.0.1) (loopback: 1)
Apr 12, 2018 21:53:34.168 [0x7f8cdf9e9700] DEBUG -  * 2 enp3s0 (10.0.0.50) (loopback: 0)
Apr 12, 2018 21:53:34.168 [0x7f8cdf9e9700] DEBUG - NetworkService: Dispatch network change after two second delay.

Apr 12, 2018 21:53:34.205 [0x7f8cdf9e9700] DEBUG - Network change.
Apr 12, 2018 21:53:34.205 [0x7f8cdf9e9700] DEBUG - NetworkInterface: Notified of network changed (force=0)
Apr 12, 2018 21:53:34.205 [0x7f8cdf9e9700] DEBUG - Network change notification but nothing changed.
Apr 12, 2018 21:53:36.168 [0x7f8cdebff700] DEBUG - NetworkService: Network change for browser (polled=0), closing 0 browse sockets.

sa2000 · April 13, 2018, 4:01pm

Sorry for giving your separate updates -
Also look into power saving options on the network card

When doing the curl tests - could you try each url with http and https

There were actually a lot of timeouts - could be an http / https issue

Maximiliano_Fernandez · April 14, 2018, 2:55pm

Did the curl tests like you said, with http and https, no problems there:

root@server:~# curl "http://image.tmdb.org/t/p/original/fGrYo9I3Rde83wGPRAmzdjUYGQo.jpg" -o 10cfl.jpg
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed
100  156k  100  156k    0     0  2611k      0 --:--:-- --:--:-- --:--:-- 2611k

root@server:~# curl "https://image.tmdb.org/t/p/original/fGrYo9I3Rde83wGPRAmzdjUYGQo.jpg" -o 10cflhttps.jpg
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed
100  156k  100  156k    0     0  1666k      0 --:--:-- --:--:-- --:--:-- 1666k

root@server:~# curl "http://image.tmdb.org/t/p/original/2vcNFtrZXNwIcBgH5e2xXCmVR8t.jpg" -o swe2aoc.jpg
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed
100  210k  100  210k    0     0  3244k      0 --:--:-- --:--:-- --:--:-- 3244k

root@server:~# curl "https://image.tmdb.org/t/p/original/2vcNFtrZXNwIcBgH5e2xXCmVR8t.jpg" -o swe2aochttps.jpg
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed
100  210k  100  210k    0     0  1989k      0 --:--:-- --:--:-- --:--:-- 1989k

There are no power savings options set in the network card, but disabled NetworkManager just to be sure.
I’ve download version 1.12.3.4973, but the problem is still there.
The problem only seems to be with movies, as I can download posters and backgrounds for TV shows correctly.
Also cast pictures are not downloading only for movies.
I’m attaching logs again.

Thanks for the help.

sa2000 · April 14, 2018, 3:10pm

@“Maximiliano Fernandez” said:
Did the curl tests like you said, with http and https, no problems there:

root@server:~# curl "http://image.tmdb.org/t/p/original/fGrYo9I3Rde83wGPRAmzdjUYGQo.jpg" -o 10cfl.jpg
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed
100  156k  100  156k    0     0  2611k      0 --:--:-- --:--:-- --:--:-- 2611k

root@server:~# curl "https://image.tmdb.org/t/p/original/fGrYo9I3Rde83wGPRAmzdjUYGQo.jpg" -o 10cflhttps.jpg
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed
100  156k  100  156k    0     0  1666k      0 --:--:-- --:--:-- --:--:-- 1666k

root@server:~# curl "http://image.tmdb.org/t/p/original/2vcNFtrZXNwIcBgH5e2xXCmVR8t.jpg" -o swe2aoc.jpg
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed
100  210k  100  210k    0     0  3244k      0 --:--:-- --:--:-- --:--:-- 3244k

root@server:~# curl "https://image.tmdb.org/t/p/original/2vcNFtrZXNwIcBgH5e2xXCmVR8t.jpg" -o swe2aochttps.jpg
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed
100  210k  100  210k    0     0  1989k      0 --:--:-- --:--:-- --:--:-- 1989k

There are no power savings options set in the network card, but disabled NetworkManager just to be sure.
I’ve download version 1.12.3.4973, but the problem is still there.
The problem only seems to be with movies, as I can download posters and backgrounds for TV shows correctly.
Also cast pictures are not downloading only for movies.
I’m attaching logs again.

Thanks for the help.

very strange because they are all failing - all the http ones are not getting a response

Apr 14, 2018 11:46:18.861 [0x7f26c4ff8700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/afhAUuWVB7k7PjJUd4lwO3rzhSq.jpg
Apr 14, 2018 11:46:18.875 [0x7f26c1ffc700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/7lyBcpYB0Qt8gYhXYaEZUNlNQAv.jpg

Apr 14, 2018 11:46:27.799 [0x7f26c77fd700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/afhAUuWVB7k7PjJUd4lwO3rzhSq.jpg

Apr 14, 2018 11:46:30.366 [0x7f26bf7ff700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/D6e8RJf2qUstnfkTslTXNTUAlT.jpg
Apr 14, 2018 11:46:30.371 [0x7f26bc3ff700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/ywb9dKBMdu3DEiTFpXrLofYizVC.jpg
Apr 14, 2018 11:46:30.385 [0x7f26c47f7700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/6qrj4xUPUHI8dLqNDdpIgcg10hr.jpg
Apr 14, 2018 11:46:30.390 [0x7f26bd3ff700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/afhAUuWVB7k7PjJUd4lwO3rzhSq.jpg

Apr 14, 2018 11:46:32.027 [0x7f26bb3ff700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/7lyBcpYB0Qt8gYhXYaEZUNlNQAv.jpg
Apr 14, 2018 11:46:32.030 [0x7f26babfe700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/j9vy0FOCga6AhYvPXtOBhdLCSfQ.jpg

Apr 14, 2018 11:47:00.837 [0x7f26b9bfc700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/xa6G3aKlysQeVg9wOb0dRcIGlWu.jpg

Apr 14, 2018 11:47:04.169 [0x7f26ba3fd700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/xa6G3aKlysQeVg9wOb0dRcIGlWu.jpg
Apr 14, 2018 11:47:04.188 [0x7f26cb7fd700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/xa6G3aKlysQeVg9wOb0dRcIGlWu.jpg
Apr 14, 2018 11:47:04.202 [0x7f26c07ff700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/tofLS5A6lBXNjeROGvgpfe2JwaT.jpg
Apr 14, 2018 11:47:04.205 [0x7f26cfffd700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/9NuZGaPn3Z1svlbF9ZkdtjgrHZo.jpg

Apr 14, 2018 11:47:08.837 [0x7f26cefff700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/xa6G3aKlysQeVg9wOb0dRcIGlWu.jpg

Apr 14, 2018 11:47:13.199 [0x7f26b8bfa700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/maP4MTfPCeVD2FZbKTLUgriOW4R.jpg
Apr 14, 2018 11:47:13.220 [0x7f26c37ff700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/kxtZpFfR0RMModlRHTFIuEPEH2O.jpg

Apr 14, 2018 11:47:16.551 [0x7f26b6bff700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/kxtZpFfR0RMModlRHTFIuEPEH2O.jpg
Apr 14, 2018 11:47:16.552 [0x7f26b93fb700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/maP4MTfPCeVD2FZbKTLUgriOW4R.jpg

Apr 14, 2018 11:47:18.435 [0x7f26b83f9700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/xa6G3aKlysQeVg9wOb0dRcIGlWu.jpg

Apr 14, 2018 11:47:19.375 [0x7f26b4bff700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/j9vy0FOCga6AhYvPXtOBhdLCSfQ.jpg

Apr 14, 2018 11:47:21.653 [0x7f26b3bfd700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/7lyBcpYB0Qt8gYhXYaEZUNlNQAv.jpg
Apr 14, 2018 11:47:21.657 [0x7f26b43fe700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/ywb9dKBMdu3DEiTFpXrLofYizVC.jpg

Apr 14, 2018 11:47:22.331 [0x7f26b33fc700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/xa6G3aKlysQeVg9wOb0dRcIGlWu.jpg

Apr 14, 2018 11:47:51.357 [0x7f26c1ffc700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/5ffpAd9hdNHcYvXeikDt74RxjqM.jpg

Apr 14, 2018 11:48:13.834 [0x7f26bf7ff700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/ywb9dKBMdu3DEiTFpXrLofYizVC.jpg
Apr 14, 2018 11:48:13.834 [0x7f26babfe700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/7lyBcpYB0Qt8gYhXYaEZUNlNQAv.jpg
Apr 14, 2018 11:48:13.838 [0x7f26c47f7700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/j9vy0FOCga6AhYvPXtOBhdLCSfQ.jpg

Apr 14, 2018 11:48:14.279 [0x7f26bc3ff700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/6qrj4xUPUHI8dLqNDdpIgcg10hr.jpg
Apr 14, 2018 11:48:44.694 [0x7f26b9bfc700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/afhAUuWVB7k7PjJUd4lwO3rzhSq.jpg

Apr 14, 2018 11:48:53.152 [0x7f26b4bff700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/xAES23Ll0JT0lJADA1SIdTAN0bf.jpg

Apr 14, 2018 11:49:04.747 [0x7f26b33fc700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/afhAUuWVB7k7PjJUd4lwO3rzhSq.jpg

Apr 14, 2018 11:49:43.388 [0x7f26c37ff700] DEBUG - HTTP requesting GET http://image.tmdb.org/t/p/original/ttPWqYFpshOCoToScRTq3EYt5oy.jpg

None of these got a response and each timed out

Was your curl test on the same machine? i.e. the Plex Media Server machine?

Since it is very clear and you appear to be able to reproduce the issue easily, could you look into using wireshark on the machine to prove that these requests are going out and to see which IP address they go to and whether it is ipv4 or ipv6

wireshark can be checked against the log = the extract above is from Plex Media Server.log and if there was a response it would have HTTP xxx response from GET http://image.tmdb.org/xxxxxxxxxxxxxxxxx etc
The xxx being response code -

sa2000 · April 14, 2018, 3:46pm

Could you see if the image files the curl commands wrote to contain any text response

We have had Cloudflare bans before - so rename the 10cfl.jpg etc files to 10cfl.txt etc and see if there is any readable text

slashmanmusic · April 14, 2018, 4:15pm

Not sure if you’ve tried this but I was having the same issue. I rebooted my router and now everything is working perfectly.

Maximiliano_Fernandez · April 14, 2018, 7:03pm

Was your curl test on the same machine? i.e. the Plex Media Server machine?
Yes, it is the same machine

Could you see if the image files the curl commands wrote to contain any text response
They are all images:

root@server:~$ file *.jpg
10cflhttps.jpg:   JPEG image data, JFIF standard 1.01, aspect ratio, density 1x1, segment length 16, progressive, precision 8, 2000x3000, frames 3
10cfl.jpg:        JPEG image data, JFIF standard 1.01, aspect ratio, density 1x1, segment length 16, progressive, precision 8, 2000x3000, frames 3
swe2aochttps.jpg: JPEG image data, JFIF standard 1.01, aspect ratio, density 1x1, segment length 16, progressive, precision 8, 1000x1500, frames 3
swe2aoc.jpg:      JPEG image data, JFIF standard 1.01, aspect ratio, density 1x1, segment length 16, progressive, precision 8, 1000x1500, frames 3

Not sure if you’ve tried this but I was having the same issue. I rebooted my router and now everything is working perfectly.
Did tried this, but the problem is still there.

I did try Wireshark, filtered for http protocol and load Plex Web:

All those gets are for the posters I’m missing I assume. Tried the last one with curl, no problems there.
I let it run for a while but never received a response for the firsts gets.

I’m attaching a wireshark pcapng file with the conversation of one of the gets.
Let me know if you need me to try anything else.

Thanks

sa2000 · April 14, 2018, 9:53pm

This wireshark capture in the conversion.zip just has one request and it was successful

The wireshark http filtered screenshot you have in your post shows only the last request being successful

sa2000 · April 14, 2018, 10:15pm

Linking to the other forum thread where I am having a parallel investigation

sa2000 · April 14, 2018, 10:17pm

@sa2000 said:
This wireshark capture in the conversion.zip just has one request and it was successful

The wireshark http filtered screenshot you have in your post shows only the last request being successful

The wireshark I had from the other investigation showed data coming in for the requests that time out but we do not get the HTTP 200 OK response

could you do a tracert image.tmdb.org from the server and PM me the wan IP address for your server

gonse · April 14, 2018, 10:46pm

Maxi are you from Argentina and your ISP is fibertel?

sa2000 · April 14, 2018, 11:03pm

Yes - I just established both of you in Argentina and with CableVision as ISP

Maximiliano_Fernandez · April 14, 2018, 11:08pm

The wireshark http filtered screenshot you have in your post shows only the last request being successful

That request was made with curl, you can see that in the user agent

could you do a tracert image.tmdb.org from the server and PM me the wan IP address for your server

root@server:~# traceroute image.tmdb.org
traceroute to image.tmdb.org (104.16.58.155), 30 hops max, 60 byte packets
1 _gateway (10.0.0.1) 1.172 ms 1.758 ms 2.859 ms
2 * * *
3 * * *
4 * * *
5 * * *
6 149-161-89-200.fibertel.com.ar (200.89.161.149) 24.060 ms 16.681 ms 145-161-89-200.fibertel.com.ar (200.89.161.145) 14.716 ms
7 150-165-89-200.fibertel.com.ar (200.89.165.150) 18.527 ms 18.764 ms 19.693 ms
8 * * *
9 104.16.58.155 (104.16.58.155) 9.943 ms 9.962 ms 9.642 ms

Maxi are you from Argentina and your ISP is fibertel?

Yes, is this an ISP issue?

sa2000 · April 14, 2018, 11:14pm

I suggest raising it your ISP - both go through fibertel.com.ar
I will raise with our Operations team to flag it to Cloudflare

Maximiliano_Fernandez · April 14, 2018, 11:35pm

Will do, but why is it that just Plex is having issues with it?
Within my network I can access those links on different devices, on the machine that is running Plex itself it works using curl.

gonse · April 14, 2018, 11:44pm

I also have fibertel and I’am having this freaking problem since about a month ago. It seems the issue is within fibertel–plex–themoviedatabase because the TV database works perfect.
I’ll try connecting my pc with a LTE modem and see what happens.

Topic		Replies	Views
Problem downloading artwork from TMDB (Argentina Users) Desktops & Laptops server-windows	92	2776	January 8, 2020
Metadata no longer being applied to Movies (confirmed as ipv6 timeout delays) Desktops & Laptops server-windows	86	3488	January 8, 2020
Plex Troubleshoot AGAIN: NOT pulling in METADATA & Posters for Movies [UNSOLVED] NAS & Devices server-synology	52	4621	June 27, 2019
Movies get metadata without an issue, tv shows do not. Desktops & Laptops server-windows	45	680	January 8, 2020
Scanning library takes hours just for a couple of movies/shows NAS & Devices server-synology	126	5938	December 6, 2020

Plex takes too long to download metadata or does not at all (Argentina issue)

Related topics