Apr 08, 2018 15:58:21.592 [5048] DEBUG - Codecs: testing aac_mf (encoder)

Apr 08, 2018 15:58:21.593 [5048] DEBUG - MDE: Selected protocol dash; container: mp4

Apr 08, 2018 15:58:21.593 [5048] DEBUG - MDE: analyzing media item 687

Apr 08, 2018 15:58:21.593 [5048] DEBUG - MDE: Ant-Man (2015): Direct Play is disabled

Apr 08, 2018 15:58:21.593 [5048] DEBUG - MDE: Ant-Man (2015): media must be transcoded in order to use the dash protocol

Apr 08, 2018 15:58:21.593 [5048] DEBUG - MDE: Ant-Man (2015): no direct play video profile exists for http/mkv/h264

Apr 08, 2018 15:58:21.593 [5048] DEBUG - MDE: Ant-Man (2015): no direct play video profile exists for http/mkv/h264/dca

Apr 08, 2018 15:58:21.593 [5048] DEBUG - Codecs: testing aac_mf (encoder)

Apr 08, 2018 15:58:21.593 [5048] DEBUG - MDE: Cannot direct stream audio stream due to codec dca when profile only allows aac

Apr 08, 2018 15:58:21.593 [5048] DEBUG - MDE: Ant-Man (2015): selected media 0 / 687

Apr 08, 2018 15:58:21.593 [5048] DEBUG - Streaming Resource: Added session 0xa3929f8:4n5yzt40h8mz6ug1lg2cek07

Apr 08, 2018 15:58:21.593 [5048] DEBUG - Streaming Resource: Reached Decision id=618 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=1001,Direct play not available; Conversion OK.) media=(id=687 part=(id=687 decision=transcode container=mp4 protocol=dash streams=(Video=(id=1427 decision=copy width=1920 height=1036) Audio=(id=1428 decision=transcode bitrate=256 encoder=aac_mf channels=2 rate=48000))))

Apr 08, 2018 15:58:21.595 [3188] DEBUG - Completed: [127.0.0.1:53043] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F618&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=ozd5tqun6pjpo49pmhbspjdp&subtitles=burn&Accept-Language=en (5 live) GZIP 12ms 4751 bytes (pipelined: 2)

Apr 08, 2018 15:58:21.635 [3188] DEBUG - Completed: [127.0.0.1:53044] 200 GET /photo/:/transcode?width=1920&height=1080&opacity=30&background=36383b&minSize=1&url=%2Flibrary%2Fmetadata%2F618%2Fart%2F1523221017%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (5 live) GZIP 163ms 47108 bytes (pipelined: 1)

