Server Version#: 1.19.3.2764 (Docker on Unraid)
Player Version#: Plex for LG4.29.4 (webOS 3.8.0)
I see this issue periodically on my LG TV. A buffering dialog box appears and the progress shows 100% complete. The media continues to play just fine the only problem is the buffering dialog that is shown for the rest of the show. I had my logging set to debug so I wont upload the whole log zip but here is the part I expect is relevant from the Media Server log:
May 10, 2020 22:12:25.462 [0x14a92f3f9700] DEBUG - Client [tzftuchawrbpgb9j4gtzl5yp] reporting timeline state playing, progress of 1459000/2563000ms for guid=, playbackTime=1454938ms ratingKey=1961 url=, key=/library/metadata/1961, containerKey=, metadataId=1961, source=
May 10, 2020 22:12:25.468 [0x14a92f3f9700] DEBUG - Play progress on 1961 'Bollywood Homicide' - got played 1459000 ms by account 1!
May 10, 2020 22:12:25.478 [0x14a92f3f9700] DEBUG - [Now] Device is webOS (LG OLED55E7P-U).
May 10, 2020 22:12:25.480 [0x14a92f3f9700] ERROR - Unable to find client profile for device; platform=webOS, platformVersion=3.8.0, device=, model=OLED55E7P-U
May 10, 2020 22:12:25.480 [0x14a92f3f9700] DEBUG - [Now] Updated play state for /library/metadata/1961.
May 10, 2020 22:12:25.481 [0x14a94d4db700] DEBUG - Completed: [10.50.0.11:49994] 200 GET /:/timeline?ratingKey=1961&key=%2Flibrary%2Fmetadata%2F1961&playbackTime=1454938&playQueueItemID=37339&state=playing&hasMDE=1&time=1459000&duration=2563000 (4 live) GZIP 19ms 573 bytes (pipelined: 375)
May 10, 2020 22:12:29.456 [0x14a92f3f9700] DEBUG - Request: [127.0.0.1:58010 (Loopback)] GET /identity (5 live) Signed-in
May 10, 2020 22:12:29.457 [0x14a94d6dc700] DEBUG - Completed: [127.0.0.1:58010] 200 GET /identity (5 live) 0ms 398 bytes (pipelined: 1)
May 10, 2020 22:12:34.978 [0x14a92f3f9700] DEBUG - Request: [127.0.0.1:58012 (Loopback)] GET /identity (5 live) Signed-in
May 10, 2020 22:12:34.978 [0x14a94d4db700] DEBUG - Completed: [127.0.0.1:58012] 200 GET /identity (5 live) 0ms 398 bytes (pipelined: 1)
May 10, 2020 22:12:35.488 [0x14a92f3f9700] DEBUG - Request: [10.50.0.11:49994 (Allowed Network (Subnet))] GET /:/timeline?ratingKey=1961&key=%2Flibrary%2Fmetadata%2F1961&playbackTime=1464945&playQueueItemID=37339&state=playing&hasMDE=1&time=1469000&duration=2563000 (5 live) GZIP Signed-in
May 10, 2020 22:12:35.489 [0x14a92f3f9700] DEBUG - Client [tzftuchawrbpgb9j4gtzl5yp] reporting timeline state playing, progress of 1469000/2563000ms for guid=, playbackTime=1464945ms ratingKey=1961 url=, key=/library/metadata/1961, containerKey=, metadataId=1961, source=
May 10, 2020 22:12:35.495 [0x14a92f3f9700] DEBUG - Play progress on 1961 'Bollywood Homicide' - got played 1469000 ms by account 1!
May 10, 2020 22:12:35.506 [0x14a92f3f9700] DEBUG - [Now] Device is webOS (LG OLED55E7P-U).
May 10, 2020 22:12:35.508 [0x14a92f3f9700] ERROR - Unable to find client profile for device; platform=webOS, platformVersion=3.8.0, device=, model=OLED55E7P-U
May 10, 2020 22:12:35.508 [0x14a92f3f9700] DEBUG - [Now] Updated play state for /library/metadata/1961.
May 10, 2020 22:12:35.509 [0x14a94d6dc700] DEBUG - Completed: [10.50.0.11:49994] 200 GET /:/timeline?ratingKey=1961&key=%2Flibrary%2Fmetadata%2F1961&playbackTime=1464945&playQueueItemID=37339&state=playing&hasMDE=1&time=1469000&duration=2563000 (5 live) GZIP 20ms 573 bytes (pipelined: 376)
May 10, 2020 22:12:40.474 [0x14a92f3f9700] DEBUG - Request: [127.0.0.1:58014 (Loopback)] GET /identity (5 live) Signed-in
May 10, 2020 22:12:40.475 [0x14a94d6dc700] DEBUG - Completed: [127.0.0.1:58014] 200 GET /identity (5 live) 0ms 398 bytes (pipelined: 1)
May 10, 2020 22:12:44.923 [0x14a94d6dc700] DEBUG - Completed: [10.50.0.11:49892] 200 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (4 live) GZIP 20000ms 5 bytes (pipelined: 557)
May 10, 2020 22:12:44.932 [0x14a94d4db700] DEBUG - Auth: authenticated user 1 as jamesw_001
May 10, 2020 22:12:44.932 [0x14a92f3f9700] DEBUG - Request: [10.50.0.11:49892 (Allowed Network (Subnet))] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (4 live) GZIP Signed-in Token (jamesw_001)
May 10, 2020 22:12:44.932 [0x14a92f3f9700] DEBUG - Content-Length is -1 (of total: -1).
May 10, 2020 22:12:45.098 [0x14a92f3f9700] DEBUG - Request: [10.50.0.11:49994 (Allowed Network (Subnet))] GET /:/timeline?ratingKey=1961&key=%2Flibrary%2Fmetadata%2F1961&playbackTime=1473954&playQueueItemID=37339&state=buffering&hasMDE=1&time=2563000&duration=2563000 (4 live) GZIP Signed-in
May 10, 2020 22:12:45.099 [0x14a92f3f9700] DEBUG - Client [tzftuchawrbpgb9j4gtzl5yp] reporting timeline state buffering, progress of 2563000/2563000ms for guid=, playbackTime=1473954ms ratingKey=1961 url=, key=/library/metadata/1961, containerKey=, metadataId=1961, source=
May 10, 2020 22:12:45.119 [0x14a92f3f9700] DEBUG - Library item 1961 'Bollywood Homicide' got played by account 1!
May 10, 2020 22:12:45.122 [0x14a92f3f9700] DEBUG - [Now] Device is webOS (LG OLED55E7P-U).
May 10, 2020 22:12:45.125 [0x14a92f3f9700] ERROR - Unable to find client profile for device; platform=webOS, platformVersion=3.8.0, device=, model=OLED55E7P-U
May 10, 2020 22:12:45.125 [0x14a92f3f9700] DEBUG - [Now] Updated play state for /library/metadata/1961.
May 10, 2020 22:12:45.126 [0x14a94d6dc700] DEBUG - Completed: [10.50.0.11:49994] 200 GET /:/timeline?ratingKey=1961&key=%2Flibrary%2Fmetadata%2F1961&playbackTime=1473954&playQueueItemID=37339&state=buffering&hasMDE=1&time=2563000&duration=2563000 (4 live) GZIP 27ms 572 bytes (pipelined: 377)
May 10, 2020 22:12:45.542 [0x14a92f3f9700] DEBUG - Request: [10.50.0.11:49994 (Allowed Network (Subnet))] GET /:/timeline?ratingKey=1961&key=%2Flibrary%2Fmetadata%2F1961&playbackTime=1474603&playQueueItemID=37339&state=buffering&hasMDE=1&time=2563000&duration=2563000 (4 live) GZIP Signed-in
May 10, 2020 22:12:45.543 [0x14a92f3f9700] DEBUG - Client [tzftuchawrbpgb9j4gtzl5yp] reporting timeline state buffering, progress of 2563000/2563000ms for guid=, playbackTime=1474603ms ratingKey=1961 url=, key=/library/metadata/1961, containerKey=, metadataId=1961, source=
The parts that seem interesting to me are “Content-Length is -1 (of total: -1).” and “progress of 2563000/2563000ms” even though the media is only halfway through.
The media being played is a 480p 1465 kbps H.264 MP4 direct play. I also saw this last night on a 1080p 7329 kbps H.264 MP4 direct play. I haven’t seen this on any other device, but I almost exclusively watch my content on this TV.
I have tried to find similar posts on these forums but the obvious symptom is the buffering dialog and there are a lot of posts about buffering on these forums making it difficult to find related posts. My issue is not actually related to buffering (I think) as the media continues to play just fine while the buffering dialog is shown.
So, has anyone seen this behavior before? Or does anyone know what the issue might be?
