Server Version#: 1.24.0.4930
Hi, recently I do an update on Plex Media Server on Linux, so, on my movies library there’s a bug, when I try to play a movie, plays the wrong file. Even if I press the Play button on the description window.
What can I do to fix it?
Which player are you using? Does it happen on all if you use more than one?
I would optimize database in troubleshooting section of server settings but likely will need to see server logs right after it happens
Thanks for the quick answer.
It happends on all the players: web, LG TV, Android, windows; and I did the optimization of rhe database, even clean the packages. I’ll check the console and the log to share it.
This is what the Plex Media Server.log file shows:
Aug 17, 2021 20:37:30.872 [0x7faeb84edb38] DEBUG - Request: [192.168.1.11:50518 (Subnet)] GET /library/sections/11/all?sort=originallyAvailableAt:desc&originallyAvailableAt%3E=-1y&contentDirectoryID=11&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (32 live) TLS Page 0-60 GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:30.872 [0x7faeb84edb38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:30.872 [0x7faeb84edb38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:30.872 [0x7faeb84edb38] DEBUG - Setting container serialization range to [0, 60] (total=-1)
Aug 17, 2021 20:37:30.888 [0x7faeb84edb38] DEBUG - Setting container serialization range to [0, 60] (total=61)
Aug 17, 2021 20:37:30.890 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:30.890 [0x7faeb84cab38] DEBUG - Request: [192.168.1.11:61460 (Subnet)] GET /library/sections/11/all?sort=lastViewedAt:desc&unwatched=0&viewOffset=0&contentDirectoryID=11&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (36 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:30.892 [0x7faeb84cab38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:30.892 [0x7faeb84cab38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:30.892 [0x7faeba6c3b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:30.893 [0x7faeba6c3b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:30.893 [0x7faeb8097b38] DEBUG - Request: [192.168.1.11:58508 (Subnet)] GET /library/sections/4/all?sort=originallyAvailableAt:desc&originallyAvailableAt%3E=-1y&contentDirectoryID=4&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:30.893 [0x7faeb84a7b38] DEBUG - Request: [192.168.1.11:55938 (Subnet)] GET /hubs/continueWatching/items?contentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:30.894 [0x7faeb84a7b38] DEBUG - HubCache: Adding '1/continueWatching/1/hubs/continueWatching/items/es-419external-media,indirect-media/contentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6' to the cache (1/continueWatching/1/hubs/continueWatching/items/es-419external-media,indirect-media/contentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6).
Aug 17, 2021 20:37:30.895 [0x7faeba6c3b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:30.895 [0x7faeb7b3cb38] DEBUG - Request: [192.168.1.11:65120 (Subnet)] GET /library/sections/10/all?type=4&sort=originallyAvailableAt:desc&contentDirectoryID=10&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS Page 0-35 GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:30.895 [0x7faeb80dab38] DEBUG - Request: [192.168.1.11:57116 (Subnet)] GET /web/static/479017288a229d836f44.png (37 live) GZIP Signed-in
Aug 17, 2021 20:37:30.897 [0x7faeb7b3cb38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:30.897 [0x7faeb80dab38] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/static/479017288a229d836f44.png"
Aug 17, 2021 20:37:30.897 [0x7faeb80dab38] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/static/479017288a229d836f44.png is 125754 (of total: 125754).
Aug 17, 2021 20:37:30.897 [0x7faeb8097b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:30.897 [0x7faeb8097b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:30.898 [0x7faeb7b3cb38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:30.898 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:57116] 200 GET /web/static/479017288a229d836f44.png (37 live) GZIP 4ms 125754 bytes (pipelined: 1)
Aug 17, 2021 20:37:30.898 [0x7faeb7b3cb38] DEBUG - Setting container serialization range to [0, 35] (total=-1)
Aug 17, 2021 20:37:30.903 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:50518] 200 GET /library/sections/11/all?sort=originallyAvailableAt:desc&originallyAvailableAt%3E=-1y&contentDirectoryID=11&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP Page 0-60 31ms 26850 bytes (pipelined: 1)
Aug 17, 2021 20:37:30.905 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:30.906 [0x7faeb80dab38] DEBUG - Request: [192.168.1.11:50518 (Subnet)] GET /library/sections/10/all?sort=lastViewedAt:desc&unwatched=0&viewOffset=0&type=4&contentDirectoryID=10&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS Page 0-35 GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:30.908 [0x7faeb80dab38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:30.908 [0x7faeb80dab38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:30.908 [0x7faeb80dab38] DEBUG - Setting container serialization range to [0, 35] (total=-1)
Aug 17, 2021 20:37:30.910 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:61460] 200 GET /library/sections/11/all?sort=lastViewedAt:desc&unwatched=0&viewOffset=0&contentDirectoryID=11&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP 19ms 4218 bytes (pipelined: 1)
Aug 17, 2021 20:37:30.912 [0x7faeba6c3b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:30.912 [0x7faeb84edb38] DEBUG - Request: [192.168.1.11:61460 (Subnet)] GET /hubs/home/recentlyAdded?type=1&pinnedContentDirectoryID=11,4,2,10,5,8,6&contentDirectoryID=11&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS Page 0-95 GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:30.914 [0x7faeb84edb38] DEBUG - Setting container serialization range to [0, 95] (total=-1)
Aug 17, 2021 20:37:30.915 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:58508] 200 GET /library/sections/4/all?sort=originallyAvailableAt:desc&originallyAvailableAt%3E=-1y&contentDirectoryID=4&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP 22ms 2790 bytes (pipelined: 1)
Aug 17, 2021 20:37:30.918 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:30.918 [0x7faeb84cab38] DEBUG - Request: [192.168.1.11:58508 (Subnet)] GET /library/sections/2/all?type=4&sort=originallyAvailableAt:desc&contentDirectoryID=2&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS Page 0-35 GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:30.921 [0x7faeb84cab38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:30.921 [0x7faeb84cab38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:30.921 [0x7faeb84cab38] DEBUG - Setting container serialization range to [0, 35] (total=-1)
Aug 17, 2021 20:37:30.957 [0x7faeb80dab38] DEBUG - It took 160.000000 ms to retrieve 36 items.
Aug 17, 2021 20:37:30.959 [0x7faeb80dab38] DEBUG - Setting container serialization range to [0, 35] (total=97)
Aug 17, 2021 20:37:30.972 [0x7faeb84cab38] DEBUG - Setting container serialization range to [0, 35] (total=2084)
Aug 17, 2021 20:37:30.973 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:50518] 200 GET /library/sections/10/all?sort=lastViewedAt:desc&unwatched=0&viewOffset=0&type=4&contentDirectoryID=10&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP Page 0-35 67ms 9632 bytes (pipelined: 2)
Aug 17, 2021 20:37:30.975 [0x7faeba6c3b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:30.976 [0x7faeb8097b38] DEBUG - Request: [192.168.1.11:50518 (Subnet)] GET /hubs/home/recentlyAdded?type=2&pinnedContentDirectoryID=11,4,2,10,5,8,6&contentDirectoryID=2&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS Page 0-35 GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:30.977 [0x7faeb8097b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:30.977 [0x7faeb8097b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:30.977 [0x7faeb8097b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:30.991 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:58508] 200 GET /library/sections/2/all?type=4&sort=originallyAvailableAt:desc&contentDirectoryID=2&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP Page 0-35 72ms 10836 bytes (pipelined: 2)
Aug 17, 2021 20:37:30.994 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:30.994 [0x7faeb80dab38] DEBUG - Request: [192.168.1.11:58508 (Subnet)] GET /library/sections/4/all?sort=lastViewedAt:desc&unwatched=0&viewOffset=0&contentDirectoryID=4&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:30.996 [0x7faeb80dab38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:30.996 [0x7faeb80dab38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:30.999 [0x7faeb8097b38] DEBUG - It took 80.000000 ms to retrieve 200 items.
Aug 17, 2021 20:37:31.002 [0x7faeb8097b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.002 [0x7faeb8097b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.002 [0x7faeb8097b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.009 [0x7faeb8097b38] DEBUG - It took 30.000000 ms to retrieve 413 items.
Aug 17, 2021 20:37:31.010 [0x7faeb8097b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.011 [0x7faeb8097b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.011 [0x7faeb8097b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.014 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:58508] 200 GET /library/sections/4/all?sort=lastViewedAt:desc&unwatched=0&viewOffset=0&contentDirectoryID=4&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP 19ms 2945 bytes (pipelined: 3)
Aug 17, 2021 20:37:31.016 [0x7faeba6c3b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:31.016 [0x7faeb84cab38] DEBUG - Request: [192.168.1.11:58508 (Subnet)] GET /library/sections/2/all?sort=lastViewedAt:desc&unwatched=0&viewOffset=0&type=4&contentDirectoryID=2&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS Page 0-35 GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:31.018 [0x7faeb84cab38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.018 [0x7faeb84cab38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.018 [0x7faeb84cab38] DEBUG - Setting container serialization range to [0, 35] (total=-1)
Aug 17, 2021 20:37:31.024 [0x7faeb8097b38] DEBUG - It took 50.000000 ms to retrieve 622 items.
Aug 17, 2021 20:37:31.026 [0x7faeb8097b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.026 [0x7faeb8097b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.026 [0x7faeb8097b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.029 [0x7faeb7b3cb38] DEBUG - It took 130.000000 ms to retrieve 36 items.
Aug 17, 2021 20:37:31.032 [0x7faeb84a7b38] DEBUG - HubCache: Computed '1/continueWatching/1/hubs/continueWatching/items/es-419external-media,indirect-media/contentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6' in 138 ms.
Aug 17, 2021 20:37:31.032 [0x7faeb7b3cb38] DEBUG - Setting container serialization range to [0, 35] (total=4878)
Aug 17, 2021 20:37:31.032 [0x7faeb84a7b38] DEBUG - HubCache: 504 hubs cached, 52.7% hit ratio.
Aug 17, 2021 20:37:31.034 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.034 [0x7faeb84a7b38] DEBUG - Selecting best audio stream for part ID 39394 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.034 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.034 [0x7faeb84a7b38] DEBUG - Selected a foreign audio track: en
Aug 17, 2021 20:37:31.034 [0x7faeb84a7b38] DEBUG - Audio Stream: 72081, Subtitle Stream: 72464
Aug 17, 2021 20:37:31.036 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.036 [0x7faeb84a7b38] DEBUG - Selecting best audio stream for part ID 43852 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.036 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.036 [0x7faeb84a7b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:31.036 [0x7faeb84a7b38] DEBUG - Audio Stream: 87381, Subtitle Stream: -1
Aug 17, 2021 20:37:31.037 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.037 [0x7faeb84a7b38] DEBUG - Selecting best audio stream for part ID 86592 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.037 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.037 [0x7faeb84a7b38] DEBUG - Subtitles: Found a candidate subtitle language [es] for a foreign film
Aug 17, 2021 20:37:31.037 [0x7faeb84a7b38] DEBUG - Audio Stream: 188310, Subtitle Stream: 188312
Aug 17, 2021 20:37:31.038 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.038 [0x7faeb84a7b38] DEBUG - Selecting best audio stream for part ID 59064 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.039 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.039 [0x7faeb84a7b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:31.039 [0x7faeb84a7b38] DEBUG - Audio Stream: 132197, Subtitle Stream: 132438
Aug 17, 2021 20:37:31.040 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.040 [0x7faeb84a7b38] DEBUG - Selecting best audio stream for part ID 88483 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.040 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.040 [0x7faeb84a7b38] DEBUG - Selected a foreign audio track: ja
Aug 17, 2021 20:37:31.040 [0x7faeb84a7b38] DEBUG - Subtitles: Found a candidate subtitle language [es] for a foreign film
Aug 17, 2021 20:37:31.040 [0x7faeb84a7b38] DEBUG - Audio Stream: 193435, Subtitle Stream: 193437
Aug 17, 2021 20:37:31.041 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:65120] 200 GET /library/sections/10/all?type=4&sort=originallyAvailableAt:desc&contentDirectoryID=10&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP Page 0-35 146ms 10647 bytes (pipelined: 1)
Aug 17, 2021 20:37:31.043 [0x7faeb8097b38] DEBUG - It took 60.000000 ms to retrieve 839 items.
Aug 17, 2021 20:37:31.044 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:31.044 [0x7faeb80dab38] DEBUG - Request: [192.168.1.11:65120 (Subnet)] GET /library/sections/5/all?type=4&sort=originallyAvailableAt:desc&contentDirectoryID=5&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS Page 0-35 GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:31.045 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:55938] 200 GET /hubs/continueWatching/items?contentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP 152ms 4778 bytes (pipelined: 1)
Aug 17, 2021 20:37:31.046 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:31.047 [0x7faeb7b3cb38] DEBUG - Request: [192.168.1.11:55938 (Subnet)] GET /library/sections/5/all?sort=lastViewedAt:desc&unwatched=0&viewOffset=0&type=4&contentDirectoryID=5&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS Page 0-35 GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:31.048 [0x7faeb80dab38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.048 [0x7faeb80dab38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.049 [0x7faeb80dab38] DEBUG - Setting container serialization range to [0, 35] (total=-1)
Aug 17, 2021 20:37:31.050 [0x7faeb7b3cb38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.050 [0x7faeb7b3cb38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.051 [0x7faeb7b3cb38] DEBUG - Setting container serialization range to [0, 35] (total=-1)
Aug 17, 2021 20:37:31.057 [0x7faeb84edb38] DEBUG - It took 190.000000 ms to retrieve 96 items.
Aug 17, 2021 20:37:31.064 [0x7faeb84cab38] DEBUG - It took 130.000000 ms to retrieve 36 items.
Aug 17, 2021 20:37:31.067 [0x7faeb84cab38] DEBUG - Setting container serialization range to [0, 35] (total=160)
Aug 17, 2021 20:37:31.077 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:58508] 200 GET /library/sections/2/all?sort=lastViewedAt:desc&unwatched=0&viewOffset=0&type=4&contentDirectoryID=2&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP Page 0-35 61ms 10516 bytes (pipelined: 4)
Aug 17, 2021 20:37:31.080 [0x7faeba6c3b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:31.080 [0x7faeb84a7b38] DEBUG - Request: [192.168.1.11:58508 (Subnet)] GET /library/sections/6/all?sort=lastViewedAt:desc&viewOffset%3E=300&lastViewedAt%3E=1626820892&contentDirectoryID=6&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:31.082 [0x7faeb84a7b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.082 [0x7faeb84a7b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.088 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:58508] 200 GET /library/sections/6/all?sort=lastViewedAt:desc&viewOffset%3E=300&lastViewedAt%3E=1626820892&contentDirectoryID=6&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP 8ms 729 bytes (pipelined: 5)
Aug 17, 2021 20:37:31.090 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:31.090 [0x7faeb84cab38] DEBUG - Request: [192.168.1.11:58508 (Subnet)] GET /hubs/home/recentlyAdded?type=1&personal=1&pinnedContentDirectoryID=11,4,2,10,5,8,6&contentDirectoryID=6&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:31.095 [0x7faeb7b3cb38] DEBUG - It took 150.000000 ms to retrieve 36 items.
Aug 17, 2021 20:37:31.096 [0x7faeb7b3cb38] DEBUG - Setting container serialization range to [0, 35] (total=59)
Aug 17, 2021 20:37:31.099 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:58508] 200 GET /hubs/home/recentlyAdded?type=1&personal=1&pinnedContentDirectoryID=11,4,2,10,5,8,6&contentDirectoryID=6&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP 9ms 2436 bytes (pipelined: 6)
Aug 17, 2021 20:37:31.101 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:61460] 200 GET /hubs/home/recentlyAdded?type=1&pinnedContentDirectoryID=11,4,2,10,5,8,6&contentDirectoryID=11&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP Page 0-95 188ms 45452 bytes (pipelined: 2)
Aug 17, 2021 20:37:31.102 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:31.102 [0x7faeb84a7b38] DEBUG - Request: [192.168.1.11:58508 (Subnet)] GET /library/sections/6/all?sort=lastViewedAt:desc&unwatched=0&viewOffset=0&contentDirectoryID=6&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:31.103 [0x7faeb84a7b38] WARN - QueryParser: Invalid field 'contentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.103 [0x7faeb84a7b38] WARN - QueryParser: Invalid field 'pinnedContentDirectoryID' found, ignoring.
Aug 17, 2021 20:37:31.105 [0x7faeb8097b38] DEBUG - It took 200.000000 ms to retrieve 50 items.
Aug 17, 2021 20:37:31.106 [0x7faeb80dab38] DEBUG - It took 110.000000 ms to retrieve 36 items.
Aug 17, 2021 20:37:31.107 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:55938] 200 GET /library/sections/5/all?sort=lastViewedAt:desc&unwatched=0&viewOffset=0&type=4&contentDirectoryID=5&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP Page 0-35 60ms 10162 bytes (pipelined: 2)
Aug 17, 2021 20:37:31.110 [0x7faeb80dab38] DEBUG - Setting container serialization range to [0, 35] (total=1035)
Aug 17, 2021 20:37:31.110 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:58508] 200 GET /library/sections/6/all?sort=lastViewedAt:desc&unwatched=0&viewOffset=0&contentDirectoryID=6&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP 8ms 1752 bytes (pipelined: 7)
Aug 17, 2021 20:37:31.116 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:65120] 200 GET /library/sections/5/all?type=4&sort=originallyAvailableAt:desc&contentDirectoryID=5&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP Page 0-35 71ms 10255 bytes (pipelined: 2)
Aug 17, 2021 20:37:31.126 [0x7faeb8097b38] DEBUG - Setting container serialization range to [0, 35] (total=-1)
Aug 17, 2021 20:37:31.129 [0x7faeb8097b38] DEBUG - There were 1 top-level paths for Rugrats: Aventuras en Pañales.
Aug 17, 2021 20:37:31.131 [0x7faeb8097b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.131 [0x7faeb8097b38] DEBUG - Selecting best audio stream for part ID 93733 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.131 [0x7faeb8097b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.131 [0x7faeb8097b38] DEBUG - Audio Stream: 210483, Subtitle Stream: -1
Aug 17, 2021 20:37:31.139 [0x7faeb8097b38] DEBUG - There were 1 top-level paths for Mujeres desesperadas.
Aug 17, 2021 20:37:31.139 [0x7faeb8097b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.139 [0x7faeb8097b38] DEBUG - Selecting best audio stream for part ID 93238 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.139 [0x7faeb8097b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.139 [0x7faeb8097b38] DEBUG - Selected a foreign audio track: ja
Aug 17, 2021 20:37:31.139 [0x7faeb8097b38] DEBUG - Audio Stream: 209305, Subtitle Stream: 209303
Aug 17, 2021 20:37:31.139 [0x7faeb8097b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.139 [0x7faeb8097b38] DEBUG - Selecting best audio stream for part ID 89174 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.139 [0x7faeb8097b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.139 [0x7faeb8097b38] DEBUG - Selected a foreign audio track: ja
Aug 17, 2021 20:37:31.139 [0x7faeb8097b38] DEBUG - Audio Stream: 195042, Subtitle Stream: 195040
Aug 17, 2021 20:37:31.141 [0x7faeb8097b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.141 [0x7faeb8097b38] DEBUG - Selecting best audio stream for part ID 93237 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.141 [0x7faeb8097b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.141 [0x7faeb8097b38] DEBUG - Selected a foreign audio track: ja
Aug 17, 2021 20:37:31.141 [0x7faeb8097b38] DEBUG - Audio Stream: 209302, Subtitle Stream: 209300
Aug 17, 2021 20:37:31.141 [0x7faeb8097b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.141 [0x7faeb8097b38] DEBUG - Selecting best audio stream for part ID 92280 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.141 [0x7faeb8097b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.141 [0x7faeb8097b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:31.141 [0x7faeb8097b38] DEBUG - Audio Stream: 206276, Subtitle Stream: -1
Aug 17, 2021 20:37:31.141 [0x7faeb8097b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.141 [0x7faeb8097b38] DEBUG - Selecting best audio stream for part ID 89173 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.141 [0x7faeb8097b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.141 [0x7faeb8097b38] DEBUG - Selected a foreign audio track: ja
Aug 17, 2021 20:37:31.141 [0x7faeb8097b38] DEBUG - Audio Stream: 195038, Subtitle Stream: 195039
Aug 17, 2021 20:37:31.142 [0x7faeb8097b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.142 [0x7faeb8097b38] DEBUG - Selecting best audio stream for part ID 87095 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.142 [0x7faeb8097b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.142 [0x7faeb8097b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:31.142 [0x7faeb8097b38] DEBUG - Audio Stream: 189443, Subtitle Stream: 189444
Aug 17, 2021 20:37:31.147 [0x7faeb8097b38] DEBUG - There were 1 top-level paths for Rick y Morty.
Aug 17, 2021 20:37:31.147 [0x7faeb8097b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.147 [0x7faeb8097b38] DEBUG - Selecting best audio stream for part ID 93236 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.147 [0x7faeb8097b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.147 [0x7faeb8097b38] DEBUG - Selected a foreign audio track: ja
Aug 17, 2021 20:37:31.147 [0x7faeb8097b38] DEBUG - Audio Stream: 209299, Subtitle Stream: 209297
Aug 17, 2021 20:37:31.148 [0x7faeb8097b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.148 [0x7faeb8097b38] DEBUG - Selecting best audio stream for part ID 89172 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.148 [0x7faeb8097b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.148 [0x7faeb8097b38] DEBUG - Selected a foreign audio track: ja
Aug 17, 2021 20:37:31.148 [0x7faeb8097b38] DEBUG - Audio Stream: 195036, Subtitle Stream: 195034
Aug 17, 2021 20:37:31.149 [0x7faeb8097b38] DEBUG - There were 1 top-level paths for Lois & Clark: The New Adventures of Superman.
Aug 17, 2021 20:37:31.149 [0x7faeb8097b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.149 [0x7faeb8097b38] DEBUG - Selecting best audio stream for part ID 59064 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.149 [0x7faeb8097b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.149 [0x7faeb8097b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:31.149 [0x7faeb8097b38] DEBUG - Audio Stream: 132197, Subtitle Stream: 132438
Aug 17, 2021 20:37:31.150 [0x7faeb8097b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.150 [0x7faeb8097b38] DEBUG - Selecting best audio stream for part ID 59061 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.150 [0x7faeb8097b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.150 [0x7faeb8097b38] DEBUG - Selected a foreign audio track: en
Aug 17, 2021 20:37:31.150 [0x7faeb8097b38] DEBUG - Subtitles: Found a candidate subtitle language [es] for a foreign film
Aug 17, 2021 20:37:31.150 [0x7faeb8097b38] DEBUG - Audio Stream: 132163, Subtitle Stream: 132178
Aug 17, 2021 20:37:31.152 [0x7faeb8097b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.152 [0x7faeb8097b38] DEBUG - Selecting best audio stream for part ID 58987 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.152 [0x7faeb8097b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.152 [0x7faeb8097b38] DEBUG - Selected a foreign audio track: en
Aug 17, 2021 20:37:31.152 [0x7faeb8097b38] DEBUG - Audio Stream: 131904, Subtitle Stream: 131969
Aug 17, 2021 20:37:31.157 [0x7faeb8097b38] DEBUG - There were 1 top-level paths for Friends.
Aug 17, 2021 20:37:31.157 [0x7faeb8097b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.157 [0x7faeb8097b38] DEBUG - Selecting best audio stream for part ID 93235 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.157 [0x7faeb8097b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.157 [0x7faeb8097b38] DEBUG - Selected a foreign audio track: ja
Aug 17, 2021 20:37:31.157 [0x7faeb8097b38] DEBUG - Audio Stream: 209296, Subtitle Stream: 209294
Aug 17, 2021 20:37:31.157 [0x7faeb8097b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.157 [0x7faeb8097b38] DEBUG - Selecting best audio stream for part ID 89171 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.157 [0x7faeb8097b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.157 [0x7faeb8097b38] DEBUG - Selected a foreign audio track: ja
Aug 17, 2021 20:37:31.157 [0x7faeb8097b38] DEBUG - Audio Stream: 195033, Subtitle Stream: 195031
Aug 17, 2021 20:37:31.158 [0x7faeb8097b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.158 [0x7faeb8097b38] DEBUG - Selecting best audio stream for part ID 58622 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.158 [0x7faeb8097b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.158 [0x7faeb8097b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:31.158 [0x7faeb8097b38] DEBUG - Audio Stream: 130474, Subtitle Stream: 130475
Aug 17, 2021 20:37:31.158 [0x7faeb8097b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:31.158 [0x7faeb8097b38] DEBUG - Selecting best audio stream for part ID 58621 (autoselect: 1 language: es)
Aug 17, 2021 20:37:31.158 [0x7faeb8097b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:31.158 [0x7faeb8097b38] DEBUG - Selected a foreign audio track: en
Aug 17, 2021 20:37:31.158 [0x7faeb8097b38] DEBUG - Subtitles: Found a candidate subtitle language [es] for a foreign film
Aug 17, 2021 20:37:31.158 [0x7faeb8097b38] DEBUG - Audio Stream: 130453, Subtitle Stream: 130468
Aug 17, 2021 20:37:31.163 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:50518] 200 GET /hubs/home/recentlyAdded?type=2&pinnedContentDirectoryID=11,4,2,10,5,8,6&contentDirectoryID=2&pinnedContentDirectoryID=11%2C4%2C2%2C10%2C5%2C8%2C6 (37 live) TLS GZIP Page 0-35 187ms 19780 bytes (pipelined: 3)
Aug 17, 2021 20:37:33.663 [0x7faeb84cab38] DEBUG - Request: [192.168.1.11:57116 (Subnet)] GET /web/chunk-829-e39c10837c604fcbcb30-plex-4.59.2.18290-5569e89.css (37 live) GZIP Signed-in
Aug 17, 2021 20:37:33.663 [0x7faeb84cab38] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/chunk-829-e39c10837c604fcbcb30-plex-4.59.2.18290-5569e89.css"
Aug 17, 2021 20:37:33.663 [0x7faeb84cab38] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/chunk-829-e39c10837c604fcbcb30-plex-4.59.2.18290-5569e89.css is 19015 (of total: 19015).
Aug 17, 2021 20:37:33.663 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:57116] 200 GET /web/chunk-829-e39c10837c604fcbcb30-plex-4.59.2.18290-5569e89.css (37 live) GZIP 0ms 19015 bytes (pipelined: 2)
Aug 17, 2021 20:37:33.672 [0x7faeb84edb38] DEBUG - Request: [192.168.1.11:57116 (Subnet)] GET /web/js/chunk-710-d557231e892f2d449849-plex-4.59.2.18290-5569e89.js (37 live) GZIP Signed-in
Aug 17, 2021 20:37:33.672 [0x7faeb84edb38] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/js/chunk-710-d557231e892f2d449849-plex-4.59.2.18290-5569e89.js"
Aug 17, 2021 20:37:33.672 [0x7faeb84edb38] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/js/chunk-710-d557231e892f2d449849-plex-4.59.2.18290-5569e89.js is 77752 (of total: 77752).
Aug 17, 2021 20:37:33.673 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:57116] 200 GET /web/js/chunk-710-d557231e892f2d449849-plex-4.59.2.18290-5569e89.js (38 live) GZIP 0ms 77752 bytes (pipelined: 3)
Aug 17, 2021 20:37:33.673 [0x7faeb7b3cb38] DEBUG - Request: [192.168.1.11:60062 (Subnet)] GET /web/js/chunk-933-24d43c832a5bed7cf4c7-plex-4.59.2.18290-5569e89.js (38 live) GZIP Signed-in
Aug 17, 2021 20:37:33.673 [0x7faeb7b3cb38] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/js/chunk-933-24d43c832a5bed7cf4c7-plex-4.59.2.18290-5569e89.js"
Aug 17, 2021 20:37:33.673 [0x7faeb84a7b38] DEBUG - Request: [192.168.1.11:53810 (Subnet)] GET /web/js/chunk-12-b0695ea9d1cb38505f7c-plex-4.59.2.18290-5569e89.js (39 live) GZIP Signed-in
Aug 17, 2021 20:37:33.673 [0x7faeb7b3cb38] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/js/chunk-933-24d43c832a5bed7cf4c7-plex-4.59.2.18290-5569e89.js is 14720 (of total: 14720).
Aug 17, 2021 20:37:33.673 [0x7faeb84a7b38] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/js/chunk-12-b0695ea9d1cb38505f7c-plex-4.59.2.18290-5569e89.js"
Aug 17, 2021 20:37:33.673 [0x7faeb84a7b38] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/js/chunk-12-b0695ea9d1cb38505f7c-plex-4.59.2.18290-5569e89.js is 24216 (of total: 24216).
Aug 17, 2021 20:37:33.673 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:60062] 200 GET /web/js/chunk-933-24d43c832a5bed7cf4c7-plex-4.59.2.18290-5569e89.js (39 live) GZIP 0ms 14720 bytes (pipelined: 1)
Aug 17, 2021 20:37:33.674 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:53810] 200 GET /web/js/chunk-12-b0695ea9d1cb38505f7c-plex-4.59.2.18290-5569e89.js (39 live) GZIP 0ms 24216 bytes (pipelined: 1)
Aug 17, 2021 20:37:33.674 [0x7faeb80dab38] DEBUG - Request: [192.168.1.11:52031 (Subnet)] GET /web/js/chunk-17-b9b4b6d4c616eb810374-plex-4.59.2.18290-5569e89.js (41 live) GZIP Signed-in
Aug 17, 2021 20:37:33.674 [0x7faeb8097b38] DEBUG - Request: [192.168.1.11:53375 (Subnet)] GET /web/js/chunk-829-e39c10837c604fcbcb30-plex-4.59.2.18290-5569e89.js (41 live) GZIP Signed-in
Aug 17, 2021 20:37:33.674 [0x7faeb80dab38] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/js/chunk-17-b9b4b6d4c616eb810374-plex-4.59.2.18290-5569e89.js"
Aug 17, 2021 20:37:33.674 [0x7faeb8097b38] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/js/chunk-829-e39c10837c604fcbcb30-plex-4.59.2.18290-5569e89.js"
Aug 17, 2021 20:37:33.674 [0x7faeb80dab38] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/js/chunk-17-b9b4b6d4c616eb810374-plex-4.59.2.18290-5569e89.js is 606 (of total: 606).
Aug 17, 2021 20:37:33.674 [0x7faeb8097b38] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/js/chunk-829-e39c10837c604fcbcb30-plex-4.59.2.18290-5569e89.js is 116832 (of total: 116832).
Aug 17, 2021 20:37:33.675 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:52031] 200 GET /web/js/chunk-17-b9b4b6d4c616eb810374-plex-4.59.2.18290-5569e89.js (41 live) GZIP 0ms 606 bytes (pipelined: 1)
Aug 17, 2021 20:37:33.675 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:53375] 200 GET /web/js/chunk-829-e39c10837c604fcbcb30-plex-4.59.2.18290-5569e89.js (41 live) GZIP 0ms 116832 bytes (pipelined: 1)
Aug 17, 2021 20:37:33.688 [0x7faeb84cab38] DEBUG - Request: [192.168.1.11:53375 (Subnet)] GET /web/static/24cb7f1a5e2d0102f05f.png (41 live) GZIP Signed-in
Aug 17, 2021 20:37:33.688 [0x7faeb84cab38] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/static/24cb7f1a5e2d0102f05f.png"
Aug 17, 2021 20:37:33.688 [0x7faeb84cab38] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/static/24cb7f1a5e2d0102f05f.png is 113817 (of total: 113817).
Aug 17, 2021 20:37:33.688 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:53375] 200 GET /web/static/24cb7f1a5e2d0102f05f.png (41 live) GZIP 0ms 113817 bytes (pipelined: 2)
Aug 17, 2021 20:37:33.725 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:33.725 [0x7faeb84edb38] DEBUG - Request: [192.168.1.11:50518 (Subnet)] GET /library/metadata/46294?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeReviews=1&includeChapters=1&includeStations=1&includeExternalMedia=1&asyncAugmentMetadata=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1 (41 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:33.730 [0x7faeb84edb38] DEBUG - It took 0.000000 ms to retrieve 251 items.
Aug 17, 2021 20:37:33.734 [0x7faeb84edb38] DEBUG - It took 0.000000 ms to retrieve 251 items.
Aug 17, 2021 20:37:33.735 [0x7faeb84edb38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:33.735 [0x7faeb84edb38] DEBUG - Selecting best audio stream for part ID 101875 (autoselect: 1 language: es)
Aug 17, 2021 20:37:33.735 [0x7faeb84edb38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:33.735 [0x7faeb84edb38] DEBUG - Selected a foreign audio track: en
Aug 17, 2021 20:37:33.735 [0x7faeb84edb38] DEBUG - Audio Stream: 227106, Subtitle Stream: 230196
Aug 17, 2021 20:37:33.745 [0x7faeb84edb38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:33.745 [0x7faeb84edb38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:33.745 [0x7faeb84edb38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:33.746 [0x7faeb84edb38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:33.746 [0x7faeb84edb38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:33.746 [0x7faeb84edb38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:33.746 [0x7faeb84edb38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:33.746 [0x7faeb84edb38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:33.747 [0x7faeb84edb38] DEBUG - Activity: registered new activity 2572a03d-28cb-4944-9820-4e6e10207015 - "Refreshing"
Aug 17, 2021 20:37:33.747 [0x7faeb7a4fb38] DEBUG - Activity: updated activity 2572a03d-28cb-4944-9820-4e6e10207015 - completed 0.0% - Refreshing
Aug 17, 2021 20:37:33.747 [0x7faeb7a4fb38] DEBUG - Activity: updated activity 2572a03d-28cb-4944-9820-4e6e10207015 - completed 33.0% - Refreshing
Aug 17, 2021 20:37:33.748 [0x7faeb7a4fb38] DEBUG - Activity: updated activity 2572a03d-28cb-4944-9820-4e6e10207015 - completed 66.0% - Refreshing
Aug 17, 2021 20:37:33.748 [0x7faeb7a4fb38] DEBUG - Activity: Ended activity 2572a03d-28cb-4944-9820-4e6e10207015.
Aug 17, 2021 20:37:33.748 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:50518] 200 GET /library/metadata/46294?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeReviews=1&includeChapters=1&includeStations=1&includeExternalMedia=1&asyncAugmentMetadata=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1 (41 live) TLS GZIP 23ms 11715 bytes (pipelined: 4)
Aug 17, 2021 20:37:33.821 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:33.821 [0x7faeb7b3cb38] DEBUG - Request: [192.168.1.11:50518 (Subnet)] GET /library/metadata/46294/related?includeAugmentations=1&includeExternalMetadata=1&includeMeta=1 (41 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:33.825 [0x7faeb7b3cb38] DEBUG - It took 0.000000 ms to retrieve 251 items.
Aug 17, 2021 20:37:33.830 [0x7faeb7b3cb38] DEBUG - It took 0.000000 ms to retrieve 251 items.
Aug 17, 2021 20:37:34.131 [0x7faeb7b3cb38] DEBUG - It took 0.000000 ms to retrieve 102 items.
Aug 17, 2021 20:37:34.134 [0x7faeb7b3cb38] DEBUG - It took 0.000000 ms to retrieve 115 items.
Aug 17, 2021 20:37:34.138 [0x7faeb7b3cb38] DEBUG - It took 0.000000 ms to retrieve 116 items.
Aug 17, 2021 20:37:34.143 [0x7faeb7b3cb38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:34.143 [0x7faeb7b3cb38] DEBUG - Selecting best audio stream for part ID 96491 (autoselect: 1 language: es)
Aug 17, 2021 20:37:34.143 [0x7faeb7b3cb38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:34.143 [0x7faeb7b3cb38] DEBUG - Audio Stream: 216185, Subtitle Stream: -1
Aug 17, 2021 20:37:34.143 [0x7faeb7b3cb38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:34.144 [0x7faeb7b3cb38] DEBUG - Selecting best audio stream for part ID 101780 (autoselect: 1 language: es)
Aug 17, 2021 20:37:34.144 [0x7faeb7b3cb38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:34.144 [0x7faeb7b3cb38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:34.144 [0x7faeb7b3cb38] DEBUG - Audio Stream: 226900, Subtitle Stream: 229232
Aug 17, 2021 20:37:34.144 [0x7faeb7b3cb38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:34.144 [0x7faeb7b3cb38] DEBUG - Selecting best audio stream for part ID 96494 (autoselect: 1 language: es)
Aug 17, 2021 20:37:34.144 [0x7faeb7b3cb38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:34.144 [0x7faeb7b3cb38] DEBUG - Audio Stream: 216187, Subtitle Stream: -1
Aug 17, 2021 20:37:34.144 [0x7faeb7b3cb38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:34.144 [0x7faeb7b3cb38] DEBUG - Selecting best audio stream for part ID 98797 (autoselect: 1 language: es)
Aug 17, 2021 20:37:34.144 [0x7faeb7b3cb38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:34.144 [0x7faeb7b3cb38] DEBUG - Audio Stream: 220828, Subtitle Stream: -1
Aug 17, 2021 20:37:34.145 [0x7faeb7b3cb38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:34.145 [0x7faeb7b3cb38] DEBUG - Selecting best audio stream for part ID 102067 (autoselect: 1 language: es)
Aug 17, 2021 20:37:34.145 [0x7faeb7b3cb38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:34.145 [0x7faeb7b3cb38] DEBUG - Audio Stream: 227526, Subtitle Stream: -1
Aug 17, 2021 20:37:34.145 [0x7faeb7b3cb38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:34.146 [0x7faeb7b3cb38] DEBUG - Selecting best audio stream for part ID 96813 (autoselect: 1 language: es)
Aug 17, 2021 20:37:34.146 [0x7faeb7b3cb38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:34.146 [0x7faeb7b3cb38] DEBUG - Audio Stream: 216823, Subtitle Stream: -1
Aug 17, 2021 20:37:34.148 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:50518] 200 GET /library/metadata/46294/related?includeAugmentations=1&includeExternalMetadata=1&includeMeta=1 (41 live) TLS GZIP 327ms 15619 bytes (pipelined: 5)
Aug 17, 2021 20:37:34.183 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:34.183 [0x7faeb84a7b38] DEBUG - Request: [192.168.1.11:50518 (Subnet)] GET /library/metadata/46294/similar (41 live) TLS Page 6-29 GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:34.188 [0x7faeb84a7b38] DEBUG - It took 10.000000 ms to retrieve 251 items.
Aug 17, 2021 20:37:34.192 [0x7faeb84a7b38] DEBUG - It took 0.000000 ms to retrieve 251 items.
Aug 17, 2021 20:37:34.485 [0x7faeb84a7b38] DEBUG - It took 0.000000 ms to retrieve 144 items.
Aug 17, 2021 20:37:34.487 [0x7faeb84a7b38] DEBUG - It took 10.000000 ms to retrieve 102 items.
Aug 17, 2021 20:37:34.490 [0x7faeb84a7b38] DEBUG - It took 0.000000 ms to retrieve 134 items.
Aug 17, 2021 20:37:34.492 [0x7faeb84a7b38] DEBUG - It took 0.000000 ms to retrieve 115 items.
Aug 17, 2021 20:37:34.497 [0x7faeb84a7b38] DEBUG - It took 10.000000 ms to retrieve 314 items.
Aug 17, 2021 20:37:34.500 [0x7faeb84a7b38] DEBUG - It took 0.000000 ms to retrieve 116 items.
Aug 17, 2021 20:37:34.501 [0x7faeb84a7b38] DEBUG - Setting container serialization range to [6, 29] (total=-1)
Aug 17, 2021 20:37:34.501 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:34.501 [0x7faeb84a7b38] DEBUG - Selecting best audio stream for part ID 102177 (autoselect: 1 language: es)
Aug 17, 2021 20:37:34.501 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:34.501 [0x7faeb84a7b38] DEBUG - Audio Stream: 227736, Subtitle Stream: -1
Aug 17, 2021 20:37:34.501 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:34.501 [0x7faeb84a7b38] DEBUG - Selecting best audio stream for part ID 102019 (autoselect: 1 language: es)
Aug 17, 2021 20:37:34.501 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:34.501 [0x7faeb84a7b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:34.501 [0x7faeb84a7b38] DEBUG - Audio Stream: 227405, Subtitle Stream: 230691
Aug 17, 2021 20:37:34.502 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:34.502 [0x7faeb84a7b38] DEBUG - Selecting best audio stream for part ID 94107 (autoselect: 1 language: es)
Aug 17, 2021 20:37:34.502 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:34.502 [0x7faeb84a7b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:34.502 [0x7faeb84a7b38] DEBUG - Audio Stream: 211319, Subtitle Stream: -1
Aug 17, 2021 20:37:34.502 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:34.502 [0x7faeb84a7b38] DEBUG - Selecting best audio stream for part ID 96935 (autoselect: 1 language: es)
Aug 17, 2021 20:37:34.502 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:34.502 [0x7faeb84a7b38] DEBUG - Audio Stream: 217069, Subtitle Stream: -1
Aug 17, 2021 20:37:34.506 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:50518] 200 GET /library/metadata/46294/similar (41 live) TLS GZIP Page 6-29 322ms 16711 bytes (pipelined: 6)
Aug 17, 2021 20:37:34.547 [0x7faeba6c3b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:34.547 [0x7faeb80dab38] DEBUG - Request: [192.168.1.11:50518 (Subnet)] GET /photo/:/transcode?width=120&height=180&minSize=1&upscale=1&url=%2Flibrary%2Fmetadata%2F46355%2Fthumb%2F1629240638%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (41 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:34.547 [0x7faeb80dab38] DEBUG - Photo transcoder: Request for url [/library/metadata/46355/thumb/1629240638?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 1)
Aug 17, 2021 20:37:34.548 [0x7faeb80dab38] DEBUG - Content-Length of /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/e4/e489ab6363093f1b9ce9535452df02f2beec0681.jpg is 8193 (of total: 8193).
Aug 17, 2021 20:37:34.549 [0x7faeba6c3b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:34.549 [0x7faeb8097b38] DEBUG - Request: [192.168.1.11:65120 (Subnet)] GET /photo/:/transcode?width=120&height=180&minSize=1&upscale=1&url=%2Flibrary%2Fmetadata%2F43153%2Fthumb%2F1629240083%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (41 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:34.549 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:34.549 [0x7faeb8097b38] DEBUG - Photo transcoder: Request for url [/library/metadata/43153/thumb/1629240083?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 1)
Aug 17, 2021 20:37:34.549 [0x7faeb8097b38] DEBUG - Content-Length of /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/a6/a62e896cfee54cd2deb19d7c92e1b16c9ba21290.jpg is 7738 (of total: 7738).
Aug 17, 2021 20:37:34.549 [0x7faeb84cab38] DEBUG - Request: [192.168.1.11:58508 (Subnet)] GET /photo/:/transcode?width=120&height=180&minSize=1&upscale=1&url=%2Flibrary%2Fmetadata%2F44343%2Fthumb%2F1629240292%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (41 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:34.549 [0x7faeb84cab38] DEBUG - Photo transcoder: Request for url [/library/metadata/44343/thumb/1629240292?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 1)
Aug 17, 2021 20:37:34.549 [0x7faeb84cab38] DEBUG - Content-Length of /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/bc/bc0d92a85ea2061d6f8a468eb1095e2455fc9713.jpg is 9235 (of total: 9235).
Aug 17, 2021 20:37:34.581 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:50518] 200 GET /photo/:/transcode?width=120&height=180&minSize=1&upscale=1&url=%2Flibrary%2Fmetadata%2F46355%2Fthumb%2F1629240638%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (41 live) TLS GZIP 33ms 8193 bytes (pipelined: 7)
Aug 17, 2021 20:37:34.596 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:65120] 200 GET /photo/:/transcode?width=120&height=180&minSize=1&upscale=1&url=%2Flibrary%2Fmetadata%2F43153%2Fthumb%2F1629240083%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (41 live) TLS GZIP 47ms 7738 bytes (pipelined: 3)
Aug 17, 2021 20:37:34.606 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:58508] 200 GET /photo/:/transcode?width=120&height=180&minSize=1&upscale=1&url=%2Flibrary%2Fmetadata%2F44343%2Fthumb%2F1629240292%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx (41 live) TLS GZIP 57ms 9235 bytes (pipelined: 8)
Aug 17, 2021 20:37:38.405 [0x7faeba6c3b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:38.405 [0x7faeb84a7b38] DEBUG - Request: [192.168.1.11:58508 (Subnet)] POST /playQueues?type=video&extrasPrefixCount=1&uri=server%3A%2F%2Ff3a99783f0ae5e593c07d9dfde9dc29870f9203a%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F46294&repeat=0&own=1&includeChapters=1&includeMarkers=1&includeGeolocation=1&includeExternalMedia=1 (41 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:38.405 [0x7faeb84a7b38] DEBUG - PlayQueue: Converted 'server://f3a99783f0ae5e593c07d9dfde9dc29870f9203a/com.plexapp.plugins.library/library/metadata/46294' to 'library://x/item/%2Flibrary%2Fmetadata%2F46294'
Aug 17, 2021 20:37:38.405 [0x7faeb84a7b38] DEBUG - PlayQueue: 0 generated IDs compressed down to a 20 byte blob.
Aug 17, 2021 20:37:38.406 [0x7faeb84a7b38] DEBUG - PlayQueue: Start index: 0 End index: 0 Count: 1
Aug 17, 2021 20:37:38.406 [0x7faeb84a7b38] DEBUG - PlayQueue: 1 generated IDs compressed down to a 24 byte blob.
Aug 17, 2021 20:37:38.407 [0x7faeb84a7b38] DEBUG - PlayQueue: Added 1 items in 0.0 sec.
Aug 17, 2021 20:37:38.411 [0x7faeb84a7b38] DEBUG - It took 10.000000 ms to retrieve 251 items.
Aug 17, 2021 20:37:38.704 [0x7faeb84a7b38] DEBUG - Extras: Looking for extras for item plex://movie/5d7768352e80df001ebde5ea with unwatched: 0 fromLibrary: 1 fromTheater: 0 fromBluray: 0 and preroll count: 0.
Aug 17, 2021 20:37:38.704 [0x7faeb84a7b38] DEBUG - Extras: Using allowable content ratings: G, PG, PG-13.
Aug 17, 2021 20:37:38.704 [0x7faeb84a7b38] DEBUG - Extras: Using genres: Comedia, Action.
Aug 17, 2021 20:37:38.720 [0x7faeb84a7b38] DEBUG - Extras: Found 1 extras from library.
Aug 17, 2021 20:37:38.720 [0x7faeb84a7b38] DEBUG - Extras: Attempting to add extra from source 0.
Aug 17, 2021 20:37:38.720 [0x7faeb84a7b38] DEBUG - Extras: Adding library extra: Star Wars: The Last Jedi.
Aug 17, 2021 20:37:38.720 [0x7faeb84a7b38] DEBUG - Extras: Attempting to add extra from source 1.
Aug 17, 2021 20:37:38.720 [0x7faeb84a7b38] DEBUG - Extras: Attempting to add extra from source 2.
Aug 17, 2021 20:37:38.721 [0x7faeb84a7b38] DEBUG - PlayQueue: Added 1 items in 0.0 sec.
Aug 17, 2021 20:37:38.726 [0x7faeb84a7b38] DEBUG - It took 0.000000 ms to retrieve 251 items.
Aug 17, 2021 20:37:38.728 [0x7faeb84a7b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:38.728 [0x7faeb84a7b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:38.728 [0x7faeb84a7b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:38.728 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:38.728 [0x7faeb84a7b38] DEBUG - Selecting best audio stream for part ID 101875 (autoselect: 1 language: es)
Aug 17, 2021 20:37:38.728 [0x7faeb84a7b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:38.728 [0x7faeb84a7b38] DEBUG - Selected a foreign audio track: en
Aug 17, 2021 20:37:38.728 [0x7faeb84a7b38] DEBUG - Audio Stream: 227106, Subtitle Stream: 230196
Aug 17, 2021 20:37:38.730 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:58508] 200 POST /playQueues?type=video&extrasPrefixCount=1&uri=server%3A%2F%2Ff3a99783f0ae5e593c07d9dfde9dc29870f9203a%2Fcom.plexapp.plugins.library%2Flibrary%2Fmetadata%2F46294&repeat=0&own=1&includeChapters=1&includeMarkers=1&includeGeolocation=1&includeExternalMedia=1 (41 live) TLS GZIP 325ms 8032 bytes (pipelined: 9)
Aug 17, 2021 20:37:38.797 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:38.797 [0x7faeb7b3cb38] DEBUG - Request: [192.168.1.11:58508 (Subnet)] GET /library/metadata/52750?Accept-Language=es-419&includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&includeMarkers=1&includeExternalMedia=1&asyncAugmentMetadata=1&checkFiles=1 (41 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:38.802 [0x7faeb7b3cb38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:38.802 [0x7faeb7b3cb38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:38.802 [0x7faeb7b3cb38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:38.803 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:58508] 200 GET /library/metadata/52750?Accept-Language=es-419&includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&includeMarkers=1&includeExternalMedia=1&asyncAugmentMetadata=1&checkFiles=1 (41 live) TLS GZIP 5ms 1222 bytes (pipelined: 10)
Aug 17, 2021 20:37:38.825 [0x7faeba6c3b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:38.825 [0x7faeb80dab38] DEBUG - Request: [192.168.1.11:58508 (Subnet)] GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F52750&mediaIndex=2&partIndex=0&protocol=dash&fastSeek=1&directPlay=1&directStream=1&subtitleSize=125&audioBoost=700&location=lan&maxVideoBitrate=2000&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=hnsoouyreo8qxw87byt22mds&subtitles=burn&Accept-Language=es-419 (41 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:38.825 [0x7faeb80dab38] DEBUG - [Transcode] Found session GUID of hnsoouyreo8qxw87byt22mds in session start.
Aug 17, 2021 20:37:38.825 [0x7faeb80dab38] DEBUG - [Transcode] TranscodeUniversalRequest: using augmented profile Web
Aug 17, 2021 20:37:38.825 [0x7faeb80dab38] DEBUG - [Transcode] Downloading document http://127.0.0.1:32400/library/metadata/52750?includeBandwidths=1&offset=-1&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=a3fp2dsoeqtu3ahxsr49j9v0
Aug 17, 2021 20:37:38.825 [0x7faeb80dab38] DEBUG - [Transcode] Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:38.829 [0x7faeb80dab38] DEBUG - [Transcode] Selected a foreign audio track:
Aug 17, 2021 20:37:38.829 [0x7faeb80dab38] DEBUG - [Transcode] Selected a foreign audio track:
Aug 17, 2021 20:37:38.829 [0x7faeb80dab38] DEBUG - [Transcode] Selected a foreign audio track:
Aug 17, 2021 20:37:38.830 [0x7faeb80dab38] DEBUG - [Transcode] Streaming Resource: Added session 0x7faebba46488:a3fp2dsoeqtu3ahxsr49j9v0
Aug 17, 2021 20:37:38.830 [0x7faeb80dab38] DEBUG - [Transcode] Streaming Resource: Reached Decision id=52750 codes=(MDE=1000,Direct play OK.) media=(id=110984 part=(id=117603 decision=direct play protocol=dash streams=(Video=(id=259155 decision= width=480 height=200) Audio=(id=259156 decision= channels=0 rate=0))))
Aug 17, 2021 20:37:38.830 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:58508] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F52750&mediaIndex=2&partIndex=0&protocol=dash&fastSeek=1&directPlay=1&directStream=1&subtitleSize=125&audioBoost=700&location=lan&maxVideoBitrate=2000&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&mediaBufferSize=102400&session=hnsoouyreo8qxw87byt22mds&subtitles=burn&Accept-Language=es-419 (41 live) TLS GZIP 5ms 1153 bytes (pipelined: 11)
Aug 17, 2021 20:37:38.906 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:38.906 [0x7faeb8097b38] DEBUG - Request: [192.168.1.11:64832 (Subnet)] GET /services/iva/assets/276758/video.mp4?fmt=4&bitrate=750&Accept-Language=es-419 (42 live) TLS Signed-in Token (MyUserAccount) (range: bytes=0-)
Aug 17, 2021 20:37:38.906 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:64832] 302 GET /services/iva/assets/276758/video.mp4?fmt=4&bitrate=750&Accept-Language=es-419 (42 live) TLS 0ms 356 bytes (pipelined: 1) (range: bytes=0-) -> https://video.internetvideoarchive.net/video.mp4?cmd=6&publishedid=276758&customerid=112548&fmt=4&videokbrate=750&e=1629250658&h=47449535047fb8d2b94b01dbc5d3ff58
Aug 17, 2021 20:37:40.190 [0x7faeba6c3b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:40.190 [0x7faeb84cab38] DEBUG - Request: [192.168.1.11:64832 (Subnet)] GET /photo/:/transcode?url=%2Flibrary%2Fmetadata%2F52750%2Fthumb%2F1629241481%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx&width=512&height=512&minSize=1 (42 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:40.190 [0x7faeb84cab38] DEBUG - Photo transcoder: Request for url [/library/metadata/52750/thumb/1629241481?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Aug 17, 2021 20:37:40.190 [0x7faeb84cab38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:40.193 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:40.194 [0x7faeb84a7b38] DEBUG - Request: [192.168.1.11:63466 (Subnet)] GET /photo/:/transcode?url=%2Flibrary%2Fmetadata%2F52750%2Fthumb%2F1629241481%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx&width=192&height=192&minSize=1 (43 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:40.194 [0x7faeb84a7b38] DEBUG - Photo transcoder: Request for url [/library/metadata/52750/thumb/1629241481?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx] (is local: 1 upscaled: 0)
Aug 17, 2021 20:37:40.194 [0x7faeb84a7b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:40.196 [0x7faeb84edb38] DEBUG - Request: [192.168.1.11:53375 (Subnet)] GET /web/favicon.ico (43 live) GZIP Signed-in
Aug 17, 2021 20:37:40.196 [0x7faeb84edb38] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/favicon.ico"
Aug 17, 2021 20:37:40.196 [0x7faeb84edb38] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/favicon.ico is 5430 (of total: 5430).
Aug 17, 2021 20:37:40.197 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:53375] 200 GET /web/favicon.ico (43 live) GZIP 0ms 5430 bytes (pipelined: 3)
Aug 17, 2021 20:37:40.198 [0x7faeb84cab38] DEBUG - Computed media url for item 52750: https://metadata-static.plex.tv/extras/iva/276758/169d78b19a41338b445fe8c2542f1730.jpg
Aug 17, 2021 20:37:40.198 [0x7faeb84cab38] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: 302)
Aug 17, 2021 20:37:40.199 [0x7faeb84a7b38] DEBUG - Computed media url for item 52750: https://metadata-static.plex.tv/extras/iva/276758/169d78b19a41338b445fe8c2542f1730.jpg
Aug 17, 2021 20:37:40.199 [0x7faeb84a7b38] DEBUG - Calling back into ourselves for photo to transcode, optimizing the process (status: 302)
Aug 17, 2021 20:37:40.230 [0x7faeb84cab38] DEBUG - HTTP requesting GET https://metadata-static.plex.tv/extras/iva/276758/169d78b19a41338b445fe8c2542f1730.jpg
Aug 17, 2021 20:37:40.230 [0x7faeb84a7b38] DEBUG - HTTP requesting GET https://metadata-static.plex.tv/extras/iva/276758/169d78b19a41338b445fe8c2542f1730.jpg
Aug 17, 2021 20:37:40.251 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:40.251 [0x7faeb7b3cb38] DEBUG - Request: [192.168.1.11:58508 (Subnet)] GET /:/timeline?ratingKey=52750&key=%2Flibrary%2Fmetadata%2F52750&playbackTime=0&playQueueItemID=18719&state=playing&hasMDE=1&context=home%3Ahub.home.movies.recent&row=2&col=2&time=0&duration=32000 (43 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:40.252 [0x7faeb7b3cb38] DEBUG - Client [a3fp2dsoeqtu3ahxsr49j9v0] reporting timeline state playing, progress of 0/32000ms for guid=, playbackTime=0ms ratingKey=52750 url=, key=/library/metadata/52750, containerKey=, metadataId=52750, source=
Aug 17, 2021 20:37:40.255 [0x7faeb7b3cb38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:40.255 [0x7faeb7b3cb38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:40.255 [0x7faeb7b3cb38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:40.255 [0x7faeb7b3cb38] DEBUG - [Now] User is MyUserAccount (ID: 1)
Aug 17, 2021 20:37:40.255 [0x7faeb7b3cb38] DEBUG - [Now] Device is Chrome (Chrome).
Aug 17, 2021 20:37:40.255 [0x7faeb7b3cb38] DEBUG - [Now] Profile is Web
Aug 17, 2021 20:37:40.255 [0x7faeb7b3cb38] DEBUG - [Now] Updated play state for /library/metadata/52750.
Aug 17, 2021 20:37:40.255 [0x7faeb7b3cb38] DEBUG - Statistics: (yvnvz1lcve0v4ic90h4do9yy) Reporting active playback in state 0 of type 12 (scrobble: 0) for account 1
Aug 17, 2021 20:37:40.257 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:58508] 200 GET /:/timeline?ratingKey=52750&key=%2Flibrary%2Fmetadata%2F52750&playbackTime=0&playQueueItemID=18719&state=playing&hasMDE=1&context=home%3Ahub.home.movies.recent&row=2&col=2&time=0&duration=32000 (43 live) TLS GZIP 5ms 592 bytes (pipelined: 12)
Aug 17, 2021 20:37:40.261 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:40.261 [0x7faeb80dab38] DEBUG - Request: [192.168.1.11:58508 (Subnet)] GET /status/sessions (43 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:40.261 [0x7faeb80dab38] DEBUG - [Now] Adding 1 sessions.
Aug 17, 2021 20:37:40.261 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:58508] 200 GET /status/sessions (43 live) TLS GZIP 0ms 1398 bytes (pipelined: 13)
Aug 17, 2021 20:37:40.264 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:40.264 [0x7faeb8097b38] DEBUG - Request: [192.168.1.11:58508 (Subnet)] GET /playQueues/848?repeat=0&own=1&includeChapters=1&includeMarkers=1&includeGeolocation=1&includeExternalMedia=1 (43 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:40.264 [0x7faeb8097b38] DEBUG - PlayQueue: total generated: 1, before cursor: 0, after cursor: 1
Aug 17, 2021 20:37:40.264 [0x7faeb8097b38] DEBUG - PlayQueue: No need to refresh, active window is operational.
Aug 17, 2021 20:37:40.269 [0x7faeb8097b38] DEBUG - It took 0.000000 ms to retrieve 251 items.
Aug 17, 2021 20:37:40.269 [0x7faeb8097b38] DEBUG - PlayQueue: Client yvnvz1lcve0v4ic90h4do9yy requested ownership of play queue 848, but already had it.
Aug 17, 2021 20:37:40.270 [0x7faeb8097b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:40.271 [0x7faeb8097b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:40.271 [0x7faeb8097b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:40.271 [0x7faeb8097b38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:40.271 [0x7faeb8097b38] DEBUG - Selecting best audio stream for part ID 101875 (autoselect: 1 language: es)
Aug 17, 2021 20:37:40.271 [0x7faeb8097b38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:40.271 [0x7faeb8097b38] DEBUG - Selected a foreign audio track: en
Aug 17, 2021 20:37:40.271 [0x7faeb8097b38] DEBUG - Audio Stream: 227106, Subtitle Stream: 230196
Aug 17, 2021 20:37:40.273 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:58508] 200 GET /playQueues/848?repeat=0&own=1&includeChapters=1&includeMarkers=1&includeGeolocation=1&includeExternalMedia=1 (43 live) TLS GZIP 9ms 8032 bytes (pipelined: 14)
Aug 17, 2021 20:37:40.585 [0x7faeb84a7b38] DEBUG - HTTP/2.0 (0.4s) 200 response from GET https://metadata-static.plex.tv/extras/iva/276758/169d78b19a41338b445fe8c2542f1730.jpg
Aug 17, 2021 20:37:40.585 [0x7faeb84a7b38] DEBUG - Photo cache obtained 503751 bytes from https://metadata-static.plex.tv/extras/iva/276758/169d78b19a41338b445fe8c2542f1730.jpg
Aug 17, 2021 20:37:40.585 [0x7faeb84a7b38] DEBUG - Saving original media file to /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/d6/d6ba1177a8d07818480f40f1176c531377869467.jpg
Aug 17, 2021 20:37:40.606 [0x7faeb84a7b38] DEBUG - Created thumbnail of size 341x192, has pixels: 1
Aug 17, 2021 20:37:40.609 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:63466] 200 GET /photo/:/transcode?url=%2Flibrary%2Fmetadata%2F52750%2Fthumb%2F1629241481%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx&width=192&height=192&minSize=1 (43 live) TLS GZIP 415ms 10538 bytes (pipelined: 1)
Aug 17, 2021 20:37:40.697 [0x7faeb84cab38] DEBUG - HTTP/2.0 (0.5s) 200 response from GET https://metadata-static.plex.tv/extras/iva/276758/169d78b19a41338b445fe8c2542f1730.jpg
Aug 17, 2021 20:37:40.698 [0x7faeb84cab38] DEBUG - Photo cache obtained 503751 bytes from https://metadata-static.plex.tv/extras/iva/276758/169d78b19a41338b445fe8c2542f1730.jpg
Aug 17, 2021 20:37:40.698 [0x7faeb84cab38] DEBUG - Saving original media file to /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/d6/d6ba1177a8d07818480f40f1176c531377869467.jpg
Aug 17, 2021 20:37:40.742 [0x7faeb84cab38] DEBUG - Created thumbnail of size 910x512, has pixels: 1
Aug 17, 2021 20:37:40.762 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:64832] 200 GET /photo/:/transcode?url=%2Flibrary%2Fmetadata%2F52750%2Fthumb%2F1629241481%3FX-Plex-Token%3Dxxxxxxxxxxxxxxxxxxxx&width=512&height=512&minSize=1 (43 live) TLS GZIP 572ms 54803 bytes (pipelined: 2)
Aug 17, 2021 20:37:44.230 [0x7faeba6c3b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:44.231 [0x7faeb7b3cb38] DEBUG - Request: [192.168.1.11:58508 (Subnet)] GET /:/timeline?ratingKey=52750&key=%2Flibrary%2Fmetadata%2F52750&playbackTime=3006&playQueueItemID=18719&state=stopped&hasMDE=1&context=home%3Ahub.home.movies.recent&row=2&col=2&time=3000&duration=32000 (43 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:44.231 [0x7faeb7b3cb38] DEBUG - Client [a3fp2dsoeqtu3ahxsr49j9v0] reporting timeline state stopped, progress of 3000/32000ms for guid=, playbackTime=3006ms ratingKey=52750 url=, key=/library/metadata/52750, containerKey=, metadataId=52750, source=
Aug 17, 2021 20:37:44.231 [0x7faeba6c3b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:44.231 [0x7faeb80dab38] DEBUG - Request: [192.168.1.11:65120 (Subnet)] GET /library/metadata/46294?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeReviews=1&includeChapters=1&includeStations=1&includeExternalMedia=1&asyncAugmentMetadata=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1 (43 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:44.232 [0x7faeb7b3cb38] DEBUG - [Now] User is MyUserAccount (ID: 1)
Aug 17, 2021 20:37:44.232 [0x7faeb7b3cb38] DEBUG - [Now] Device is Chrome (Chrome).
Aug 17, 2021 20:37:44.232 [0x7faeb7b3cb38] DEBUG - [Now] Profile is Web
Aug 17, 2021 20:37:44.232 [0x7faeb7b3cb38] DEBUG - [Now] Updated play state for /library/metadata/52750.
Aug 17, 2021 20:37:44.232 [0x7faeb7b3cb38] DEBUG - Statistics: (yvnvz1lcve0v4ic90h4do9yy) Reporting active playback in state 3 of type 12 (scrobble: 0) for account 1
Aug 17, 2021 20:37:44.233 [0x7faeb7b3cb38] DEBUG - Streaming Resource: Terminated session 0x7faebba46488:a3fp2dsoeqtu3ahxsr49j9v0 with reason Client stopped playback.
Aug 17, 2021 20:37:44.233 [0x7faeb7b3cb38] DEBUG - Streaming Resource: Removing session 0x7faebba46488:a3fp2dsoeqtu3ahxsr49j9v0
Aug 17, 2021 20:37:44.234 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:58508] 200 GET /:/timeline?ratingKey=52750&key=%2Flibrary%2Fmetadata%2F52750&playbackTime=3006&playQueueItemID=18719&state=stopped&hasMDE=1&context=home%3Ahub.home.movies.recent&row=2&col=2&time=3000&duration=32000 (43 live) TLS GZIP 3ms 592 bytes (pipelined: 15)
Aug 17, 2021 20:37:44.237 [0x7faeb80dab38] DEBUG - It took 0.000000 ms to retrieve 251 items.
Aug 17, 2021 20:37:44.242 [0x7faeb80dab38] DEBUG - It took 10.000000 ms to retrieve 251 items.
Aug 17, 2021 20:37:44.243 [0x7faeb80dab38] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 17, 2021 20:37:44.243 [0x7faeb80dab38] DEBUG - Selecting best audio stream for part ID 101875 (autoselect: 1 language: es)
Aug 17, 2021 20:37:44.243 [0x7faeb80dab38] DEBUG - We're going to try to auto-select a subtitle.
Aug 17, 2021 20:37:44.243 [0x7faeb80dab38] DEBUG - Selected a foreign audio track: en
Aug 17, 2021 20:37:44.243 [0x7faeb80dab38] DEBUG - Audio Stream: 227106, Subtitle Stream: 230196
Aug 17, 2021 20:37:44.252 [0x7faeb80dab38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:44.252 [0x7faeb80dab38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:44.252 [0x7faeb80dab38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:44.252 [0x7faeb80dab38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:44.253 [0x7faeb80dab38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:44.253 [0x7faeb80dab38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:44.253 [0x7faeb80dab38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:44.253 [0x7faeb80dab38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:44.254 [0x7faeb80dab38] DEBUG - Activity: registered new activity b04173a4-d938-4fd8-b785-59945f44bb31 - "Refreshing"
Aug 17, 2021 20:37:44.254 [0x7faeb6ed5b38] DEBUG - Activity: updated activity b04173a4-d938-4fd8-b785-59945f44bb31 - completed 0.0% - Refreshing
Aug 17, 2021 20:37:44.255 [0x7faeb6ed5b38] DEBUG - Activity: updated activity b04173a4-d938-4fd8-b785-59945f44bb31 - completed 33.0% - Refreshing
Aug 17, 2021 20:37:44.255 [0x7faeb6ed5b38] DEBUG - Activity: updated activity b04173a4-d938-4fd8-b785-59945f44bb31 - completed 66.0% - Refreshing
Aug 17, 2021 20:37:44.256 [0x7faeb6ed5b38] DEBUG - Activity: Ended activity b04173a4-d938-4fd8-b785-59945f44bb31.
Aug 17, 2021 20:37:44.258 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:65120] 200 GET /library/metadata/46294?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeReviews=1&includeChapters=1&includeStations=1&includeExternalMedia=1&asyncAugmentMetadata=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1 (43 live) TLS GZIP 26ms 11715 bytes (pipelined: 4)
Aug 17, 2021 20:37:44.266 [0x7faeba6c3b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:44.266 [0x7faeb8097b38] DEBUG - Request: [192.168.1.11:65120 (Subnet)] GET /library/metadata/46294/extras (43 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:44.271 [0x7faeb8097b38] DEBUG - It took 0.000000 ms to retrieve 251 items.
Aug 17, 2021 20:37:44.278 [0x7faeb8097b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:44.279 [0x7faeb8097b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:44.279 [0x7faeb8097b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:44.279 [0x7faeb8097b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:44.280 [0x7faeb8097b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:44.280 [0x7faeb8097b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:44.280 [0x7faeb8097b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:44.281 [0x7faeb8097b38] DEBUG - Selected a foreign audio track:
Aug 17, 2021 20:37:44.281 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:65120] 200 GET /library/metadata/46294/extras (43 live) TLS GZIP 14ms 1537 bytes (pipelined: 5)
Aug 17, 2021 20:37:45.014 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:59046] 200 GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (43 live) TLS GZIP 20000ms 5 bytes (pipelined: 33)
Aug 17, 2021 20:37:45.029 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:45.029 [0x7faeb84edb38] DEBUG - Request: [192.168.1.11:59046 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (43 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:45.029 [0x7faeb84edb38] DEBUG - Content-Length is -1 (of total: -1).
Aug 17, 2021 20:37:46.254 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.32:35580] 200 GET /player/proxy/poll?deviceClass=pc&protocolVersion=1&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (43 live) GZIP 20001ms 5 bytes (pipelined: 217)
Aug 17, 2021 20:37:46.288 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:46.288 [0x7faeb84a7b38] DEBUG - Request: [192.168.1.32:35580 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=1&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (43 live) GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:46.288 [0x7faeb84a7b38] DEBUG - Content-Length is -1 (of total: -1).
Aug 17, 2021 20:37:49.368 [0x7faeb84cab38] DEBUG - Request: [192.168.1.11:53375 (Subnet)] GET /web/static/24cb7f1a5e2d0102f05f.png (43 live) GZIP Signed-in
Aug 17, 2021 20:37:49.368 [0x7faeb84cab38] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/static/24cb7f1a5e2d0102f05f.png"
Aug 17, 2021 20:37:49.368 [0x7faeb84cab38] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-ab6e1a058/WebClient.bundle/Contents/Resources/static/24cb7f1a5e2d0102f05f.png is 113817 (of total: 113817).
Aug 17, 2021 20:37:49.369 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:53375] 200 GET /web/static/24cb7f1a5e2d0102f05f.png (43 live) GZIP 0ms 113817 bytes (pipelined: 4)
Aug 17, 2021 20:37:51.863 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:51.863 [0x7faeb84edb38] DEBUG - Request: [192.168.1.11:65120 (Subnet)] GET /:/prefs (41 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:51.864 [0x7faeba6c3b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:51.864 [0x7faeb7b3cb38] DEBUG - Request: [192.168.1.11:58508 (Subnet)] GET /accounts/1 (41 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:51.865 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:51.865 [0x7faeb80dab38] DEBUG - Request: [192.168.1.11:50518 (Subnet)] GET /myplex/account (41 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:51.865 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:58508] 200 GET /accounts/1 (41 live) TLS GZIP 0ms 647 bytes (pipelined: 16)
Aug 17, 2021 20:37:51.865 [0x7faeba6a0b38] DEBUG - Completed: [192.168.1.11:50518] 200 GET /myplex/account (42 live) TLS GZIP 0ms 3659 bytes (pipelined: 8)
Aug 17, 2021 20:37:51.867 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:65120] 200 GET /:/prefs (42 live) TLS GZIP 4ms 9693 bytes (pipelined: 6)
Aug 17, 2021 20:37:51.868 [0x7faeba6c3b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:51.868 [0x7faeb8097b38] DEBUG - Request: [192.168.1.11:55565 (Subnet)] GET /system/:/prefs (42 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:51.868 [0x7faeb8097b38] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/:/prefs
Aug 17, 2021 20:37:51.868 [0x7faeb8097b38] DEBUG - HTTP requesting GET http://127.0.0.1:40451/system/:/prefs
Aug 17, 2021 20:37:51.873 [0x7faeb8097b38] DEBUG - HTTP/1.1 (0.0s) 200 response from GET http://127.0.0.1:40451/system/:/prefs
Aug 17, 2021 20:37:51.873 [0x7faeb8097b38] DEBUG - [com.plexapp.system] HTTP reply status 200, with 419 bytes of content.
Aug 17, 2021 20:37:51.874 [0x7faeba6c3b38] DEBUG - Completed: [192.168.1.11:55565] 200 GET /system/:/prefs (42 live) TLS GZIP 5ms 867 bytes (pipelined: 1)
Aug 17, 2021 20:37:54.749 [0x7faeba6a0b38] DEBUG - Auth: authenticated user 1 as MyUserAccount
Aug 17, 2021 20:37:54.749 [0x7faeb7b3cb38] DEBUG - Request: [192.168.1.11:64832 (Subnet)] GET /diagnostics/logs (38 live) TLS GZIP Signed-in Token (MyUserAccount)
Aug 17, 2021 20:37:54.749 [0x7faeb7b3cb38] DEBUG - Diagnostics: Building logfile zip
If I may augment here?
Is this for every file or a selected few?
If a selected few, “Plex Dance” that media.
(move out, scan files, empty trash, clean bundles, optimize DB, move back)
I’ve seen this happen a few times but don’t yet have enough information to start reproducing / tracking it down.
I suspect it has something to do with hard shutdown/restart of PMS (possibly crash & restart) which causes DB pointers to be criss-crossed in the records.
Hi there, I seem to experience a similar issue on my Plex WebApp player via Chrome.
With me It plays a random file, when trying to play a movie from my Plex library.
I did the “Plex dance”, but that did not solve the issue. I also tested on a different player (iOS mobile), which did display the correct movie.
@ChuckPa: Is there any way, that you know of, which might correct these “criss-crossed” DB pointers?
Love to hear from you.
BR,
Wouter.
When you pull up the “Get Info” for one of these media files which is incorrect, does the database actually contain the incorrect path?
The path listed there is what Plex will play.
If the path is correct but the wrong file is still being played, the problem isn’t Plex.
The problem is how that path resolves
Possible causes:
If the path is incorrect, please show me.
This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.