Apr 08, 2018 15:58:21.642 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:21.643 [0508] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F618&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=ozd5tqun6pjpo49pmhbspjdp&subtitles=burn&Accept-Language=en (5 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:21.643 [0508] DEBUG - Found session GUID of ozd5tqun6pjpo49pmhbspjdp in session start.

Apr 08, 2018 15:58:21.643 [0508] DEBUG - TranscodeUniversalRequest: using profile Web

Apr 08, 2018 15:58:21.643 [0508] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/618?checkFiles=1&includeBandwidths=1&offset=-1&X-Plex-Session-Identifier=4n5yzt40h8mz6ug1lg2cek07

Apr 08, 2018 15:58:21.644 [0508] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:21.650 [0508] DEBUG - It took 0.000000 ms to retrieve 116 items.

Apr 08, 2018 15:58:21.653 [0508] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:21.653 [0508] DEBUG - Selecting best audio stream for part ID 687 (autoselect: 1 language: en)

Apr 08, 2018 15:58:21.653 [0508] DEBUG - Audio Stream: 1428, Subtitle Stream: -1

Apr 08, 2018 15:58:21.655 [0508] DEBUG - Found session GUID of ozd5tqun6pjpo49pmhbspjdp in session start.

Apr 08, 2018 15:58:21.655 [0508] DEBUG - Cleaning directory for session ozd5tqun6pjpo49pmhbspjdp ()

Apr 08, 2018 15:58:21.655 [0508] DEBUG - Starting a transcode session ozd5tqun6pjpo49pmhbspjdp at offset -1.0 (state=3)

Apr 08, 2018 15:58:21.657 [0508] INFO - CodecManager: obtaining decoder 'h264'

Apr 08, 2018 15:58:21.657 [0508] DEBUG - HTTP requesting GET https://plex.tv/api/codecs/h264_decoder?build=windows-i386&deviceId=6e8ce0c2-ac7b-4242-a726-7e7d97feaa73&oldestPreviousVersion=1%2E3%2E3%2E3148-b38628e&version=7814ac9-1285

Apr 08, 2018 15:58:22.204 [0508] DEBUG - HTTP 200 response from GET https://plex.tv/api/codecs/h264_decoder?build=windows-i386&deviceId=6e8ce0c2-ac7b-4242-a726-7e7d97feaa73&oldestPreviousVersion=1%2E3%2E3%2E3148-b38628e&version=7814ac9-1285

Apr 08, 2018 15:58:22.205 [0508] ERROR - Unexpected error configuring transcoder: boost::filesystem::unique_path: Keyset as registered is invalid

Apr 08, 2018 15:58:22.205 [0508] DEBUG - Streaming Resource: Terminated session 0xa3929f8:4n5yzt40h8mz6ug1lg2cek07 with reason Conversion failed. The transcoder crashed or failed to start up.

Apr 08, 2018 15:58:22.205 [12008] DEBUG - Killing job.

Apr 08, 2018 15:58:22.206 [12008] DEBUG - Job was already killed, not killing again.

Apr 08, 2018 15:58:22.206 [12008] DEBUG - Stopping transcode session ozd5tqun6pjpo49pmhbspjdp

Apr 08, 2018 15:58:22.206 [9092] DEBUG - Completed: [127.0.0.1:53044] 404 GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F618&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=ozd5tqun6pjpo49pmhbspjdp&subtitles=burn&Accept-Language=en (5 live) GZIP 563ms 379 bytes (pipelined: 2)

Apr 08, 2018 15:58:22.206 [5136] DEBUG - Cleaning directory for session ozd5tqun6pjpo49pmhbspjdp (C:\Users\Kurt Bowie\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-ozd5tqun6pjpo49pmhbspjdp-1b15af0c-f576-4442-82be-8644781191ba)

Apr 08, 2018 15:58:22.213 [12008] DEBUG - Transcoder: Cleaning old transcode directories.

Apr 08, 2018 15:58:22.214 [12008] DEBUG - Whacked session ozd5tqun6pjpo49pmhbspjdp, 0 remaining.

Apr 08, 2018 15:58:23.017 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:23.017 [5048] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F618&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=ozd5tqun6pjpo49pmhbspjdp&subtitles=burn&Accept-Language=en (5 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:23.017 [5048] DEBUG - Found session GUID of ozd5tqun6pjpo49pmhbspjdp in session start.

Apr 08, 2018 15:58:23.018 [5048] DEBUG - TranscodeUniversalRequest: using profile Web

Apr 08, 2018 15:58:23.018 [5048] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/618?checkFiles=1&includeBandwidths=1&offset=-1&X-Plex-Session-Identifier=4n5yzt40h8mz6ug1lg2cek07

Apr 08, 2018 15:58:23.018 [5048] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:23.021 [5048] DEBUG - It took 0.000000 ms to retrieve 116 items.

Apr 08, 2018 15:58:23.024 [5048] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:23.024 [5048] DEBUG - Selecting best audio stream for part ID 687 (autoselect: 1 language: en)

Apr 08, 2018 15:58:23.024 [5048] DEBUG - Audio Stream: 1428, Subtitle Stream: -1

Apr 08, 2018 15:58:23.025 [5048] WARN - Denying access to transcode of key /library/metadata/618 due to terminated session

Apr 08, 2018 15:58:23.025 [9092] DEBUG - Completed: [127.0.0.1:53044] 400 GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F618&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=ozd5tqun6pjpo49pmhbspjdp&subtitles=burn&Accept-Language=en (5 live) GZIP 8ms 384 bytes (pipelined: 3)

Apr 08, 2018 15:58:23.095 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:23.095 [2468] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F618&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=jnzgubcqrhr98ujebkejtoh2&subtitles=burn&Accept-Language=en (5 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:23.095 [2468] DEBUG - Found session GUID of jnzgubcqrhr98ujebkejtoh2 in session start.

Apr 08, 2018 15:58:23.096 [2468] DEBUG - TranscodeUniversalRequest: using profile Web

Apr 08, 2018 15:58:23.096 [2468] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/618?includeBandwidths=1&offset=-1&X-Plex-Session-Identifier=4n5yzt40h8mz6ug1lg2cek07

Apr 08, 2018 15:58:23.096 [2468] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:23.100 [2468] DEBUG - It took 0.000000 ms to retrieve 116 items.

Apr 08, 2018 15:58:23.101 [2468] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:23.101 [2468] DEBUG - Selecting best audio stream for part ID 687 (autoselect: 1 language: en)

Apr 08, 2018 15:58:23.101 [2468] DEBUG - Audio Stream: 1428, Subtitle Stream: -1

Apr 08, 2018 15:58:23.102 [2468] DEBUG - Scaled up video bitrate to 4275Kbps based on 1.500000x fudge factor.

Apr 08, 2018 15:58:23.103 [2468] DEBUG - Codecs: testing aac_mf (encoder)

Apr 08, 2018 15:58:23.103 [2468] DEBUG - MDE: Selected protocol dash; container: mp4

Apr 08, 2018 15:58:23.103 [2468] DEBUG - MDE: analyzing media item 687

Apr 08, 2018 15:58:23.103 [2468] DEBUG - MDE: Ant-Man (2015): Direct Play is disabled

Apr 08, 2018 15:58:23.103 [2468] DEBUG - MDE: Ant-Man (2015): media must be transcoded in order to use the dash protocol

Apr 08, 2018 15:58:23.103 [2468] DEBUG - MDE: Ant-Man (2015): no direct play video profile exists for http/mkv/h264

Apr 08, 2018 15:58:23.103 [2468] DEBUG - MDE: Ant-Man (2015): no direct play video profile exists for http/mkv/h264/dca

Apr 08, 2018 15:58:23.103 [2468] DEBUG - MDE: Ant-Man (2015): Direct Streaming is disabled, so video stream will be transcoded

Apr 08, 2018 15:58:23.103 [2468] DEBUG - MDE: Ant-Man (2015): no remuxable profile found, so video stream will be transcoded

Apr 08, 2018 15:58:23.103 [2468] DEBUG - MDE: Cannot direct stream video stream due to profile or setting limitations

Apr 08, 2018 15:58:23.103 [2468] DEBUG - Scaled up video bitrate to 4275Kbps based on 1.500000x fudge factor.

Apr 08, 2018 15:58:23.104 [2468] DEBUG - Codecs: testing aac_mf (encoder)

Apr 08, 2018 15:58:23.104 [2468] DEBUG - MDE: Cannot direct stream audio stream due to codec dca when profile only allows aac

Apr 08, 2018 15:58:23.104 [2468] DEBUG - MDE: Ant-Man (2015): selected media 0 / 687

Apr 08, 2018 15:58:23.104 [2468] DEBUG - Streaming Resource: Adding session 0xa392ad8:4n5yzt40h8mz6ug1lg2cek07 which is using transcoder slot. Used slots is now 1

Apr 08, 2018 15:58:23.104 [2468] DEBUG - Streaming Resource: Added session 0xa392ad8:4n5yzt40h8mz6ug1lg2cek07

Apr 08, 2018 15:58:23.104 [2468] DEBUG - Streaming Resource: Reached Decision id=618 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=1001,Direct play not available; Conversion OK.) media=(id=687 part=(id=687 decision=transcode container=mp4 protocol=dash streams=(Video=(id=1427 decision=transcode bitrate=4275 encoder=libx264 width=1920 height=1036) Audio=(id=1428 decision=transcode bitrate=256 encoder=aac_mf channels=2 rate=48000))))

Apr 08, 2018 15:58:23.105 [3188] DEBUG - Completed: [127.0.0.1:53044] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F618&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=jnzgubcqrhr98ujebkejtoh2&subtitles=burn&Accept-Language=en (5 live) GZIP 10ms 4745 bytes (pipelined: 4)

Apr 08, 2018 15:58:23.149 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:23.149 [0508] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F618&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=jnzgubcqrhr98ujebkejtoh2&subtitles=burn&Accept-Language=en (5 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:23.150 [0508] DEBUG - Found session GUID of jnzgubcqrhr98ujebkejtoh2 in session start.

Apr 08, 2018 15:58:23.150 [0508] DEBUG - TranscodeUniversalRequest: using profile Web

Apr 08, 2018 15:58:23.150 [0508] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/618?checkFiles=1&includeBandwidths=1&offset=-1&X-Plex-Session-Identifier=4n5yzt40h8mz6ug1lg2cek07

Apr 08, 2018 15:58:23.151 [0508] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:23.154 [0508] DEBUG - It took 0.000000 ms to retrieve 116 items.

Apr 08, 2018 15:58:23.157 [0508] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:23.157 [0508] DEBUG - Selecting best audio stream for part ID 687 (autoselect: 1 language: en)

Apr 08, 2018 15:58:23.157 [0508] DEBUG - Audio Stream: 1428, Subtitle Stream: -1

Apr 08, 2018 15:58:23.158 [0508] DEBUG - Found session GUID of jnzgubcqrhr98ujebkejtoh2 in session start.

Apr 08, 2018 15:58:23.158 [0508] DEBUG - Cleaning directory for session jnzgubcqrhr98ujebkejtoh2 ()

Apr 08, 2018 15:58:23.158 [0508] DEBUG - Starting a transcode session jnzgubcqrhr98ujebkejtoh2 at offset -1.0 (state=3)

Apr 08, 2018 15:58:23.160 [0508] INFO - CodecManager: obtaining decoder 'h264'

Apr 08, 2018 15:58:23.160 [0508] DEBUG - HTTP requesting GET https://plex.tv/api/codecs/h264_decoder?build=windows-i386&deviceId=6e8ce0c2-ac7b-4242-a726-7e7d97feaa73&oldestPreviousVersion=1%2E3%2E3%2E3148-b38628e&version=7814ac9-1285

Apr 08, 2018 15:58:23.675 [0508] DEBUG - HTTP 200 response from GET https://plex.tv/api/codecs/h264_decoder?build=windows-i386&deviceId=6e8ce0c2-ac7b-4242-a726-7e7d97feaa73&oldestPreviousVersion=1%2E3%2E3%2E3148-b38628e&version=7814ac9-1285

Apr 08, 2018 15:58:23.676 [0508] ERROR - Unexpected error configuring transcoder: boost::filesystem::unique_path: Keyset as registered is invalid

Apr 08, 2018 15:58:23.676 [0508] DEBUG - Streaming Resource: Terminating session 0xa392ad8:4n5yzt40h8mz6ug1lg2cek07 which is using transcoder slot. Used slots is now 0

Apr 08, 2018 15:58:23.676 [0508] DEBUG - Streaming Resource: Terminated session 0xa392ad8:4n5yzt40h8mz6ug1lg2cek07 with reason Conversion failed. The transcoder crashed or failed to start up.

Apr 08, 2018 15:58:23.676 [12008] DEBUG - Killing job.

Apr 08, 2018 15:58:23.676 [12008] DEBUG - Job was already killed, not killing again.

Apr 08, 2018 15:58:23.676 [12008] DEBUG - Stopping transcode session jnzgubcqrhr98ujebkejtoh2

Apr 08, 2018 15:58:23.676 [5136] DEBUG - Cleaning directory for session ozd5tqun6pjpo49pmhbspjdp (C:\Users\Kurt Bowie\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-ozd5tqun6pjpo49pmhbspjdp-1b15af0c-f576-4442-82be-8644781191ba)

Apr 08, 2018 15:58:23.677 [5136] DEBUG - Cleaning directory for session jnzgubcqrhr98ujebkejtoh2 (C:\Users\Kurt Bowie\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-jnzgubcqrhr98ujebkejtoh2-523def2e-022b-41d0-9b86-da9d6074d04a)

Apr 08, 2018 15:58:23.677 [9092] DEBUG - Completed: [127.0.0.1:53044] 404 GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F618&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=jnzgubcqrhr98ujebkejtoh2&subtitles=burn&Accept-Language=en (5 live) GZIP 527ms 379 bytes (pipelined: 5)

Apr 08, 2018 15:58:23.684 [12008] DEBUG - Transcoder: Cleaning old transcode directories.

Apr 08, 2018 15:58:23.685 [12008] DEBUG - Whacked session jnzgubcqrhr98ujebkejtoh2, 0 remaining.

Apr 08, 2018 15:58:24.357 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:24.357 [5048] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F618&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=jnzgubcqrhr98ujebkejtoh2&subtitles=burn&Accept-Language=en (5 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:24.357 [5048] DEBUG - Found session GUID of jnzgubcqrhr98ujebkejtoh2 in session start.

Apr 08, 2018 15:58:24.358 [5048] DEBUG - TranscodeUniversalRequest: using profile Web

Apr 08, 2018 15:58:24.358 [5048] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/618?checkFiles=1&includeBandwidths=1&offset=-1&X-Plex-Session-Identifier=4n5yzt40h8mz6ug1lg2cek07

Apr 08, 2018 15:58:24.358 [5048] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:24.361 [5048] DEBUG - It took 0.000000 ms to retrieve 116 items.

Apr 08, 2018 15:58:24.364 [5048] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:24.364 [5048] DEBUG - Selecting best audio stream for part ID 687 (autoselect: 1 language: en)

Apr 08, 2018 15:58:24.364 [5048] DEBUG - Audio Stream: 1428, Subtitle Stream: -1

Apr 08, 2018 15:58:24.366 [5048] WARN - Denying access to transcode of key /library/metadata/618 due to terminated session

Apr 08, 2018 15:58:24.366 [3188] DEBUG - Completed: [127.0.0.1:53044] 400 GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F618&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=jnzgubcqrhr98ujebkejtoh2&subtitles=burn&Accept-Language=en (5 live) GZIP 9ms 384 bytes (pipelined: 6)

Apr 08, 2018 15:58:24.407 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:24.408 [2468] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /:/timeline?ratingKey=618&key=%2Flibrary%2Fmetadata%2F618&playQueueItemID=6603&state=error&hasMDE=1&time=0&duration=7026000 (5 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:24.408 [2468] DEBUG - Client [4n5yzt40h8mz6ug1lg2cek07] reporting timeline state error, progress of 0/7026000ms for guid=, ratingKey=618 url=, key=/library/metadata/618, containerKey=, metadataId=618

Apr 08, 2018 15:58:24.411 [2468] DEBUG - It took 0.000000 ms to retrieve 116 items.

Apr 08, 2018 15:58:24.416 [2468] DEBUG - It took 0.000000 ms to retrieve 116 items.

Apr 08, 2018 15:58:24.418 [2468] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:24.418 [2468] DEBUG - Selecting best audio stream for part ID 687 (autoselect: 1 language: en)

Apr 08, 2018 15:58:24.418 [2468] DEBUG - Audio Stream: 1428, Subtitle Stream: -1

Apr 08, 2018 15:58:24.419 [2468] DEBUG - [Now] User is kbowie (ID: 1)

Apr 08, 2018 15:58:24.420 [2468] DEBUG - [Now] Device is Chrome (Chrome).

Apr 08, 2018 15:58:24.420 [2468] DEBUG - [Now] Profile is Web

Apr 08, 2018 15:58:24.420 [2468] DEBUG - [Now] Updated play state for /library/metadata/618.

Apr 08, 2018 15:58:24.421 [2468] DEBUG - Statistics: (0ac6i7pt0hdbvkj476re8kt9) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 1

Apr 08, 2018 15:58:24.422 [9092] DEBUG - Completed: [127.0.0.1:53044] 200 GET /:/timeline?ratingKey=618&key=%2Flibrary%2Fmetadata%2F618&playQueueItemID=6603&state=error&hasMDE=1&time=0&duration=7026000 (5 live) GZIP 14ms 524 bytes (pipelined: 7)

Apr 08, 2018 15:58:24.429 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:24.430 [0508] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /status/sessions (5 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:24.432 [0508] DEBUG - [Now] Adding 1 sessions.

Apr 08, 2018 15:58:24.432 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:24.433 [5048] DEBUG - Request: [127.0.0.1:53043 (Loopback)] GET /playQueues/234?repeat=0&own=1&includeChapters=1&includeGeolocation=1 (5 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:24.433 [5048] DEBUG - PlayQueue: total generated: 1, before cursor: 0, after cursor: 0

Apr 08, 2018 15:58:24.433 [5048] DEBUG - PlayQueue: No need to refresh, active window is operational.

Apr 08, 2018 15:58:24.435 [9092] DEBUG - Completed: [127.0.0.1:53044] 200 GET /status/sessions (5 live) GZIP 5ms 5083 bytes (pipelined: 8)

Apr 08, 2018 15:58:24.437 [5048] DEBUG - It took 0.000000 ms to retrieve 116 items.

Apr 08, 2018 15:58:24.437 [5048] DEBUG - PlayQueue: Client 0ac6i7pt0hdbvkj476re8kt9 requested ownership of play queue 234, but already had it.

Apr 08, 2018 15:58:24.438 [5048] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:24.438 [5048] DEBUG - Selecting best audio stream for part ID 687 (autoselect: 1 language: en)

Apr 08, 2018 15:58:24.438 [5048] DEBUG - Audio Stream: 1428, Subtitle Stream: -1

Apr 08, 2018 15:58:24.439 [9092] DEBUG - Completed: [127.0.0.1:53043] 200 GET /playQueues/234?repeat=0&own=1&includeChapters=1&includeGeolocation=1 (5 live) GZIP 6ms 5499 bytes (pipelined: 3)

Apr 08, 2018 15:58:24.475 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:24.475 [2468] DEBUG - Request: [127.0.0.1:53043 (Loopback)] GET /:/timeline?ratingKey=618&key=%2Flibrary%2Fmetadata%2F618&playQueueItemID=6603&state=stopped&hasMDE=1&time=0&duration=7026000 (5 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:24.476 [2468] DEBUG - Client [4n5yzt40h8mz6ug1lg2cek07] reporting timeline state stopped, progress of 0/7026000ms for guid=, ratingKey=618 url=, key=/library/metadata/618, containerKey=, metadataId=618

Apr 08, 2018 15:58:24.479 [2468] DEBUG - It took 0.000000 ms to retrieve 116 items.

Apr 08, 2018 15:58:24.483 [2468] DEBUG - [Now] User is kbowie (ID: 1)

Apr 08, 2018 15:58:24.483 [2468] DEBUG - [Now] Device is Chrome (Chrome).

Apr 08, 2018 15:58:24.484 [2468] DEBUG - [Now] Profile is Web

Apr 08, 2018 15:58:24.484 [2468] DEBUG - [Now] Updated play state for /library/metadata/618.

Apr 08, 2018 15:58:24.484 [2468] DEBUG - Statistics: (0ac6i7pt0hdbvkj476re8kt9) Reporting active playback in state 3 of type 1 (scrobble: 0) for account 1

Apr 08, 2018 15:58:24.485 [2468] DEBUG - Streaming Resource: Removing session 0xa392ad8:4n5yzt40h8mz6ug1lg2cek07

Apr 08, 2018 15:58:24.485 [3188] DEBUG - Completed: [127.0.0.1:53043] 200 GET /:/timeline?ratingKey=618&key=%2Flibrary%2Fmetadata%2F618&playQueueItemID=6603&state=stopped&hasMDE=1&time=0&duration=7026000 (5 live) GZIP 10ms 524 bytes (pipelined: 4)

Apr 08, 2018 15:58:24.547 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:24.548 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:24.549 [5048] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /hubs/home/onDeck (5 live) Page 0-11 GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:24.550 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:24.551 [2468] DEBUG - Request: [127.0.0.1:53049 (Loopback)] GET /hubs/home/recentlyAdded?type=2 (6 live) Page 0-11 GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:24.552 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:24.553 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:24.554 [7216] DEBUG - Request: [127.0.0.1:53050 (Loopback)] GET /hubs/home/recentlyAdded?type=1 (8 live) Page 0-38 GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:24.557 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:24.558 [7216] DEBUG - Setting container serialization range to [0, 38] (total=-1)

Apr 08, 2018 15:58:24.559 [2468] DEBUG - It took 46.875000 ms to retrieve 200 items.

Apr 08, 2018 15:58:24.564 [2468] DEBUG - It took 0.000000 ms to retrieve 405 items.

Apr 08, 2018 15:58:24.566 [2468] DEBUG - It took 0.000000 ms to retrieve 121 items.

Apr 08, 2018 15:58:24.574 [2468] DEBUG - Setting container serialization range to [0, 11] (total=-1)

Apr 08, 2018 15:58:24.575 [2468] DEBUG - There were 2 top-level paths for The Man in the High Castle.

Apr 08, 2018 15:58:24.578 [2468] DEBUG - There were 1 top-level paths for Archer (2009).

Apr 08, 2018 15:58:24.578 [4036] DEBUG - Request: [127.0.0.1:53051 (Loopback)] GET /hubs/home/recentlyAdded?type=1&personal=1 (9 live) Page 0-11 GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:24.579 [2468] DEBUG - There were 1 top-level paths for Rick and Morty.

Apr 08, 2018 15:58:24.579 [5048] DEBUG - Setting container serialization range to [0, 11] (total=-1)

Apr 08, 2018 15:58:24.580 [11472] DEBUG - Request: [127.0.0.1:53052 (Loopback)] GET /hubs/home/recentlyAdded?type=13 (9 live) Page 0-11 GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:24.580 [11472] DEBUG - Setting container serialization range to [0, 11] (total=-1)

Apr 08, 2018 15:58:24.580 [7216] DEBUG - Setting container serialization range to [0, 38] (total=279)

Apr 08, 2018 15:58:24.581 [5048] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:24.581 [5048] DEBUG - Selecting best audio stream for part ID 3160 (autoselect: 1 language: en)

Apr 08, 2018 15:58:24.581 [5048] DEBUG - Audio Stream: 7440, Subtitle Stream: -1

Apr 08, 2018 15:58:24.581 [2468] DEBUG - There were 1 top-level paths for Game of Thrones.

Apr 08, 2018 15:58:24.583 [3188] DEBUG - Completed: [127.0.0.1:53044] 200 GET /hubs/home/onDeck (9 live) GZIP Page 0-11 33ms 3487 bytes (pipelined: 9)

Apr 08, 2018 15:58:24.583 [3188] DEBUG - Completed: [127.0.0.1:53052] 200 GET /hubs/home/recentlyAdded?type=13 (9 live) GZIP Page 0-11 25ms 480 bytes (pipelined: 1)

Apr 08, 2018 15:58:24.583 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:24.584 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:24.584 [5048] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /library/sections/2/all?type=1&includeCollections=1 (9 live) Page 0-164 GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:24.585 [5048] DEBUG - Setting container serialization range to [0, 164] (total=-1)

Apr 08, 2018 15:58:24.585 [2468] DEBUG - There were 1 top-level paths for Top Gear.

Apr 08, 2018 15:58:24.588 [4036] DEBUG - Setting container serialization range to [0, 11] (total=-1)

Apr 08, 2018 15:58:24.589 [9092] DEBUG - Completed: [127.0.0.1:53051] 200 GET /hubs/home/recentlyAdded?type=1&personal=1 (9 live) GZIP Page 0-11 35ms 480 bytes (pipelined: 1)

Apr 08, 2018 15:58:24.589 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:24.590 [11472] DEBUG - Request: [127.0.0.1:53052 (Loopback)] GET /hubs/home/recentlyAdded?type=8 (9 live) Page 0-11 GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:24.590 [3188] DEBUG - Completed: [127.0.0.1:53050] 200 GET /hubs/home/recentlyAdded?type=1 (9 live) GZIP Page 0-38 37ms 15276 bytes (pipelined: 1)

Apr 08, 2018 15:58:24.590 [11472] DEBUG - Setting container serialization range to [0, 11] (total=-1)

Apr 08, 2018 15:58:24.591 [3188] DEBUG - Completed: [127.0.0.1:53052] 200 GET /hubs/home/recentlyAdded?type=8 (9 live) GZIP Page 0-11 6ms 480 bytes (pipelined: 2)

Apr 08, 2018 15:58:24.591 [0508] DEBUG - Request: [127.0.0.1:53043 (Loopback)] GET /hubs/home/continueWatching (9 live) Page 0-11 GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:24.595 [6036] DEBUG - Request: [127.0.0.1:53051 (Loopback)] GET /library/sections/2/firstCharacter?type=1&includeCollections=1 (9 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:24.604 [3188] DEBUG - Completed: [127.0.0.1:53051] 200 GET /library/sections/2/firstCharacter?type=1&includeCollections=1 (9 live) GZIP 13ms 750 bytes (pipelined: 2)

Apr 08, 2018 15:58:24.606 [0508] DEBUG - Setting container serialization range to [0, 11] (total=-1)

Apr 08, 2018 15:58:24.608 [2468] DEBUG - There were 1 top-level paths for Futurama.

Apr 08, 2018 15:58:24.610 [3188] DEBUG - Completed: [127.0.0.1:53043] 200 GET /hubs/home/continueWatching (9 live) GZIP Page 0-11 61ms 2499 bytes (pipelined: 5)

Apr 08, 2018 15:58:24.610 [2468] DEBUG - There were 1 top-level paths for Star Wars: The Clone Wars.

Apr 08, 2018 15:58:24.612 [2468] DEBUG - There were 1 top-level paths for Downton Abbey.

Apr 08, 2018 15:58:24.614 [3188] DEBUG - Completed: [127.0.0.1:53049] 200 GET /hubs/home/recentlyAdded?type=2 (9 live) GZIP Page 0-11 63ms 9892 bytes (pipelined: 1)

Apr 08, 2018 15:58:24.623 [5048] DEBUG - It took 15.625000 ms to retrieve 165 items.

Apr 08, 2018 15:58:24.625 [5048] DEBUG - Setting container serialization range to [0, 164] (total=279)

Apr 08, 2018 15:58:24.655 [3188] DEBUG - Completed: [127.0.0.1:53044] 200 GET /library/sections/2/all?type=1&includeCollections=1 (9 live) GZIP Page 0-164 71ms 61298 bytes (pipelined: 10)

Apr 08, 2018 15:58:26.840 [12008] DEBUG - Cleaning directory for session jnzgubcqrhr98ujebkejtoh2 (C:\Users\Kurt Bowie\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-jnzgubcqrhr98ujebkejtoh2-523def2e-022b-41d0-9b86-da9d6074d04a)

Apr 08, 2018 15:58:29.520 [2940] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /web/common/img/backgrounds/preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png (9 live) GZIP Signed-in

Apr 08, 2018 15:58:29.520 [2940] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-1046ba85f\WebClient.bundle\Contents\Resources\common\img\backgrounds\preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png"

Apr 08, 2018 15:58:29.520 [2940] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-1046ba85f\WebClient.bundle\Contents\Resources\common\img\backgrounds\preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png is 31365.

Apr 08, 2018 15:58:29.521 [9092] DEBUG - Completed: [127.0.0.1:53044] 200 GET /web/common/img/backgrounds/preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png (9 live) GZIP 1ms 31365 bytes (pipelined: 11)

Apr 08, 2018 15:58:38.768 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:38.770 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:38.772 [2940] DEBUG - Request: [127.0.0.1:53049 (Loopback)] GET /accounts/1 (9 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:38.774 [9092] DEBUG - Completed: [127.0.0.1:53049] 200 GET /accounts/1 (9 live) GZIP 3ms 508 bytes (pipelined: 2)

Apr 08, 2018 15:58:38.776 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:38.776 [4036] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /:/prefs (9 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:38.776 [2940] DEBUG - Request: [127.0.0.1:53049 (Loopback)] GET /myplex/account (9 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:38.777 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:38.777 [9092] DEBUG - Completed: [127.0.0.1:53049] 200 GET /myplex/account (9 live) GZIP 1ms 627 bytes (pipelined: 3)

Apr 08, 2018 15:58:38.778 [8004] DEBUG - Request: [127.0.0.1:53043 (Loopback)] GET /system/:/prefs (9 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:38.779 [8004] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs

Apr 08, 2018 15:58:38.779 [8004] DEBUG - HTTP requesting GET http://127.0.0.1:52652/system/:/prefs

Apr 08, 2018 15:58:38.786 [8004] DEBUG - HTTP 304 response from GET http://127.0.0.1:52652/system/:/prefs

Apr 08, 2018 15:58:38.786 [8004] DEBUG - [com.plexapp.system] HTTP reply status 304, with 0 bytes of content.

Apr 08, 2018 15:58:38.786 [9092] DEBUG - Completed: [127.0.0.1:53043] 304 GET /system/:/prefs (9 live) GZIP 9ms 324 bytes (pipelined: 6)

Apr 08, 2018 15:58:38.788 [9092] DEBUG - Completed: [127.0.0.1:53044] 200 GET /:/prefs (9 live) GZIP 20ms 6409 bytes (pipelined: 12)

Apr 08, 2018 15:58:38.805 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:38.808 [9240] DEBUG - Request: [127.0.0.1:53044 (Loopback)] PUT /myplex/refreshReachability (9 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:38.808 [9240] DEBUG - MyPlex: Requesting reachability check.

Apr 08, 2018 15:58:38.809 [9240] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/6d016da02bad43ca50189c0fbc45fe87acf90cfd/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=dbdd3385-4739-43b1-87a8-0372df1d666c

Apr 08, 2018 15:58:38.840 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:38.841 [2940] DEBUG - Request: [127.0.0.1:53043 (Loopback)] GET /updater/status (9 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:38.843 [9092] DEBUG - Completed: [127.0.0.1:53043] 200 GET /updater/status (9 live) GZIP 2ms 454 bytes (pipelined: 7)

Apr 08, 2018 15:58:38.860 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:38.860 [8004] DEBUG - Request: [127.0.0.1:53043 (Loopback)] PUT /updater/check?download=0 (9 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:38.860 [8004] DEBUG - AutoUpdate: requesting check endpoint: /updater/products/1/check.xml?build=windows-i386&channel=16&distribution=english&version=1.12.1.4885-1046ba85f, download: NO

Apr 08, 2018 15:58:38.860 [8004] DEBUG - Downloading document https://plex.tv/updater/products/1/check.xml?build=windows-i386&channel=16&distribution=english&version=1.12.1.4885-1046ba85f

Apr 08, 2018 15:58:38.860 [8004] DEBUG - HTTP requesting GET https://plex.tv/updater/products/1/check.xml?build=windows-i386&channel=16&distribution=english&version=1.12.1.4885-1046ba85f

Apr 08, 2018 15:58:39.314 [9240] DEBUG - HTTP 200 response from PUT https://plex.tv/api/servers/6d016da02bad43ca50189c0fbc45fe87acf90cfd/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=dbdd3385-4739-43b1-87a8-0372df1d666c

Apr 08, 2018 15:58:39.315 [3188] DEBUG - Completed: [127.0.0.1:53044] 200 PUT /myplex/refreshReachability (9 live) GZIP 509ms 268 bytes (pipelined: 13)

Apr 08, 2018 15:58:39.372 [8004] DEBUG - HTTP 200 response from GET https://plex.tv/updater/products/1/check.xml?build=windows-i386&channel=16&distribution=english&version=1.12.1.4885-1046ba85f

Apr 08, 2018 15:58:39.372 [8004] INFO - AutoUpdate: no updates available

Apr 08, 2018 15:58:39.373 [3188] DEBUG - Completed: [127.0.0.1:53043] 200 PUT /updater/check?download=0 (9 live) GZIP 512ms 268 bytes (pipelined: 8)

Apr 08, 2018 15:58:39.768 [2940] DEBUG - Request: [52.213.132.78:51607 (WAN)] GET /identity (10 live) TLS Signed-in Token (kbowie)

Apr 08, 2018 15:58:39.769 [3188] DEBUG - Completed: [52.213.132.78:51607] 200 GET /identity (10 live) TLS 0ms 357 bytes

Apr 08, 2018 15:58:40.197 [3860] DEBUG - EventSource: Got event [data] '<Message address="24.209.146.168" port="10042" asyncIdentifier="dbdd3385-4739-43b1-87a8-0372df1d666c" connectivity="1" command="notifyConnectivity"/>'

Apr 08, 2018 15:58:40.197 [3860] DEBUG - PubSub: Got notified of reachability: 1 for 24.209.146.168:10042

Apr 08, 2018 15:58:40.200 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:40.200 [4036] DEBUG - Request: [127.0.0.1:53043 (Loopback)] GET /myplex/account (10 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:40.201 [3188] DEBUG - Completed: [127.0.0.1:53043] 200 GET /myplex/account (10 live) GZIP 0ms 627 bytes (pipelined: 9)

Apr 08, 2018 15:58:40.524 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:40.526 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:40.526 [9240] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /library/sections (9 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:40.526 [8004] DEBUG - Request: [127.0.0.1:53043 (Loopback)] GET /music/iTunes (9 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:40.527 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:40.527 [2940] DEBUG - Request: [127.0.0.1:53049 (Loopback)] GET /media/providers (9 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:40.527 [9092] DEBUG - Completed: [127.0.0.1:53043] 404 GET /music/iTunes (9 live) GZIP 3ms 379 bytes (pipelined: 10)

Apr 08, 2018 15:58:40.528 [9092] DEBUG - Completed: [127.0.0.1:53044] 200 GET /library/sections (9 live) GZIP 1ms 827 bytes (pipelined: 14)

Apr 08, 2018 15:58:40.528 [9092] DEBUG - Completed: [127.0.0.1:53049] 200 GET /media/providers (9 live) GZIP 1ms 866 bytes (pipelined: 4)

Apr 08, 2018 15:58:40.533 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:40.533 [4036] DEBUG - Request: [127.0.0.1:53049 (Loopback)] GET /hubs?excludeFields=summary&count=12&includeEmpty=1&includeFeaturedTags=1&excludePlaylists=1&onlyTransient=1 (9 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:40.533 [4036] DEBUG - HubCache: Adding '1/home.continue/hubs/12/en' to the cache (1/home.continue/hubs/12/en).

Apr 08, 2018 15:58:40.533 [4036] DEBUG - HubCache: Adding '1/home.ondeck/hubs/12/en' to the cache (1/home.ondeck/hubs/12/en).

Apr 08, 2018 15:58:40.539 [4036] DEBUG - HubCache: Computed '1/home.ondeck/hubs/12/en' in 5 ms.

Apr 08, 2018 15:58:40.539 [4036] DEBUG - HubCache: 7 hubs cached, 41.2% hit ratio.

Apr 08, 2018 15:58:40.539 [4036] DEBUG - HubCache: Computed '1/home.continue/hubs/12/en' in 5 ms.

Apr 08, 2018 15:58:40.539 [4036] DEBUG - HubCache: Retrieving '1/home.ondeck/hubs/12/en' from the cache.

Apr 08, 2018 15:58:40.539 [4036] DEBUG - HubCache: Retrieving '1/home.movies.recent/hubs/12/en' from the cache.

Apr 08, 2018 15:58:40.539 [4036] DEBUG - HubCache: Retrieving '1/home.television.recent/hubs/12/en' from the cache.

Apr 08, 2018 15:58:40.540 [4036] DEBUG - HubCache: Retrieving '1/home.videos.recent/hubs/12/en' from the cache.

Apr 08, 2018 15:58:40.540 [4036] DEBUG - HubCache: Retrieving '1/home.photos.recent/hubs/12/en' from the cache.

Apr 08, 2018 15:58:40.540 [4036] DEBUG - HubCache: Retrieving '1/home.music.recent/hubs/12/en' from the cache.

Apr 08, 2018 15:58:40.542 [8004] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /web/common/img/backgrounds/preset-light.770a0981b66e038d3ffffbcc4f5a26a4.png (9 live) GZIP Signed-in

Apr 08, 2018 15:58:40.542 [4036] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:40.542 [8004] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-1046ba85f\WebClient.bundle\Contents\Resources\common\img\backgrounds\preset-light.770a0981b66e038d3ffffbcc4f5a26a4.png"

Apr 08, 2018 15:58:40.542 [4036] DEBUG - Selecting best audio stream for part ID 3160 (autoselect: 1 language: en)

Apr 08, 2018 15:58:40.542 [4036] DEBUG - Audio Stream: 7440, Subtitle Stream: -1

Apr 08, 2018 15:58:40.542 [8004] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-1046ba85f\WebClient.bundle\Contents\Resources\common\img\backgrounds\preset-light.770a0981b66e038d3ffffbcc4f5a26a4.png is 42165.

Apr 08, 2018 15:58:40.543 [9092] DEBUG - Completed: [127.0.0.1:53044] 200 GET /web/common/img/backgrounds/preset-light.770a0981b66e038d3ffffbcc4f5a26a4.png (9 live) GZIP 1ms 42165 bytes (pipelined: 15)

Apr 08, 2018 15:58:40.544 [4036] DEBUG - There were 2 top-level paths for The Man in the High Castle.

Apr 08, 2018 15:58:40.545 [4036] DEBUG - There were 1 top-level paths for Archer (2009).

Apr 08, 2018 15:58:40.546 [4036] DEBUG - There were 1 top-level paths for Rick and Morty.

Apr 08, 2018 15:58:40.547 [4036] DEBUG - There were 1 top-level paths for Game of Thrones.

Apr 08, 2018 15:58:40.550 [4036] DEBUG - There were 1 top-level paths for Top Gear.

Apr 08, 2018 15:58:40.552 [4036] DEBUG - There were 1 top-level paths for Futurama.

Apr 08, 2018 15:58:40.554 [4036] DEBUG - There were 1 top-level paths for Star Wars: The Clone Wars.

Apr 08, 2018 15:58:40.555 [4036] DEBUG - There were 1 top-level paths for Downton Abbey.

Apr 08, 2018 15:58:40.561 [9092] DEBUG - Completed: [127.0.0.1:53049] 200 GET /hubs?excludeFields=summary&count=12&includeEmpty=1&includeFeaturedTags=1&excludePlaylists=1&onlyTransient=1 (9 live) GZIP 27ms 14331 bytes (pipelined: 5)

Apr 08, 2018 15:58:40.911 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:40.911 [9240] DEBUG - Request: [127.0.0.1:53049 (Loopback)] GET /hubs/home/recentlyAdded?type=1 (9 live) Page 12-38 GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:40.912 [9240] DEBUG - Setting container serialization range to [12, 38] (total=-1)

Apr 08, 2018 15:58:40.917 [9240] DEBUG - Setting container serialization range to [12, 38] (total=279)

Apr 08, 2018 15:58:40.923 [3188] DEBUG - Completed: [127.0.0.1:53049] 200 GET /hubs/home/recentlyAdded?type=1 (9 live) GZIP Page 12-38 11ms 10950 bytes (pipelined: 6)

Apr 08, 2018 15:58:43.393 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:43.393 [2940] DEBUG - Request: [127.0.0.1:53049 (Loopback)] POST /playQueues?type=video&extrasPrefixCount=0&uri=library%3A%2F%2F869fe558-7eb7-4a51-970f-50f2e14c6f1c%2Fitem%2F%252Flibrary%252Fmetadata%252F1326&repeat=0&own=1&includeChapters=1&includeGeolocation=1 (9 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:43.394 [2940] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.

Apr 08, 2018 15:58:43.396 [2940] DEBUG - PlayQueue: Start index: 0 End index: 0 Count: 1

Apr 08, 2018 15:58:43.396 [2940] DEBUG - PlayQueue: 1 generated IDs compressed down to a 24 byte blob.

Apr 08, 2018 15:58:43.398 [2940] DEBUG - PlayQueue: Added 1 items in 0.0 sec.

Apr 08, 2018 15:58:43.404 [2940] DEBUG - It took 0.000000 ms to retrieve 140 items.

Apr 08, 2018 15:58:43.411 [2940] DEBUG - Extras: Preroll values looks like a file, and we had 1 of them.

Apr 08, 2018 15:58:43.411 [2940] ERROR - Extras: Preroll file didn't exist or couldn't be read: ''.

Apr 08, 2018 15:58:43.411 [2940] DEBUG - Extras: Looking for extras for item com.plexapp.agents.imdb://tt0121765?lang=en with unwatched: 1 fromLibrary: 0 fromTheater: 0 fromBluray: 0 and preroll count: 0.

Apr 08, 2018 15:58:43.411 [2940] DEBUG - Extras: Using allowable content ratings: G, PG.

Apr 08, 2018 15:58:43.415 [2940] DEBUG - It took 15.625000 ms to retrieve 140 items.

Apr 08, 2018 15:58:43.416 [2940] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:43.416 [2940] DEBUG - Selecting best audio stream for part ID 1672 (autoselect: 1 language: en)

Apr 08, 2018 15:58:43.416 [2940] DEBUG - Audio Stream: 3832, Subtitle Stream: -1

Apr 08, 2018 15:58:43.418 [9092] DEBUG - Completed: [127.0.0.1:53049] 200 POST /playQueues?type=video&extrasPrefixCount=0&uri=library%3A%2F%2F869fe558-7eb7-4a51-970f-50f2e14c6f1c%2Fitem%2F%252Flibrary%252Fmetadata%252F1326&repeat=0&own=1&includeChapters=1&includeGeolocation=1 (9 live) GZIP 24ms 6439 bytes (pipelined: 7)

Apr 08, 2018 15:58:43.475 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:43.475 [4036] DEBUG - Request: [127.0.0.1:53049 (Loopback)] GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1326&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=tbft9sk0zsd9du5r81q4it2w&subtitles=burn&Accept-Language=en (9 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:43.475 [4036] DEBUG - Found session GUID of tbft9sk0zsd9du5r81q4it2w in session start.

Apr 08, 2018 15:58:43.476 [4036] DEBUG - TranscodeUniversalRequest: using profile Web

Apr 08, 2018 15:58:43.476 [4036] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/1326?includeBandwidths=1&offset=-1&X-Plex-Session-Identifier=4n5yzt40h8mz6ug1lg2cek07

Apr 08, 2018 15:58:43.476 [4036] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:43.480 [4036] DEBUG - It took 0.000000 ms to retrieve 140 items.

Apr 08, 2018 15:58:43.481 [4036] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:43.481 [4036] DEBUG - Selecting best audio stream for part ID 1672 (autoselect: 1 language: en)

Apr 08, 2018 15:58:43.481 [4036] DEBUG - Audio Stream: 3832, Subtitle Stream: -1

Apr 08, 2018 15:58:43.482 [4036] DEBUG - MDE: Selected protocol dash; container: mp4

Apr 08, 2018 15:58:43.482 [4036] DEBUG - MDE: analyzing media item 1658

Apr 08, 2018 15:58:43.482 [4036] DEBUG - MDE: Star Wars: Episode II - Attack of the Clones (2002): Direct Play is disabled

Apr 08, 2018 15:58:43.482 [4036] DEBUG - MDE: Star Wars: Episode II - Attack of the Clones (2002): media must be transcoded in order to use the dash protocol

Apr 08, 2018 15:58:43.482 [4036] DEBUG - MDE: Star Wars: Episode II - Attack of the Clones (2002): no direct play video profile exists for http/mkv/h264

Apr 08, 2018 15:58:43.483 [4036] DEBUG - MDE: Star Wars: Episode II - Attack of the Clones (2002): no direct play video profile exists for http/mkv/h264/aac

Apr 08, 2018 15:58:43.484 [4036] DEBUG - MDE: Star Wars: Episode II - Attack of the Clones (2002): selected media 0 / 1658

Apr 08, 2018 15:58:43.484 [4036] DEBUG - Streaming Resource: Added session 0xa392918:4n5yzt40h8mz6ug1lg2cek07

Apr 08, 2018 15:58:43.485 [4036] DEBUG - Streaming Resource: Reached Decision id=1326 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=1001,Direct play not available; Conversion OK.) media=(id=1658 part=(id=1672 decision=transcode container=mp4 protocol=dash streams=(Video=(id=3831 decision=copy width=1280 height=544) Audio=(id=3832 decision=copy channels=6 rate=48000))))

Apr 08, 2018 15:58:43.486 [3188] DEBUG - Completed: [127.0.0.1:53049] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1326&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=tbft9sk0zsd9du5r81q4it2w&subtitles=burn&Accept-Language=en (9 live) GZIP 11ms 4461 bytes (pipelined: 8)

Apr 08, 2018 15:58:43.531 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:43.531 [8004] DEBUG - Request: [127.0.0.1:53049 (Loopback)] GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1326&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=tbft9sk0zsd9du5r81q4it2w&subtitles=burn&Accept-Language=en (9 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:43.532 [8004] DEBUG - Found session GUID of tbft9sk0zsd9du5r81q4it2w in session start.

Apr 08, 2018 15:58:43.532 [8004] DEBUG - TranscodeUniversalRequest: using profile Web

Apr 08, 2018 15:58:43.532 [8004] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/1326?checkFiles=1&includeBandwidths=1&offset=-1&X-Plex-Session-Identifier=4n5yzt40h8mz6ug1lg2cek07

Apr 08, 2018 15:58:43.533 [8004] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:43.537 [8004] DEBUG - It took 0.000000 ms to retrieve 140 items.

Apr 08, 2018 15:58:43.546 [8004] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:43.546 [8004] DEBUG - Selecting best audio stream for part ID 1672 (autoselect: 1 language: en)

Apr 08, 2018 15:58:43.546 [8004] DEBUG - Audio Stream: 3832, Subtitle Stream: -1

Apr 08, 2018 15:58:43.547 [8004] DEBUG - Found session GUID of tbft9sk0zsd9du5r81q4it2w in session start.

Apr 08, 2018 15:58:43.548 [8004] DEBUG - Cleaning directory for session tbft9sk0zsd9du5r81q4it2w ()

Apr 08, 2018 15:58:43.548 [8004] DEBUG - Starting a transcode session tbft9sk0zsd9du5r81q4it2w at offset -1.0 (state=3)

Apr 08, 2018 15:58:43.551 [8004] INFO - CodecManager: obtaining decoder 'h264'

Apr 08, 2018 15:58:43.551 [8004] DEBUG - HTTP requesting GET https://plex.tv/api/codecs/h264_decoder?build=windows-i386&deviceId=6e8ce0c2-ac7b-4242-a726-7e7d97feaa73&oldestPreviousVersion=1%2E3%2E3%2E3148-b38628e&version=7814ac9-1285

Apr 08, 2018 15:58:44.048 [8004] DEBUG - HTTP 200 response from GET https://plex.tv/api/codecs/h264_decoder?build=windows-i386&deviceId=6e8ce0c2-ac7b-4242-a726-7e7d97feaa73&oldestPreviousVersion=1%2E3%2E3%2E3148-b38628e&version=7814ac9-1285

Apr 08, 2018 15:58:44.049 [8004] ERROR - Unexpected error configuring transcoder: boost::filesystem::unique_path: Keyset as registered is invalid

Apr 08, 2018 15:58:44.049 [8004] DEBUG - Streaming Resource: Terminated session 0xa392918:4n5yzt40h8mz6ug1lg2cek07 with reason Conversion failed. The transcoder crashed or failed to start up.

Apr 08, 2018 15:58:44.049 [5136] DEBUG - Killing job.

Apr 08, 2018 15:58:44.050 [5136] DEBUG - Job was already killed, not killing again.

Apr 08, 2018 15:58:44.050 [5136] DEBUG - Stopping transcode session tbft9sk0zsd9du5r81q4it2w

Apr 08, 2018 15:58:44.050 [9092] DEBUG - Completed: [127.0.0.1:53049] 404 GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1326&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=tbft9sk0zsd9du5r81q4it2w&subtitles=burn&Accept-Language=en (9 live) GZIP 518ms 379 bytes (pipelined: 9)

Apr 08, 2018 15:58:44.051 [11972] DEBUG - Cleaning directory for session tbft9sk0zsd9du5r81q4it2w (C:\Users\Kurt Bowie\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-tbft9sk0zsd9du5r81q4it2w-25b3a485-5c0d-485c-a3ae-f207c3d9378c)

Apr 08, 2018 15:58:44.058 [5136] DEBUG - Transcoder: Cleaning old transcode directories.

Apr 08, 2018 15:58:44.059 [5136] DEBUG - Whacked session tbft9sk0zsd9du5r81q4it2w, 0 remaining.

Apr 08, 2018 15:58:44.898 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:44.899 [9240] DEBUG - Request: [127.0.0.1:53049 (Loopback)] GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1326&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=tbft9sk0zsd9du5r81q4it2w&subtitles=burn&Accept-Language=en (6 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:44.899 [9240] DEBUG - Found session GUID of tbft9sk0zsd9du5r81q4it2w in session start.

Apr 08, 2018 15:58:44.899 [9240] DEBUG - TranscodeUniversalRequest: using profile Web

Apr 08, 2018 15:58:44.900 [9240] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/1326?checkFiles=1&includeBandwidths=1&offset=-1&X-Plex-Session-Identifier=4n5yzt40h8mz6ug1lg2cek07

Apr 08, 2018 15:58:44.900 [9240] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:44.904 [9240] DEBUG - It took 0.000000 ms to retrieve 140 items.

Apr 08, 2018 15:58:44.910 [9240] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:44.910 [9240] DEBUG - Selecting best audio stream for part ID 1672 (autoselect: 1 language: en)

Apr 08, 2018 15:58:44.910 [9240] DEBUG - Audio Stream: 3832, Subtitle Stream: -1

Apr 08, 2018 15:58:44.911 [9240] WARN - Denying access to transcode of key /library/metadata/1326 due to terminated session

Apr 08, 2018 15:58:44.912 [9092] DEBUG - Completed: [127.0.0.1:53049] 400 GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1326&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=tbft9sk0zsd9du5r81q4it2w&subtitles=burn&Accept-Language=en (6 live) GZIP 13ms 384 bytes (pipelined: 10)

Apr 08, 2018 15:58:44.938 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:44.938 [4036] DEBUG - Request: [127.0.0.1:53049 (Loopback)] GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1326&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=afh999y583ljwndh462jjmik&subtitles=burn&Accept-Language=en (6 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:44.939 [4036] DEBUG - Found session GUID of afh999y583ljwndh462jjmik in session start.

Apr 08, 2018 15:58:44.939 [4036] DEBUG - TranscodeUniversalRequest: using profile Web

Apr 08, 2018 15:58:44.939 [4036] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/1326?includeBandwidths=1&offset=-1&X-Plex-Session-Identifier=4n5yzt40h8mz6ug1lg2cek07

Apr 08, 2018 15:58:44.940 [4036] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:44.943 [4036] DEBUG - It took 0.000000 ms to retrieve 140 items.

Apr 08, 2018 15:58:44.944 [4036] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:44.944 [4036] DEBUG - Selecting best audio stream for part ID 1672 (autoselect: 1 language: en)

Apr 08, 2018 15:58:44.944 [4036] DEBUG - Audio Stream: 3832, Subtitle Stream: -1

Apr 08, 2018 15:58:44.946 [4036] DEBUG - Scaled up video bitrate to 1941Kbps based on 1.500000x fudge factor.

Apr 08, 2018 15:58:44.946 [4036] DEBUG - MDE: Selected protocol dash; container: mp4

Apr 08, 2018 15:58:44.946 [4036] DEBUG - MDE: analyzing media item 1658

Apr 08, 2018 15:58:44.946 [4036] DEBUG - MDE: Star Wars: Episode II - Attack of the Clones (2002): Direct Play is disabled

Apr 08, 2018 15:58:44.946 [4036] DEBUG - MDE: Star Wars: Episode II - Attack of the Clones (2002): media must be transcoded in order to use the dash protocol

Apr 08, 2018 15:58:44.946 [4036] DEBUG - MDE: Star Wars: Episode II - Attack of the Clones (2002): no direct play video profile exists for http/mkv/h264

Apr 08, 2018 15:58:44.946 [4036] DEBUG - MDE: Star Wars: Episode II - Attack of the Clones (2002): no direct play video profile exists for http/mkv/h264/aac

Apr 08, 2018 15:58:44.946 [4036] DEBUG - MDE: Star Wars: Episode II - Attack of the Clones (2002): Direct Streaming is disabled, so video stream will be transcoded

Apr 08, 2018 15:58:44.946 [4036] DEBUG - MDE: Star Wars: Episode II - Attack of the Clones (2002): no remuxable profile found, so video stream will be transcoded

Apr 08, 2018 15:58:44.946 [4036] DEBUG - MDE: Cannot direct stream video stream due to profile or setting limitations

Apr 08, 2018 15:58:44.946 [4036] DEBUG - Scaled up video bitrate to 1941Kbps based on 1.500000x fudge factor.

Apr 08, 2018 15:58:44.946 [4036] DEBUG - MDE: Star Wars: Episode II - Attack of the Clones (2002): selected media 0 / 1658

Apr 08, 2018 15:58:44.946 [4036] DEBUG - Streaming Resource: Adding session 0xa394588:4n5yzt40h8mz6ug1lg2cek07 which is using transcoder slot. Used slots is now 1

Apr 08, 2018 15:58:44.946 [4036] DEBUG - Streaming Resource: Added session 0xa394588:4n5yzt40h8mz6ug1lg2cek07

Apr 08, 2018 15:58:44.947 [4036] DEBUG - Streaming Resource: Reached Decision id=1326 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=1001,Direct play not available; Conversion OK.) media=(id=1658 part=(id=1672 decision=transcode container=mp4 protocol=dash streams=(Video=(id=3831 decision=transcode bitrate=1941 encoder=libx264 width=1280 height=544) Audio=(id=3832 decision=copy channels=6 rate=48000))))

Apr 08, 2018 15:58:44.948 [3188] DEBUG - Completed: [127.0.0.1:53049] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1326&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=afh999y583ljwndh462jjmik&subtitles=burn&Accept-Language=en (6 live) GZIP 9ms 4463 bytes (pipelined: 11)

Apr 08, 2018 15:58:44.991 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:44.991 [2940] DEBUG - Request: [127.0.0.1:53049 (Loopback)] GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1326&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=afh999y583ljwndh462jjmik&subtitles=burn&Accept-Language=en (6 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:44.991 [2940] DEBUG - Found session GUID of afh999y583ljwndh462jjmik in session start.

Apr 08, 2018 15:58:44.992 [2940] DEBUG - TranscodeUniversalRequest: using profile Web

Apr 08, 2018 15:58:44.992 [2940] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/1326?checkFiles=1&includeBandwidths=1&offset=-1&X-Plex-Session-Identifier=4n5yzt40h8mz6ug1lg2cek07

Apr 08, 2018 15:58:44.992 [2940] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:44.996 [2940] DEBUG - It took 0.000000 ms to retrieve 140 items.

Apr 08, 2018 15:58:45.004 [2940] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:45.004 [2940] DEBUG - Selecting best audio stream for part ID 1672 (autoselect: 1 language: en)

Apr 08, 2018 15:58:45.004 [2940] DEBUG - Audio Stream: 3832, Subtitle Stream: -1

Apr 08, 2018 15:58:45.005 [2940] DEBUG - Found session GUID of afh999y583ljwndh462jjmik in session start.

Apr 08, 2018 15:58:45.006 [2940] DEBUG - Cleaning directory for session afh999y583ljwndh462jjmik ()

Apr 08, 2018 15:58:45.006 [2940] DEBUG - Starting a transcode session afh999y583ljwndh462jjmik at offset -1.0 (state=3)

Apr 08, 2018 15:58:45.008 [2940] INFO - CodecManager: obtaining decoder 'h264'

Apr 08, 2018 15:58:45.008 [2940] DEBUG - HTTP requesting GET https://plex.tv/api/codecs/h264_decoder?build=windows-i386&deviceId=6e8ce0c2-ac7b-4242-a726-7e7d97feaa73&oldestPreviousVersion=1%2E3%2E3%2E3148-b38628e&version=7814ac9-1285

Apr 08, 2018 15:58:45.499 [2940] DEBUG - HTTP 200 response from GET https://plex.tv/api/codecs/h264_decoder?build=windows-i386&deviceId=6e8ce0c2-ac7b-4242-a726-7e7d97feaa73&oldestPreviousVersion=1%2E3%2E3%2E3148-b38628e&version=7814ac9-1285

Apr 08, 2018 15:58:45.500 [2940] ERROR - Unexpected error configuring transcoder: boost::filesystem::unique_path: Keyset as registered is invalid

Apr 08, 2018 15:58:45.500 [2940] DEBUG - Streaming Resource: Terminating session 0xa394588:4n5yzt40h8mz6ug1lg2cek07 which is using transcoder slot. Used slots is now 0

Apr 08, 2018 15:58:45.501 [2940] DEBUG - Streaming Resource: Terminated session 0xa394588:4n5yzt40h8mz6ug1lg2cek07 with reason Conversion failed. The transcoder crashed or failed to start up.

Apr 08, 2018 15:58:45.501 [12008] DEBUG - Killing job.

Apr 08, 2018 15:58:45.501 [12008] DEBUG - Job was already killed, not killing again.

Apr 08, 2018 15:58:45.501 [12008] DEBUG - Stopping transcode session afh999y583ljwndh462jjmik

Apr 08, 2018 15:58:45.501 [5136] DEBUG - Cleaning directory for session afh999y583ljwndh462jjmik (C:\Users\Kurt Bowie\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-afh999y583ljwndh462jjmik-8c4a4d55-d601-49b1-afb7-04f815ead92d)

Apr 08, 2018 15:58:45.502 [9092] DEBUG - Completed: [127.0.0.1:53049] 404 GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1326&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=afh999y583ljwndh462jjmik&subtitles=burn&Accept-Language=en (6 live) GZIP 511ms 379 bytes (pipelined: 12)

Apr 08, 2018 15:58:45.508 [12008] DEBUG - Transcoder: Cleaning old transcode directories.

Apr 08, 2018 15:58:45.509 [12008] DEBUG - Whacked session afh999y583ljwndh462jjmik, 0 remaining.

Apr 08, 2018 15:58:46.735 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:46.735 [8004] DEBUG - Request: [127.0.0.1:53049 (Loopback)] GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1326&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=afh999y583ljwndh462jjmik&subtitles=burn&Accept-Language=en (6 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:46.735 [8004] DEBUG - Found session GUID of afh999y583ljwndh462jjmik in session start.

Apr 08, 2018 15:58:46.736 [8004] DEBUG - TranscodeUniversalRequest: using profile Web

Apr 08, 2018 15:58:46.737 [8004] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/1326?checkFiles=1&includeBandwidths=1&offset=-1&X-Plex-Session-Identifier=4n5yzt40h8mz6ug1lg2cek07

Apr 08, 2018 15:58:46.738 [8004] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:46.742 [8004] DEBUG - It took 15.625000 ms to retrieve 140 items.

Apr 08, 2018 15:58:46.748 [8004] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:46.748 [8004] DEBUG - Selecting best audio stream for part ID 1672 (autoselect: 1 language: en)

Apr 08, 2018 15:58:46.748 [8004] DEBUG - Audio Stream: 3832, Subtitle Stream: -1

Apr 08, 2018 15:58:46.749 [8004] WARN - Denying access to transcode of key /library/metadata/1326 due to terminated session

Apr 08, 2018 15:58:46.750 [3188] DEBUG - Completed: [127.0.0.1:53049] 400 GET /video/:/transcode/universal/start.mpd?hasMDE=1&path=%2Flibrary%2Fmetadata%2F1326&mediaIndex=0&partIndex=0&protocol=dash&fastSeek=1&directPlay=0&directStream=0&subtitleSize=100&audioBoost=100&location=lan&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=afh999y583ljwndh462jjmik&subtitles=burn&Accept-Language=en (6 live) GZIP 14ms 384 bytes (pipelined: 13)

Apr 08, 2018 15:58:46.790 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:46.791 [9240] DEBUG - Request: [127.0.0.1:53049 (Loopback)] GET /:/timeline?ratingKey=1326&key=%2Flibrary%2Fmetadata%2F1326&playQueueItemID=6604&state=error&hasMDE=1&time=0&duration=8547000 (6 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:46.791 [9240] DEBUG - Client [4n5yzt40h8mz6ug1lg2cek07] reporting timeline state error, progress of 0/8547000ms for guid=, ratingKey=1326 url=, key=/library/metadata/1326, containerKey=, metadataId=1326

Apr 08, 2018 15:58:46.794 [9240] DEBUG - It took 0.000000 ms to retrieve 140 items.

Apr 08, 2018 15:58:46.799 [9240] DEBUG - It took 0.000000 ms to retrieve 140 items.

Apr 08, 2018 15:58:46.802 [9240] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:46.802 [9240] DEBUG - Selecting best audio stream for part ID 1672 (autoselect: 1 language: en)

Apr 08, 2018 15:58:46.802 [9240] DEBUG - Audio Stream: 3832, Subtitle Stream: -1

Apr 08, 2018 15:58:46.803 [9240] DEBUG - [Now] User is kbowie (ID: 1)

Apr 08, 2018 15:58:46.804 [9240] DEBUG - [Now] Device is Chrome (Chrome).

Apr 08, 2018 15:58:46.804 [9240] DEBUG - [Now] Profile is Web

Apr 08, 2018 15:58:46.804 [9240] DEBUG - [Now] Updated play state for /library/metadata/1326.

Apr 08, 2018 15:58:46.804 [9240] DEBUG - HubCache: Expiring '1/home.continue/hubs/12/en' because event plex.event.play.session.state.update referenced an item the hub contained

Apr 08, 2018 15:58:46.804 [11972] DEBUG - Cleaning directory for session tbft9sk0zsd9du5r81q4it2w (C:\Users\Kurt Bowie\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-tbft9sk0zsd9du5r81q4it2w-25b3a485-5c0d-485c-a3ae-f207c3d9378c)

Apr 08, 2018 15:58:46.805 [9240] DEBUG - Statistics: (0ac6i7pt0hdbvkj476re8kt9) Reporting active playback in state 0 of type 1 (scrobble: 0) for account 1

Apr 08, 2018 15:58:46.806 [9092] DEBUG - Completed: [127.0.0.1:53049] 200 GET /:/timeline?ratingKey=1326&key=%2Flibrary%2Fmetadata%2F1326&playQueueItemID=6604&state=error&hasMDE=1&time=0&duration=8547000 (6 live) GZIP 15ms 526 bytes (pipelined: 14)

Apr 08, 2018 15:58:46.813 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:46.814 [2940] DEBUG - Request: [127.0.0.1:53049 (Loopback)] GET /status/sessions (6 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:46.814 [2940] DEBUG - [Now] Adding 1 sessions.

Apr 08, 2018 15:58:46.815 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:46.815 [4036] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /playQueues/235?repeat=0&own=1&includeChapters=1&includeGeolocation=1 (6 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:46.815 [9092] DEBUG - Completed: [127.0.0.1:53049] 200 GET /status/sessions (6 live) GZIP 2ms 4809 bytes (pipelined: 15)

Apr 08, 2018 15:58:46.816 [4036] DEBUG - PlayQueue: total generated: 1, before cursor: 0, after cursor: 0

Apr 08, 2018 15:58:46.816 [4036] DEBUG - PlayQueue: No need to refresh, active window is operational.

Apr 08, 2018 15:58:46.820 [4036] DEBUG - It took 15.625000 ms to retrieve 140 items.

Apr 08, 2018 15:58:46.820 [4036] DEBUG - PlayQueue: Client 0ac6i7pt0hdbvkj476re8kt9 requested ownership of play queue 235, but already had it.

Apr 08, 2018 15:58:46.821 [4036] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:46.821 [4036] DEBUG - Selecting best audio stream for part ID 1672 (autoselect: 1 language: en)

Apr 08, 2018 15:58:46.821 [4036] DEBUG - Audio Stream: 3832, Subtitle Stream: -1

Apr 08, 2018 15:58:46.822 [9092] DEBUG - Completed: [127.0.0.1:53044] 200 GET /playQueues/235?repeat=0&own=1&includeChapters=1&includeGeolocation=1 (6 live) GZIP 7ms 6366 bytes (pipelined: 16)

Apr 08, 2018 15:58:46.854 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:46.854 [8004] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /:/timeline?ratingKey=1326&key=%2Flibrary%2Fmetadata%2F1326&playQueueItemID=6604&state=stopped&hasMDE=1&time=0&duration=8547000 (6 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:46.855 [8004] DEBUG - Client [4n5yzt40h8mz6ug1lg2cek07] reporting timeline state stopped, progress of 0/8547000ms for guid=, ratingKey=1326 url=, key=/library/metadata/1326, containerKey=, metadataId=1326

Apr 08, 2018 15:58:46.859 [8004] DEBUG - It took 0.000000 ms to retrieve 140 items.

Apr 08, 2018 15:58:46.861 [8004] DEBUG - [Now] User is kbowie (ID: 1)

Apr 08, 2018 15:58:46.862 [8004] DEBUG - [Now] Device is Chrome (Chrome).

Apr 08, 2018 15:58:46.862 [8004] DEBUG - [Now] Profile is Web

Apr 08, 2018 15:58:46.862 [8004] DEBUG - [Now] Updated play state for /library/metadata/1326.

Apr 08, 2018 15:58:46.862 [8004] DEBUG - Statistics: (0ac6i7pt0hdbvkj476re8kt9) Reporting active playback in state 3 of type 1 (scrobble: 0) for account 1

Apr 08, 2018 15:58:46.863 [8004] DEBUG - Streaming Resource: Removing session 0xa394588:4n5yzt40h8mz6ug1lg2cek07

Apr 08, 2018 15:58:46.863 [3188] DEBUG - Completed: [127.0.0.1:53044] 200 GET /:/timeline?ratingKey=1326&key=%2Flibrary%2Fmetadata%2F1326&playQueueItemID=6604&state=stopped&hasMDE=1&time=0&duration=8547000 (6 live) GZIP 9ms 526 bytes (pipelined: 17)

Apr 08, 2018 15:58:46.879 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:46.879 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:46.880 [4036] DEBUG - Request: [127.0.0.1:53049 (Loopback)] GET /hubs/home/onDeck (6 live) Page 0-11 GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:46.880 [9240] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /hubs/home/continueWatching (6 live) Page 0-11 GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:46.881 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:46.890 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:46.891 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:46.891 [4036] DEBUG - Setting container serialization range to [0, 11] (total=-1)

Apr 08, 2018 15:58:46.892 [4036] DEBUG - We're going to try to auto-select an audio stream for account 1.

Apr 08, 2018 15:58:46.892 [4036] DEBUG - Selecting best audio stream for part ID 3160 (autoselect: 1 language: en)

Apr 08, 2018 15:58:46.892 [4036] DEBUG - Audio Stream: 7440, Subtitle Stream: -1

Apr 08, 2018 15:58:46.893 [8004] DEBUG - Request: [127.0.0.1:53043 (Loopback)] GET /hubs/home/recentlyAdded?type=2 (8 live) Page 0-11 GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:46.893 [9092] DEBUG - Completed: [127.0.0.1:53049] 200 GET /hubs/home/onDeck (8 live) GZIP Page 0-11 13ms 3487 bytes (pipelined: 16)

Apr 08, 2018 15:58:46.895 [9240] DEBUG - Setting container serialization range to [0, 11] (total=-1)

Apr 08, 2018 15:58:46.896 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:46.896 [3188] DEBUG - Completed: [127.0.0.1:53044] 200 GET /hubs/home/continueWatching (8 live) GZIP Page 0-11 17ms 2499 bytes (pipelined: 18)

Apr 08, 2018 15:58:46.898 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:46.899 [2940] DEBUG - Request: [127.0.0.1:53061 (Loopback)] GET /hubs/home/recentlyAdded?type=1 (8 live) Page 0-38 GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:46.900 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:46.900 [2940] DEBUG - Setting container serialization range to [0, 38] (total=-1)

Apr 08, 2018 15:58:46.900 [8004] DEBUG - It took 15.625000 ms to retrieve 200 items.

Apr 08, 2018 15:58:46.902 [4036] DEBUG - Request: [127.0.0.1:53062 (Loopback)] GET /hubs/home/recentlyAdded?type=1&personal=1 (9 live) Page 0-11 GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:46.902 [4036] DEBUG - Setting container serialization range to [0, 11] (total=-1)

Apr 08, 2018 15:58:46.903 [3188] DEBUG - Completed: [127.0.0.1:53062] 200 GET /hubs/home/recentlyAdded?type=1&personal=1 (9 live) GZIP Page 0-11 11ms 480 bytes (pipelined: 1)

Apr 08, 2018 15:58:46.904 [9240] DEBUG - Request: [127.0.0.1:53049 (Loopback)] GET /hubs/home/recentlyAdded?type=13 (9 live) Page 0-11 GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:46.904 [9240] DEBUG - Setting container serialization range to [0, 11] (total=-1)

Apr 08, 2018 15:58:46.905 [3188] DEBUG - Completed: [127.0.0.1:53049] 200 GET /hubs/home/recentlyAdded?type=13 (9 live) GZIP Page 0-11 8ms 480 bytes (pipelined: 17)

Apr 08, 2018 15:58:46.905 [8004] DEBUG - It took 0.000000 ms to retrieve 405 items.

Apr 08, 2018 15:58:46.907 [8004] DEBUG - It took 0.000000 ms to retrieve 121 items.

Apr 08, 2018 15:58:46.911 [12304] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /hubs/home/recentlyAdded?type=8 (9 live) Page 0-11 GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:46.911 [1324] DEBUG - Request: [127.0.0.1:53063 (Loopback)] GET /library/sections/2/firstCharacter?type=1&includeCollections=1 (9 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:46.911 [2940] DEBUG - Setting container serialization range to [0, 38] (total=279)

Apr 08, 2018 15:58:46.911 [12304] DEBUG - Setting container serialization range to [0, 11] (total=-1)

Apr 08, 2018 15:58:46.912 [3188] DEBUG - Completed: [127.0.0.1:53044] 200 GET /hubs/home/recentlyAdded?type=8 (9 live) GZIP Page 0-11 13ms 480 bytes (pipelined: 19)

Apr 08, 2018 15:58:46.913 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 15:58:46.914 [4036] DEBUG - Request: [127.0.0.1:53044 (Loopback)] GET /library/sections/2/all?type=1&includeCollections=1 (9 live) Page 0--1 GZIP Signed-in Token (kbowie)

Apr 08, 2018 15:58:46.915 [4036] DEBUG - Setting container serialization range to [0, -1] (total=-1)

Apr 08, 2018 15:58:46.915 [8004] DEBUG - Setting container serialization range to [0, 11] (total=-1)

Apr 08, 2018 15:58:46.915 [9092] DEBUG - Completed: [127.0.0.1:53063] 200 GET /library/sections/2/firstCharacter?type=1&includeCollections=1 (9 live) GZIP 14ms 750 bytes (pipelined: 1)

Apr 08, 2018 15:58:46.916 [8004] DEBUG - There were 2 top-level paths for The Man in the High Castle.

Apr 08, 2018 15:58:46.922 [9092] DEBUG - Completed: [127.0.0.1:53061] 200 GET /hubs/home/recentlyAdded?type=1 (9 live) GZIP Page 0-38 31ms 15276 bytes (pipelined: 1)

Apr 08, 2018 15:58:46.924 [8004] DEBUG - There were 1 top-level paths for Archer (2009).

Apr 08, 2018 15:58:46.925 [8004] DEBUG - There were 1 top-level paths for Rick and Morty.

Apr 08, 2018 15:58:46.926 [4036] DEBUG - Setting container serialization range to [0, -1] (total=279)

Apr 08, 2018 15:58:46.926 [8004] DEBUG - There were 1 top-level paths for Game of Thrones.

Apr 08, 2018 15:58:46.927 [9092] DEBUG - Completed: [127.0.0.1:53044] 200 GET /library/sections/2/all?type=1&includeCollections=1 (9 live) GZIP Page 0--1 13ms 667 bytes (pipelined: 20)

Apr 08, 2018 15:58:46.930 [8004] DEBUG - There were 1 top-level paths for Top Gear.

Apr 08, 2018 15:58:46.931 [8004] DEBUG - There were 1 top-level paths for Futurama.

Apr 08, 2018 15:58:46.934 [8004] DEBUG - There were 1 top-level paths for Star Wars: The Clone Wars.

Apr 08, 2018 15:58:46.935 [8004] DEBUG - There were 1 top-level paths for Downton Abbey.

Apr 08, 2018 15:58:46.938 [9092] DEBUG - Completed: [127.0.0.1:53043] 200 GET /hubs/home/recentlyAdded?type=2 (9 live) GZIP Page 0-11 57ms 9892 bytes (pipelined: 11)

Apr 08, 2018 15:58:52.249 [5136] DEBUG - Cleaning directory for session afh999y583ljwndh462jjmik (C:\Users\Kurt Bowie\AppData\Local\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-afh999y583ljwndh462jjmik-8c4a4d55-d601-49b1-afb7-04f815ead92d)

Apr 08, 2018 15:59:21.470 [11972] DEBUG - BPQ: deferring onLibraryChanged callback, only 38 seconds since last library change

Apr 08, 2018 15:59:38.842 [10504] DEBUG - NetworkServiceBrowser: SSDP departed after not being seen for 21.959340 seconds: 192.168.1.207

Apr 08, 2018 15:59:38.956 [10504] DEBUG - NetworkServiceBrowser: SSDP arrived: 192.168.1.207 (http://192.168.1.207:8008/ssdp/device-desc.xml)

Apr 08, 2018 15:59:38.956 [11972] DEBUG - HTTP requesting GET http://192.168.1.207:8008/ssdp/device-desc.xml

Apr 08, 2018 15:59:38.963 [11972] DEBUG - HTTP 200 response from GET http://192.168.1.207:8008/ssdp/device-desc.xml

Apr 08, 2018 15:59:43.470 [9428] DEBUG - BPQ: onConsiderProcessing: Idle (true)

Apr 08, 2018 15:59:43.470 [9428] DEBUG - BPQ: [Idle] -> [Processing]

Apr 08, 2018 15:59:43.471 [9428] DEBUG - BPQ: generating queue items from 0 generator(s)

Apr 08, 2018 15:59:43.471 [9428] DEBUG - BPQ: generated 0 item(s) for queue

Apr 08, 2018 15:59:43.471 [9428] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.

Apr 08, 2018 15:59:43.471 [9428] DEBUG - PlayQueue: 0 generated IDs compressed down to a 2 byte blob.

Apr 08, 2018 15:59:43.473 [9428] DEBUG - BPQ: [Processing] -> [Idle]

Apr 08, 2018 16:00:04.430 [11972] DEBUG - Statistics: Flushing 1 expired bandwidth entries, 1 expired media entries.

Apr 08, 2018 16:00:20.406 [6732] DEBUG - Request: [127.0.0.1:53066 (Loopback)] GET /web/common/img/backgrounds/preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png (4 live) GZIP Signed-in

Apr 08, 2018 16:00:20.406 [6732] DEBUG - Final path: "C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-1046ba85f\WebClient.bundle\Contents\Resources\common\img\backgrounds\preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png"

Apr 08, 2018 16:00:20.406 [6732] DEBUG - Content-Length of C:\Program Files (x86)\Plex\Plex Media Server\Resources\Plug-ins-1046ba85f\WebClient.bundle\Contents\Resources\common\img\backgrounds\preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png is 31365.

Apr 08, 2018 16:00:20.407 [3188] DEBUG - Completed: [127.0.0.1:53066] 200 GET /web/common/img/backgrounds/preset-dark.64cc1c942221cd2c153244bd8ecfb67a.png (4 live) GZIP 1ms 31365 bytes (pipelined: 1)

Apr 08, 2018 16:00:29.370 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 16:00:29.378 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 16:00:29.378 [6732] DEBUG - Request: [127.0.0.1:53067 (Loopback)] GET /accounts/1 (7 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 16:00:29.379 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 16:00:29.379 [3188] DEBUG - Completed: [127.0.0.1:53067] 200 GET /accounts/1 (7 live) GZIP 0ms 508 bytes (pipelined: 1)

Apr 08, 2018 16:00:29.380 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 16:00:29.381 [6732] DEBUG - Request: [127.0.0.1:53069 (Loopback)] GET /system/:/prefs (7 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 16:00:29.381 [10736] DEBUG - Request: [127.0.0.1:53066 (Loopback)] GET /:/prefs (7 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 16:00:29.381 [6732] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs

Apr 08, 2018 16:00:29.381 [6732] DEBUG - HTTP requesting GET http://127.0.0.1:52652/system/:/prefs

Apr 08, 2018 16:00:29.385 [4644] DEBUG - Request: [127.0.0.1:53068 (Loopback)] GET /myplex/account (7 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 16:00:29.389 [3188] DEBUG - Completed: [127.0.0.1:53066] 200 GET /:/prefs (7 live) GZIP 18ms 6409 bytes (pipelined: 2)

Apr 08, 2018 16:00:29.391 [9092] DEBUG - Completed: [127.0.0.1:53068] 200 GET /myplex/account (7 live) GZIP 10ms 627 bytes (pipelined: 1)

Apr 08, 2018 16:00:29.399 [6732] DEBUG - HTTP 304 response from GET http://127.0.0.1:52652/system/:/prefs

Apr 08, 2018 16:00:29.399 [6732] DEBUG - [com.plexapp.system] HTTP reply status 304, with 0 bytes of content.

Apr 08, 2018 16:00:29.400 [9092] DEBUG - Completed: [127.0.0.1:53069] 304 GET /system/:/prefs (7 live) GZIP 21ms 324 bytes (pipelined: 1)

Apr 08, 2018 16:00:29.408 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 16:00:29.408 [7032] DEBUG - Request: [127.0.0.1:53069 (Loopback)] PUT /myplex/refreshReachability (7 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 16:00:29.408 [7032] DEBUG - MyPlex: Requesting reachability check.

Apr 08, 2018 16:00:29.409 [7032] DEBUG - HTTP requesting PUT https://plex.tv/api/servers/6d016da02bad43ca50189c0fbc45fe87acf90cfd/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=2514ac5c-6086-442a-8cc1-ee0e69101116

Apr 08, 2018 16:00:29.420 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 16:00:29.421 [4644] DEBUG - Request: [127.0.0.1:53066 (Loopback)] GET /updater/status (7 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 16:00:29.421 [9092] DEBUG - Completed: [127.0.0.1:53066] 200 GET /updater/status (7 live) GZIP 0ms 454 bytes (pipelined: 3)

Apr 08, 2018 16:00:29.448 [9092] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 16:00:29.448 [10736] DEBUG - Request: [127.0.0.1:53066 (Loopback)] PUT /updater/check?download=0 (7 live) GZIP Signed-in Token (kbowie)

Apr 08, 2018 16:00:29.448 [10736] DEBUG - AutoUpdate: requesting check endpoint: /updater/products/1/check.xml?build=windows-i386&channel=16&distribution=english&version=1.12.1.4885-1046ba85f, download: NO

Apr 08, 2018 16:00:29.448 [10736] DEBUG - Downloading document https://plex.tv/updater/products/1/check.xml?build=windows-i386&channel=16&distribution=english&version=1.12.1.4885-1046ba85f

Apr 08, 2018 16:00:29.449 [10736] DEBUG - HTTP requesting GET https://plex.tv/updater/products/1/check.xml?build=windows-i386&channel=16&distribution=english&version=1.12.1.4885-1046ba85f

Apr 08, 2018 16:00:29.939 [7032] DEBUG - HTTP 200 response from PUT https://plex.tv/api/servers/6d016da02bad43ca50189c0fbc45fe87acf90cfd/connectivity?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx&asyncIdentifier=2514ac5c-6086-442a-8cc1-ee0e69101116

Apr 08, 2018 16:00:29.939 [3188] DEBUG - Completed: [127.0.0.1:53069] 200 PUT /myplex/refreshReachability (7 live) GZIP 531ms 268 bytes (pipelined: 2)

Apr 08, 2018 16:00:29.970 [10736] DEBUG - HTTP 200 response from GET https://plex.tv/updater/products/1/check.xml?build=windows-i386&channel=16&distribution=english&version=1.12.1.4885-1046ba85f

Apr 08, 2018 16:00:29.970 [10736] INFO - AutoUpdate: no updates available

Apr 08, 2018 16:00:29.970 [3188] DEBUG - Completed: [127.0.0.1:53066] 200 PUT /updater/check?download=0 (7 live) GZIP 522ms 268 bytes (pipelined: 4)

Apr 08, 2018 16:00:31.655 [6732] DEBUG - Request: [34.244.145.207:23263 (WAN)] GET /identity (8 live) TLS Signed-in Token (kbowie)

Apr 08, 2018 16:00:31.656 [9092] DEBUG - Completed: [34.244.145.207:23263] 200 GET /identity (8 live) TLS 0ms 357 bytes

Apr 08, 2018 16:00:31.760 [3188] DEBUG - Auth: authenticated user 1 as kbowie

Apr 08, 2018 16:00:31.760 [4644] DEBUG - Request: [127.0.0.1:53066 (Loopback)] GET /diagnostics/logs (8 live) GZIP Signed-in Token (kbowie)