Randomly plays next file, freezes, plays and just stops - client probably disconnected: 10054

chromecast

#1

So i have seen a variation of the three above for over 6 months. There was a time when it seemed to work great but I haven't had time to diagnose the error messages. I have gotten two logs downloaded from producing it two separate times. In this case I was casting from app.plex.tv (web) but I see it from android also. I think i have also seen it with vudu. Until i figure out why its happening, i just can't use Chromecast anymore.

I believe its a Chromecast issue.

The error comes down to:

Sep 18, 2017 13:26:53.069 [67940] DEBUG - Request: [192.168.0.100:33126 (Subnet)] GET /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=stopped&continuing=1&playQueueItemID=19636&time=840966&duration=1385600 (16 live) TLS GZIP Signed-in Token (t-zone)
Sep 18, 2017 13:26:53.069 [67940] DEBUG - Client [1bfu729jqwtphzvz2lc9yfq7] reporting timeline state stopped, progress of 840966/1385600ms for guid=, ratingKey=88565 url=, key=/library/metadata/88565, containerKey=, metadataId=88565
Sep 18, 2017 13:26:53.071 [15256] DEBUG - ** Failed to stream media, client probably disconnected: 10054 - An existing connection was forcibly closed by the remote host**
Sep 18, 2017 13:26:53.071 [15256] DEBUG - Completed: [192.168.0.100:33018] 200 GET /library/parts/99426/1503706784/file.mp4 (16 live) TLS 305253ms 222674944 bytes (pipelined: 1)

Also saw this,

Sender disconnected. Reason: requested_by_sender. Total number of senders: 0

I have tried, 2 different Chromecast ultras, same problem. Two different environments.

I cast a show or movie to the chromecast and it will randomly stop playing, go to the next episode automatically even when only being played a few minutes, or randomly freezes (buffers) not fixes itself.

I have the latest code (as of today). I have tried changing the Transcoder (even though its direct playing typically) settings in-case it was timing out for some odd reason. One chromecast is over 5k wireless. The other one is over Ethernet. Both connected to the router (TP Link C7 1750) in house.

The errors shows a disconnect problem. I will copy a brief portion of both as it might help see different scenarios of the error.

Extended Log 1)

