Problema con PLEX Server

04 Nov 2023, 14:23

javi@raspberrypi:~ $ getfacl /media/javi
getfacl: Eliminando '/' inicial en nombres de ruta absolutos
# file: media/javi
# owner: root
# group: root
user::rwx
user:javi:r-x
group::---
mask::r-x
other::---

javi@raspberrypi:~ $

04 Nov 2023, 14:45

Bien, ahí tenemos la información que nos faltaba:

user:javi:r-x

Eso permite que el usuario "javi" tenga acceso (de lectura) al directorio /media/javi
Y esto me hace sospechar que el problema puede venir de que el usuario "plex" no tiene ningún tipo de acceso al directorio /media/javi

Para estar seguros, antes de liarnos a cambiar propietarios y permisos, estaría bien consultar el log de Plex para ver si se queja de permisos.
Te hablo de cabeza, no tengo plex delante, pero creo que los logs los tienes en Settings > Manage > Troubleshooting

04 Nov 2023, 14:53

te refieres dentro de plex a traves del navegador? que es donde lo veo yo, pero me meto en ajustes y no veo lo que me dices

04 Nov 2023, 14:56

Debería haber un botón para descargar los logs, creo que se baja un archivo zip, pero de memoria no lo sé.
La otra opcion es mirar dónde está instalado Plex y buscar el directorio de logs.

Por ejemplo, si Plex está en /var/lib/plexmediaserver
Entonces los logs estarán en /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Logs

04 Nov 2023, 14:58

voy a buscarlo a ver...

04 Nov 2023, 15:05

