Server Version#: Version 1.24.4.5081
Player Version#:8.11.0.22186
I have a WD PR4100 and have tested previous versions of PMS, but this issue still occurs.
I receive the error “An error occurred while playing this item” only when playing any track/Album/Artist in my Music Library.
I do not receive this error in any other library other than Music, I would update my client Plex app, but due to the infamous auto-play issue on the newer client apps, I need to keep the client version 8.11.0.22186.
Below, is a snippet of the logs when trying to play anything in the Music Library.
Oct 05, 2021 12:39:48.829 [0x7fa448355b38] INFO - Plex Media Server v1.24.4.5081-e362dc1ee - Western Digital MyCloudPR4100 x86_64 - build: linux-x86_64 wd-pr4100-os5 - GMT -07:00
Oct 05, 2021 12:39:48.830 [0x7fa448355b38] INFO - Linux version: 5.17.107, language: en-US
Oct 05, 2021 12:39:48.831 [0x7fa448355b38] INFO - Processor Intel(R) Pentium(R) CPU N3710 @ 1.60GHz
Oct 05, 2021 12:39:48.831 [0x7fa448355b38] INFO - Compiler is - Clang 11.0.1 (https://plex.tv e0c29d5827bc4eaaa2ceb882cbeed224b0960173)
Oct 05, 2021 12:39:48.831 [0x7fa448355b38] INFO - /mnt/HD/HD_a2/Nas_Prog/plexmediaserver/binaries/Plex Media Server
Oct 05, 2021 12:39:48.828 [0x7fa4431aab38] DEBUG - [Now] Updated play state for /library/metadata/117092.
Oct 05, 2021 12:39:48.829 [0x7fa443c56b38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:48.829 [0x7fa443c56b38] DEBUG - Selecting best audio stream for part ID 3949072 (autoselect: 1 language: en)
Oct 05, 2021 12:39:48.829 [0x7fa443c56b38] DEBUG - Audio Stream: 7846390, Subtitle Stream: -1
Oct 05, 2021 12:39:48.830 [0x7fa443c56b38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:48.830 [0x7fa443c56b38] DEBUG - Selecting best audio stream for part ID 4259155 (autoselect: 1 language: en)
Oct 05, 2021 12:39:48.831 [0x7fa4431aab38] DEBUG - Statistics: (1ed731cf2dc61155-com-plexapp-android) Reporting active playback in state 0 of type 10 (scrobble: 0) for account 1
Oct 05, 2021 12:39:48.831 [0x7fa443730b38] DEBUG - It took 60.000000 ms to retrieve 1 items.
Oct 05, 2021 12:39:48.832 [0x7fa443c56b38] DEBUG - Audio Stream: 8464946, Subtitle Stream: -1
Oct 05, 2021 12:39:48.833 [0x7fa443c56b38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:48.833 [0x7fa443c56b38] DEBUG - Selecting best audio stream for part ID 3941322 (autoselect: 1 language: en)
Oct 05, 2021 12:39:48.833 [0x7fa443c56b38] DEBUG - Audio Stream: 7918620, Subtitle Stream: -1
Oct 05, 2021 12:39:48.835 [0x7fa4453a9b38] DEBUG - Completed: [10.0.0.6:42769] 200 GET /:/timeline?bufferedTime=0&duration=7703000&guid=plex%3A%2F%2Ftrack%2F5edade3ac5e64fcc10b7a762&key=%2Flibrary%2Fmetadata%2F117092&playbackTime=275&playQueueItemID=256034&ratingKey=117092&state=playing&time=0&token=xxxxxxxxxxxxxxxxxxxx (76 live) TLS GZIP 107ms 421 bytes (pipelined: 17)
Oct 05, 2021 12:39:48.835 [0x7fa4430cfb38] DEBUG - [Now] Adding 1 sessions.
Oct 05, 2021 12:39:48.836 [0x7fa443236b38] DEBUG - [Now] User is sothis (ID: 1)
Oct 05, 2021 12:39:48.837 [0x7fa443236b38] DEBUG - [Now] Device is Android (AFTJMST12).
Oct 05, 2021 12:39:48.837 [0x7fa443c56b38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:48.837 [0x7fa443c56b38] DEBUG - Selecting best audio stream for part ID 3941601 (autoselect: 1 language: en)
Oct 05, 2021 12:39:48.837 [0x7fa443c56b38] DEBUG - Audio Stream: 7867664, Subtitle Stream: -1
Oct 05, 2021 12:39:48.839 [0x7fa443236b38] DEBUG - [Now] Profile is Android
Oct 05, 2021 12:39:48.839 [0x7fa443236b38] DEBUG - [Now] Updated play state for /library/metadata/117092.
Oct 05, 2021 12:39:48.840 [0x7fa443c56b38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:48.840 [0x7fa443c56b38] DEBUG - Selecting best audio stream for part ID 3948484 (autoselect: 1 language: en)
Oct 05, 2021 12:39:48.840 [0x7fa443c56b38] DEBUG - Audio Stream: 7859630, Subtitle Stream: -1
Oct 05, 2021 12:39:48.841 [0x7fa445386b38] DEBUG - Completed: [10.0.0.200:57516] 200 GET /status/sessions (77 live) TLS GZIP 18ms 1436 bytes (pipelined: 283)
Oct 05, 2021 12:39:48.844 [0x7fa443850b38] DEBUG - Request: [10.0.0.200:57516 (Subnet)] GET /status/sessions (77 live) TLS GZIP Signed-in Token (sothis)
Oct 05, 2021 12:39:48.844 [0x7fa443236b38] DEBUG - Statistics: (1ed731cf2dc61155-com-plexapp-android) Reporting active playback in state 0 of type 10 (scrobble: 0) for account 1
Oct 05, 2021 12:39:48.846 [0x7fa443c56b38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:48.847 [0x7fa443c56b38] DEBUG - Selecting best audio stream for part ID 3967397 (autoselect: 1 language: en)
Oct 05, 2021 12:39:48.847 [0x7fa443c56b38] DEBUG - Audio Stream: 7929735, Subtitle Stream: -1
Oct 05, 2021 12:39:48.847 [0x7fa4431aab38] DEBUG - PlayQueue: Setting new active item 256034 for play queue 8171.
Oct 05, 2021 12:39:48.848 [0x7fa4431aab38] DEBUG - PlayQueue: total generated: 1427, before cursor: 295, after cursor: 104
Oct 05, 2021 12:39:48.848 [0x7fa443c56b38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:48.848 [0x7fa443c56b38] DEBUG - Selecting best audio stream for part ID 3989154 (autoselect: 1 language: en)
Oct 05, 2021 12:39:48.849 [0x7fa443c56b38] DEBUG - Audio Stream: 7868780, Subtitle Stream: -1
Oct 05, 2021 12:39:48.851 [0x7fa44358bb38] DEBUG - Request: [10.0.0.6:42769 (Subnet)] GET /:/timeline?bufferedTime=0&duration=7703000&guid=plex%3A%2F%2Ftrack%2F5edade3ac5e64fcc10b7a762&key=%2Flibrary%2Fmetadata%2F117092&playbackTime=275&playQueueItemID=256034&ratingKey=117092&state=playing&time=0&token=xxxxxxxxxxxxxxxxxxxx (77 live) TLS GZIP Signed-in Token (sothis)
Oct 05, 2021 12:39:48.854 [0x7fa4431aab38] DEBUG - PlayQueue: No need to refresh, active window is operational.
Oct 05, 2021 12:39:48.858 [0x7fa4453a9b38] DEBUG - Completed: [10.0.0.6:42563] 200 GET /playQueues/8171?center=256033&includeLoudnessRamps=1&repeat=0 (77 live) TLS GZIP 499ms 18906 bytes (pipelined: 336)
Oct 05, 2021 12:39:48.862 [0x7fa44358bb38] DEBUG - Client [1ed731cf2dc61155-com-plexapp-android] reporting timeline state playing, progress of 0/7703000ms for guid=plex://track/5edade3ac5e64fcc10b7a762, playbackTime=275ms ratingKey=117092 url=, key=/library/metadata/117092, containerKey=, metadataId=117092, source=
Oct 05, 2021 12:39:48.870 [0x7fa4453a9b38] DEBUG - Completed: [10.0.0.6:42634] 200 GET /:/timeline?bufferedTime=0&duration=7703000&guid=plex%3A%2F%2Ftrack%2F5edade3ac5e64fcc10b7a762&key=%2Flibrary%2Fmetadata%2F117092&playbackTime=275&playQueueItemID=256034&ratingKey=117092&state=playing&time=0&token=xxxxxxxxxxxxxxxxxxxx (77 live) TLS GZIP 162ms 421 bytes (pipelined: 226)
Oct 05, 2021 12:39:48.872 [0x7fa443850b38] DEBUG - [Now] Adding 1 sessions.
Oct 05, 2021 12:39:48.875 [0x7fa44358bb38] DEBUG - [Now] User is sothis (ID: 1)
Oct 05, 2021 12:39:48.876 [0x7fa4453a9b38] DEBUG - Completed: [10.0.0.200:57516] 200 GET /status/sessions (77 live) TLS GZIP 32ms 1436 bytes (pipelined: 284)
Oct 05, 2021 12:39:48.877 [0x7fa44358bb38] DEBUG - [Now] Device is Android (AFTJMST12).
Oct 05, 2021 12:39:48.878 [0x7fa4453a9b38] DEBUG - Completed: [10.0.0.6:42708] 200 GET /library/metadata/117092?includeChapters=1&includeLoudnessRamps=1&includeMarkers=1&includeRelated=1 (77 live) TLS GZIP 174ms 1307 bytes (pipelined: 101)
Oct 05, 2021 12:39:48.880 [0x7fa44358bb38] DEBUG - [Now] Profile is Android
Oct 05, 2021 12:39:48.880 [0x7fa44358bb38] DEBUG - [Now] Updated play state for /library/metadata/117092.
Oct 05, 2021 12:39:48.881 [0x7fa44358bb38] DEBUG - Statistics: (1ed731cf2dc61155-com-plexapp-android) Reporting active playback in state 0 of type 10 (scrobble: 0) for account 1
Oct 05, 2021 12:39:48.883 [0x7fa443bb5b38] DEBUG - HTTP/1.1 (0.1s) 200 response from GET http://127.0.0.1:41573/system/agents/media/get?guid=plex%3A%2F%2Falbum%2F5d07c3be403c640290998aac&mediaType=9&url=metadata%3A%2F%2Fposters%2Ftv.plex.agents.music_fb7e4a07bf64d06c6082bcadadc0eae0556f3ee3
Oct 05, 2021 12:39:48.883 [0x7fa443bb5b38] DEBUG - Calculated media file path for path [metadata://posters/tv.plex.agents.music_fb7e4a07bf64d06c6082bcadadc0eae0556f3ee3]: ["/mnt/HD/HD_a2/Nas_Prog/plex_conf/Plex Media Server/Metadata/Albums/f/3f9c99598eae013b9cbc495555f47982619486f.bundle/Contents/_combined/posters/tv.plex.agents.music_fb7e4a07bf64d06c6082bcadadc0eae0556f3ee3"]
Oct 05, 2021 12:39:48.883 [0x7fa443bb5b38] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Oct 05, 2021 12:39:48.883 [0x7fa443bb5b38] DEBUG - Photo cache obtained 12026 bytes from http://127.0.0.1:32400/library/metadata/133514/thumb/1629198986
Oct 05, 2021 12:39:48.883 [0x7fa443bb5b38] DEBUG - Saving original media file to /mnt/HD/HD_a2/Nas_Prog/plex_conf/Plex Media Server/Cache/PhotoTranscoder/9f/9f2d4247db232b9b3a1c30010245f171d45a90d0.jpg
Oct 05, 2021 12:39:48.886 [0x7fa443bb5b38] DEBUG - Created thumbnail of size 200x200, has pixels: 1
Oct 05, 2021 12:39:48.887 [0x7fa445386b38] DEBUG - Completed: [10.0.0.6:42622] 200 GET /:/timeline?bufferedTime=0&duration=7703000&guid=plex%3A%2F%2Ftrack%2F5edade3ac5e64fcc10b7a762&key=%2Flibrary%2Fmetadata%2F117092&playbackTime=275&playQueueItemID=256034&ratingKey=117092&state=playing&time=0&token=xxxxxxxxxxxxxxxxxxxx (77 live) TLS GZIP 79ms 421 bytes (pipelined: 240)
Oct 05, 2021 12:39:48.894 [0x7fa445386b38] DEBUG - Completed: [10.0.0.6:42419] 200 GET /photo/:/transcode?height=512&width=512&quality=90&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F133514%2Fthumb%2F1629198986 (77 live) TLS GZIP 152ms 14112 bytes (pipelined: 279)
Oct 05, 2021 12:39:48.898 [0x7fa4437eab38] DEBUG - Request: [10.0.0.6:42634 (Subnet)] GET /:/timeline?bufferedTime=0&duration=7703000&guid=plex%3A%2F%2Ftrack%2F5edade3ac5e64fcc10b7a762&key=%2Flibrary%2Fmetadata%2F117092&playbackTime=275&playQueueItemID=256034&ratingKey=117092&state=playing&time=0&token=xxxxxxxxxxxxxxxxxxxx (77 live) TLS GZIP Signed-in Token (sothis)
Oct 05, 2021 12:39:48.902 [0x7fa4437eab38] DEBUG - Client [1ed731cf2dc61155-com-plexapp-android] reporting timeline state playing, progress of 0/7703000ms for guid=plex://track/5edade3ac5e64fcc10b7a762, playbackTime=275ms ratingKey=117092 url=, key=/library/metadata/117092, containerKey=, metadataId=117092, source=
Oct 05, 2021 12:39:48.904 [0x7fa4435b5b38] DEBUG - HTTP/1.1 (0.1s) 200 response from GET http://127.0.0.1:41573/system/agents/media/get?guid=plex%3A%2F%2Falbum%2F5edaddfbc5e64fcc10b2838e&mediaType=9&url=metadata%3A%2F%2Fposters%2Ftv.plex.agents.music_29628ed89c6fcecf0f8c93d2537bb27167bf428d
Oct 05, 2021 12:39:48.905 [0x7fa4435b5b38] DEBUG - Calculated media file path for path [metadata://posters/tv.plex.agents.music_29628ed89c6fcecf0f8c93d2537bb27167bf428d]: ["/mnt/HD/HD_a2/Nas_Prog/plex_conf/Plex Media Server/Metadata/Albums/3/9d0f29ae5cebf0dcc2bc403b3239eb06e67a9ce.bundle/Contents/_combined/posters/tv.plex.agents.music_29628ed89c6fcecf0f8c93d2537bb27167bf428d"]
Oct 05, 2021 12:39:48.905 [0x7fa4453a9b38] DEBUG - Completed: [10.0.0.6:42769] 200 GET /:/timeline?bufferedTime=0&duration=7703000&guid=plex%3A%2F%2Ftrack%2F5edade3ac5e64fcc10b7a762&key=%2Flibrary%2Fmetadata%2F117092&playbackTime=275&playQueueItemID=256034&ratingKey=117092&state=playing&time=0&token=xxxxxxxxxxxxxxxxxxxx (77 live) TLS GZIP 54ms 421 bytes (pipelined: 18)
Oct 05, 2021 12:39:48.909 [0x7fa443b92b38] DEBUG - Request: [10.0.0.6:42708 (Subnet)] GET /:/timeline?bufferedTime=0&duration=7703000&guid=plex%3A%2F%2Ftrack%2F5edade3ac5e64fcc10b7a762&key=%2Flibrary%2Fmetadata%2F117092&playbackTime=275&playQueueItemID=256034&ratingKey=117092&state=playing&time=0&token=xxxxxxxxxxxxxxxxxxxx (77 live) TLS GZIP Signed-in Token (sothis)
Oct 05, 2021 12:39:48.914 [0x7fa4437eab38] DEBUG - [Now] User is sothis (ID: 1)
Oct 05, 2021 12:39:48.914 [0x7fa4437eab38] DEBUG - [Now] Device is Android (AFTJMST12).
Oct 05, 2021 12:39:48.915 [0x7fa4437eab38] DEBUG - [Now] Profile is Android
Oct 05, 2021 12:39:48.915 [0x7fa4437eab38] DEBUG - [Now] Updated play state for /library/metadata/117092.
Oct 05, 2021 12:39:48.916 [0x7fa4437eab38] DEBUG - Statistics: (1ed731cf2dc61155-com-plexapp-android) Reporting active playback in state 0 of type 10 (scrobble: 0) for account 1
Oct 05, 2021 12:39:48.919 [0x7fa443b92b38] DEBUG - Client [1ed731cf2dc61155-com-plexapp-android] reporting timeline state playing, progress of 0/7703000ms for guid=plex://track/5edade3ac5e64fcc10b7a762, playbackTime=275ms ratingKey=117092 url=, key=/library/metadata/117092, containerKey=, metadataId=117092, source=
Oct 05, 2021 12:39:48.927 [0x7fa443815b38] DEBUG - Request: [10.0.0.6:42622 (Subnet)] GET /library/streams/7904068/levels?subsample=256 (77 live) TLS GZIP Signed-in Token (sothis)
Oct 05, 2021 12:39:48.928 [0x7fa443b92b38] DEBUG - [Now] User is sothis (ID: 1)
Oct 05, 2021 12:39:48.928 [0x7fa443b92b38] DEBUG - [Now] Device is Android (AFTJMST12).
Oct 05, 2021 12:39:48.929 [0x7fa443b92b38] DEBUG - [Now] Profile is Android
Oct 05, 2021 12:39:48.929 [0x7fa443b92b38] DEBUG - [Now] Updated play state for /library/metadata/117092.
Oct 05, 2021 12:39:48.929 [0x7fa443b92b38] DEBUG - Statistics: (1ed731cf2dc61155-com-plexapp-android) Reporting active playback in state 0 of type 10 (scrobble: 0) for account 1
Oct 05, 2021 12:39:48.931 [0x7fa4435b5b38] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: -1)
Oct 05, 2021 12:39:48.931 [0x7fa4435b5b38] DEBUG - Photo cache obtained 17248 bytes from http://127.0.0.1:32400/library/metadata/117091/thumb/1629118329
Oct 05, 2021 12:39:48.931 [0x7fa4435b5b38] DEBUG - Saving original media file to /mnt/HD/HD_a2/Nas_Prog/plex_conf/Plex Media Server/Cache/PhotoTranscoder/c0/c059ceffc7e9438d44c001a8489aa307d99de531.jpg
Oct 05, 2021 12:39:48.936 [0x7fa4453a9b38] DEBUG - Completed: [10.0.0.6:42634] 200 GET /:/timeline?bufferedTime=0&duration=7703000&guid=plex%3A%2F%2Ftrack%2F5edade3ac5e64fcc10b7a762&key=%2Flibrary%2Fmetadata%2F117092&playbackTime=275&playQueueItemID=256034&ratingKey=117092&state=playing&time=0&token=xxxxxxxxxxxxxxxxxxxx (77 live) TLS GZIP 38ms 421 bytes (pipelined: 227)
Oct 05, 2021 12:39:48.937 [0x7fa445386b38] DEBUG - CERT: incomplete TLS handshake from [::ffff:10.0.0.6]:42779: sslv3 alert certificate expired
Oct 05, 2021 12:39:48.937 [0x7fa4430cfb38] DEBUG - Request: [10.0.0.6:42563 (Subnet)] GET /playQueues/8171?center=256034&includeLoudnessRamps=1&repeat=0 (77 live) TLS GZIP Signed-in Token (sothis)
Oct 05, 2021 12:39:48.938 [0x7fa443c56b38] DEBUG - Request: [10.0.0.6:42419 (Subnet)] GET /photo/:/transcode?height=512&width=512&quality=90&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F117091%2Fthumb%2F1629118329 (77 live) TLS GZIP Signed-in Token (sothis)
Oct 05, 2021 12:39:48.938 [0x7fa443c56b38] DEBUG - Photo transcoder: Request for url [http://127.0.0.1:32400/library/metadata/117091/thumb/1629118329] (is local: 1 upscaled: 0)
Oct 05, 2021 12:39:48.939 [0x7fa443c56b38] DEBUG - The cache file did not exist, but the original did [/mnt/HD/HD_a2/Nas_Prog/plex_conf/Plex Media Server/Cache/PhotoTranscoder/c0/c059ceffc7e9438d44c001a8489aa307d99de531.jpg].
Oct 05, 2021 12:39:48.939 [0x7fa4430cfb38] DEBUG - PlayQueue: total generated: 1427, before cursor: 295, after cursor: 104
Oct 05, 2021 12:39:48.941 [0x7fa4430cfb38] DEBUG - PlayQueue: No need to refresh, active window is operational.
Oct 05, 2021 12:39:48.944 [0x7fa443c56b38] DEBUG - Created thumbnail of size 300x300, has pixels: 1
Oct 05, 2021 12:39:48.946 [0x7fa4431aab38] DEBUG - Request: [10.0.0.6:42769 (Subnet)] GET /:/timeline?bufferedTime=0&duration=7703000&guid=plex%3A%2F%2Ftrack%2F5edade3ac5e64fcc10b7a762&key=%2Flibrary%2Fmetadata%2F117092&playbackTime=200&playQueueItemID=256034&ratingKey=117092&state=stopped&time=0&timeStalled=0&token=xxxxxxxxxxxxxxxxxxxx (77 live) TLS GZIP Signed-in Token (sothis)
Oct 05, 2021 12:39:48.949 [0x7fa4431aab38] DEBUG - Client [1ed731cf2dc61155-com-plexapp-android] reporting timeline state stopped, progress of 0/7703000ms for guid=plex://track/5edade3ac5e64fcc10b7a762, playbackTime=200ms ratingKey=117092 url=, key=/library/metadata/117092, containerKey=, metadataId=117092, source=
Oct 05, 2021 12:39:48.955 [0x7fa4453a9b38] DEBUG - Completed: [10.0.0.6:42708] 200 GET /:/timeline?bufferedTime=0&duration=7703000&guid=plex%3A%2F%2Ftrack%2F5edade3ac5e64fcc10b7a762&key=%2Flibrary%2Fmetadata%2F117092&playbackTime=275&playQueueItemID=256034&ratingKey=117092&state=playing&time=0&token=xxxxxxxxxxxxxxxxxxxx (77 live) TLS GZIP 45ms 421 bytes (pipelined: 102)
Oct 05, 2021 12:39:48.955 [0x7fa4431aab38] DEBUG - [Now] User is sothis (ID: 1)
Oct 05, 2021 12:39:48.956 [0x7fa4431aab38] DEBUG - [Now] Device is Android (AFTJMST12).
Oct 05, 2021 12:39:48.959 [0x7fa4431aab38] DEBUG - [Now] Profile is Android
Oct 05, 2021 12:39:48.959 [0x7fa4431aab38] DEBUG - [Now] Updated play state for /library/metadata/117092.
Oct 05, 2021 12:39:48.962 [0x7fa4431aab38] DEBUG - Statistics: (1ed731cf2dc61155-com-plexapp-android) Reporting active playback in state 3 of type 10 (scrobble: 0) for account 1
Oct 05, 2021 12:39:48.962 [0x7fa4435b5b38] DEBUG - Created thumbnail of size 128x128, has pixels: 1
Oct 05, 2021 12:39:48.966 [0x7fa445386b38] DEBUG - Completed: [10.0.0.6:42419] 200 GET /photo/:/transcode?height=512&width=512&quality=90&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F117091%2Fthumb%2F1629118329 (77 live) TLS GZIP 27ms 23798 bytes (pipelined: 280)
Oct 05, 2021 12:39:48.968 [0x7fa445386b38] DEBUG - Completed: [10.0.0.6:42420] 200 GET /photo/:/transcode?height=128&width=128&quality=90&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F117091%2Fthumb%2F1629118329 (77 live) TLS GZIP 249ms 6388 bytes (pipelined: 185)
Oct 05, 2021 12:39:48.976 [0x7fa445386b38] DEBUG - Completed: [10.0.0.6:42769] 200 GET /:/timeline?bufferedTime=0&duration=7703000&guid=plex%3A%2F%2Ftrack%2F5edade3ac5e64fcc10b7a762&key=%2Flibrary%2Fmetadata%2F117092&playbackTime=200&playQueueItemID=256034&ratingKey=117092&state=stopped&time=0&timeStalled=0&token=xxxxxxxxxxxxxxxxxxxx (77 live) TLS GZIP 30ms 421 bytes (pipelined: 19)
Oct 05, 2021 12:39:48.978 [0x7fa445386b38] DEBUG - Completed: [10.0.0.6:42622] 200 GET /library/streams/7904068/levels?subsample=256 (77 live) TLS GZIP 51ms 1036 bytes (pipelined: 241)
Oct 05, 2021 12:39:49.025 [0x7fa4430cfb38] DEBUG - It took 200.000000 ms to retrieve 41 items.
Oct 05, 2021 12:39:49.050 [0x7fa4430cfb38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:49.050 [0x7fa4430cfb38] DEBUG - Selecting best audio stream for part ID 3986813 (autoselect: 1 language: en)
Oct 05, 2021 12:39:49.050 [0x7fa4430cfb38] DEBUG - Audio Stream: 7865076, Subtitle Stream: -1
Oct 05, 2021 12:39:49.051 [0x7fa4430cfb38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:49.051 [0x7fa4430cfb38] DEBUG - Selecting best audio stream for part ID 3942023 (autoselect: 1 language: en)
Oct 05, 2021 12:39:49.051 [0x7fa4430cfb38] DEBUG - Audio Stream: 7861799, Subtitle Stream: -1
Oct 05, 2021 12:39:49.052 [0x7fa4430cfb38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:49.052 [0x7fa4430cfb38] DEBUG - Selecting best audio stream for part ID 3965329 (autoselect: 1 language: en)
Oct 05, 2021 12:39:49.052 [0x7fa4430cfb38] DEBUG - Audio Stream: 7937928, Subtitle Stream: -1
Oct 05, 2021 12:39:49.052 [0x7fa4430cfb38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:49.052 [0x7fa4430cfb38] DEBUG - Selecting best audio stream for part ID 4274453 (autoselect: 1 language: en)
Oct 05, 2021 12:39:49.052 [0x7fa4430cfb38] DEBUG - Audio Stream: 8502830, Subtitle Stream: -1
Oct 05, 2021 12:39:49.053 [0x7fa4430cfb38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:49.053 [0x7fa4430cfb38] DEBUG - Selecting best audio stream for part ID 3978910 (autoselect: 1 language: en)
Oct 05, 2021 12:39:49.053 [0x7fa4430cfb38] DEBUG - Audio Stream: 7904377, Subtitle Stream: -1
Oct 05, 2021 12:39:49.055 [0x7fa4430cfb38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:49.055 [0x7fa4430cfb38] DEBUG - Selecting best audio stream for part ID 3939556 (autoselect: 1 language: en)
Oct 05, 2021 12:39:49.055 [0x7fa4430cfb38] DEBUG - Audio Stream: 7850786, Subtitle Stream: -1
Oct 05, 2021 12:39:49.057 [0x7fa4430cfb38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:49.057 [0x7fa4430cfb38] DEBUG - Selecting best audio stream for part ID 3965149 (autoselect: 1 language: en)
Oct 05, 2021 12:39:49.057 [0x7fa4430cfb38] DEBUG - Audio Stream: 7920492, Subtitle Stream: -1
Oct 05, 2021 12:39:49.058 [0x7fa4430cfb38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:49.058 [0x7fa4430cfb38] DEBUG - Selecting best audio stream for part ID 3979228 (autoselect: 1 language: en)
Oct 05, 2021 12:39:49.058 [0x7fa4430cfb38] DEBUG - Audio Stream: 7839248, Subtitle Stream: -1
Oct 05, 2021 12:39:49.059 [0x7fa4430cfb38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:49.059 [0x7fa4430cfb38] DEBUG - Selecting best audio stream for part ID 3949072 (autoselect: 1 language: en)
Oct 05, 2021 12:39:49.059 [0x7fa4430cfb38] DEBUG - Audio Stream: 7846390, Subtitle Stream: -1
Oct 05, 2021 12:39:49.060 [0x7fa4430cfb38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:49.060 [0x7fa4430cfb38] DEBUG - Selecting best audio stream for part ID 4259155 (autoselect: 1 language: en)
Oct 05, 2021 12:39:49.060 [0x7fa4430cfb38] DEBUG - Audio Stream: 8464946, Subtitle Stream: -1
Oct 05, 2021 12:39:49.060 [0x7fa4430cfb38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:49.060 [0x7fa4430cfb38] DEBUG - Selecting best audio stream for part ID 3941322 (autoselect: 1 language: en)
Oct 05, 2021 12:39:49.060 [0x7fa4430cfb38] DEBUG - Audio Stream: 7918620, Subtitle Stream: -1
Oct 05, 2021 12:39:49.061 [0x7fa4430cfb38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:49.061 [0x7fa4430cfb38] DEBUG - Selecting best audio stream for part ID 3941601 (autoselect: 1 language: en)
Oct 05, 2021 12:39:49.061 [0x7fa4430cfb38] DEBUG - Audio Stream: 7867664, Subtitle Stream: -1
Oct 05, 2021 12:39:49.062 [0x7fa4430cfb38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:49.062 [0x7fa4430cfb38] DEBUG - Selecting best audio stream for part ID 3948484 (autoselect: 1 language: en)
Oct 05, 2021 12:39:49.062 [0x7fa4430cfb38] DEBUG - Audio Stream: 7859630, Subtitle Stream: -1
Oct 05, 2021 12:39:49.064 [0x7fa4430cfb38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:49.064 [0x7fa4430cfb38] DEBUG - Selecting best audio stream for part ID 3967397 (autoselect: 1 language: en)
Oct 05, 2021 12:39:49.064 [0x7fa4430cfb38] DEBUG - Audio Stream: 7929735, Subtitle Stream: -1
Oct 05, 2021 12:39:49.064 [0x7fa4430cfb38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:49.064 [0x7fa4430cfb38] DEBUG - Selecting best audio stream for part ID 3989154 (autoselect: 1 language: en)
Oct 05, 2021 12:39:49.064 [0x7fa4430cfb38] DEBUG - Audio Stream: 7868780, Subtitle Stream: -1
Oct 05, 2021 12:39:49.065 [0x7fa4430cfb38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:49.065 [0x7fa4430cfb38] DEBUG - Selecting best audio stream for part ID 3957644 (autoselect: 1 language: en)
Oct 05, 2021 12:39:49.065 [0x7fa4430cfb38] DEBUG - Audio Stream: 7937828, Subtitle Stream: -1
Oct 05, 2021 12:39:49.071 [0x7fa445386b38] DEBUG - Completed: [10.0.0.6:42563] 200 GET /playQueues/8171?center=256034&includeLoudnessRamps=1&repeat=0 (77 live) TLS GZIP 134ms 18803 bytes (pipelined: 337)
Oct 05, 2021 12:39:49.136 [0x7fa443850b38] DEBUG - Request: [10.0.0.6:42634 (Subnet)] GET /:/timeline?bufferedTime=0&duration=7703000&guid=plex%3A%2F%2Ftrack%2F5edade3ac5e64fcc10b7a762&key=%2Flibrary%2Fmetadata%2F117092&playbackTime=423&playQueueItemID=256034&ratingKey=117092&state=stopped&time=0&timeStalled=0&token=xxxxxxxxxxxxxxxxxxxx (77 live) TLS GZIP Signed-in Token (sothis)
Oct 05, 2021 12:39:49.138 [0x7fa443850b38] DEBUG - Client [1ed731cf2dc61155-com-plexapp-android] reporting timeline state stopped, progress of 0/7703000ms for guid=plex://track/5edade3ac5e64fcc10b7a762, playbackTime=423ms ratingKey=117092 url=, key=/library/metadata/117092, containerKey=, metadataId=117092, source=
Oct 05, 2021 12:39:49.143 [0x7fa443730b38] DEBUG - Request: [10.0.0.6:42708 (Subnet)] GET /:/timeline?bufferedTime=0&duration=7703000&guid=plex%3A%2F%2Ftrack%2F5edade3ac5e64fcc10b7a762&key=%2Flibrary%2Fmetadata%2F117092&playbackTime=430&playQueueItemID=256034&ratingKey=117092&state=stopped&time=0&token=xxxxxxxxxxxxxxxxxxxx (77 live) TLS GZIP Signed-in Token (sothis)
Oct 05, 2021 12:39:49.144 [0x7fa443850b38] DEBUG - [Now] User is sothis (ID: 1)
Oct 05, 2021 12:39:49.144 [0x7fa443850b38] DEBUG - [Now] Device is Android (AFTJMST12).
Oct 05, 2021 12:39:49.144 [0x7fa443850b38] DEBUG - [Now] Profile is Android
Oct 05, 2021 12:39:49.144 [0x7fa443850b38] DEBUG - [Now] Updated play state for /library/metadata/117092.
Oct 05, 2021 12:39:49.145 [0x7fa443850b38] DEBUG - Statistics: (1ed731cf2dc61155-com-plexapp-android) Reporting active playback in state 3 of type 10 (scrobble: 0) for account 1
Oct 05, 2021 12:39:49.145 [0x7fa443730b38] DEBUG - Client [1ed731cf2dc61155-com-plexapp-android] reporting timeline state stopped, progress of 0/7703000ms for guid=plex://track/5edade3ac5e64fcc10b7a762, playbackTime=430ms ratingKey=117092 url=, key=/library/metadata/117092, containerKey=, metadataId=117092, source=
Oct 05, 2021 12:39:49.150 [0x7fa4453a9b38] DEBUG - Completed: [10.0.0.6:42634] 200 GET /:/timeline?bufferedTime=0&duration=7703000&guid=plex%3A%2F%2Ftrack%2F5edade3ac5e64fcc10b7a762&key=%2Flibrary%2Fmetadata%2F117092&playbackTime=423&playQueueItemID=256034&ratingKey=117092&state=stopped&time=0&timeStalled=0&token=xxxxxxxxxxxxxxxxxxxx (77 live) TLS GZIP 13ms 421 bytes (pipelined: 228)
Oct 05, 2021 12:39:49.154 [0x7fa443730b38] DEBUG - [Now] User is sothis (ID: 1)
Oct 05, 2021 12:39:49.154 [0x7fa443730b38] DEBUG - [Now] Device is Android (AFTJMST12).
Oct 05, 2021 12:39:49.154 [0x7fa443730b38] DEBUG - [Now] Profile is Android
Oct 05, 2021 12:39:49.155 [0x7fa443730b38] DEBUG - [Now] Updated play state for /library/metadata/117092.
Oct 05, 2021 12:39:49.155 [0x7fa443730b38] DEBUG - Statistics: (1ed731cf2dc61155-com-plexapp-android) Reporting active playback in state 3 of type 10 (scrobble: 0) for account 1
Oct 05, 2021 12:39:49.158 [0x7fa445386b38] DEBUG - Completed: [10.0.0.6:42708] 200 GET /:/timeline?bufferedTime=0&duration=7703000&guid=plex%3A%2F%2Ftrack%2F5edade3ac5e64fcc10b7a762&key=%2Flibrary%2Fmetadata%2F117092&playbackTime=430&playQueueItemID=256034&ratingKey=117092&state=stopped&time=0&token=xxxxxxxxxxxxxxxxxxxx (77 live) TLS GZIP 15ms 421 bytes (pipelined: 103)
Oct 05, 2021 12:39:49.169 [0x7fa443236b38] DEBUG - Request: [10.0.0.6:42769 (Subnet)] GET /:/timeline?bufferedTime=0&duration=7703000&guid=plex%3A%2F%2Ftrack%2F5d07cdea403c640290f9f441&key=%2Flibrary%2Fmetadata%2F166176&playbackTime=442&playQueueItemID=256035&ratingKey=166176&state=playing&time=0&token=xxxxxxxxxxxxxxxxxxxx (77 live) TLS GZIP Signed-in Token (sothis)
Oct 05, 2021 12:39:49.171 [0x7fa443236b38] DEBUG - Client [1ed731cf2dc61155-com-plexapp-android] reporting timeline state playing, progress of 0/7703000ms for guid=plex://track/5d07cdea403c640290f9f441, playbackTime=442ms ratingKey=166176 url=, key=/library/metadata/166176, containerKey=, metadataId=166176, source=
Oct 05, 2021 12:39:49.172 [0x7fa443bb5b38] DEBUG - Request: [10.0.0.6:42622 (Subnet)] GET /library/metadata/166176?includeChapters=1&includeLoudnessRamps=1&includeMarkers=1&includeRelated=1 (77 live) TLS GZIP Signed-in Token (sothis)
Oct 05, 2021 12:39:49.181 [0x7fa44358bb38] DEBUG - Request: [10.0.0.6:42563 (Subnet)] GET /library/streams/7839248/levels?subsample=256 (77 live) TLS GZIP Signed-in Token (sothis)
Oct 05, 2021 12:39:49.181 [0x7fa4437eab38] DEBUG - Request: [10.0.0.6:42634 (Subnet)] GET /playQueues/8171?center=256035&includeLoudnessRamps=1&repeat=0 (77 live) TLS GZIP Signed-in Token (sothis)
Oct 05, 2021 12:39:49.181 [0x7fa443b92b38] DEBUG - Request: [10.0.0.6:42419 (Subnet)] GET /photo/:/transcode?height=512&width=512&quality=90&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F166169%2Fthumb%2F1629116099 (77 live) TLS GZIP Signed-in Token (sothis)
Oct 05, 2021 12:39:49.182 [0x7fa443b92b38] DEBUG - Photo transcoder: Request for url [http://127.0.0.1:32400/library/metadata/166169/thumb/1629116099] (is local: 1 upscaled: 0)
Oct 05, 2021 12:39:49.183 [0x7fa443c56b38] DEBUG - Request: [10.0.0.6:42708 (Subnet)] GET /:/timeline?bufferedTime=0&duration=7703000&guid=plex%3A%2F%2Ftrack%2F5d07cdea403c640290f9f441&key=%2Flibrary%2Fmetadata%2F166176&playbackTime=442&playQueueItemID=256035&ratingKey=166176&state=playing&time=0&token=xxxxxxxxxxxxxxxxxxxx (77 live) TLS GZIP Signed-in Token (sothis)
Oct 05, 2021 12:39:49.187 [0x7fa4437eab38] DEBUG - PlayQueue: total generated: 1427, before cursor: 296, after cursor: 103
Oct 05, 2021 12:39:49.188 [0x7fa443c56b38] DEBUG - Client [1ed731cf2dc61155-com-plexapp-android] reporting timeline state playing, progress of 0/7703000ms for guid=plex://track/5d07cdea403c640290f9f441, playbackTime=442ms ratingKey=166176 url=, key=/library/metadata/166176, containerKey=, metadataId=166176, source=
Oct 05, 2021 12:39:49.189 [0x7fa4437eab38] DEBUG - PlayQueue: No need to refresh, active window is operational.
Oct 05, 2021 12:39:49.190 [0x7fa4435b5b38] DEBUG - Request: [10.0.0.6:42420 (Subnet)] GET /photo/:/transcode?height=128&width=128&quality=90&url=http%3A%2F%2F127.0.0.1%3A32400%2Flibrary%2Fmetadata%2F166169%2Fthumb%2F1629116099 (77 live) TLS GZIP Signed-in Token (sothis)
Oct 05, 2021 12:39:49.190 [0x7fa4435b5b38] DEBUG - Photo transcoder: Request for url [http://127.0.0.1:32400/library/metadata/166169/thumb/1629116099] (is local: 1 upscaled: 0)
Oct 05, 2021 12:39:49.200 [0x7fa443bb5b38] DEBUG - It took 60.000000 ms to retrieve 1 items.
Oct 05, 2021 12:39:49.204 [0x7fa443236b38] DEBUG - It took 90.000000 ms to retrieve 1 items.
Oct 05, 2021 12:39:49.212 [0x7fa443b92b38] DEBUG - It took 60.000000 ms to retrieve 1 items.
Oct 05, 2021 12:39:49.214 [0x7fa4435b5b38] DEBUG - It took 60.000000 ms to retrieve 1 items.
Oct 05, 2021 12:39:49.222 [0x7fa443236b38] DEBUG - We’re going to try to auto-select an audio stream for account 1.
Oct 05, 2021 12:39:49.222 [0x7fa443236b38] DEBUG - Selecting best audio stream for part ID 3979228 (autoselect: 1 language: en)
Oct 05, 2021 12:39:49.223 [0x7fa443236b38] DEBUG - Audio Stream: 7839248, Subtitle Stream: -1
Oct 05, 2021 12:39:49.227 [0x7fa4453a9b38] DEBUG - Completed: [10.0.0.6:42563] 200 GET /library/streams/7839248/levels?subsample=256 (77 live) TLS GZIP 46ms 1005 bytes (pipelined: 338)
Oct 05, 2021 12:39:49.228 [0x7fa443236b38] DEBUG - [Now] User is sothis (ID: 1)
Oct 05, 2021 12:39:49.228 [0x7fa443236b38] DEBUG - [Now] Device is Android (AFTJMST12).
Oct 05, 2021 12:39:49.229 [0x7fa443236b38] DEBUG - [Now] Profile is Android
Oct 05, 2021 12:39:49.229 [0x7fa443236b38] DEBUG - [Now] Updated play state for /library/metadata/166176.
Oct 05, 2021 12:39:49.231 [0x7fa443236b38] DEBUG - Statistics: (1ed731cf2dc61155-com-plexapp-android) Reporting active playback in state 0 of type 10 (scrobble: 0) for account 1