Sep 18, 2017 13:26:37.983 [67940] DEBUG - [Now] User is t-zone (ID: 1)
Sep 18, 2017 13:26:37.983 [67940] DEBUG - [Now] Device is Chromecast (Chromecast).
Sep 18, 2017 13:26:37.983 [67940] DEBUG - [Now] Profile is Chromecast
Sep 18, 2017 13:26:37.983 [67940] DEBUG - [Now] Updated play state for /library/metadata/88565.
Sep 18, 2017 13:26:37.984 [67940] DEBUG - Statistics: (56tz8220xld2ytir688p5sc3) Reporting active playback in state 1 of type 4 (scrobble: 0) for account 1
Sep 18, 2017 13:26:37.985 [15252] DEBUG - Completed: [192.168.0.100:33120] 200 GET /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=paused&playQueueItemID=19636&time=840966&duration=1385600 (15 live) TLS GZIP 10ms 538 bytes (pipelined: 2)
Sep 18, 2017 13:26:38.287 [15256] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Sep 18, 2017 13:26:38.287 [15256] DEBUG - Auth: authenticated user 1 as t-zone
Sep 18, 2017 13:26:38.287 [15256] DEBUG - Auth: Came in with a super-token, authorization succeeded.
Sep 18, 2017 13:26:38.287 [68840] DEBUG - Request: [192.168.0.107:52903 (Subnet)] GET /status/sessions (15 live) TLS Signed-in Token (t-zone)
Sep 18, 2017 13:26:38.287 [68840] DEBUG - [Now] Adding 1 sessions.
Sep 18, 2017 13:26:38.289 [15252] DEBUG - Completed: [192.168.0.107:52903] 200 GET /status/sessions (15 live) TLS 2ms 3410 bytes (pipelined: 1)
Sep 18, 2017 13:26:41.556 [15256] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxxb93-4280-941a-3329873dced1), enabling token-based authentication.
Sep 18, 2017 13:26:41.556 [15256] DEBUG - Auth: authenticated user 1 as t-zone
Sep 18, 2017 13:26:41.556 [15256] DEBUG - Auth: Came in with a super-token, authorization succeeded.
Sep 18, 2017 13:26:41.556 [68840] DEBUG - Request: [192.168.0.100:33122 (Subnet)] GET /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=paused&playQueueItemID=19636&time=840966&duration=1385600 (15 live) TLS GZIP Signed-in Token (t-zone)
Sep 18, 2017 13:26:41.557 [68840] DEBUG - Client [1bfu729jqwtphzvz2lc9yfq7] reporting timeline state paused, progress of 840966/1385600ms for guid=, ratingKey=88565 url=, key=/library/metadata/88565, containerKey=, metadataId=88565
Sep 18, 2017 13:26:41.560 [68840] DEBUG - Play progress on 88565 'The Name of the Show is Here' - got played 840966 ms by account 1!
Sep 18, 2017 13:26:41.564 [68840] DEBUG - [Now] User is t-zone (ID: 1)
Sep 18, 2017 13:26:41.564 [68840] DEBUG - [Now] Device is Chromecast (Chromecast).
Sep 18, 2017 13:26:41.564 [68840] DEBUG - [Now] Profile is Chromecast
Sep 18, 2017 13:26:41.564 [68840] DEBUG - [Now] Updated play state for /library/metadata/88565.
Sep 18, 2017 13:26:41.565 [68840] DEBUG - Statistics: (56tz8220xld2ytir688p5sc3) Reporting active playback in state 1 of type 4 (scrobble: 0) for account 1
Sep 18, 2017 13:26:41.567 [15256] DEBUG - Completed: [192.168.0.100:33122] 200 GET /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=paused&playQueueItemID=19636&time=840966&duration=1385600 (15 live) TLS GZIP 10ms 538 bytes (pipelined: 3)
Sep 18, 2017 13:26:51.564 [15252] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxxb93-4280-941a-3329873dced1), enabling token-based authentication.
Sep 18, 2017 13:26:51.564 [15252] DEBUG - Auth: authenticated user 1 as t-zone
Sep 18, 2017 13:26:51.564 [15252] DEBUG - Auth: Came in with a super-token, authorization succeeded.
Sep 18, 2017 13:26:51.564 [70648] DEBUG - Request: [192.168.0.100:33124 (Subnet)] GET /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=paused&playQueueItemID=19636&time=840966&duration=1385600 (15 live) TLS GZIP Signed-in Token (t-zone)
Sep 18, 2017 13:26:51.564 [70648] DEBUG - Client [1bfu729jqwtphzvz2lc9yfq7] reporting timeline state paused, progress of 840966/1385600ms for guid=, ratingKey=88565 url=, key=/library/metadata/88565, containerKey=, metadataId=88565
Sep 18, 2017 13:26:51.568 [70648] DEBUG - Play progress on 88565 'The Name of the Show Here' - got played 840966 ms by account 1!
Sep 18, 2017 13:26:51.572 [70648] DEBUG - [Now] User is t-zone (ID: 1)
Sep 18, 2017 13:26:51.572 [70648] DEBUG - [Now] Device is Chromecast (Chromecast).
Sep 18, 2017 13:26:51.572 [70648] DEBUG - [Now] Profile is Chromecast
Sep 18, 2017 13:26:51.572 [70648] DEBUG - [Now] Updated play state for /library/metadata/88565.
Sep 18, 2017 13:26:51.573 [70648] DEBUG - Statistics: (56tz8220xld2ytir688p5sc3) Reporting active playback in state 1 of type 4 (scrobble: 0) for account 1
Sep 18, 2017 13:26:51.575 [15252] DEBUG - Completed: [192.168.0.100:33124] 200 GET /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=paused&playQueueItemID=19636&time=840966&duration=1385600 (15 live) TLS GZIP 11ms 538 bytes (pipelined: 1)
Sep 18, 2017 13:26:52.364 [15252] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Sep 18, 2017 13:26:52.364 [15252] DEBUG - Auth: authenticated user 1 as t-zone
Sep 18, 2017 13:26:52.365 [15252] DEBUG - Auth: Came in with a super-token, authorization succeeded.
Sep 18, 2017 13:26:52.365 [67940] DEBUG - Request: [127.0.0.1:52905 (Loopback)] GET /clients (16 live) GZIP Signed-in Token (t-zone)
Sep 18, 2017 13:26:52.365 [15256] DEBUG - Completed: [127.0.0.1:52905] 200 GET /clients (16 live) GZIP 0ms 470 bytes (pipelined: 1)
Sep 18, 2017 13:26:52.478 [15252] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Sep 18, 2017 13:26:52.478 [15252] DEBUG - Auth: authenticated user 1 as t-zone
Sep 18, 2017 13:26:52.478 [15252] DEBUG - Auth: Came in with a super-token, authorization succeeded.
Sep 18, 2017 13:26:52.478 [70648] DEBUG - Request: [127.0.0.1:52906 (Loopback)] GET / (16 live) GZIP Signed-in Token (t-zone)
Sep 18, 2017 13:26:52.479 [70648] DEBUG - (Capabilities) Platform 'Windows' not matched by plugin platform requirements
Sep 18, 2017 13:26:52.480 [15252] DEBUG - Completed: [127.0.0.1:52906] 200 GET / (16 live) GZIP 1ms 1408 bytes (pipelined: 1)
Sep 18, 2017 13:26:52.483 [15256] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Sep 18, 2017 13:26:52.483 [15256] DEBUG - Auth: authenticated user 1 as t-zone
Sep 18, 2017 13:26:52.483 [15256] DEBUG - Auth: Came in with a super-token, authorization succeeded.
Sep 18, 2017 13:26:52.483 [67940] DEBUG - Request: [192.168.0.107:52907 (Subnet)] GET / (16 live) TLS GZIP Signed-in Token (t-zone)
Sep 18, 2017 13:26:52.484 [67940] DEBUG - (Capabilities) Platform 'Windows' not matched by plugin platform requirements
Sep 18, 2017 13:26:52.486 [15252] DEBUG - Completed: [192.168.0.107:52907] 200 GET / (16 live) TLS GZIP 3ms 1408 bytes (pipelined: 1)
Sep 18, 2017 13:26:52.538 [15256] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Sep 18, 2017 13:26:52.538 [15256] DEBUG - Auth: authenticated user 1 as t-zone
Sep 18, 2017 13:26:52.538 [15256] DEBUG - Auth: Came in with a super-token, authorization succeeded.
Sep 18, 2017 13:26:52.538 [70648] DEBUG - Request: [24.11.97.82:52908 (WAN)] GET / (16 live) TLS GZIP Signed-in Token (t-zone)
Sep 18, 2017 13:26:52.538 [70648] DEBUG - (Capabilities) Platform 'Windows' not matched by plugin platform requirements
Sep 18, 2017 13:26:52.541 [15252] DEBUG - Completed: [24.11.97.82:52908] 200 GET / (16 live) TLS GZIP 2ms 1408 bytes (pipelined: 1)
Sep 18, 2017 13:26:52.897 [70648] DEBUG - Request: [192.168.0.100:33124 (Subnet)] OPTIONS /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=stopped&continuing=1&playQueueItemID=19636&time=840966&duration=1385600 (15 live) TLS GZIP Signed-in
Sep 18, 2017 13:26:52.898 [15256] DEBUG - Completed: [192.168.0.100:33124] 200 OPTIONS /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=stopped&continuing=1&playQueueItemID=19636&time=840966&duration=1385600 (15 live) TLS GZIP 1ms 547 bytes (pipelined: 2)
Sep 18, 2017 13:26:53.023 [15252] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Sep 18, 2017 13:26:53.023 [15252] DEBUG - Auth: authenticated user 1 as t-zone
Sep 18, 2017 13:26:53.024 [15252] DEBUG - Auth: Came in with a super-token, authorization succeeded.
Sep 18, 2017 13:26:53.024 [67940] DEBUG - Request: [127.0.0.1:52905 (Loopback)] GET /playQueues/314?repeat=0&own=0&includeChapters=1 (15 live) GZIP Signed-in Token (t-zone)
Sep 18, 2017 13:26:53.024 [67940] DEBUG - PlayQueue: total generated: 4, before cursor: 0, after cursor: 3
Sep 18, 2017 13:26:53.024 [67940] DEBUG - PlayQueue: No need to refresh, active window is operational.
Sep 18, 2017 13:26:53.029 [67940] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 18, 2017 13:26:53.029 [67940] DEBUG - Selecting best audio stream for part ID 99426 (autoselect: 1 language: en)
Sep 18, 2017 13:26:53.029 [67940] DEBUG - Audio Stream: 99574, Subtitle Stream: -1
Sep 18, 2017 13:26:53.029 [67940] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 18, 2017 13:26:53.029 [67940] DEBUG - Selecting best audio stream for part ID 99427 (autoselect: 1 language: en)
Sep 18, 2017 13:26:53.029 [67940] DEBUG - Audio Stream: 99578, Subtitle Stream: -1
Sep 18, 2017 13:26:53.030 [67940] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 18, 2017 13:26:53.030 [67940] DEBUG - Selecting best audio stream for part ID 105556 (autoselect: 1 language: en)
Sep 18, 2017 13:26:53.030 [67940] DEBUG - Audio Stream: 113003, Subtitle Stream: -1
Sep 18, 2017 13:26:53.031 [67940] DEBUG - We're going to try to auto-select an audio stream for account 1.
Sep 18, 2017 13:26:53.031 [67940] DEBUG - Selecting best audio stream for part ID 105557 (autoselect: 1 language: en)
Sep 18, 2017 13:26:53.031 [67940] DEBUG - Audio Stream: 113006, Subtitle Stream: -1
Sep 18, 2017 13:26:53.032 [15256] DEBUG - Completed: [127.0.0.1:52905] 200 GET /playQueues/314?repeat=0&own=0&includeChapters=1 (14 live) GZIP 8ms 2605 bytes (pipelined: 2)
Sep 18, 2017 13:26:53.069 [15256] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxxb93-4280-941a-3329873dced1), enabling token-based authentication.
Sep 18, 2017 13:26:53.069 [15256] DEBUG - Auth: authenticated user 1 as t-zone
Sep 18, 2017 13:26:53.069 [15256] DEBUG - Auth: Came in with a super-token, authorization succeeded.
Sep 18, 2017 13:26:53.069 [67940] DEBUG - Request: [192.168.0.100:33126 (Subnet)] GET /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=stopped&continuing=1&playQueueItemID=19636&time=840966&duration=1385600 (16 live) TLS GZIP Signed-in Token (t-zone)
Sep 18, 2017 13:26:53.069 [67940] DEBUG - Client [1bfu729jqwtphzvz2lc9yfq7] reporting timeline state stopped, progress of 840966/1385600ms for guid=, ratingKey=88565 url=, key=/library/metadata/88565, containerKey=, metadataId=88565
Sep 18, 2017 13:26:53.071 [15256] DEBUG - Failed to stream media, client probably disconnected: 10054 - An existing connection was forcibly closed by the remote host
Sep 18, 2017 13:26:53.071 [15256] DEBUG - Completed: [192.168.0.100:33018] 200 GET /library/parts/99426/1503706784/file.mp4 (16 live) TLS 305253ms 222674944 bytes (pipelined: 1)
Sep 18, 2017 13:26:53.077 [67940] DEBUG - Play progress on 88565 'The Name of the Show Here' - got played 840966 ms by account 1!
Sep 18, 2017 13:26:53.084 [67940] DEBUG - [Now] User is t-zone (ID: 1)
Sep 18, 2017 13:26:53.084 [67940] DEBUG - [Now] Device is Chromecast (Chromecast).
Sep 18, 2017 13:26:53.085 [67940] DEBUG - [Now] Profile is Chromecast
Sep 18, 2017 13:26:53.085 [67940] DEBUG - [Now] Updated play state for /library/metadata/88565.
Sep 18, 2017 13:26:53.085 [67940] DEBUG - Statistics: (56tz8220xld2ytir688p5sc3) Reporting active playback in state 3 of type 4 (scrobble: 0) for account 1
Sep 18, 2017 13:26:53.087 [15252] DEBUG - Completed: [192.168.0.100:33126] 200 GET /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=stopped&continuing=1&playQueueItemID=19636&time=840966&duration=1385600 (15 live) TLS GZIP 17ms 538 bytes (pipelined: 1)
Sep 18, 2017 13:26:53.089 [15252] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Sep 18, 2017 13:26:53.089 [15252] DEBUG - Auth: authenticated user 1 as t-zone
Sep 18, 2017 13:26:53.089 [15252] DEBUG - Auth: Came in with a super-token, authorization succeeded.