Nov 04, 2023 14:25:55.800 [548210643168] DEBUG - [Req#270/MediaProviderManager] cloud provider (Movies & TV) is online and available
Nov 04, 2023 14:25:55.843 [548222587104] DEBUG - [HttpClient/HCl#a7] HTTP/2.0 (0.1s) 200 response from GET https://plex.tv/api/v2/server/users/sub ... xxxxxxxxxx (reused)
Nov 04, 2023 14:25:55.845 [548199162080] DEBUG - [Req#270] MyPlex: using cached data for request for https://plex.tv/api/v2/server/users/ser ... xxxxxxxxxx
Nov 04, 2023 14:25:55.927 [548213993696] DEBUG - Request: [127.0.0.1:58730 (Loopback)] GET /myplex/account (8 live) #28e GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:25:55.931 [548240072928] DEBUG - Completed: [127.0.0.1:58730] 200 GET /myplex/account (8 live) #28e GZIP 4ms 2838 bytes (pipelined: 6)
Nov 04, 2023 14:25:55.943 [548224696544] DEBUG - Request: [[::1]:54104 (Loopback)] GET /media/providers (8 live) #26b GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:25:55.951 [548242215136] DEBUG - Completed: [[::1]:54104] 200 GET /media/providers (8 live) #26b GZIP 7ms 3780 bytes (pipelined: 2)
Nov 04, 2023 14:25:55.971 [548213993696] DEBUG - Request: [127.0.0.1:58730 (Loopback)] GET /media/providers (8 live) #293 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:25:55.976 [548240072928] DEBUG - Completed: [127.0.0.1:58730] 200 GET /media/providers (8 live) #293 GZIP 5ms 3908 bytes (pipelined: 7)
Nov 04, 2023 14:25:55.997 [548224696544] DEBUG - Request: [192.168.1.41:33874 (Subnet)] GET /media/providers (8 live) #260 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:25:56.002 [548242215136] DEBUG - Completed: [192.168.1.41:33874] 200 GET /media/providers (8 live) #260 GZIP 4ms 3908 bytes (pipelined: 2)
Nov 04, 2023 14:25:56.135 [548213993696] DEBUG - Request: [[::1]:54104 (Loopback)] GET /:/prefs/get?id=MergedRecentlyAdded (8 live) #295 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:25:56.136 [548240072928] DEBUG - Completed: [[::1]:54104] 200 GET /:/prefs/get?id=MergedRecentlyAdded (8 live) #295 GZIP 1ms 455 bytes (pipelined: 3)
Nov 04, 2023 14:25:56.290 [548224696544] DEBUG - Request: [127.0.0.1:58730 (Loopback)] GET /:/prefs/get?id=MergedRecentlyAdded (8 live) #297 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:25:56.292 [548240072928] DEBUG - Completed: [127.0.0.1:58730] 200 GET /:/prefs/get?id=MergedRecentlyAdded (8 live) #297 GZIP 1ms 583 bytes (pipelined: 8)
Nov 04, 2023 14:25:56.462 [548213993696] DEBUG - Request: [192.168.1.41:32932 (Subnet)] GET /media/providers (9 live) #26a TLS GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:25:56.468 [548240072928] DEBUG - Completed: [192.168.1.41:32932] 200 GET /media/providers (9 live) #26a TLS GZIP 5ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:26:08.870 [548242215136] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:44896 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:26:08.881 [548240072928] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]:44896: sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:26:08.888 [548213993696] DEBUG - Request: [192.168.1.41:33874 (Subnet)] GET /media/providers (8 live) #299 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:26:08.896 [548242215136] DEBUG - Completed: [192.168.1.41:33874] 200 GET /media/providers (8 live) #299 GZIP 8ms 3908 bytes (pipelined: 3)
Nov 04, 2023 14:26:39.868 [548242215136] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:47370 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:26:39.880 [548242215136] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]:47370: sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:26:39.883 [548213993696] DEBUG - Request: [192.168.1.41:47386 (Subnet)] GET /media/providers (4 live) #2aa GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:26:39.888 [548240072928] DEBUG - Completed: [192.168.1.41:47386] 200 GET /media/providers (4 live) #2aa GZIP 5ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:27:02.006 [548162236640] DEBUG - Putting plug-in com.plexapp.agents.thetvdb to sleep.
Nov 04, 2023 14:27:02.007 [548162236640] DEBUG - Stopping [com.plexapp.agents.thetvdb].
Nov 04, 2023 14:27:02.007 [548162236640] DEBUG - [com.plexapp.agents.thetvdb] Killing.
Nov 04, 2023 14:27:02.015 [548244664544] WARN - JobManager: Could not find job for handle 4940
Nov 04, 2023 14:27:12.007 [548162236640] DEBUG - Stopping file watcher for com.plexapp.agents.thetvdb
Nov 04, 2023 14:27:12.494 [548151689440] DEBUG - Putting plug-in com.plexapp.agents.imdb to sleep.
Nov 04, 2023 14:27:12.494 [548151689440] DEBUG - Stopping [com.plexapp.agents.imdb].
Nov 04, 2023 14:27:12.494 [548151689440] DEBUG - [com.plexapp.agents.imdb] Killing.
Nov 04, 2023 14:27:12.501 [548244664544] WARN - JobManager: Could not find job for handle 5078
Nov 04, 2023 14:27:22.495 [548151689440] DEBUG - Stopping file watcher for com.plexapp.agents.imdb
Nov 04, 2023 14:28:02.996 [548213993696] DEBUG - Request: [192.168.1.41:44236 (Subnet)] GET /media/providers (3 live) #2ab GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:28:03.000 [548240072928] DEBUG - Completed: [192.168.1.41:44236] 200 GET /media/providers (3 live) #2ab GZIP 4ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:28:03.016 [548240072928] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:44244 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:28:03.030 [548242215136] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]:44244: sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:28:31.110 [548242215136] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:46998 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:28:31.124 [548240072928] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]:46998: sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:28:31.132 [548213993696] DEBUG - Request: [192.168.1.41:47006 (Subnet)] GET /media/providers (4 live) #2b6 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:28:31.139 [548242215136] DEBUG - Completed: [192.168.1.41:47006] 200 GET /media/providers (4 live) #2b6 GZIP 6ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:29:02.961 [548240072928] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:52186 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:29:02.972 [548242215136] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]:52186: sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:29:02.981 [548213993696] DEBUG - Request: [192.168.1.41:52202 (Subnet)] GET /media/providers (4 live) #2bc GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:29:02.987 [548240072928] DEBUG - Completed: [192.168.1.41:52202] 200 GET /media/providers (4 live) #2bc GZIP 6ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:29:33.868 [548240072928] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:42636 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:29:33.880 [548240072928] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]:42636: sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:29:33.882 [548213993696] DEBUG - Request: [192.168.1.41:42650 (Subnet)] GET /media/providers (4 live) #2c2 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:29:33.887 [548242215136] DEBUG - Completed: [192.168.1.41:42650] 200 GET /media/providers (4 live) #2c2 GZIP 5ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:32:02.915 [548240072928] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:53930 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:32:02.933 [548242215136] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]:53930: sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:32:02.946 [548213993696] DEBUG - Request: [192.168.1.41:53938 (Subnet)] GET /media/providers (4 live) #2c8 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:32:02.955 [548242215136] DEBUG - Completed: [192.168.1.41:53938] 200 GET /media/providers (4 live) #2c8 GZIP 8ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:34:02.952 [548213993696] DEBUG - Request: [192.168.1.41:55186 (Subnet)] GET /media/providers (3 live) #2c9 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:34:02.958 [548240072928] DEBUG - Completed: [192.168.1.41:55186] 200 GET /media/providers (3 live) #2c9 GZIP 6ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:34:02.977 [548242215136] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:55188 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:34:02.987 [548240072928] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]:55188: sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:36:02.897 [548242215136] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:40950 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:36:02.907 [548240072928] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]:40950: sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:36:02.912 [548213993696] DEBUG - Request: [192.168.1.41:40958 (Subnet)] GET /media/providers (4 live) #2d4 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:36:02.918 [548240072928] DEBUG - Completed: [192.168.1.41:40958] 200 GET /media/providers (4 live) #2d4 GZIP 5ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:38:02.916 [548240072928] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:35928 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:38:02.936 [548242215136] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]

sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:38:02.941 [548213993696] DEBUG - Request: [192.168.1.41:35940 (Subnet)] GET /media/providers (4 live) #2da GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:38:02.958 [548240072928] DEBUG - Completed: [192.168.1.41:35940] 200 GET /media/providers (4 live) #2da GZIP 16ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:40:02.954 [548213993696] DEBUG - Request: [192.168.1.41:49012 (Subnet)] GET /media/providers (3 live) #2db GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:40:02.962 [548240072928] DEBUG - Completed: [192.168.1.41:49012] 200 GET /media/providers (3 live) #2db GZIP 8ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:40:03.069 [548240072928] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:49024 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:40:03.078 [548242215136] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]:49024: sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:42:02.928 [548240072928] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:55562 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:42:02.940 [548242215136] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]:55562: sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:42:02.962 [548213993696] DEBUG - Request: [192.168.1.41:55568 (Subnet)] GET /media/providers (4 live) #2e6 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:42:02.968 [548242215136] DEBUG - Completed: [192.168.1.41:55568] 200 GET /media/providers (4 live) #2e6 GZIP 5ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:42:16.828 [548210643168] DEBUG - [Req#1c4/ViewStateSync] Starting
Nov 04, 2023 14:42:16.828 [548210643168] DEBUG - [Req#1c4/ViewStateSync] Got token for user 1 (Sh4ck)
Nov 04, 2023 14:42:16.829 [548210643168] DEBUG - [Req#1c4/ViewStateSync] Starting for user 1
Nov 04, 2023 14:42:16.829 [548210643168] DEBUG - [Req#1c4/ViewStateSync] Sending state
Nov 04, 2023 14:42:16.832 [548210643168] DEBUG - [Req#1c4/ViewStateSync] Sending state
Nov 04, 2023 14:42:16.834 [548210643168] DEBUG - [Req#1c4/ViewStateSync] All state sent
Nov 04, 2023 14:42:16.835 [548210643168] DEBUG - [Req#1c4/ViewStateSync] Getting state
Nov 04, 2023 14:42:16.835 [548210643168] DEBUG - [Req#1c4/ViewStateSync/HCl#a8] HTTP requesting GET https://scrobbles.plex.tv/state/2169468 ... &count=100
Nov 04, 2023 14:42:16.962 [548222587104] DEBUG - [HttpClient/HCl#a8] HTTP/2.0 (0.1s) 200 response from GET https://scrobbles.plex.tv/state/2169468 ... &count=100
Nov 04, 2023 14:42:16.963 [548224696544] DEBUG - [Req#1c4/ViewStateSync] Reading 0 states
Nov 04, 2023 14:42:16.963 [548224696544] DEBUG - [Req#1c4/ViewStateSync] Done getting states
Nov 04, 2023 14:42:16.964 [548224696544] DEBUG - [Req#1c4/ViewStateSync] Finished
Nov 04, 2023 14:43:31.799 [548240072928] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:51808 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:43:31.821 [548213993696] DEBUG - Request: [192.168.1.41:51816 (Subnet)] GET /media/providers (4 live) #2ec GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:43:31.828 [548240072928] DEBUG - Completed: [192.168.1.41:51816] 200 GET /media/providers (4 live) #2ec GZIP 7ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:43:31.835 [548242215136] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]:51808: sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:43:33.023 [548242215136] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:51820 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:43:33.033 [548242215136] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]:51820: sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:43:33.046 [548213993696] DEBUG - Request: [192.168.1.41:51816 (Subnet)] GET /media/providers (5 live) #2ef GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:43:33.052 [548240072928] DEBUG - Completed: [192.168.1.41:51816] 200 GET /media/providers (5 live) #2ef GZIP 6ms 3908 bytes (pipelined: 2)
Nov 04, 2023 14:44:04.867 [548242215136] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:36870 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:44:04.876 [548240072928] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]

sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:44:04.881 [548213993696] DEBUG - Request: [192.168.1.41:36882 (Subnet)] GET /media/providers (4 live) #2f6 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:44:04.885 [548240072928] DEBUG - Completed: [192.168.1.41:36882] 200 GET /media/providers (4 live) #2f6 GZIP 4ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:44:35.869 [548240072928] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:44714 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:44:35.879 [548213993696] DEBUG - Request: [192.168.1.41:44720 (Subnet)] GET /media/providers (4 live) #2fc GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:44:35.880 [548240072928] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]:44714: sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:44:35.883 [548240072928] DEBUG - Completed: [192.168.1.41:44720] 200 GET /media/providers (4 live) #2fc GZIP 4ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:47:02.935 [548213993696] DEBUG - Request: [192.168.1.41:48380 (Subnet)] GET /media/providers (3 live) #2fd GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:47:02.941 [548240072928] DEBUG - Completed: [192.168.1.41:48380] 200 GET /media/providers (3 live) #2fd GZIP 5ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:47:02.987 [548242215136] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:48384 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:47:03.000 [548240072928] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]:48384: sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:49:02.931 [548242215136] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:56376 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:49:02.941 [548240072928] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]:56376: sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:49:02.949 [548213993696] DEBUG - Request: [192.168.1.41:56388 (Subnet)] GET /media/providers (4 live) #308 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:49:02.954 [548240072928] DEBUG - Completed: [192.168.1.41:56388] 200 GET /media/providers (4 live) #308 GZIP 5ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:51:02.920 [548242215136] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:40378 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:51:02.934 [548240072928] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]:40378: sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:51:02.942 [548213993696] DEBUG - Request: [192.168.1.41:40388 (Subnet)] GET /media/providers (4 live) #30e GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:51:02.948 [548240072928] DEBUG - Completed: [192.168.1.41:40388] 200 GET /media/providers (4 live) #30e GZIP 5ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:51:33.027 [548240072928] WARN - [CERT] TLS connection from [::ffff:192.168.1.41]:39940 came in with unrecognized plex.direct SNI name '192-168-1-41.3ab1735bb04f40878a866a8a504d8fc0.plex.direct'; using installed plex.direct cert
Nov 04, 2023 14:51:33.039 [548240072928] DEBUG - CERT: incomplete TLS handshake from [::ffff:192.168.1.41]

sslv3 alert certificate unknown (SSL routines)
Nov 04, 2023 14:51:33.042 [548213993696] DEBUG - Request: [192.168.1.41:39948 (Subnet)] GET /media/providers (4 live) #314 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:51:33.047 [548242215136] DEBUG - Completed: [192.168.1.41:39948] 200 GET /media/providers (4 live) #314 GZIP 5ms 3908 bytes (pipelined: 1)
Nov 04, 2023 14:51:45.789 [548240072928] DEBUG - [EventSourceClient/pubsub/151.236.217.85:443] EventSource: Got event [data] '<Message command="notifyFavoritesChange"/>'
Nov 04, 2023 14:51:45.974 [548213993696] DEBUG - Request: [127.0.0.1:38296 (Loopback)] GET /hubs?identifier=home.continue,home.ondeck&contentDirectoryID=1&includeMeta=1&excludeFields=summary (5 live) #315 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:51:45.980 [548213993696] DEBUG - [Req#315] HubCache: Adding '1/home.continue/1/hubs/esexternal-media,indirect-media,hub-style-list/contentDirectoryID=1&excludeFields=summary&identifier=home%2Econtinue%2Chome%2Eondeck&includeMeta=1' to the cache (1/home.continue/1/hubs/esexternal-media,indirect-media,hub-style-list/contentDirectoryID=1&excludeFields=summary&identifier=home%2Econtinue%2Chome%2Eondeck&includeMeta=1).
Nov 04, 2023 14:51:45.980 [548213993696] DEBUG - [Req#315] HubCache: Adding '1/home.ondeck/1/hubs/esexternal-media,indirect-media,hub-style-list/contentDirectoryID=1&excludeFields=summary&identifier=home%2Econtinue%2Chome%2Eondeck&includeMeta=1' to the cache (1/home.ondeck/1/hubs/esexternal-media,indirect-media,hub-style-list/contentDirectoryID=1&excludeFields=summary&identifier=home%2Econtinue%2Chome%2Eondeck&includeMeta=1).
Nov 04, 2023 14:51:45.992 [548213993696] DEBUG - [Req#315] HubCache: Computed '1/home.ondeck/1/hubs/esexternal-media,indirect-media,hub-style-list/contentDirectoryID=1&excludeFields=summary&identifier=home%2Econtinue%2Chome%2Eondeck&includeMeta=1' in 11 ms.
Nov 04, 2023 14:51:45.992 [548213993696] DEBUG - [Req#315] HubCache: 2 hubs cached, 0.0% hit ratio.
Nov 04, 2023 14:51:45.992 [548213993696] DEBUG - [Req#315] HubCache: Computed '1/home.continue/1/hubs/esexternal-media,indirect-media,hub-style-list/contentDirectoryID=1&excludeFields=summary&identifier=home%2Econtinue%2Chome%2Eondeck&includeMeta=1' in 11 ms.
Nov 04, 2023 14:51:45.992 [548213993696] DEBUG - [Req#315] HubCache: Retrieving '1/home.ondeck/1/hubs/esexternal-media,indirect-media,hub-style-list/contentDirectoryID=1&excludeFields=summary&identifier=home%2Econtinue%2Chome%2Eondeck&includeMeta=1' from the cache.
Nov 04, 2023 14:51:45.994 [548240072928] DEBUG - Completed: [127.0.0.1:38296] 200 GET /hubs?identifier=home.continue,home.ondeck&contentDirectoryID=1&includeMeta=1&excludeFields=summary (5 live) #315 GZIP 19ms 690 bytes (pipelined: 1)
Nov 04, 2023 14:51:46.004 [548213993696] DEBUG - Request: [127.0.0.1:38296 (Loopback)] GET /hubs/promoted?contentDirectoryID=1&pinnedContentDirectoryID=1&includeMeta=1&excludeFields=summary&count=12&includeStations=1&includeLibraryPlaylists=1&includeRecentChannels=1&excludeContinueWatching=1 (5 live) #31b GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:51:46.005 [548213993696] DEBUG - [Req#31b] HubCache: Adding '1/home.continue/1/hubs/promoted/esexternal-media,indirect-media,hub-style-list/contentDirectoryID=1&count=12&excludeContinueWatching=1&excludeFields=summary&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&pinnedContentDirectoryID=1' to the cache (1/home.continue/1/hubs/promoted/esexternal-media,indirect-media,hub-style-list/contentDirectoryID=1&count=12&excludeContinueWatching=1&excludeFields=summary&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&pinnedContentDirectoryID=1).
Nov 04, 2023 14:51:46.005 [548213993696] DEBUG - [Req#31b] HubCache: Adding '1/home.ondeck/1/hubs/promoted/esexternal-media,indirect-media,hub-style-list/contentDirectoryID=1&count=12&excludeContinueWatching=1&excludeFields=summary&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&pinnedContentDirectoryID=1' to the cache (1/home.ondeck/1/hubs/promoted/esexternal-media,indirect-media,hub-style-list/contentDirectoryID=1&count=12&excludeContinueWatching=1&excludeFields=summary&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&pinnedContentDirectoryID=1).
Nov 04, 2023 14:51:46.008 [548213993696] DEBUG - [Req#31b] HubCache: Adding '1/movie.recentlyadded.1/1/hubs/promoted/esexternal-media,indirect-media,hub-style-list/contentDirectoryID=1&count=12&excludeContinueWatching=1&excludeFields=summary&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&pinnedContentDirectoryID=1' to the cache (1/movie.recentlyadded.1/1/hubs/promoted/esexternal-media,indirect-media,hub-style-list/contentDirectoryID=1&count=12&excludeContinueWatching=1&excludeFields=summary&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&pinnedContentDirectoryID=1).
Nov 04, 2023 14:51:46.015 [548213993696] DEBUG - [Req#31b] HubCache: Computed '1/movie.recentlyadded.1/1/hubs/promoted/esexternal-media,indirect-media,hub-style-list/contentDirectoryID=1&count=12&excludeContinueWatching=1&excludeFields=summary&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&pinnedContentDirectoryID=1' in 7 ms.
Nov 04, 2023 14:51:46.016 [548213993696] DEBUG - [Req#31b] HubCache: Adding '1/movie.curated.1/1/hubs/promoted/esexternal-media,indirect-media,hub-style-list/contentDirectoryID=1&count=12&excludeContinueWatching=1&excludeFields=summary&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&pinnedContentDirectoryID=1' to the cache (1/movie.curated.1/1/hubs/promoted/esexternal-media,indirect-media,hub-style-list/contentDirectoryID=1&count=12&excludeContinueWatching=1&excludeFields=summary&includeLibraryPlaylists=1&includeMeta=1&includeRecentChannels=1&includeStations=1&pinnedContentDirectoryID=1).
Nov 04, 2023 14:51:46.017 [548242215136] DEBUG - Completed: [127.0.0.1:38296] 200 GET /hubs/promoted?contentDirectoryID=1&pinnedContentDirectoryID=1&includeMeta=1&excludeFields=summary&count=12&includeStations=1&includeLibraryPlaylists=1&includeRecentChannels=1&excludeContinueWatching=1 (5 live) #31b GZIP 12ms 687 bytes (pipelined: 2)
Nov 04, 2023 14:51:55.656 [548213993696] DEBUG - Request: [127.0.0.1:38296 (Loopback)] GET /:/prefs (3 live) #31e GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:51:55.674 [548242215136] DEBUG - Completed: [127.0.0.1:38296] 200 GET /:/prefs (3 live) #31e GZIP 18ms 8281 bytes (pipelined: 3)
Nov 04, 2023 14:51:55.690 [548213993696] DEBUG - Request: [127.0.0.1:52430 (Loopback)] GET /accounts/1 (5 live) #318 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:51:55.691 [548199162080] DEBUG - Request: [127.0.0.1:52440 (Loopback)] GET /myplex/account (5 live) #322 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:51:55.692 [548242215136] DEBUG - Completed: [127.0.0.1:52430] 200 GET /accounts/1 (5 live) #318 GZIP 2ms 647 bytes (pipelined: 1)
Nov 04, 2023 14:51:55.695 [548242215136] DEBUG - Completed: [127.0.0.1:52440] 200 GET /myplex/account (5 live) #322 GZIP 4ms 2838 bytes (pipelined: 1)
Nov 04, 2023 14:51:56.087 [548213993696] DEBUG - Request: [127.0.0.1:52440 (Loopback)] OPTIONS /myplex/refreshReachability (5 live) #325 GZIP Signed-in Token ()
Nov 04, 2023 14:51:56.088 [548242215136] DEBUG - Completed: [127.0.0.1:52440] 200 OPTIONS /myplex/refreshReachability (5 live) #325 GZIP 0ms 379 bytes (pipelined: 2)
Nov 04, 2023 14:51:56.097 [548199162080] DEBUG - Request: [127.0.0.1:52440 (Loopback)] PUT /myplex/refreshReachability (5 live) #326 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:51:56.097 [548199162080] DEBUG - [Req#326] MyPlex: async reachability check - current mapped state: 'Mapped - Not Published (Not Reachable)'.
Nov 04, 2023 14:51:56.098 [548199162080] DEBUG - [Req#326] MyPlex: Requesting reachability check.
Nov 04, 2023 14:51:56.098 [548199162080] DEBUG - [Req#326/HCl#a9] HTTP requesting PUT https://plex.tv/api/servers/109017b38b6 ... edc7106e9b
Nov 04, 2023 14:51:56.146 [548213993696] DEBUG - Request: [127.0.0.1:52430 (Loopback)] GET /updater/status (5 live) #324 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:51:56.148 [548242215136] DEBUG - Completed: [127.0.0.1:52430] 200 GET /updater/status (5 live) #324 GZIP 1ms 568 bytes (pipelined: 2)
Nov 04, 2023 14:51:56.247 [548213993696] DEBUG - Request: [127.0.0.1:52430 (Loopback)] GET /photo/:/transcode?width=60&height=60&minSize=1&upscale=1&url=https%3A%2F%2Fplex.tv%2Fusers%2Fb73a04a39b00405a%2Favatar%3Fc%3D1699103509 (5 live) #327 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:51:56.255 [548213993696] DEBUG - [Req#327] Photo transcoder: Request for url [https://plex.tv/users/b73a04a39b00405a/ ... 1699103509] (is local: 0 upscaled: 1)
Nov 04, 2023 14:51:56.265 [548213993696] DEBUG - [Req#327/HCl#aa] HTTP requesting GET https://plex.tv/users/b73a04a39b00405a/ ... 1699103509
Nov 04, 2023 14:51:56.265 [548222587104] DEBUG - [HttpClient/HCl#a9] HTTP/2.0 (0.2s) 200 response from PUT https://plex.tv/api/servers/109017b38b6 ... edc7106e9b
Nov 04, 2023 14:51:56.266 [548240072928] DEBUG - Completed: [127.0.0.1:52440] 200 PUT /myplex/refreshReachability (5 live) #326 GZIP 169ms 323 bytes (pipelined: 3)
Nov 04, 2023 14:51:56.299 [548242215136] DEBUG - [EventSourceClient/pubsub/151.236.217.85:443] EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="1a31e64f-9263-4e7e-8d2e-dfedc7106e9b" connectivity="0" command="notifyConnectivity"/>'
Nov 04, 2023 14:51:56.300 [548242215136] DEBUG - [EventSourceClient/pubsub/151.236.217.85:443] PubSub: Got notified of reachability for async identifier 1a31e64f-9263-4e7e-8d2e-dfedc7106e9b: 0 for :0 (responded in 33 ms)
Nov 04, 2023 14:51:56.300 [548242215136] DEBUG - [EventSourceClient/pubsub/151.236.217.85:443] MyPlex: reachability check - current mapping state: 'Mapped - Not Published (Not Reachable)'.
Nov 04, 2023 14:51:56.300 [548242215136] DEBUG - [EventSourceClient/pubsub/151.236.217.85:443] MyPlex: mapping state set to 'Mapped - Not Published (Not Reachable)'.
Nov 04, 2023 14:51:56.411 [548213993696] DEBUG - Request: [127.0.0.1:52440 (Loopback)] GET /myplex/account (5 live) #328 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:51:56.413 [548240072928] DEBUG - Completed: [127.0.0.1:52440] 200 GET /myplex/account (5 live) #328 GZIP 2ms 2838 bytes (pipelined: 4)
Nov 04, 2023 14:51:56.479 [548199162080] DEBUG - Request: [127.0.0.1:52440 (Loopback)] OPTIONS /updater/check?download=0 (5 live) #32b GZIP Signed-in Token ()
Nov 04, 2023 14:51:56.480 [548242215136] DEBUG - Completed: [127.0.0.1:52440] 200 OPTIONS /updater/check?download=0 (5 live) #32b GZIP 0ms 379 bytes (pipelined: 5)
Nov 04, 2023 14:51:56.480 [548222587104] DEBUG - [HttpClient/HCl#aa] HTTP/2.0 (0.2s) 200 response from GET https://plex.tv/users/b73a04a39b00405a/ ... 1699103509
Nov 04, 2023 14:51:56.480 [548182089952] DEBUG - [HttpClient] Photo cache obtained 14393 bytes from https://plex.tv/users/b73a04a39b00405a/ ... 1699103509
Nov 04, 2023 14:51:56.480 [548182089952] DEBUG - [HttpClient] Saving original media file to /var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Cache/PhotoTranscoder/f8/f81187a60d39984c089e6419d706abd77a461be1.jpg
Nov 04, 2023 14:51:56.487 [548213993696] DEBUG - Request: [127.0.0.1:52440 (Loopback)] PUT /updater/check?download=0 (5 live) #32c GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:51:56.487 [548213993696] DEBUG - [Req#32c] AutoUpdate: requesting check endpoint: /updater/products/5/check.xml?build=linux-aarch64&channel=16&distribution=debian&version=1.32.7.7621-871adbd44, download: NO
Nov 04, 2023 14:51:56.487 [548213993696] DEBUG - [Req#32c] Downloading document https://plex.tv/updater/products/5/chec ... -871adbd44
Nov 04, 2023 14:51:56.487 [548213993696] DEBUG - [Req#32c/HCl#ab] HTTP requesting GET https://plex.tv/updater/products/5/chec ... -871adbd44
Nov 04, 2023 14:51:56.501 [548182089952] DEBUG - [HttpClient] Created thumbnail of size 60x60, has pixels: 1
Nov 04, 2023 14:51:56.505 [548240072928] DEBUG - Completed: [127.0.0.1:52430] 200 GET /photo/:/transcode?width=60&height=60&minSize=1&upscale=1&url=https%3A%2F%2Fplex.tv%2Fusers%2Fb73a04a39b00405a%2Favatar%3Fc%3D1699103509 (5 live) #327 GZIP 258ms 4719 bytes (pipelined: 3)
Nov 04, 2023 14:51:56.548 [548222587104] DEBUG - [HttpClient/HCl#ab] HTTP/2.0 (0.1s) 200 response from GET https://plex.tv/updater/products/5/chec ... -871adbd44 (reused)
Nov 04, 2023 14:51:56.548 [548213993696] INFO - [Req#32c] AutoUpdate: no updates available
Nov 04, 2023 14:51:56.548 [548242215136] DEBUG - Completed: [127.0.0.1:52440] 200 PUT /updater/check?download=0 (5 live) #32c GZIP 61ms 323 bytes (pipelined: 6)
Nov 04, 2023 14:52:00.489 [548213993696] DEBUG - Request: [[::1]:46800 (Loopback)] GET /web/js/chunk-8111-1a0362c3593945860679-plex-4.108.0.32184-ffafb0d.js (6 live) #323 GZIP Signed-in
Nov 04, 2023 14:52:00.489 [548213993696] DEBUG - [Req#323] Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-871adbd44/WebClient.bundle/Contents/Resources/js/chunk-8111-1a0362c3593945860679-plex-4.108.0.32184-ffafb0d.js"
Nov 04, 2023 14:52:00.489 [548213993696] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-871adbd44/WebClient.bundle/Contents/Resources/js/chunk-8111-1a0362c3593945860679-plex-4.108.0.32184-ffafb0d.js is 44715 (of total: 44715).
Nov 04, 2023 14:52:00.490 [548240072928] DEBUG - Completed: [[::1]:46800] 200 GET /web/js/chunk-8111-1a0362c3593945860679-plex-4.108.0.32184-ffafb0d.js (6 live) #323 GZIP 1ms 44715 bytes (pipelined: 1)
Nov 04, 2023 14:52:00.504 [548199162080] DEBUG - Request: [[::1]:46800 (Loopback)] GET /web/js/chunk-7790-d4d9ef82e27a5667d6d9-plex-4.108.0.32184-ffafb0d.js (6 live) #332 GZIP Signed-in
Nov 04, 2023 14:52:00.505 [548199162080] DEBUG - [Req#332] Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-871adbd44/WebClient.bundle/Contents/Resources/js/chunk-7790-d4d9ef82e27a5667d6d9-plex-4.108.0.32184-ffafb0d.js"
Nov 04, 2023 14:52:00.505 [548199162080] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-871adbd44/WebClient.bundle/Contents/Resources/js/chunk-7790-d4d9ef82e27a5667d6d9-plex-4.108.0.32184-ffafb0d.js is 24961 (of total: 24961).
Nov 04, 2023 14:52:00.506 [548242215136] DEBUG - Completed: [[::1]:46800] 200 GET /web/js/chunk-7790-d4d9ef82e27a5667d6d9-plex-4.108.0.32184-ffafb0d.js (6 live) #332 GZIP 2ms 24961 bytes (pipelined: 2)
Nov 04, 2023 14:52:00.517 [548213993696] DEBUG - Request: [[::1]:46804 (Loopback)] GET /web/chunk-6693-652b850b03f806e0089c-plex-4.108.0.32184-ffafb0d.css (7 live) #331 GZIP Signed-in
Nov 04, 2023 14:52:00.517 [548213993696] DEBUG - [Req#331] Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-871adbd44/WebClient.bundle/Contents/Resources/chunk-6693-652b850b03f806e0089c-plex-4.108.0.32184-ffafb0d.css"
Nov 04, 2023 14:52:00.517 [548213993696] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-871adbd44/WebClient.bundle/Contents/Resources/chunk-6693-652b850b03f806e0089c-plex-4.108.0.32184-ffafb0d.css is 21636 (of total: 21636).
Nov 04, 2023 14:52:00.521 [548242215136] DEBUG - Completed: [[::1]:46804] 200 GET /web/chunk-6693-652b850b03f806e0089c-plex-4.108.0.32184-ffafb0d.css (7 live) #331 GZIP 4ms 21636 bytes (pipelined: 1)
Nov 04, 2023 14:52:00.539 [548199162080] DEBUG - Request: [[::1]:46804 (Loopback)] GET /web/chunk-800-f16fffe537518fbe08ea-plex-4.108.0.32184-ffafb0d.css (7 live) #335 GZIP Signed-in
Nov 04, 2023 14:52:00.539 [548199162080] DEBUG - [Req#335] Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-871adbd44/WebClient.bundle/Contents/Resources/chunk-800-f16fffe537518fbe08ea-plex-4.108.0.32184-ffafb0d.css"
Nov 04, 2023 14:52:00.540 [548199162080] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-871adbd44/WebClient.bundle/Contents/Resources/chunk-800-f16fffe537518fbe08ea-plex-4.108.0.32184-ffafb0d.css is 4379 (of total: 4379).
Nov 04, 2023 14:52:00.541 [548242215136] DEBUG - Completed: [[::1]:46804] 200 GET /web/chunk-800-f16fffe537518fbe08ea-plex-4.108.0.32184-ffafb0d.css (8 live) #335 GZIP 2ms 4379 bytes (pipelined: 2)
Nov 04, 2023 14:52:00.545 [548213993696] DEBUG - Request: [[::1]:46800 (Loopback)] GET /web/js/chunk-6693-652b850b03f806e0089c-plex-4.108.0.32184-ffafb0d.js (8 live) #333 GZIP Signed-in
Nov 04, 2023 14:52:00.546 [548213993696] DEBUG - [Req#333] Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-871adbd44/WebClient.bundle/Contents/Resources/js/chunk-6693-652b850b03f806e0089c-plex-4.108.0.32184-ffafb0d.js"
Nov 04, 2023 14:52:00.546 [548213993696] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-871adbd44/WebClient.bundle/Contents/Resources/js/chunk-6693-652b850b03f806e0089c-plex-4.108.0.32184-ffafb0d.js is 111216 (of total: 111216).
Nov 04, 2023 14:52:00.547 [548242215136] DEBUG - Completed: [[::1]:46800] 200 GET /web/js/chunk-6693-652b850b03f806e0089c-plex-4.108.0.32184-ffafb0d.js (8 live) #333 GZIP 1ms 111216 bytes (pipelined: 3)
Nov 04, 2023 14:52:00.555 [548199162080] DEBUG - Request: [[::1]:46810 (Loopback)] GET /web/js/chunk-800-f16fffe537518fbe08ea-plex-4.108.0.32184-ffafb0d.js (8 live) #334 GZIP Signed-in
Nov 04, 2023 14:52:00.555 [548199162080] DEBUG - [Req#334] Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-871adbd44/WebClient.bundle/Contents/Resources/js/chunk-800-f16fffe537518fbe08ea-plex-4.108.0.32184-ffafb0d.js"
Nov 04, 2023 14:52:00.556 [548199162080] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-871adbd44/WebClient.bundle/Contents/Resources/js/chunk-800-f16fffe537518fbe08ea-plex-4.108.0.32184-ffafb0d.js is 53885 (of total: 53885).
Nov 04, 2023 14:52:00.557 [548242215136] DEBUG - Completed: [[::1]:46810] 200 GET /web/js/chunk-800-f16fffe537518fbe08ea-plex-4.108.0.32184-ffafb0d.js (8 live) #334 GZIP 1ms 53885 bytes (pipelined: 1)
Nov 04, 2023 14:55:39.311 [548224696544] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Nov 04, 2023 14:55:39.311 [548184199392] DEBUG - PublicAddressManager: Obtaining public IP.
Nov 04, 2023 14:55:39.312 [548213993696] DEBUG - NAT: UPnP, attempting port mapping.
Nov 04, 2023 14:55:39.312 [548184199392] DEBUG - [HCl#ac] HTTP requesting GET https://v4.plex.tv/pms/:/ip
Nov 04, 2023 14:55:39.467 [548222587104] DEBUG - [HttpClient/HCl#ac] HTTP/2.0 (0.2s) 200 response from GET https://v4.plex.tv/pms/:/ip
Nov 04, 2023 14:55:39.468 [548184199392] DEBUG - PublicAddressManager: Got public IP from v4.plex.tv: 80.29.180.230
Nov 04, 2023 14:55:51.515 [548184199392] DEBUG - NAT: UPnP, found device <http://192.168.1.136:2869/upnphost/udhi ... 2cc13da3b5> with private address <192.168.1.41>
Nov 04, 2023 14:55:51.515 [548184199392] DEBUG - NAT: UPnP, not an IGD: <http://192.168.1.136:2869/upnphost/udhi ... 2cc13da3b5>.
Nov 04, 2023 14:55:51.515 [548184199392] DEBUG - NAT: UPnP, getPublicIP didn't find usable IGD.
Nov 04, 2023 14:55:51.766 [548184199392] WARN - NAT: PMP, got an error: Not Supported by gateway.
Nov 04, 2023 14:55:51.769 [548184199392] DEBUG - MyPlex: sendMapping resetting state - previous mapping state: 'Mapped - Not Published (Not Reachable)'.
Nov 04, 2023 14:55:51.769 [548184199392] DEBUG - MyPlex: mapping state set to 'Unknown'.
Nov 04, 2023 14:55:51.774 [548184199392] DEBUG - MyPlex: Sending Server Info to myPlex (user=Javier.Avalos81@gmail.com, ip=80.29.180.230, port=0)
Nov 04, 2023 14:55:51.774 [548184199392] DEBUG - [HCl#ad] HTTP requesting POST https://plex.tv/servers.xml?auth_token= ... xxxxxxxxxx
Nov 04, 2023 14:55:51.973 [548222587104] DEBUG - [HttpClient/HCl#ad] HTTP/2.0 (0.2s) 422 response from POST https://plex.tv/servers.xml?auth_token= ... xxxxxxxxxx
Nov 04, 2023 14:55:51.973 [548184199392] DEBUG - MyPlex: Published Mapping State response was 422
Nov 04, 2023 14:55:51.973 [548184199392] DEBUG - MyPlex: Got response for 109017b38b6eb6e57af85d2ea752084767c7fe89 ~ registered :0
Nov 04, 2023 14:55:51.973 [548184199392] DEBUG - MyPlex: updating mapped state - current state: 'Mapped - Not Published (Not Reachable)'
Nov 04, 2023 14:55:51.973 [548184199392] DEBUG - MyPlex: mapping state set to 'Mapped - Publishing'.
Nov 04, 2023 14:55:51.973 [548184199392] DEBUG - MyPlex: async reachability check - current mapped state: 'Mapped - Publishing'.
Nov 04, 2023 14:55:51.973 [548184199392] DEBUG - MyPlex: Requesting reachability check.
Nov 04, 2023 14:55:51.974 [548184199392] DEBUG - [HCl#ae] HTTP requesting PUT https://plex.tv/api/servers/109017b38b6 ... f6e46f450a
Nov 04, 2023 14:55:51.999 [548213993696] DEBUG - NAT: UPnP, found device <http://192.168.1.136:2869/upnphost/udhi ... 2cc13da3b5> with private address <192.168.1.41>
Nov 04, 2023 14:55:51.999 [548213993696] DEBUG - NAT: UPnP, not an IGD: <http://192.168.1.136:2869/upnphost/udhi ... 2cc13da3b5>.
Nov 04, 2023 14:55:51.999 [548213993696] DEBUG - NAT: UPnP, didn't find usable IGD.
Nov 04, 2023 14:55:51.999 [548213993696] DEBUG - NAT: PMP, attempting mapping.
Nov 04, 2023 14:55:52.035 [548222587104] DEBUG - [HttpClient/HCl#ae] HTTP/2.0 (0.1s) 200 response from PUT https://plex.tv/api/servers/109017b38b6 ... f6e46f450a (reused)
Nov 04, 2023 14:55:52.038 [548184199392] DEBUG - MyPlex: Last published value didn't change, we're done.
Nov 04, 2023 14:55:52.096 [548240072928] DEBUG - [EventSourceClient/pubsub/151.236.217.85:443] EventSource: Got event [data] '<Message address="" port="0" asyncIdentifier="23a9d88d-ad1d-4561-84a8-8bf6e46f450a" connectivity="0" command="notifyConnectivity"/>'
Nov 04, 2023 14:55:52.096 [548240072928] DEBUG - [EventSourceClient/pubsub/151.236.217.85:443] PubSub: Got notified of reachability for async identifier 23a9d88d-ad1d-4561-84a8-8bf6e46f450a: 0 for :0 (responded in 61 ms)
Nov 04, 2023 14:55:52.096 [548240072928] DEBUG - [EventSourceClient/pubsub/151.236.217.85:443] MyPlex: reachability check - current mapping state: 'Mapped - Publishing'.
Nov 04, 2023 14:55:52.096 [548240072928] DEBUG - [EventSourceClient/pubsub/151.236.217.85:443] MyPlex: mapping state set to 'Mapped - Not Published (Not Reachable)'.
Nov 04, 2023 14:55:52.157 [548199162080] DEBUG - Request: [127.0.0.1:46566 (Loopback)] GET /myplex/account (3 live) #336 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 04, 2023 14:55:52.159 [548242215136] DEBUG - Completed: [127.0.0.1:46566] 200 GET /myplex/account (3 live) #336 GZIP 2ms 2828 bytes (pipelined: 1)
Nov 04, 2023 14:55:52.250 [548213993696] WARN - NAT: PMP, got an error: Not Supported by gateway.
Nov 04, 2023 14:55:52.250 [548213993696] DEBUG - NAT: UPnP, attempting port mapping.
Nov 04, 2023 14:55:52.250 [548213993696] DEBUG - NAT: UPnP, not an IGD: <http://192.168.1.136:2869/upnphost/udhi ... 2cc13da3b5>.
Nov 04, 2023 14:55:52.250 [548213993696] DEBUG - NAT: UPnP, didn't find usable IGD.
Nov 04, 2023 14:55:52.250 [548213993696] DEBUG - PublicAddressManager: Mapping failed.
Nov 04, 2023 14:55:52.252 [548213993696] DEBUG - MyPlex: mapping state set to 'Mapped - Not Published'.
Nov 04, 2023 14:55:52.252 [548213993696] DEBUG - MyPlex: sendMapping resetting state - previous mapping state: 'Mapped - Not Published'.
Nov 04, 2023 14:55:52.252 [548213993696] DEBUG - MyPlex: mapping state set to 'Unknown'.
Nov 04, 2023 14:55:52.255 [548213993696] DEBUG - MyPlex: Sending Server Info to myPlex (user=Javier.Avalos81@gmail.com, ip=80.29.180.230, port=0)
Nov 04, 2023 14:55:52.256 [548213993696] DEBUG - [HCl#af] HTTP requesting POST https://plex.tv/servers.xml?auth_token= ... xxxxxxxxxx
Nov 04, 2023 14:55:52.339 [548222587104] DEBUG - [HttpClient/HCl#af] HTTP/2.0 (0.1s) 422 response from POST https://plex.tv/servers.xml?auth_token= ... xxxxxxxxxx (reused)
Nov 04, 2023 14:55:52.339 [548213993696] DEBUG - MyPlex: Published Mapping State response was 422
Nov 04, 2023 14:55:52.339 [548213993696] DEBUG - MyPlex: Got response for 109017b38b6eb6e57af85d2ea752084767c7fe89 ~ registered :0
Nov 04, 2023 14:55:52.339 [548213993696] DEBUG - MyPlex: updating mapped state - current state: 'Mapped - Not Published'
Nov 04, 2023 14:55:52.339 [548213993696] DEBUG - MyPlex: mapping state set to 'Mapped - Publishing'.
Nov 04, 2023 14:55:52.339 [548213993696] DEBUG - MyPlex: async reachability check - current mapped state: 'Mapped - Publishing'.
Nov 04, 2023 14:55:52.339 [548213993696] DEBUG - MyPlex: requesting connectivity refresh too soon after the last request, skipping.
Nov 04, 2023 14:55:52.342 [548213993696] DEBUG - MyPlex: Last published value didn't change, we're done.
Nov 04, 2023 15:00:04.011 [548184199392] DEBUG - Statistics: Flushing 1 expired bandwidth entries, 0 expired media entries.

04 Nov 2023, 15:05

Te he puesto un poco de lo ultimo del archivo porque todo no me lo dejaba poner (789.000 letras y el maximo son 60.000)

04 Nov 2023, 16:41

Para eso usamos servicios como https://pastebin.com/
En el trozo de log que has puesto no veo nada, puede que esté en otro lado.
Necesito que fuerces el error, que hagas algo donde se produzca el error, y entonces nos compartes el log completo (usando pastebin o similar)

04 Nov 2023, 17:56

Pero como hago para forzar ese error???.

He intentado agregar varias veces los discos duros. Y el problema es que como los detecta como si estuviesen vacios pues no añade ninguna pelicula. Pero no da ningun tipo de error como tal.

Como podriamos para cambiar los permisos de plex? Imagino que irá por ahí, no? El concederle permisos a plex o cambiar el ususario de plex por el mio de javi

04 Nov 2023, 18:03

No se si algo de lo que dicen aquí podría solucionar el problema que tengo...

https://www.reddit.com/r/PleX/comments/ ... ?rdt=51951

04 Nov 2023, 18:10

Estoy intentando ponerte todo el LOG con pastebin y a la hora de crear el archivo me dice que he excedido los 512kb maximos de paste jajajaja

05 Nov 2023, 02:45

Buenas noches una vez mas.

A ver si te vale esto que he intentado forzar del LOG. Desde luego en la cabecera de la linea del LOG pone claramente ERROR. Me metí en la biblioteca y borre los 4 discos duros que tenía asociados a ella. Luego simplemente añadí uno de ellos para ver si ponía algo y esto es lo que ha salido en el LOG después de buscarlo un ratito:

Código: Seleccionar todo

Nov 05, 2023 02:28:32.094 [547771154656] INFO - Completed: [127.0.0.1:43626] 200 GET /services/browse?includeFiles=1 (6 live) #cb GZIP 3ms 784 bytes (pipelined: 1)
Nov 05, 2023 02:28:32.187 [547695272160] INFO - Request: [127.0.0.1:43626 (Loopback)] GET /services/browse/L3Zhci9saWIvcGxleG1lZGlhc2VydmVy?includeFiles=1 (6 live) #ce GZIP Signed-in Token (Sh4ck) (Plex Web)
Nov 05, 2023 02:28:32.187 [547695272160] DEBUG - [Req#ce] DirectoryBrowser: Decoded [L3Zhci9saWIvcGxleG1lZGlhc2VydmVy] to [/var/lib/plexmediaserver]
Nov 05, 2023 02:28:32.190 [547773264096] INFO - Completed: [127.0.0.1:43626] 200 GET /services/browse/L3Zhci9saWIvcGxleG1lZGlhc2VydmVy?includeFiles=1 (6 live) #ce GZIP 3ms 600 bytes (pipelined: 2)
Nov 05, 2023 02:28:33.855 [547682615520] INFO - Request: [127.0.0.1:43626 (Loopback)] GET /services/browse/L21lZGlhL2phdmkvSEREXDA0MDRUYlwwNDBSRU1VWC0x?includeFiles=1 (5 live) #cf GZIP Signed-in Token (Sh4ck) (Plex Web)
Nov 05, 2023 02:28:33.856 [547682615520] DEBUG - [Req#cf] DirectoryBrowser: Decoded [L21lZGlhL2phdmkvSEREXDA0MDRUYlwwNDBSRU1VWC0x] to [/media/javi/HDD\0404Tb\040REMUX-1]
Nov 05, 2023 02:28:33.856 [547682615520] ERROR - [Req#cf] Couldn't check for the existence of file "/media/javi/HDD\0404Tb\040REMUX-1": boost::filesystem::status: Permission denied [system:13]: "/media/javi/HDD\0404Tb\040REMUX-1"
Nov 05, 2023 02:28:33.858 [547771154656] INFO - Completed: [127.0.0.1:43626] 200 GET /services/browse/L21lZGlhL2phdmkvSEREXDA0MDRUYlwwNDBSRU1VWC0x?includeFiles=1 (5 live) #cf GZIP 2ms 481 bytes (pipelined: 3)

Yo no entiendo practicamente nada, pero si alcanzo a traducir con mi mal inglés que "No puede chequear la existencia de archivos en el directorio indicado. Permiso denegado [system:13]

Couldn't check for the existence of file "/media/javi/HDD\0404Tb\040REMUX-1": boost::filesystem::status: Permission denied [system:13]

A ver si con esto sacamos algo en claro.

Muchas gracias y espero tus noticias :-)

05 Nov 2023, 07:33

¡Estupendo! Tenemos exactamente lo que buscábamos :-D
Ahora sí estoy bastante seguro de que es un problema de permisos.

¿Probamos a ponérselos?

Código: Seleccionar todo

setfacl -m u:plex:rx /media/javi

Y después comprobamos que los tenemos:

Código: Seleccionar todo

getfacl /media/javi

Otra opción, en lugar de asignar permisos a usuarios, sería cambiar los permisos del directorio /media/javi
Algo como

Código: Seleccionar todo

chmod 777 /media/javi

debería dar permisos a cualquier usuario para acceder al directorio /media/javi

Dos soluciones, una más sutil y otra más tosca.
Después de ejecutar una de las dos soluciones deberíamos reiniciar plex y probar.

¡Dale!

05 Nov 2023, 11:23

En cuanto llegue a casa lo pruebo y te cuento.

Muchas gracias

05 Nov 2023, 12:28

Poniendo el primer comando me decia que operacion no permitida asique le añadí sudo y parece que funcionó. Te adjunto lo que me aparece en el terminal a ver si tu lo ves OK

Código: Seleccionar todo

javi@raspberrypi:~ $ setfacl -m u:plex:rx /media/javi
setfacl: /media/javi: Operación no permitida
javi@raspberrypi:~ $ sudo setfacl -m u:plex:rx /media/javi
javi@raspberrypi:~ $ getfacl /media/javi
getfacl: Eliminando '/' inicial en nombres de ruta absolutos
# file: media/javi
# owner: root
# group: root
user::rwx
user:plex:r-x
user:javi:r-x
group::---
mask::r-x
other::---

javi@raspberrypi:~ $ sudo service plexmediaserver restart
javi@raspberrypi:~ $

05 Nov 2023, 12:36

Parece que de momento sigue sin reconocer nada dentro de los discos duros.
He probado a añadirle dos discos duros y ahora no parece dar error pero sigue sin encontrar nada.
Te adjunto la parte del LOG:

Código: Seleccionar todo

Nov 05, 2023 12:33:29.849 [548548698336] DEBUG - Library timeline cull complete with 0 items left.
Nov 05, 2023 12:33:29.850 [548548698336] DEBUG - Scanning Películas using es-ES(Plex Movie) with 0 current media items and 2 section locations in the database.
Nov 05, 2023 12:33:29.850 [548548698336] DEBUG - Performing a scan with 'Plex Movie' (language: es-ES virtual: 0).
Nov 05, 2023 12:33:29.850 [548548698336] DEBUG -   * Scanning /media/javi/HDD\04010Tb\040REMUX-2
Nov 05, 2023 12:33:29.850 [548548698336] DEBUG -   * Marking media not available in /media/javi/HDD\04010Tb\040REMUX-2
Nov 05, 2023 12:33:29.850 [548548698336] DEBUG -   * Taking 0 items out of the map (which has 0 items) for being unavailable.
Nov 05, 2023 12:33:29.851 [548548698336] DEBUG -   * Taking 0 directories out of the map (which has 0 dirs) for being unavailable.
Nov 05, 2023 12:33:29.853 [548548698336] DEBUG - Activity: updated activity 6b0653a9-b0fd-42ac-9e8e-5c7e432739bd - completed 49.5% - Scanning Películas
Nov 05, 2023 12:33:29.854 [548548698336] DEBUG - Performing a scan with 'Plex Movie' (language: es-ES virtual: 0).
Nov 05, 2023 12:33:29.854 [548548698336] DEBUG -   * Scanning /media/javi/HDD\0404Tb\040REMUX-1
Nov 05, 2023 12:33:29.854 [548548698336] DEBUG -   * Marking media not available in /media/javi/HDD\0404Tb\040REMUX-1
Nov 05, 2023 12:33:29.855 [548548698336] DEBUG -   * Taking 0 items out of the map (which has 0 items) for being unavailable.
Nov 05, 2023 12:33:29.859 [548548698336] DEBUG -   * Taking 0 directories out of the map (which has 0 dirs) for being unavailable.
Nov 05, 2023 12:33:29.860 [548548698336] DEBUG - Activity: updated activity 6b0653a9-b0fd-42ac-9e8e-5c7e432739bd - completed 99.0% - Scanning Películas
Nov 05, 2023 12:33:29.861 [548548698336] DEBUG - Scanner [Plex Movie]: Idle and left with 0 media items.
Nov 05, 2023 12:33:29.861 [548548698336] DEBUG - Removing 0 media items that were left.
Nov 05, 2023 12:33:29.861 [548548698336] DEBUG - Removing 0 directories that were left.
Nov 05, 2023 12:33:29.866 [548548698336] DEBUG - Collections: Found 0 collections tags in section 2.
Nov 05, 2023 12:33:29.867 [548548698336] DEBUG - Collections: We had 0 collections in the library already.
Nov 05, 2023 12:33:29.867 [548548698336] DEBUG - Collections: Synced tags in section 2 in 2ms.
Nov 05, 2023 12:33:29.872 [548548698336] DEBUG - Collections: Refreshed attributes for 0 collections in section 2 in 4ms.
Nov 05, 2023 12:33:29.873 [548548698336] DEBUG - Collection: Refreshed visibility in section 2 in 1ms.
Nov 05, 2023 12:33:29.874 [548548698336] DEBUG - Activity: updated activity 6b0653a9-b0fd-42ac-9e8e-5c7e432739bd - completed 100.0% - Scanning Películas
Nov 05, 2023 12:33:29.876 [548548698336] DEBUG - Refreshing section 2 of type: 1
Nov 05, 2023 12:33:29.879 [548533932256] DEBUG - Refreshing 0 IDs.

06 Nov 2023, 13:04

La buena noticia es que parece que el error de permisos ha desaparecido :-)
Ahora, respecto a que no encuentre nada, ¿es posible que no esté haciendo una búsqueda recursiva?
Parece que está buscando en "/media/javi/HDD 404Tb 040REMUX-1" pero no busca por ejemplo en ""/media/javi/HDD 404Tb 040REMUX-1/21 gramos (2003)"
¿Entiendes lo que te digo?

06 Nov 2023, 13:42

Te entiendo perfectamente. Podría probar a poner directamente los mkv en la raíz del disco duro en lugar de tener cada uno dentro de su correspondiente carpeta. A lo mejor así podría funcionar.

No obstante, lo que no entiendo es que una vez sí funcionó (antes de empezar con este hilo en el foro) y se cargaron todas las películas que había dentro de los discos duros, con sus metadatos, imágenes....

Voy a probar lo de los archivos directamente.

06 Nov 2023, 13:45

Si te ayuda, puedes ver los logs en tiempo real.
Si entras en plex, te metes en "gestionar bibliotecas" y pulsas en el botón "Buscar archivos en la biblioteca", seguramente puedas ver en los logs qué está pasando.
Antes de darle al botón, desde consola mete estos comandos para ver los logs:

Código: Seleccionar todo

cd "/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Logs"
tail -f *log

06 Nov 2023, 16:12

Vamos a ver. He probado a hacer lo del LOG en directo. Borré la biblioteca de plex y volví a crear una nueva. En el momento en el que he agregado el disco duro a la biblioteca, todo esto es lo que ha salido en el LOG:

Código: Seleccionar todo

Nov 06, 2023 16:05:33.272 [548420727008] INFO - Request: [127.0.0.1:57864 (Loopback)] GET /services/browse?includeFiles=1 (4 live) #d2 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: devtmpfs Path: /dev Name: udev
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: ext4 Path: / Name: /dev/mmcblk0p2
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: securityfs Path: /sys/kernel/security Name: securityfs
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: cgroup2 Path: /sys/fs/cgroup Name: cgroup2
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: pstore Path: /sys/fs/pstore Name: pstore
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: bpf Path: /sys/fs/bpf Name: bpf
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: mqueue Path: /dev/mqueue Name: mqueue
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: debugfs Path: /sys/kernel/debug Name: debugfs
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: tracefs Path: /sys/kernel/tracing Name: tracefs
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: configfs Path: /sys/kernel/config Name: configfs
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: fusectl Path: /sys/fs/fuse/connections Name: fusectl
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: ramfs Path: /run/credentials/systemd-sysctl.service Name: ramfs
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: ramfs Path: /run/credentials/systemd-sysusers.service Name: ramfs
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: ramfs Path: /run/credentials/systemd-tmpfiles-setup-dev.service Name: ramfs
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: vfat Path: /boot/firmware Name: /dev/mmcblk0p1
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: ramfs Path: /run/credentials/systemd-tmpfiles-setup.service Name: ramfs
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: binfmt_misc Path: /proc/sys/fs/binfmt_misc Name: binfmt_misc
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: fuse.gvfsd-fuse Path: /run/user/1000/gvfs Name: gvfsd-fuse
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: fuse.portal Path: /run/user/1000/doc Name: portal
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: fuseblk Path: /media/javi/HDD\0404Tb\040REMUX-1 Name: /dev/sdc2
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: fuseblk Path: /media/javi/HDD\0404Tb\040REMUX-2 Name: /dev/sdd2
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: fuseblk Path: /media/javi/HDD\0408Tb\040REMUX\0403D-1 Name: /dev/sde1
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: fuseblk Path: /media/javi/HDD\04010Tb\040REMUX-1 Name: /dev/sda2
Nov 06, 2023 16:05:33.272 [548420727008] DEBUG - [Req#d2] Filesystem Type: fuseblk Path: /media/javi/HDD\04010Tb\040REMUX-2 Name: /dev/sdb1
Nov 06, 2023 16:05:33.274 [548439011552] INFO - Completed: [127.0.0.1:57864] 200 GET /services/browse?includeFiles=1 (4 live) #d2 GZIP 2ms 784 bytes (pipelined: 1)
Nov 06, 2023 16:05:33.373 [548407980256] INFO - Request: [127.0.0.1:57864 (Loopback)] GET /services/browse/L3Zhci9saWIvcGxleG1lZGlhc2VydmVy?includeFiles=1 (4 live) #d8 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 06, 2023 16:05:33.374 [548407980256] DEBUG - [Req#d8] DirectoryBrowser: Decoded [L3Zhci9saWIvcGxleG1lZGlhc2VydmVy] to [/var/lib/plexmediaserver]
Nov 06, 2023 16:05:33.375 [548439011552] INFO - Completed: [127.0.0.1:57864] 200 GET /services/browse/L3Zhci9saWIvcGxleG1lZGlhc2VydmVy?includeFiles=1 (4 live) #d8 GZIP 2ms 600 bytes (pipelined: 2)
Nov 06, 2023 16:05:38.583 [548420727008] INFO - Request: [127.0.0.1:57864 (Loopback)] GET /services/browse/L21lZGlhL2phdmkvSEREXDA0MDRUYlwwNDBSRU1VWC0x?includeFiles=1 (4 live) #d9 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 06, 2023 16:05:38.584 [548420727008] DEBUG - [Req#d9] DirectoryBrowser: Decoded [L21lZGlhL2phdmkvSEREXDA0MDRUYlwwNDBSRU1VWC0x] to [/media/javi/HDD\0404Tb\040REMUX-1]
Nov 06, 2023 16:05:38.585 [548441120992] INFO - Completed: [127.0.0.1:57864] 200 GET /services/browse/L21lZGlhL2phdmkvSEREXDA0MDRUYlwwNDBSRU1VWC0x?includeFiles=1 (4 live) #d9 GZIP 1ms 481 bytes (pipelined: 3)
Nov 06, 2023 16:05:45.267 [548420727008] INFO - Request: [127.0.0.1:57864 (Loopback)] POST /library/sections?name=Pel%C3%ADculas&type=movie&agent=tv.plex.agents.movie&scanner=Plex%20Movie&language=es-ES&importFromiTunes=&enableAutoPhotoTags=&location=%2Fmedia%2Fjavi%2FHDD%5C0404Tb%5C040REMUX-1 (3 live) #da GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 06, 2023 16:05:45.272 [548420727008] DEBUG - [Req#da] Flushing directory modification time cache.
Nov 06, 2023 16:05:45.273 [548391104736] DEBUG - [Req#da] MyPlex: sendMapping resetting state - previous mapping state: 'Not Mapped'.
Nov 06, 2023 16:05:45.273 [548391104736] DEBUG - [Req#da] MyPlex: mapping state set to 'Unknown'.
Nov 06, 2023 16:05:45.275 [548420727008] DEBUG - [Req#da] Library Updater: Requested that section 3 be updated, force=0, deep=0, subdir= iTunes=0
Nov 06, 2023 16:05:45.275 [548420727008] DEBUG - [Req#da] Library Updater: Queueing section 3
Nov 06, 2023 16:05:45.275 [548420727008] INFO - [Req#da] LibraryUpdateManager path watching is disabled
Nov 06, 2023 16:05:45.278 [548391104736] DEBUG - [Req#da] MyPlex: Sending Server Info to myPlex (user=Javier.Avalos81@gmail.com, ip=80.29.180.230, port=0)
Nov 06, 2023 16:05:45.278 [548439011552] INFO - Completed: [127.0.0.1:57864] 201 POST /library/sections?name=Pel%C3%ADculas&type=movie&agent=tv.plex.agents.movie&scanner=Plex%20Movie&language=es-ES&importFromiTunes=&enableAutoPhotoTags=&location=%2Fmedia%2Fjavi%2FHDD%5C0404Tb%5C040REMUX-1 (3 live) #da GZIP 11ms 861 bytes (pipelined: 4)
Nov 06, 2023 16:05:45.278 [548391104736] DEBUG - [Req#da/HCl#43] HTTP requesting POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx
Nov 06, 2023 16:05:45.279 [548407980256] DEBUG - Activity: registered new activity 0fa606df-3405-4f1c-8046-fb7804dc19b6 - "Scanning"
Nov 06, 2023 16:05:45.279 [548407980256] DEBUG - Scanning section 3
Nov 06, 2023 16:05:45.280 [548407980256] DEBUG - Activity: updated activity 0fa606df-3405-4f1c-8046-fb7804dc19b6 - completed 0.0% - Scanning PelÃculas
Nov 06, 2023 16:05:45.281 [548407980256] DEBUG - Asked to cull library timeline before time 1699281945, we're starting with 0 items.
Nov 06, 2023 16:05:45.281 [548407980256] DEBUG - Library timeline cull complete with 0 items left.
Nov 06, 2023 16:05:45.281 [548407980256] DEBUG - Scanning PelÃculas using es-ES(Plex Movie) with 0 current media items and 1 section locations in the database.
Nov 06, 2023 16:05:45.281 [548407980256] DEBUG - Performing a scan with 'Plex Movie' (language: es-ES virtual: 0).
Nov 06, 2023 16:05:45.281 [548407980256] DEBUG -   * Scanning /media/javi/HDD\0404Tb\040REMUX-1
Nov 06, 2023 16:05:45.281 [548407980256] DEBUG -   * Marking media not available in /media/javi/HDD\0404Tb\040REMUX-1
Nov 06, 2023 16:05:45.282 [548407980256] DEBUG -   * Taking 0 items out of the map (which has 0 items) for being unavailable.
Nov 06, 2023 16:05:45.282 [548407980256] DEBUG -   * Taking 0 directories out of the map (which has 0 dirs) for being unavailable.
Nov 06, 2023 16:05:45.283 [548407980256] DEBUG - Activity: updated activity 0fa606df-3405-4f1c-8046-fb7804dc19b6 - completed 99.0% - Scanning PelÃculas
Nov 06, 2023 16:05:45.283 [548407980256] DEBUG - Scanner [Plex Movie]: Idle and left with 0 media items.
Nov 06, 2023 16:05:45.283 [548407980256] DEBUG - Removing 0 media items that were left.
Nov 06, 2023 16:05:45.283 [548407980256] DEBUG - Removing 0 directories that were left.
Nov 06, 2023 16:05:45.289 [548407980256] DEBUG - Collections: Found 0 collections tags in section 3.
Nov 06, 2023 16:05:45.290 [548407980256] DEBUG - Collections: We had 0 collections in the library already.
Nov 06, 2023 16:05:45.290 [548407980256] DEBUG - Collections: Synced tags in section 3 in 0ms.
Nov 06, 2023 16:05:45.300 [548407980256] DEBUG - Collections: Refreshed attributes for 0 collections in section 3 in 10ms.
Nov 06, 2023 16:05:45.306 [548407980256] DEBUG - Collection: Refreshed visibility in section 3 in 5ms.
Nov 06, 2023 16:05:45.306 [548407980256] DEBUG - Activity: updated activity 0fa606df-3405-4f1c-8046-fb7804dc19b6 - completed 100.0% - Scanning PelÃculas
Nov 06, 2023 16:05:45.307 [548407980256] DEBUG - Refreshing section 3 of type: 1
Nov 06, 2023 16:05:45.310 [548405870816] DEBUG - Refreshing 0 IDs.
Nov 06, 2023 16:05:45.311 [548407980256] DEBUG - Activity: registered new activity 85b68adc-efc5-49e5-980d-c26d98a46a15 - "Processing subscriptions"
Nov 06, 2023 16:05:45.313 [548352631008] DEBUG - Grabber: Cleaning up orphaned grabs.
Nov 06, 2023 16:05:45.313 [548407980256] DEBUG - Activity: Ended activity 0fa606df-3405-4f1c-8046-fb7804dc19b6.
Nov 06, 2023 16:05:45.318 [548352631008] DEBUG - Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Nov 06, 2023 16:05:45.320 [548352631008] DEBUG - Activity: registered new activity 54a8ef35-d751-4131-a1ed-5fdfb0fab1d6 - "Processing subscriptions"
Nov 06, 2023 16:05:45.322 [548352631008] DEBUG - Subscription: Scheduling subscriptions.
Nov 06, 2023 16:05:45.325 [548352631008] DEBUG - Subscription: Initialized 0 schedulers.
Nov 06, 2023 16:05:45.325 [548352631008] DEBUG - Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Nov 06, 2023 16:05:45.325 [548352631008] DEBUG - Subscription: Scheduled all subscriptions in 2 ms.
Nov 06, 2023 16:05:45.326 [548352631008] DEBUG - Activity: Ended activity 54a8ef35-d751-4131-a1ed-5fdfb0fab1d6.
Nov 06, 2023 16:05:45.328 [548352631008] DEBUG - Subscription: There are 0 active grabs at the end.
Nov 06, 2023 16:05:45.330 [548352631008] DEBUG - Activity: Ended activity 85b68adc-efc5-49e5-980d-c26d98a46a15.
Nov 06, 2023 16:05:45.336 [548352631008] DEBUG - Subscription: Refreshed all content in 24 ms.
Nov 06, 2023 16:05:45.386 [548418560224] DEBUG - [HttpClient/HCl#43] HTTP/2.0 (0.1s) 422 response from POST https://plex.tv/servers.xml?auth_token=xxxxxxxxxxxxxxxxxxxx (reused)
Nov 06, 2023 16:05:45.387 [548391104736] DEBUG - [Req#da] MyPlex: Published Mapping State response was 422
Nov 06, 2023 16:05:45.387 [548391104736] DEBUG - [Req#da] MyPlex: Got response for 109017b38b6eb6e57af85d2ea752084767c7fe89 ~ registered :0
Nov 06, 2023 16:05:45.387 [548391104736] DEBUG - [Req#da] MyPlex: updating mapped state - current state: 'Not Mapped'
Nov 06, 2023 16:05:45.387 [548391104736] DEBUG - [Req#da] MyPlex: mapping state set to 'Not Mapped'.
Nov 06, 2023 16:05:45.388 [548391104736] DEBUG - [Req#da/HCl#44] HTTP requesting GET https://plex.tv/api/v2/server/access_tokens?auth_token=xxxxxxxxxxxxxxxxxxxx
Nov 06, 2023 16:05:45.406 [548420727008] DEBUG - Request: [127.0.0.1:57864 (Loopback)] GET /media/providers (3 live) #e0 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 06, 2023 16:05:45.413 [548441120992] DEBUG - Completed: [127.0.0.1:57864] 200 GET /media/providers (3 live) #e0 GZIP 6ms 3916 bytes (pipelined: 5)
Nov 06, 2023 16:05:45.425 [548420727008] DEBUG - Request: [127.0.0.1:57864 (Loopback)] GET /library/sections (3 live) #fa GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 06, 2023 16:05:45.431 [548441120992] DEBUG - [EventSourceClient/pubsub/178.79.165.194:443] EventSource: Got event [data] '<Message command="notifyFavoritesChange"/>'
Nov 06, 2023 16:05:45.432 [548439011552] DEBUG - Completed: [127.0.0.1:57864] 200 GET /library/sections (3 live) #fa GZIP 7ms 847 bytes (pipelined: 6)
Nov 06, 2023 16:05:45.462 [548418560224] DEBUG - [HttpClient/HCl#44] HTTP/2.0 (0.1s) 200 response from GET https://plex.tv/api/v2/server/access_tokens?auth_token=xxxxxxxxxxxxxxxxxxxx (reused)
Nov 06, 2023 16:05:45.466 [548391104736] DEBUG - [Req#da] MyPlex: updating with 15 access tokens
Nov 06, 2023 16:05:45.466 [548391104736] DEBUG - [Req#da/HCl#45] HTTP requesting GET https://plex.tv/api/v2/server/users?auth_token=xxxxxxxxxxxxxxxxxxxx
Nov 06, 2023 16:05:45.467 [548371648736] DEBUG - [Req#da/MediaProviderManager/HCl#46] HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
Nov 06, 2023 16:05:45.541 [548418560224] DEBUG - [HttpClient/HCl#45] HTTP/2.0 (0.1s) 200 response from GET https://plex.tv/api/v2/server/users?auth_token=xxxxxxxxxxxxxxxxxxxx (reused)
Nov 06, 2023 16:05:45.541 [548418560224] DEBUG - [HttpClient/HCl#46] HTTP/2.0 (0.1s) 200 response from GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx (reused)
Nov 06, 2023 16:05:45.543 [548371648736] DEBUG - [Req#da/MediaProviderManager] discovered cloud provider (Movies & TV)
Nov 06, 2023 16:05:45.543 [548371648736] DEBUG - [Req#da/MediaProviderManager] discovered cloud provider (Music)
Nov 06, 2023 16:05:45.543 [548371648736] DEBUG - [Req#da/MediaProviderManager] discovered cloud provider (Metadata)
Nov 06, 2023 16:05:45.543 [548371648736] DEBUG - [Req#da/MediaProviderManager] loading cloud provider details (Metadata) (alive: 1)
Nov 06, 2023 16:05:45.543 [548371648736] DEBUG - [Req#da/MediaProviderManager] loading cloud provider details (Music) (alive: 1)
Nov 06, 2023 16:05:45.543 [548371648736] DEBUG - [Req#da/MediaProviderManager] loading cloud provider details (Movies & TV) (alive: 1)
Nov 06, 2023 16:05:45.543 [548371648736] DEBUG - [Req#da/MediaProviderManager] we had 3 cloud providers online, we now have 3
Nov 06, 2023 16:05:45.543 [548371648736] DEBUG - [Req#da/MediaProviderManager] cloud provider (Metadata) is online and available
Nov 06, 2023 16:05:45.543 [548371648736] DEBUG - [Req#da/MediaProviderManager] cloud provider (Music) is online and available
Nov 06, 2023 16:05:45.543 [548371648736] DEBUG - [Req#da/MediaProviderManager] cloud provider (Movies & TV) is online and available
Nov 06, 2023 16:05:45.545 [548382261472] DEBUG - [Req#da/HCl#47] HTTP requesting GET https://plex.tv/api/v2/server/users/subscriptions?auth_token=xxxxxxxxxxxxxxxxxxxx
Nov 06, 2023 16:05:45.606 [548418560224] DEBUG - [HttpClient/HCl#47] HTTP/2.0 (0.1s) 200 response from GET https://plex.tv/api/v2/server/users/subscriptions?auth_token=xxxxxxxxxxxxxxxxxxxx (reused)
Nov 06, 2023 16:05:45.609 [548377977056] DEBUG - [Req#da] MyPlex: using cached data for request for https://plex.tv/api/v2/server/users/services?auth_token=xxxxxxxxxxxxxxxxxxxx
Nov 06, 2023 16:05:45.970 [548420727008] DEBUG - Request: [192.168.1.41:39726 (Subnet)] GET /media/providers (4 live) #d7 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 06, 2023 16:05:45.977 [548441120992] DEBUG - Completed: [192.168.1.41:39726] 200 GET /media/providers (5 live) #d7 GZIP 7ms 3785 bytes (pipelined: 1)
Nov 06, 2023 16:05:45.991 [548420727008] DEBUG - Request: [192.168.1.41:39736 (Subnet)] GET /media/providers (5 live) #fc TLS GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 06, 2023 16:05:45.998 [548439011552] DEBUG - Completed: [192.168.1.41:39736] 200 GET /media/providers (5 live) #fc TLS GZIP 6ms 3916 bytes (pipelined: 1)
Nov 06, 2023 16:05:46.021 [548420727008] DEBUG - Request: [127.0.0.1:57864 (Loopback)] GET /media/providers (5 live) #fb GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 06, 2023 16:05:46.026 [548441120992] DEBUG - Completed: [127.0.0.1:57864] 200 GET /media/providers (5 live) #fb GZIP 5ms 3916 bytes (pipelined: 7)
Nov 06, 2023 16:05:46.267 [548420727008] DEBUG - Request: [192.168.1.41:39726 (Subnet)] GET /:/prefs/get?id=MergedRecentlyAdded (5 live) #ff GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 06, 2023 16:05:46.269 [548439011552] DEBUG - Completed: [192.168.1.41:39726] 200 GET /:/prefs/get?id=MergedRecentlyAdded (5 live) #ff GZIP 1ms 455 bytes (pipelined: 2)
Nov 06, 2023 16:05:46.465 [548420727008] DEBUG - Request: [127.0.0.1:57864 (Loopback)] GET /:/prefs/get?id=MergedRecentlyAdded (5 live) #103 GZIP Signed-in Token (Sh4ck) (Chrome)
Nov 06, 2023 16:05:46.466 [548441120992] DEBUG - Completed: [127.0.0.1:57864] 200 GET /:/prefs/get?id=MergedRecentlyAdded (5 live) #103 GZIP 1ms 586 bytes (pipelined: 8)
Nov 06, 2023 16:05:58.459 [548441120992] DEBUG - Request: [127.0.0.1:57864 (Loopback)] GET /media/providers (5 live) #105 GZIP Signed-in
Nov 06, 2023 16:05:58.460 [548441120992] DEBUG - Completed: [127.0.0.1:57864] 401 GET /media/providers (5 live) #105 GZIP 0ms 482 bytes
Nov 06, 2023 16:05:58.469 [548441120992] DEBUG - Request: [127.0.0.1:47402 (Loopback)] GET / (5 live) #fd GZIP Signed-in
Nov 06, 2023 16:05:58.470 [548441120992] DEBUG - Completed: [127.0.0.1:47402] 401 GET / (5 live) #fd GZIP 0ms 560 bytes
Nov 06, 2023 16:06:01.392 [548393214176] DEBUG - [NSB/SSDP] Parsing SSDP schema for http://192.168.1.35:9080
Nov 06, 2023 16:06:01.413 [548418560224] DEBUG - [HttpClient/HCl#48] HTTP/1.1 (0.0s) 200 response from GET http://192.168.1.35:9080

Por otro lado no he podido copiar el mkv desde dentro de la carpeta a la raiz del disco duro para hacer la otra prueba, porque me dice que es solo de lectura y me tira error.

Otra prueba que he hecho ha sido poner el otro disco duro que tenia en formato ext4 con una pelicula de prueba y ese disco duro con la correspondiente carpeta si que la ha detectado, eso si, he probado a ejecutar la película y cual ha sido mi sorpresa cuando he visto que iba a tirones