#2

Extended Reproduced Log 2) (See Sep 18, 2017 13:04:57.808 [15256])

> Sep 18, 2017 13:04:33.907 [15252] DEBUG - Completed: [192.168.0.100:32927] 200 GET /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=playing&playQueueItemID=19632&time=536665&duration=1385600 (29 live) TLS GZIP 10ms 538 bytes (pipelined: 1)
> Sep 18, 2017 13:04:38.292 [15252] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
> Sep 18, 2017 13:04:38.292 [15252] DEBUG - Auth: authenticated user 1 as t-zone
> Sep 18, 2017 13:04:38.292 [15252] DEBUG - Auth: Came in with a super-token, authorization succeeded.
> Sep 18, 2017 13:04:38.292 [67740] DEBUG - Request: [192.168.0.107:52180 (Subnet)] GET /status/sessions (30 live) TLS Signed-in Token (t-zone)
> Sep 18, 2017 13:04:38.292 [67740] DEBUG - [Now] Adding 1 sessions.
> Sep 18, 2017 13:04:38.292 [15256] DEBUG - Completed: [192.168.0.107:52180] 200 GET /status/sessions (30 live) TLS 2ms 3411 bytes (pipelined: 1)
> Sep 18, 2017 13:04:43.874 [69820] DEBUG - Request: [192.168.0.100:32927 (Subnet)] OPTIONS /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=playing&playQueueItemID=19632&time=546917&duration=1385600 (28 live) TLS GZIP Signed-in
> Sep 18, 2017 13:04:43.874 [15256] DEBUG - Completed: [192.168.0.100:32927] 200 OPTIONS /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=playing&playQueueItemID=19632&time=546917&duration=1385600 (27 live) TLS GZIP 1ms 547 bytes (pipelined: 2)
> Sep 18, 2017 13:04:43.889 [15256] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxxcbe-45bc-b8c8-794743924de6), enabling token-based authentication.
> Sep 18, 2017 13:04:43.889 [15256] DEBUG - Auth: authenticated user 1 as t-zone
> Sep 18, 2017 13:04:43.889 [15256] DEBUG - Auth: Came in with a super-token, authorization succeeded.
> Sep 18, 2017 13:04:43.889 [56720] DEBUG - Request: [192.168.0.100:32929 (Subnet)] GET /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=playing&playQueueItemID=19632&time=546917&duration=1385600 (28 live) TLS GZIP Signed-in Token (t-zone)
> Sep 18, 2017 13:04:43.889 [56720] DEBUG - Client [ms3inqfmcspzs6aljdj9wlgk] reporting timeline state playing, progress of 546917/1385600ms for guid=, ratingKey=88565 url=, key=/library/metadata/88565, containerKey=, metadataId=88565
> Sep 18, 2017 13:04:43.905 [56720] DEBUG - Play progress on 88565 'The Name of the Show Here' - got played 546917 ms by account 1!
> Sep 18, 2017 13:04:43.905 [56720] DEBUG - [Now] User is t-zone (ID: 1)
> Sep 18, 2017 13:04:43.905 [56720] DEBUG - [Now] Device is Chromecast (Chromecast).
> Sep 18, 2017 13:04:43.905 [56720] DEBUG - [Now] Profile is Chromecast
> Sep 18, 2017 13:04:43.905 [56720] DEBUG - [Now] Updated play state for /library/metadata/88565.
> Sep 18, 2017 13:04:43.905 [56720] DEBUG - Statistics: (56tz8220xld2ytir688p5sc3) Reporting active playback in state 0 of type 4 (scrobble: 0) for account 1
> Sep 18, 2017 13:04:43.912 [15252] DEBUG - Completed: [192.168.0.100:32929] 200 GET /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=playing&playQueueItemID=19632&time=546917&duration=1385600 (28 live) TLS GZIP 10ms 538 bytes (pipelined: 1)
> Sep 18, 2017 13:04:48.299 [15252] DEBUG - handleStreamRead code 335544539: short read
> Sep 18, 2017 13:04:48.367 [15256] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
> Sep 18, 2017 13:04:48.367 [15256] DEBUG - Auth: authenticated user 1 as t-zone
> Sep 18, 2017 13:04:48.367 [15256] DEBUG - Auth: Came in with a super-token, authorization succeeded.
> Sep 18, 2017 13:04:48.367 [67740] DEBUG - Request: [192.168.0.104:39466 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=1&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (28 live) TLS GZIP Signed-in Token (t-zone)
> Sep 18, 2017 13:04:48.367 [67740] DEBUG - Beginning read from two-way stream.
> Sep 18, 2017 13:04:49.268 [69820] DEBUG - Request: [192.168.0.100:32929 (Subnet)] OPTIONS /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=paused&playQueueItemID=19632&time=552162&duration=1385600 (27 live) TLS GZIP Signed-in
> Sep 18, 2017 13:04:49.268 [15256] DEBUG - Completed: [192.168.0.100:32929] 200 OPTIONS /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=paused&playQueueItemID=19632&time=552162&duration=1385600 (26 live) TLS GZIP 1ms 547 bytes (pipelined: 2)
> Sep 18, 2017 13:04:49.388 [15256] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxxcbe-45bc-b8c8-794743924de6), enabling token-based authentication.
> Sep 18, 2017 13:04:49.388 [15256] DEBUG - Auth: authenticated user 1 as t-zone
> Sep 18, 2017 13:04:49.388 [15256] DEBUG - Auth: Came in with a super-token, authorization succeeded.
> Sep 18, 2017 13:04:49.388 [56720] DEBUG - Request: [192.168.0.100:32930 (Subnet)] GET /photo/:/transcode?url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F88565%2Fthumb%2F1505452111%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxxcbe-45bc-b8c8-794743924de6&width=374&height=210&minSize=1 (27 live) TLS GZIP Signed-in Token (t-zone)
> Sep 18, 2017 13:04:49.389 [56720] DEBUG - Photo transcoder: Request for url [127.0.0.1:32400/library/metadata/88565/thumb/1505452111?X-Plex-Token=xxxxxxxxxxxxxxxxxxxxcbe-45bc-b8c8-794743924de6] (is local: 1 upscaled: 0)
> Sep 18, 2017 13:04:49.389 [15252] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxxcbe-45bc-b8c8-794743924de6), enabling token-based authentication.
> Sep 18, 2017 13:04:49.389 [15252] DEBUG - Auth: authenticated user 1 as t-zone
> Sep 18, 2017 13:04:49.389 [15252] DEBUG - Auth: Came in with a super-token, authorization succeeded.
> Sep 18, 2017 13:04:49.389 [69820] DEBUG - Request: [192.168.0.100:32931 (Subnet)] GET /photo/:/transcode?url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F88566%2Fthumb%2F1505452111%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxxcbe-45bc-b8c8-794743924de6&width=587&height=330&minSize=1 (27 live) TLS GZIP Signed-in Token (t-zone)
> Sep 18, 2017 13:04:49.389 [69820] DEBUG - Photo transcoder: Request for url [127.0.0.1:32400/library/metadata/88566/thumb/1505452111?X-Plex-Token=xxxxxxxxxxxxxxxxxxxxcbe-45bc-b8c8-794743924de6] (is local: 1 upscaled: 0)
> Sep 18, 2017 13:04:49.391 [56720] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxxcbe-45bc-b8c8-794743924de6), enabling token-based authentication.
> Sep 18, 2017 13:04:49.391 [69820] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxxcbe-45bc-b8c8-794743924de6), enabling token-based authentication.
> Sep 18, 2017 13:04:49.391 [56720] DEBUG - Auth: authenticated user 1 as t-zone
> Sep 18, 2017 13:04:49.391 [69820] DEBUG - Auth: authenticated user 1 as t-zone
> Sep 18, 2017 13:04:49.391 [56720] DEBUG - Auth: Came in with a super-token, authorization succeeded.
> Sep 18, 2017 13:04:49.391 [69820] DEBUG - Auth: Came in with a super-token, authorization succeeded.
> Sep 18, 2017 13:04:49.393 [69820] DEBUG - Computed media url for item 88566: http://127.0.0.1:51363/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethetvdb%3A%2F%2F275274%2F3%2F5%3Flang%3Den&mediaType=4&url=metadata%3A%2F%2Fseasons%2F3%2Fepisodes%2F5%2Fthumbs%2Fcom%2Eplexapp%2Eagents%2Ethetvdb_e8014817d8b96c2fe4887cab82bd419cf9fbba39
> Sep 18, 2017 13:04:49.393 [69820] DEBUG - HTTP requesting GET http://127.0.0.1:51363/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethetvdb%3A%2F%2F275274%2F3%2F5%3Flang%3Den&mediaType=4&url=metadata%3A%2F%2Fseasons%2F3%2Fepisodes%2F5%2Fthumbs%2Fcom%2Eplexapp%2Eagents%2Ethetvdb_e8014817d8b96c2fe4887cab82bd419cf9fbba39
> Sep 18, 2017 13:04:49.393 [56720] DEBUG - Computed media url for item 88565: http://127.0.0.1:51363/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethetvdb%3A%2F%2F275274%2F3%2F4%3Flang%3Den&mediaType=4&url=metadata%3A%2F%2Fseasons%2F3%2Fepisodes%2F4%2Fthumbs%2Fcom%2Eplexapp%2Eagents%2Ethetvdb_560cda5524c79eeb6d01a7567bbd6082c697e325
> Sep 18, 2017 13:04:49.393 [56720] DEBUG - HTTP requesting GET http://127.0.0.1:51363/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethetvdb%3A%2F%2F275274%2F3%2F4%3Flang%3Den&mediaType=4&url=metadata%3A%2F%2Fseasons%2F3%2Fepisodes%2F4%2Fthumbs%2Fcom%2Eplexapp%2Eagents%2Ethetvdb_560cda5524c79eeb6d01a7567bbd6082c697e325
> Sep 18, 2017 13:04:49.399 [69820] DEBUG - HTTP 200 response from GET http://127.0.0.1:51363/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethetvdb%3A%2F%2F275274%2F3%2F5%3Flang%3Den&mediaType=4&url=metadata%3A%2F%2Fseasons%2F3%2Fepisodes%2F5%2Fthumbs%2Fcom%2Eplexapp%2Eagents%2Ethetvdb_e8014817d8b96c2fe4887cab82bd419cf9fbba39
> Sep 18, 2017 13:04:49.400 [69820] DEBUG - Calculated media file path for item 88566: C:\Users\T-Zone\Plex\Plex Media Server\Metadata\TV Shows\6\2795a893b19b16d1d7da4d57e1635da65fe3ddb.bundle\Contents\_combined/seasons/3/episodes/5/thumbs/com.plexapp.agents.thetvdb_e8014817d8b96c2fe4887cab82bd419cf9fbba39
> Sep 18, 2017 13:04:49.400 [56720] DEBUG - HTTP 200 response from GET http://127.0.0.1:51363/system/agents/media/get?guid=com%2Eplexapp%2Eagents%2Ethetvdb%3A%2F%2F275274%2F3%2F4%3Flang%3Den&mediaType=4&url=metadata%3A%2F%2Fseasons%2F3%2Fepisodes%2F4%2Fthumbs%2Fcom%2Eplexapp%2Eagents%2Ethetvdb_560cda5524c79eeb6d01a7567bbd6082c697e325
> Sep 18, 2017 13:04:49.400 [56720] DEBUG - Calculated media file path for item 88565: C:\Users\T-Zone\Plex\Plex Media Server\Metadata\TV Shows\6\2795a893b19b16d1d7da4d57e1635da65fe3ddb.bundle\Contents\_combined/seasons/3/episodes/4/thumbs/com.plexapp.agents.thetvdb_560cda5524c79eeb6d01a7567bbd6082c697e325
> Sep 18, 2017 13:04:49.400 [69820] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
> Sep 18, 2017 13:04:49.400 [56720] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
> Sep 18, 2017 13:04:49.400 [69820] DEBUG - Photo cache obtained 41886 bytes from http://127.0.0.1:32400/library/metadata/88566/thumb/1505452111?X-Plex-Token=xxxxxxxxxxxxxxxxxxxxcbe-45bc-b8c8-794743924de6
> Sep 18, 2017 13:04:49.400 [69820] DEBUG - Saving original media file to C:\Users\T-Zone\Plex\Plex Media Server\Cache\PhotoTranscoder\47\47f6ed3d98528e6fa6d095ef6b1744d08cfe90d9.jpg
> Sep 18, 2017 13:04:49.400 [56720] DEBUG - Photo cache obtained 19275 bytes from http://127.0.0.1:32400/library/metadata/88565/thumb/1505452111?X-Plex-Token=xxxxxxxxxxxxxxxxxxxxcbe-45bc-b8c8-794743924de6
> Sep 18, 2017 13:04:49.400 [56720] DEBUG - Saving original media file to C:\Users\T-Zone\Plex\Plex Media Server\Cache\PhotoTranscoder\3b\3b2692867b04b69abeb59486ffc2e60ce381c3ac.jpg
> Sep 18, 2017 13:04:49.404 [69820] DEBUG - Created thumbnail of size 400x225, has pixels: 1
> Sep 18, 2017 13:04:49.405 [56720] DEBUG - Created thumbnail of size 374x210, has pixels: 1
> Sep 18, 2017 13:04:49.411 [15256] DEBUG - Completed: [192.168.0.100:32930] 200 GET /photo/:/transcode?url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F88565%2Fthumb%2F1505452111%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxxcbe-45bc-b8c8-794743924de6&width=374&height=210&minSize=1 (27 live) TLS GZIP 22ms 16113 bytes (pipelined: 1)
> Sep 18, 2017 13:04:49.411 [15252] DEBUG - Completed: [192.168.0.100:32931] 200 GET /photo/:/transcode?url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F88566%2Fthumb%2F1505452111%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxxcbe-45bc-b8c8-794743924de6&width=587&height=330&minSize=1 (27 live) TLS GZIP 22ms 27828 bytes (pipelined: 1)
> Sep 18, 2017 13:04:49.463 [15252] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxxcbe-45bc-b8c8-794743924de6), enabling token-based authentication.
> Sep 18, 2017 13:04:49.463 [15252] DEBUG - Auth: authenticated user 1 as t-zone
> Sep 18, 2017 13:04:49.463 [15252] DEBUG - Auth: Came in with a super-token, authorization succeeded.
> Sep 18, 2017 13:04:49.463 [69820] DEBUG - Request: [192.168.0.100:32933 (Subnet)] GET /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=paused&playQueueItemID=19632&time=552162&duration=1385600 (29 live) TLS GZIP Signed-in Token (t-zone)
> Sep 18, 2017 13:04:49.463 [69820] DEBUG - Client [ms3inqfmcspzs6aljdj9wlgk] reporting timeline state paused, progress of 552162/1385600ms for guid=, ratingKey=88565 url=, key=/library/metadata/88565, containerKey=, metadataId=88565
> Sep 18, 2017 13:04:49.466 [69820] DEBUG - Play progress on 88565 'The Name of the Show HEre' - got played 552162 ms by account 1!
> Sep 18, 2017 13:04:49.471 [69820] DEBUG - [Now] User is t-zone (ID: 1)
> Sep 18, 2017 13:04:49.472 [69820] DEBUG - [Now] Device is Chromecast (Chromecast).
> Sep 18, 2017 13:04:49.472 [69820] DEBUG - [Now] Profile is Chromecast
> Sep 18, 2017 13:04:49.472 [69820] DEBUG - [Now] Updated play state for /library/metadata/88565.
> Sep 18, 2017 13:04:49.472 [69820] DEBUG - Statistics: (56tz8220xld2ytir688p5sc3) Reporting active playback in state 1 of type 4 (scrobble: 0) for account 1
> Sep 18, 2017 13:04:49.475 [15252] DEBUG - Completed: [192.168.0.100:32933] 200 GET /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=paused&playQueueItemID=19632&time=552162&duration=1385600 (29 live) TLS GZIP 11ms 538 bytes (pipelined: 1)
> Sep 18, 2017 13:04:49.716 [15256] DEBUG - handleStreamRead code 2: End of file
> Sep 18, 2017 13:04:49.716 [15252] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
> Sep 18, 2017 13:04:49.716 [15252] DEBUG - Auth: authenticated user 1 as t-zone
> Sep 18, 2017 13:04:49.716 [15252] DEBUG - Auth: Came in with a super-token, authorization succeeded.
> Sep 18, 2017 13:04:49.716 [69820] DEBUG - Request: [127.0.0.1:52184 (Loopback)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=1&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (29 live) GZIP Signed-in Token (t-zone)
> Sep 18, 2017 13:04:49.716 [69820] DEBUG - Beginning read from two-way stream.
> Sep 18, 2017 13:04:52.474 [15252] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
> Sep 18, 2017 13:04:52.474 [15252] DEBUG - Auth: authenticated user 1 as t-zone
> Sep 18, 2017 13:04:52.474 [15252] DEBUG - Auth: Came in with a super-token, authorization succeeded.
> Sep 18, 2017 13:04:52.474 [56720] DEBUG - Request: [127.0.0.1:52185 (Loopback)] GET / (28 live) GZIP Signed-in Token (t-zone)
> Sep 18, 2017 13:04:52.474 [56720] DEBUG - (Capabilities) Platform 'Windows' not matched by plugin platform requirements
> Sep 18, 2017 13:04:52.474 [15256] DEBUG - Completed: [127.0.0.1:52185] 200 GET / (28 live) GZIP 1ms 1409 bytes (pipelined: 1)
> Sep 18, 2017 13:04:52.474 [15252] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
> Sep 18, 2017 13:04:52.474 [15252] DEBUG - Auth: authenticated user 1 as t-zone
> Sep 18, 2017 13:04:52.474 [15252] DEBUG - Auth: Came in with a super-token, authorization succeeded.
> Sep 18, 2017 13:04:52.474 [67740] DEBUG - Request: [192.168.0.107:52186 (Subnet)] GET / (28 live) TLS GZIP Signed-in Token (t-zone)
> Sep 18, 2017 13:04:52.474 [67740] DEBUG - (Capabilities) Platform 'Windows' not matched by plugin platform requirements
> Sep 18, 2017 13:04:52.474 [15256] DEBUG - Completed: [192.168.0.107:52186] 200 GET / (28 live) TLS GZIP 3ms 1409 bytes (pipelined: 1)
> Sep 18, 2017 13:04:52.537 [15252] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
> Sep 18, 2017 13:04:52.537 [15252] DEBUG - Auth: authenticated user 1 as t-zone
> Sep 18, 2017 13:04:52.537 [15252] DEBUG - Auth: Came in with a super-token, authorization succeeded.
> Sep 18, 2017 13:04:52.537 [69820] DEBUG - Request: [24.11.97.82:52187 (WAN)] GET / (28 live) TLS GZIP Signed-in Token (t-zone)
> Sep 18, 2017 13:04:52.537 [69820] DEBUG - (Capabilities) Platform 'Windows' not matched by plugin platform requirements
> Sep 18, 2017 13:04:52.537 [15256] DEBUG - Completed: [24.11.97.82:52187] 200 GET / (28 live) TLS GZIP 2ms 1409 bytes (pipelined: 1)
> Sep 18, 2017 13:04:53.874 [15256] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxxcbe-45bc-b8c8-794743924de6), enabling token-based authentication.
> Sep 18, 2017 13:04:53.874 [15256] DEBUG - Auth: authenticated user 1 as t-zone
> Sep 18, 2017 13:04:53.874 [15256] DEBUG - Auth: Came in with a super-token, authorization succeeded.
> Sep 18, 2017 13:04:53.874 [69820] DEBUG - Request: [192.168.0.100:32931 (Subnet)] GET /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=paused&playQueueItemID=19632&time=552162&duration=1385600 (24 live) TLS GZIP Signed-in Token (t-zone)
> Sep 18, 2017 13:04:53.874 [69820] DEBUG - Client [ms3inqfmcspzs6aljdj9wlgk] reporting timeline state paused, progress of 552162/1385600ms for guid=, ratingKey=88565 url=, key=/library/metadata/88565, containerKey=, metadataId=88565
> Sep 18, 2017 13:04:53.874 [69820] DEBUG - Play progress on 88565 'The Name of the Show Here' - got played 552162 ms by account 1!
> Sep 18, 2017 13:04:53.890 [69820] DEBUG - [Now] User is t-zone (ID: 1)
> Sep 18, 2017 13:04:53.890 [69820] DEBUG - [Now] Device is Chromecast (Chromecast).
> Sep 18, 2017 13:04:53.890 [69820] DEBUG - [Now] Profile is Chromecast
> Sep 18, 2017 13:04:53.890 [69820] DEBUG - [Now] Updated play state for /library/metadata/88565.
> Sep 18, 2017 13:04:53.890 [69820] DEBUG - Statistics: (56tz8220xld2ytir688p5sc3) Reporting active playback in state 1 of type 4 (scrobble: 0) for account 1
> Sep 18, 2017 13:04:53.890 [15256] DEBUG - Completed: [192.168.0.100:32931] 200 GET /:/timeline?hasMDE=1&ratingKey=88565&key=%2Flibrary%2Fmetadata%2F88565&state=paused&playQueueItemID=19632&time=552162&duration=1385600 (24 live) TLS GZIP 10ms 538 bytes (pipelined: 3)
> Sep 18, 2017 13:04:55.409 [15252] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
> Sep 18, 2017 13:04:55.409 [15252] DEBUG - Auth: authenticated user 1 as t-zone
> Sep 18, 2017 13:04:55.409 [15252] DEBUG - Auth: Came in with a super-token, authorization succeeded.
> Sep 18, 2017 13:04:55.410 [56720] DEBUG - Request: [127.0.0.1:52188 (Loopback)] GET /clients (25 live) GZIP Signed-in Token (t-zone)
> Sep 18, 2017 13:04:55.410 [15256] DEBUG - Completed: [127.0.0.1:52188] 200 GET /clients (25 live) GZIP 0ms 754 bytes (pipelined: 1)
> **Sep 18, 2017 13:04:57.808 [15256] DEBUG - Failed to stream media, client probably disconnected: 10054 - An existing connection was forcibly closed by the remote host**
> Sep 18, 2017 13:04:57.808 [15256] DEBUG - Completed: [192.168.0.100:32844] 200 GET /library/parts/99426/1503706784/file.mp4 (25 live) TLS 258000ms 217874432 bytes (pipelined: 1)
> **Sep 18, 2017 13:04:57.821 [67740] WARN - [Chromecast] Sender disconnected. Reason: requested_by_sender. Total number of senders: 0**
> Sep 18, 2017 13:04:58.911 [15252] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
> Sep 18, 2017 13:04:58.911 [15252] DEBUG - Auth: authenticated user 1 as t-zone
> Sep 18, 2017 13:04:58.911 [15252] DEBUG - Auth: Came in with a super-token, authorization succeeded.
> Sep 18, 2017 13:04:58.911 [69820] DEBUG - Request: [127.0.0.1:52185 (Loopback)] GET /library/sections (22 live) Page 0-49 GZIP Signed-in Token (t-zone)
> Sep 18, 2017 13:04:58.912 [69820] DEBUG - Setting container serialization range to [0, 49] (total=-1)
> Sep 18, 2017 13:04:58.913 [15256] DEBUG - Completed: [127.0.0.1:52185] 200 GET /library/sections (22 live) GZIP Page 0-49 2ms 1461 bytes (pipelined: 2)
> Sep 18, 2017 13:04:58.937 [56720] DEBUG - Request: [127.0.0.1:52185 (Loopback)] GET /identity (22 live) GZIP Signed-in
> Sep 18, 2017 13:04:58.938 [15252] DEBUG - Completed: [127.0.0.1:52185] 200 GET /identity (22 live) GZIP 0ms 470 bytes (pipelined: 3)

#3

Yes. I've seen this too. I have several friends and family using Plex and recently (2-3 months ago till now) they have reported me that Plex stops after more or less 10 minutes with Chromecast. Every other device works without problems.

This doesn't happen always, but it's always with Chromecast.

I've searched in these forums without any answer, even posting logs. Dunno what to do.


#4

Well i got a Nvidia shield and abandoned the Chromecasts for the time being. No problems with it :)


#5

I have this exact issue. Either the first show plays fine and the next episode does this after around 10 seconds or it just refuses to play with the same issue. I'm using a local media server via my lan and playing through Amazon Fire TV 4K via ethernet cables. My old Amazon Fire TV box worked fine but the new one just doesn't do it right. I try and find advanced settings on the app but get nowhere fast. Anyone found a fix yet?