PMS doesn't start: getaddrinfo() thread failed to start

Plex Media Server
,
1

Server Version#: v1.19.4.2935-79e214ead
Player Version#: Web

This is on a Raspberry Pi 4, Ubuntu 20.04. I have tried the snap and the .deb package (same version for both).

When starting PMS, with all state removed (or with existing state), certain URL fetches fail with the message “getaddrinfo() thread failed to start”. This appears to be due to a failure to issue a libcurl_easy_perform call on a URL to https://plex.tv/. I have run a tcpdump on outgoing traffic and do see DNS lookups happening, but curiously, they do not happen at the time these https://plex.tv/ URLs first appear in the logs.

This issue appears to be unique: I cannot find any posts with “thread failed to start” in the forums. I found some references to this libcurl error, related to Windows firewalls (this is Linux) and HP/UX.

I have straced the processes and can’t keep up with what’s being passed around between threads.

I have disabled AppArmor. No change.

I have tried changing the nameserver in /etc/resolv.conf to 8.8.8.8. No change.

If I run curl https://plex.tv/ on the system, the hostname is resolved and I get the redirect HTML. So I’m certain my resolver is okay.

I’m not opposed to burning this OS install to the ground and starting over, but I would like to salvage things if possible.

Plex Media Server log, truncated to what I assume is the part where it fails:

neale@sweetums:/var/lib/plexmediaserver/Library/Application Support/Plex Media Server/Logs $ grep https: Plex\ Media\ Server.log  | grep -o  '\[0x[0-9a-f]*\]' | sort | uniq | while read tid; do fgrep "$tid" Plex\ Media\ Server.log ; done | sort | dd bs=1 count=38000 2>/dev/null; echo
Jul 21, 2020 22:30:16.289 [0xffff80f7c1c0] DEBUG - Grabber: Cleaning up orphaned grabs.
Jul 21, 2020 22:30:16.290 [0xffff7affd1c0] DEBUG - Auth: Refreshing tokens inside the token-based authentication filter.
Jul 21, 2020 22:30:16.290 [0xffff80f7c1c0] DEBUG - Grabber: Cleaned up 0 decrepit directories in 0.0 sec.
Jul 21, 2020 22:30:16.290 [0xffff80f7c1c0] DEBUG - HTTP requesting GET https://plex.tv/media/providers?X-Plex-Token=
Jul 21, 2020 22:30:16.290 [0xffff80f7c1c0] DEBUG - MyPlex: mapping state set to 'Unknown'.
Jul 21, 2020 22:30:16.290 [0xffff80f7c1c0] DEBUG - Relay: read 0 cached entries from hosts file
Jul 21, 2020 22:30:22.704 [0xffff7affd1c0] DEBUG - HTTP requesting GET https://plex.tv/api/photos/profile
Jul 21, 2020 22:30:22.705 [0xffff65ffb1c0] DEBUG - HttpServer: Set up a thread pool with 2 threads.
Jul 21, 2020 22:30:22.706 [0xffff65ffb1c0] DEBUG - HTTP requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=
Jul 21, 2020 22:30:22.711 [0xffff437fe1c0] DEBUG - Request: [127.0.0.1:38674 (Loopback)] GET /:/plugins/com.plexapp.agents.plexthememusic (7 live) GZIP
Jul 21, 2020 22:30:22.712 [0xffff42ffd1c0] DEBUG - Request: [127.0.0.1:38676 (Loopback)] GET / (7 live) GZIP
Jul 21, 2020 22:30:22.718 [0xffff437fe1c0] WARN - Request for plug-in [com.plexapp.agents.plexthememusic] but it doesn't exist.
Jul 21, 2020 22:30:22.735 [0xffff437fe1c0] DEBUG - Request: [127.0.0.1:38688 (Loopback)] GET /:/plugins/org.musicbrainz.agents.music (5 live) GZIP
Jul 21, 2020 22:30:22.735 [0xffff437fe1c0] WARN - Request for plug-in [org.musicbrainz.agents.music] but it doesn't exist.
Jul 21, 2020 22:30:22.768 [0xffff42ffd1c0] DEBUG - Request: [127.0.0.1:38694 (Loopback)] GET / (5 live) GZIP
Jul 21, 2020 22:30:22.778 [0xffff437fe1c0] DEBUG - Request: [127.0.0.1:38696 (Loopback)] GET /channels/all (4 live) GZIP
Jul 21, 2020 22:30:22.852 [0xffff42ffd1c0] DEBUG - Request: [127.0.0.1:38704 (Loopback)] GET /:/plugins/com.plexapp.agents.fanarttv (4 live) GZIP
Jul 21, 2020 22:30:22.852 [0xffff42ffd1c0] WARN - Request for plug-in [com.plexapp.agents.fanarttv] but it doesn't exist.
Jul 21, 2020 22:30:22.862 [0xffff437fe1c0] DEBUG - Request: [127.0.0.1:38706 (Loopback)] GET /:/plugins/com.plexapp.agents.opensubtitles (4 live) GZIP
Jul 21, 2020 22:30:22.862 [0xffff437fe1c0] WARN - Request for plug-in [com.plexapp.agents.opensubtitles] but it doesn't exist.
Jul 21, 2020 22:30:22.904 [0xffff42ffd1c0] DEBUG - Request: [127.0.0.1:38716 (Loopback)] GET /:/plugins/com.plexapp.agents.lastfm (4 live) GZIP
Jul 21, 2020 22:30:22.904 [0xffff42ffd1c0] WARN - Request for plug-in [com.plexapp.agents.lastfm] but it doesn't exist.
Jul 21, 2020 22:30:22.914 [0xffff437fe1c0] DEBUG - Request: [127.0.0.1:38718 (Loopback)] GET /:/plugins/tv.plex.agents.movie (4 live) GZIP
Jul 21, 2020 22:30:22.914 [0xffff437fe1c0] WARN - Request for plug-in [tv.plex.agents.movie] but it doesn't exist.
Jul 21, 2020 22:30:26.290 [0xffff677fe1c0] DEBUG - DVR:Device: Discovering and refreshing all devices.
Jul 21, 2020 22:30:26.291 [0xffff677fe1c0] DEBUG - Grabber: HDHomerun discovered 0 compatible devices.
Jul 21, 2020 22:30:26.291 [0xffff677fe1c0] DEBUG - HTTP requesting POST http://127.0.0.1:32600/devices/discover
Jul 21, 2020 22:30:29.339 [0xffff437fe1c0] DEBUG - Request: [192.168.86.150:39770 (Subnet)] GET /media/providers (3 live) GZIP
Jul 21, 2020 22:30:42.344 [0xffff437fe1c0] DEBUG - Request: [192.168.86.150:39784 (Subnet)] GET /media/providers (3 live) GZIP
Jul 21, 2020 22:30:53.345 [0xffff437fe1c0] DEBUG - Request: [192.168.86.150:39796 (Subnet)] GET /media/providers (3 live) GZIP
Jul 21, 2020 22:31:13.351 [0xffff437fe1c0] DEBUG - Request: [192.168.86.150:39822 (Subnet)] GET /media/providers (3 live) GZIP
Jul 21, 2020 22:31:24.359 [0xffff437fe1c0] DEBUG - Request: [192.168.86.150:39838 (Subnet)] GET /media/providers (3 live) GZIP
Jul 21, 2020 22:31:32.777 [0xffff437fe1c0] DEBUG - Request: [192.168.86.150:39850 (Subnet)] GET /web/index.html (4 live) GZIP
Jul 21, 2020 22:31:32.778 [0xffff437fe1c0] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/WebClient.bundle/Contents/Resources/index.html is 11363 (of total: 11363).
Jul 21, 2020 22:31:32.778 [0xffff437fe1c0] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/WebClient.bundle/Contents/Resources/index.html"
Jul 21, 2020 22:31:32.814 [0xffff437fe1c0] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/WebClient.bundle/Contents/Resources/chunk-3-3f60d034937c1e92cdb6-plex-4.30.2-225a4ec.css is 994717 (of total: 994717).
Jul 21, 2020 22:31:32.814 [0xffff437fe1c0] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/WebClient.bundle/Contents/Resources/chunk-3-3f60d034937c1e92cdb6-plex-4.30.2-225a4ec.css"
Jul 21, 2020 22:31:32.814 [0xffff437fe1c0] DEBUG - Request: [192.168.86.150:39850 (Subnet)] GET /web/chunk-3-3f60d034937c1e92cdb6-plex-4.30.2-225a4ec.css (4 live) GZIP
Jul 21, 2020 22:31:32.817 [0xffff437fe1c0] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/WebClient.bundle/Contents/Resources/js/chunk-5-7549aa70dd5d5c397569-plex-4.30.2-225a4ec.js"
Jul 21, 2020 22:31:32.817 [0xffff437fe1c0] DEBUG - Request: [192.168.86.150:39852 (Subnet)] GET /web/js/chunk-5-7549aa70dd5d5c397569-plex-4.30.2-225a4ec.js (4 live) GZIP
Jul 21, 2020 22:31:32.818 [0xffff437fe1c0] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/WebClient.bundle/Contents/Resources/js/chunk-5-7549aa70dd5d5c397569-plex-4.30.2-225a4ec.js is 970731 (of total: 970731).
Jul 21, 2020 22:31:32.825 [0xffff437fe1c0] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/WebClient.bundle/Contents/Resources/js/chunk-3-3f60d034937c1e92cdb6-plex-4.30.2-225a4ec.js is 3357157 (of total: 3357157).
Jul 21, 2020 22:31:32.825 [0xffff437fe1c0] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/WebClient.bundle/Contents/Resources/js/chunk-3-3f60d034937c1e92cdb6-plex-4.30.2-225a4ec.js"
Jul 21, 2020 22:31:32.825 [0xffff437fe1c0] DEBUG - Request: [192.168.86.150:39854 (Subnet)] GET /web/js/chunk-3-3f60d034937c1e92cdb6-plex-4.30.2-225a4ec.js (5 live) GZIP
Jul 21, 2020 22:31:33.465 [0xffff437fe1c0] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/WebClient.bundle/Contents/Resources/common/img/backgrounds/preset-dark2.6d2c8336496f761f38de8558a2b9709b.png"
Jul 21, 2020 22:31:33.465 [0xffff437fe1c0] DEBUG - Request: [192.168.86.150:39854 (Subnet)] GET /web/common/img/backgrounds/preset-dark2.6d2c8336496f761f38de8558a2b9709b.png (5 live) GZIP
Jul 21, 2020 22:31:33.466 [0xfffefaffd1c0] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/WebClient.bundle/Contents/Resources/common/img/backgrounds/noise.8b05ce45d0df59343e206bc9ae78d85d.png"
Jul 21, 2020 22:31:33.466 [0xfffefaffd1c0] DEBUG - Request: [192.168.86.150:39850 (Subnet)] GET /web/common/img/backgrounds/noise.8b05ce45d0df59343e206bc9ae78d85d.png (5 live) GZIP
Jul 21, 2020 22:31:33.466 [0xffff437fe1c0] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/WebClient.bundle/Contents/Resources/common/img/backgrounds/preset-dark2.6d2c8336496f761f38de8558a2b9709b.png is 113817 (of total: 113817).
Jul 21, 2020 22:31:33.467 [0xfffefaffd1c0] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/WebClient.bundle/Contents/Resources/common/img/backgrounds/noise.8b05ce45d0df59343e206bc9ae78d85d.png is 54413 (of total: 54413).
Jul 21, 2020 22:31:33.488 [0xffff437fe1c0] DEBUG - Request: [192.168.86.150:39852 (Subnet)] GET /web/translations/en.json (5 live) GZIP
Jul 21, 2020 22:31:33.489 [0xffff437fe1c0] DEBUG - Content-Length of /usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/WebClient.bundle/Contents/Resources/translations/en.json is 2 (of total: 2).
Jul 21, 2020 22:31:33.489 [0xffff437fe1c0] DEBUG - Final path: "/usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/WebClient.bundle/Contents/Resources/translations/en.json"
Jul 21, 2020 22:31:33.634 [0xfffefaffd1c0] DEBUG - Request: [192.168.86.150:39852 (Subnet)] GET /media/providers (5 live) GZIP
Jul 21, 2020 22:31:33.635 [0xffff437fe1c0] DEBUG - Request: [192.168.86.150:39850 (Subnet)] GET / (5 live) GZIP
Jul 21, 2020 22:31:33.722 [0xffff437fe1c0] DEBUG - Request: [192.168.86.150:39850 (Subnet)] GET /:/prefs (5 live) GZIP
Jul 21, 2020 22:31:33.725 [0xfffefaffd1c0] DEBUG - Request: [192.168.86.150:39852 (Subnet)] GET /updater/status (5 live) GZIP
Jul 21, 2020 22:31:33.731 [0xffff437fe1c0] WARN - [Preferences] No update channels loaded, so showing the previously-selected channel, labelled 'unknown' since we don't really know the name.
Jul 21, 2020 22:31:33.738 [0xfffefaffd1c0] DEBUG - Beginning read from WebSocket
Jul 21, 2020 22:31:33.738 [0xfffefaffd1c0] DEBUG - Request: [192.168.86.150:39866 (Subnet)] GET /:/websockets/notifications (6 live) GZIP
Jul 21, 2020 22:31:33.738 [0xfffefaffd1c0] DEBUG - WebSocket: Performing handshake from origin http://192.168.86.2:32400
Jul 21, 2020 22:31:33.758 [0xffff437fe1c0] DEBUG - Request: [192.168.86.150:39850 (Subnet)] GET /status/sessions (6 live) GZIP
Jul 21, 2020 22:31:33.759 [0xffff437fe1c0] DEBUG - [Now] Adding 0 sessions.
Jul 21, 2020 22:31:33.842 [0xfffefaffd1c0] DEBUG - Request: [192.168.86.150:39850 (Subnet)] GET /activities (6 live) GZIP
Jul 21, 2020 22:31:33.856 [0xffff437fe1c0] DEBUG - AutoUpdate: requesting check endpoint: /updater/products/5/check.xml?build=linux-aarch64&channel=16&distribution=debian&version=1.19.4.2935-79e214ead, download: NO
Jul 21, 2020 22:31:33.856 [0xffff437fe1c0] DEBUG - Request: [192.168.86.150:39850 (Subnet)] PUT /updater/check?download=0 (6 live) GZIP
Jul 21, 2020 22:31:33.974 [0xffff437fe1c0] DEBUG - Downloading document https://plex.tv/updater/products/5/check.xml?build=linux-aarch64&channel=16&distribution=debian&version=1.19.4.2935-79e214ead
Jul 21, 2020 22:31:33.974 [0xffff437fe1c0] DEBUG - HTTP requesting GET https://plex.tv/updater/products/5/check.xml?build=linux-aarch64&channel=16&distribution=debian&version=1.19.4.2935-79e214ead
Jul 21, 2020 22:31:36.077 [0xfffefaffd1c0] DEBUG - Request: [192.168.86.150:39852 (Subnet)] GET /photo/:/transcode?url=https%3A%2F%2Fplex.tv%2Fusers%2F0897d5ca54ae6621%2Favatar%3Fc%3D1595132543%26size%3D144&width=144&height=144&minSize=1 (6 live) GZIP
Jul 21, 2020 22:31:36.078 [0xfffefa7fc1c0] DEBUG - Request: [192.168.86.150:39854 (Subnet)] GET /photo/:/transcode?url=https%3A%2F%2Fplex.tv%2Fusers%2F0897d5ca54ae6621%2Favatar%3Fc%3D1595132543%26size%3D216&width=216&height=216&minSize=1&blur=20&opacity=50&background=000000 (6 live) GZIP
Jul 21, 2020 22:31:36.090 [0xfffef9ffb1c0] DEBUG - Request: [192.168.86.150:39892 (Subnet)] GET /photo/:/transcode?url=https%3A%2F%2Fplex.tv%2Fusers%2Fa739eb27a098d811%2Favatar%3Fc%3D1590965255%26size%3D144&width=144&height=144&minSize=1 (7 live) GZIP
Jul 21, 2020 22:31:36.096 [0xfffef97fa1c0] DEBUG - Request: [192.168.86.150:39894 (Subnet)] GET /photo/:/transcode?url=https%3A%2F%2Fplex.tv%2Fusers%2Fa739eb27a098d811%2Favatar%3Fc%3D1590965255%26size%3D216&width=216&height=216&minSize=1&blur=20&opacity=50&background=000000 (9 live) GZIP
Jul 21, 2020 22:31:36.097 [0xfffef8ff91c0] DEBUG - Request: [192.168.86.150:39896 (Subnet)] GET /photo/:/transcode?url=https%3A%2F%2Fplex.tv%2Fusers%2F6743338b4f443125%2Favatar%3Fc%3D1590969953%26size%3D144&width=144&height=144&minSize=1 (9 live) GZIP
Jul 21, 2020 22:31:36.125 [0xfffef97fa1c0] DEBUG - Photo transcoder: Request for url [https://plex.tv/users/a739eb27a098d811/avatar?c=1590965255&size=216] (is local: 0 upscaled: 0)
Jul 21, 2020 22:31:36.125 [0xfffef9ffb1c0] DEBUG - Photo transcoder: Request for url [https://plex.tv/users/a739eb27a098d811/avatar?c=1590965255&size=144] (is local: 0 upscaled: 0)
Jul 21, 2020 22:31:36.126 [0xfffefa7fc1c0] DEBUG - Photo transcoder: Request for url [https://plex.tv/users/0897d5ca54ae6621/avatar?c=1595132543&size=216] (is local: 0 upscaled: 0)
Jul 21, 2020 22:31:36.126 [0xfffefaffd1c0] DEBUG - Photo transcoder: Request for url [https://plex.tv/users/0897d5ca54ae6621/avatar?c=1595132543&size=144] (is local: 0 upscaled: 0)
Jul 21, 2020 22:31:36.127 [0xfffef8ff91c0] DEBUG - Photo transcoder: Request for url [https://plex.tv/users/6743338b4f443125/avatar?c=1590969953&size=144] (is local: 0 upscaled: 0)
Jul 21, 2020 22:31:36.128 [0xfffef97fa1c0] DEBUG - HTTP requesting GET https://plex.tv/users/a739eb27a098d811/avatar?c=1590965255&size=216
Jul 21, 2020 22:31:36.129 [0xfffef9ffb1c0] DEBUG - HTTP requesting GET https://plex.tv/users/a739eb27a098d811/avatar?c=1590965255&size=144
Jul 21, 2020 22:31:36.129 [0xfffefaffd1c0] DEBUG - HTTP requesting GET https://plex.tv/users/0897d5ca54ae6621/avatar?c=1595132543&size=144
Jul 21, 2020 22:31:36.130 [0xfffef8ff91c0] DEBUG - HTTP requesting GET https://plex.tv/users/6743338b4f443125/avatar?c=1590969953&size=144
Jul 21, 2020 22:31:36.131 [0xfffefa7fc1c0] DEBUG - HTTP requesting GET https://plex.tv/users/0897d5ca54ae6621/avatar?c=1595132543&size=216
Jul 21, 2020 22:31:46.164 [0xffff417fa1c0] DEBUG - Request: [192.168.86.150:39938 (Subnet)] GET /photo/:/transcode?url=https%3A%2F%2Fplex.tv%2Fusers%2F6743338b4f443125%2Favatar%3Fc%3D1590969953%26size%3D216&width=216&height=216&minSize=1&blur=20&opacity=50&background=000000 (9 live) GZIP
Jul 21, 2020 22:31:46.167 [0xffff417fa1c0] DEBUG - Photo transcoder: Request for url [https://plex.tv/users/6743338b4f443125/avatar?c=1590969953&size=216] (is local: 0 upscaled: 0)
Jul 21, 2020 22:31:46.170 [0xffff417fa1c0] DEBUG - HTTP requesting GET https://plex.tv/users/6743338b4f443125/avatar?c=1590969953&size=216
Jul 21, 2020 22:32:47.928 [0xffff1bfff1c0] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/TheTVDB.bundle.
Jul 21, 2020 22:32:47.931 [0xffff1bfff1c0] DEBUG - Starting file watcher for com.plexapp.agents.thetvdb
Jul 21, 2020 22:32:47.931 [0xffff1bfff1c0] DEBUG - [com.plexapp.agents.thetvdb] Agent not known by system, so we're starting for sure.
Jul 21, 2020 22:32:47.932 [0xffff1bfff1c0] DEBUG - Plugin: not setting user token for plugin
Jul 21, 2020 22:32:47.936 [0xffff1bfff1c0] DEBUG - Spawned plug-in com.plexapp.agents.thetvdb with PID 12496
Jul 21, 2020 22:32:47.937 [0xffff1bfff1c0] DEBUG - [com.plexapp.agents.thetvdb] Sending command: GET /:/prefixes
Jul 21, 2020 22:32:52.022 [0xffff677fe1c0] ERROR - Error issuing curl_easy_perform(handle): 28
Jul 21, 2020 22:32:52.023 [0xffff677fe1c0] DEBUG - Grabber: Failed to retrieve device data
Jul 21, 2020 22:32:52.023 [0xffff677fe1c0] DEBUG - HTTP simulating 408 after curl timeout
Jul 21, 2020 22:33:02.546 [0xffff677fe1c0] DEBUG - Request: [127.0.0.1:38814 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo1CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdAoyCnMyNgpjb20ucGxleGFwcC5hZ2VudHMudGhldHZkYnMxMAppZGVudGlmaWVycjEKczEwCmFnZW50X2luZm8xCnIyCjEwCnIzCnMxMQptZWRpYV90eXBlc25zMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudG5zMTQKY29udHJpYnV0ZXNfdG9yNApzOQpsYW5ndWFnZXNiMXMyMApwZXJzaXN0X3N0b3JlZF9maWxlc2kwCnM3CnZlcnNpb25iMXMxNgpwcmltYXJ5X3Byb3ZpZGVyYjFzNQpwcmVmc3M3ClRoZVRWREJzNApuYW1lMQpzNwpUVl9TaG93MjMKczIKZW5zMgpmcnMyCnpoczIKc3ZzMgpub3MyCmRhczIKZmlzMgpubHMyCmRlczIKaXRzMgplc3MyCnBsczIKaHVzMgplbHMyCnRyczIKcnVzMgpoZXMyCmphczIKcHRzMgpjc3MyCmtvczIKc2xzMgpocnIwCg__ (22 live) GZIP
Jul 21, 2020 22:33:02.547 [0xffff677fe1c0] DEBUG - HTTP requesting GET http://127.0.0.1:40049/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo1CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdAoyCnMyNgpjb20ucGxleGFwcC5hZ2VudHMudGhldHZkYnMxMAppZGVudGlmaWVycjEKczEwCmFnZW50X2luZm8xCnIyCjEwCnIzCnMxMQptZWRpYV90eXBlc25zMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudG5zMTQKY29udHJpYnV0ZXNfdG9yNApzOQpsYW5ndWFnZXNiMXMyMApwZXJzaXN0X3N0b3JlZF9maWxlc2kwCnM3CnZlcnNpb25iMXMxNgpwcmltYXJ5X3Byb3ZpZGVyYjFzNQpwcmVmc3M3ClRoZVRWREJzNApuYW1lMQpzNwpUVl9TaG93MjMKczIKZW5zMgpmcnMyCnpoczIKc3ZzMgpub3MyCmRhczIKZmlzMgpubHMyCmRlczIKaXRzMgplc3MyCnBsczIKaHVzMgplbHMyCnRyczIKcnVzMgpoZXMyCmphczIKcHRzMgpjc3MyCmtvczIKc2xzMgpocnIwCg__
Jul 21, 2020 22:33:02.547 [0xffff677fe1c0] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo1CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdAoyCnMyNgpjb20ucGxleGFwcC5hZ2VudHMudGhldHZkYnMxMAppZGVudGlmaWVycjEKczEwCmFnZW50X2luZm8xCnIyCjEwCnIzCnMxMQptZWRpYV90eXBlc25zMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudG5zMTQKY29udHJpYnV0ZXNfdG9yNApzOQpsYW5ndWFnZXNiMXMyMApwZXJzaXN0X3N0b3JlZF9maWxlc2kwCnM3CnZlcnNpb25iMXMxNgpwcmltYXJ5X3Byb3ZpZGVyYjFzNQpwcmVmc3M3ClRoZVRWREJzNApuYW1lMQpzNwpUVl9TaG93MjMKczIKZW5zMgpmcnMyCnpoczIKc3ZzMgpub3MyCmRhczIKZmlzMgpubHMyCmRlczIKaXRzMgplc3MyCnBsczIKaHVzMgplbHMyCnRyczIKcnVzMgpoZXMyCmphczIKcHRzMgpjc3MyCmtvczIKc2xzMgpocnIwCg__
Jul 21, 2020 22:34:37.853 [0xffff80f7c1c0] ERROR - Error issuing curl_easy_perform(handle): 6
Jul 21, 2020 22:34:37.853 [0xffff80f7c1c0] WARN - HTTP error requesting GET https://plex.tv/media/providers?X-Plex-Token=xxxxxxxxxxxxxxxxxxxxe host name) (getaddrinfo() thread failed to start
Jul 21, 2020 22:34:37.854 [0xffff80f7c1c0] ERROR - Error parsing XML: Error parsing file.
Jul 21, 2020 22:34:37.854 [0xffff80f7c1c0] ERROR - Error parsing content.
Jul 21, 2020 22:34:43.997 [0xffff65ffb1c0] ERROR - Error issuing curl_easy_perform(handle): 6
Jul 21, 2020 22:34:43.997 [0xffff65ffb1c0] WARN - HTTP error requesting GET https://plex.tv/api/v2/release_channels?X-Plex-Token=xxxxxxxxxxxxxxxxxxxxe host name) (getaddrinfo() thread failed to start
Jul 21, 2020 22:34:43.997 [0xffff7affd1c0] ERROR - Error issuing curl_easy_perform(handle): 6
Jul 21, 2020 22:34:43.997 [0xffff7affd1c0] WARN - HTTP error requesting GET https://plex.tv/api/photos/profile (6, Couldn't resolve host name) (getaddrinfo() thread failed to start
Jul 21, 2020 22:34:43.998 [0xffff65ffb1c0] WARN - [AutoUpdateRequestHandler] Couldn't get update channels. Trying again soon.
Jul 21, 2020 22:34:43.998 [0xffff7affd1c0] ERROR - AutoTagging: unable to retrieve updated word lists
Jul 21, 2020 22:34:43.999 [0xffff65ffb1c0] DEBUG - Running database fix-ups.
Jul 21, 2020 22:34:44.001 [0xffff65ffb1c0] DEBUG - MyPlex: Update my mapping state on myPlex
Jul 21, 2020 22:34:44.003 [0xffff65ffb1c0] DEBUG - Database Fixups: Destroyed 0 transient items.
Jul 21, 2020 22:34:44.011 [0xffff65ffb1c0] DEBUG - Running database fix-ups completed in 0.0 seconds.
Jul 21, 2020 22:34:44.012 [0xffff65ffb1c0] DEBUG - HTTP requesting GET https://plex.tv/api/v2/user/privacy?X-Plex-Token=
Jul 21, 2020 22:35:27.671 [0xffff677fe1c0] DEBUG - HTTP simulating 408 after curl timeout
Jul 21, 2020 22:35:27.671 [0xffff677fe1c0] DEBUG - [com.plexapp.system] HTTP reply status 408, with 0 bytes of content.
Jul 21, 2020 22:35:27.671 [0xffff677fe1c0] ERROR - Error issuing curl_easy_perform(handle): 28
Jul 21, 2020 22:35:48.001 [0xffff1bfff1c0] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/MoviePosterDB.bundle.
Jul 21, 2020 22:35:48.001 [0xffff1bfff1c0] DEBUG - Stopping [com.plexapp.agents.thetvdb].
Jul 21, 2020 22:35:48.001 [0xffff1bfff1c0] DEBUG - Stopping [com.plexapp.agents.thetvdb].
Jul 21, 2020 22:35:48.001 [0xffff1bfff1c0] DEBUG - [com.plexapp.agents.thetvdb] Killing.
Jul 21, 2020 22:35:48.001 [0xffff1bfff1c0] ERROR - Error received reading configuration for com.plexapp.agents.thetvdb
Jul 21, 2020 22:35:48.001 [0xffff1bfff1c0] ERROR - Timeout or error reading status line from plug-in pipe [com.plexapp.agents.thetvdb], we're killing it.
Jul 21, 2020 22:35:48.004 [0xffff1bfff1c0] DEBUG - Plugin: not setting user token for plugin
Jul 21, 2020 22:35:48.004 [0xffff1bfff1c0] DEBUG - Starting file watcher for com.plexapp.agents.movieposterdb
Jul 21, 2020 22:35:48.004 [0xffff1bfff1c0] DEBUG - [com.plexapp.agents.movieposterdb] Agent not known by system, so we're starting for sure.
Jul 21, 2020 22:35:48.009 [0xffff1bfff1c0] DEBUG - Spawned plug-in com.plexapp.agents.movieposterdb with PID 12572
Jul 21, 2020 22:35:48.009 [0xffff1bfff1c0] DEBUG - [com.plexapp.agents.movieposterdb] Sending command: GET /:/prefixes
Jul 21, 2020 22:35:48.049 [0xffff42ffd1c0] DEBUG - Updating the list of agents known by the system.
Jul 21, 2020 22:35:55.677 [0xffff437fe1c0] ERROR - Error issuing curl_easy_perform(handle): 6
Jul 21, 2020 22:35:55.677 [0xffff437fe1c0] WARN - HTTP error requesting GET https://plex.tv/updater/products/5/check.xml?build=linux-aarch64&channel=16&distribution=debian&version=1.19.4.2935-79e214ead (6, Couldn't resolve host name) (getaddrinfo() thread failed to start
Jul 21, 2020 22:35:55.678 [0xffff437fe1c0] ERROR - HTTP -6 downloading url https://plex.tv/updater/products/5/check.xml?build=linux-aarch64&channel=16&distribution=debian&version=1.19.4.2935-79e214ead
Jul 21, 2020 22:35:55.678 [0xffff437fe1c0] INFO - AutoUpdate: error getting data from plex.tv
Jul 21, 2020 22:35:57.725 [0xfffef8ff91c0] ERROR - Error issuing curl_easy_perform(handle): 6
Jul 21, 2020 22:35:57.725 [0xfffef97fa1c0] ERROR - Error issuing curl_easy_perform(handle): 6
Jul 21, 2020 22:35:57.725 [0xfffefa7fc1c0] ERROR - Error issuing curl_easy_perform(handle): 6
Jul 21, 2020 22:35:57.725 [0xfffefa7fc1c0] WARN - HTTP error requesting GET https://plex.tv/users/0897d5ca54ae6621/avatar?c=1595132543&size=216 (6, Couldn't resolve host name) (getaddrinfo() thread failed to start
Jul 21, 2020 22:35:57.725 [0xfffefaffd1c0] ERROR - Error issuing curl_easy_perform(handle): 6
Jul 21, 2020 22:35:57.725 [0xfffefaffd1c0] WARN - HTTP error requesting GET https://plex.tv/users/0897d5ca54ae6621/avatar?c=1595132543&size=144 (6, Couldn't resolve host name) (getaddrinfo() thread failed to start
Jul 21, 2020 22:35:57.726 [0xfffef8ff91c0] WARN - HTTP error requesting GET https://plex.tv/users/6743338b4f443125/avatar?c=1590969953&size=144 (6, Couldn't resolve host name) (getaddrinfo() thread failed to start
Jul 21, 2020 22:35:57.726 [0xfffef97fa1c0] WARN - HTTP error requesting GET https://plex.tv/users/a739eb27a098d811/avatar?c=1590965255&size=216 (6, Couldn't resolve host name) (getaddrinfo() thread failed to start
Jul 21, 2020 22:35:57.726 [0xfffef9ffb1c0] ERROR - Error issuing curl_easy_perform(handle): 6
Jul 21, 2020 22:35:57.726 [0xfffef9ffb1c0] WARN - HTTP error requesting GET https://plex.tv/users/a739eb27a098d811/avatar?c=1590965255&size=144 (6, Couldn't resolve host name) (getaddrinfo() thread failed to start
Jul 21, 2020 22:36:01.341 [0xffff437fe1c0] DEBUG - Request: [127.0.0.1:38904 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (11 live) GZIP
Jul 21, 2020 22:36:01.341 [0xffff437fe1c0] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jul 21, 2020 22:36:01.342 [0xffff437fe1c0] DEBUG - HTTP requesting GET http://127.0.0.1:40049/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jul 21, 2020 22:36:01.353 [0xfffefa7fc1c0] DEBUG - Request: [127.0.0.1:38908 (Loopback)] GET / (12 live) GZIP
Jul 21, 2020 22:36:01.358 [0xfffef8ff91c0] DEBUG - HTTP requesting GET http://127.0.0.1:40049/:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jul 21, 2020 22:36:01.358 [0xfffef8ff91c0] DEBUG - Request: [127.0.0.1:38910 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_ (12 live) GZIP
Jul 21, 2020 22:36:01.358 [0xfffef8ff91c0] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0J1bmRsZVNlcnZpY2U6QWxsU2VydmljZXM_/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQoxCmRpY3QKMApyMAo_
Jul 21, 2020 22:36:01.369 [0xfffef97fa1c0] DEBUG - Request: [127.0.0.1:38914 (Loopback)] GET / (14 live) GZIP
Jul 21, 2020 22:36:01.456 [0xfffefaffd1c0] DEBUG - Request: [127.0.0.1:38922 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo2CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczMyCmNvbS5wbGV4YXBwLmFnZW50cy5tb3ZpZXBvc3RlcmRiczEwCmlkZW50aWZpZXJyMQpzMTAKYWdlbnRfaW5mbzEKcjIKMTAKcjMKczExCm1lZGlhX3R5cGVzbnMxMgphY2NlcHRzX2Zyb21uczE0CmZhbGxiYWNrX2FnZW50cjQKczE0CmNvbnRyaWJ1dGVzX3RvcjUKczkKbGFuZ3VhZ2VzYjFzMjAKcGVyc2lzdF9zdG9yZWRfZmlsZXNpMApzNwp2ZXJzaW9uYjBzMTYKcHJpbWFyeV9wcm92aWRlcmIwczUKcHJlZnNzMTIKQ2luZU1hdGVyaWFsczQKbmFtZTEKczUKTW92aWUxCnMyMwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjEKczIKeG5yMAo_ (15 live) GZIP
Jul 21, 2020 22:36:01.456 [0xfffefaffd1c0] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo2CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczMyCmNvbS5wbGV4YXBwLmFnZW50cy5tb3ZpZXBvc3RlcmRiczEwCmlkZW50aWZpZXJyMQpzMTAKYWdlbnRfaW5mbzEKcjIKMTAKcjMKczExCm1lZGlhX3R5cGVzbnMxMgphY2NlcHRzX2Zyb21uczE0CmZhbGxiYWNrX2FnZW50cjQKczE0CmNvbnRyaWJ1dGVzX3RvcjUKczkKbGFuZ3VhZ2VzYjFzMjAKcGVyc2lzdF9zdG9yZWRfZmlsZXNpMApzNwp2ZXJzaW9uYjBzMTYKcHJpbWFyeV9wcm92aWRlcmIwczUKcHJlZnNzMTIKQ2luZU1hdGVyaWFsczQKbmFtZTEKczUKTW92aWUxCnMyMwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjEKczIKeG5yMAo_
Jul 21, 2020 22:36:01.457 [0xfffefaffd1c0] DEBUG - HTTP requesting GET http://127.0.0.1:40049/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo2CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczMyCmNvbS5wbGV4YXBwLmFnZW50cy5tb3ZpZXBvc3RlcmRiczEwCmlkZW50aWZpZXJyMQpzMTAKYWdlbnRfaW5mbzEKcjIKMTAKcjMKczExCm1lZGlhX3R5cGVzbnMxMgphY2NlcHRzX2Zyb21uczE0CmZhbGxiYWNrX2FnZW50cjQKczE0CmNvbnRyaWJ1dGVzX3RvcjUKczkKbGFuZ3VhZ2VzYjFzMjAKcGVyc2lzdF9zdG9yZWRfZmlsZXNpMApzNwp2ZXJzaW9uYjBzMTYKcHJpbWFyeV9wcm92aWRlcmIwczUKcHJlZnNzMTIKQ2luZU1hdGVyaWFsczQKbmFtZTEKczUKTW92aWUxCnMyMwpjb20ucGxleGFwcC5hZ2VudHMuaW1kYjEKczIKeG5yMAo_
Jul 21, 2020 22:36:01.463 [0xfffef9ffb1c0] DEBUG - Request: [127.0.0.1:38920 (Loopback)] GET / (15 live) GZIP
Jul 21, 2020 22:36:01.550 [0xfffefa7fc1c0] DEBUG - Request: [127.0.0.1:38926 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo2CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczI4CmNvbS5wbGV4YXBwLmFnZW50cy5seXJpY2ZpbmRzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNuczEyCmFjY2VwdHNfZnJvbW5zMTQKZmFsbGJhY2tfYWdlbnRyNApzMTQKY29udHJpYnV0ZXNfdG9yNQpzOQpsYW5ndWFnZXNiMXMyMApwZXJzaXN0X3N0b3JlZF9maWxlc2kwCnM3CnZlcnNpb25iMHMxNgpwcmltYXJ5X3Byb3ZpZGVyYjBzNQpwcmVmc3M5Ckx5cmljRmluZHM0Cm5hbWUxCnM1CkFsYnVtMwpzMjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbXMyMAp0di5wbGV4LmFnZW50cy5tdXNpY3MyOApvcmcubXVzaWNicmFpbnouYWdlbnRzLm11c2ljMQpzMgp4bnIwCg__ (15 live) GZIP
Jul 21, 2020 22:36:01.550 [0xfffefa7fc1c0] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo2CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczI4CmNvbS5wbGV4YXBwLmFnZW50cy5seXJpY2ZpbmRzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNuczEyCmFjY2VwdHNfZnJvbW5zMTQKZmFsbGJhY2tfYWdlbnRyNApzMTQKY29udHJpYnV0ZXNfdG9yNQpzOQpsYW5ndWFnZXNiMXMyMApwZXJzaXN0X3N0b3JlZF9maWxlc2kwCnM3CnZlcnNpb25iMHMxNgpwcmltYXJ5X3Byb3ZpZGVyYjBzNQpwcmVmc3M5Ckx5cmljRmluZHM0Cm5hbWUxCnM1CkFsYnVtMwpzMjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbXMyMAp0di5wbGV4LmFnZW50cy5tdXNpY3MyOApvcmcubXVzaWNicmFpbnouYWdlbnRzLm11c2ljMQpzMgp4bnIwCg__
Jul 21, 2020 22:36:01.551 [0xfffefa7fc1c0] DEBUG - HTTP requesting GET http://127.0.0.1:40049/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo2CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0CjIKczI4CmNvbS5wbGV4YXBwLmFnZW50cy5seXJpY2ZpbmRzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNuczEyCmFjY2VwdHNfZnJvbW5zMTQKZmFsbGJhY2tfYWdlbnRyNApzMTQKY29udHJpYnV0ZXNfdG9yNQpzOQpsYW5ndWFnZXNiMXMyMApwZXJzaXN0X3N0b3JlZF9maWxlc2kwCnM3CnZlcnNpb25iMHMxNgpwcmltYXJ5X3Byb3ZpZGVyYjBzNQpwcmVmc3M5Ckx5cmljRmluZHM0Cm5hbWUxCnM1CkFsYnVtMwpzMjUKY29tLnBsZXhhcHAuYWdlbnRzLmxhc3RmbXMyMAp0di5wbGV4LmFnZW50cy5tdXNpY3MyOApvcmcubXVzaWNicmFpbnouYWdlbnRzLm11c2ljMQpzMgp4bnIwCg__
Jul 21, 2020 22:36:03.135 [0xfffef97fa1c0] DEBUG - Request: [127.0.0.1:38930 (Loopback)] GET /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_ (16 live) GZIP
Jul 21, 2020 22:36:03.136 [0xfffef97fa1c0] DEBUG - HTTP requesting GET http://127.0.0.1:40049/:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Jul 21, 2020 22:36:03.136 [0xfffef97fa1c0] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /:/plugins/com.plexapp.system/messaging/function/X0FnZW50U2VydmljZTpVcGRhdGVJbmZv/Y2VyZWFsMQoxCmxpc3QKMApyMAo_/Y2VyZWFsMQo3CmRpY3QKbGlzdApkaWN0Cmxpc3QKbGlzdApsaXN0Cmxpc3QKMgpzMjMKY29tLnBsZXhhcHAuYWdlbnRzLmltZGJzMTAKaWRlbnRpZmllcnIxCnMxMAphZ2VudF9pbmZvMQpyMgoxMApyMwpzMTEKbWVkaWFfdHlwZXNyNApzMTIKYWNjZXB0c19mcm9tbnMxNApmYWxsYmFja19hZ2VudHI1CnMxNApjb250cmlidXRlc190b3I2CnM5Cmxhbmd1YWdlc2IxczIwCnBlcnNpc3Rfc3RvcmVkX2ZpbGVzaTAKczcKdmVyc2lvbmIxczE2CnByaW1hcnlfcHJvdmlkZXJiMXM1CnByZWZzczEwClBsZXggTW92aWVzNApuYW1lMQpzNQpNb3ZpZTEKczI5CmNvbS5wbGV4YXBwLmFnZW50cy5sb2NhbG1lZGlhMQpzMjkKY29tLnBsZXhhcHAuYWdlbnRzLnRoZW1vdmllZGIzMwpzMgplbnMyCnN2czIKZnJzMgplc3MyCm5sczIKZGVzMgppdHMyCmRhczIKYXJzMgpjYXMyCnpoczIKY3NzMgpldHMyCmZpczIKZWxzMgpoZXMyCmhpczIKaHVzMgppZHMyCmphczIKa29zMgpsdnMyCm5vczIKZmFzMgpwbHMyCnB0czIKcm9zMgpydXMyCnNrczIKdGhzMgp0cnMyCnVrczIKdmlyMAo_
Jul 21, 2020 22:36:07.962 [0xfffef9ffb1c0] DEBUG - Content-Length is -1 (of total: -1).
Jul 21, 2020 22:36:07.962 [0xfffef9ffb1c0] DEBUG - Request: [192.168.86.150:40052 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (16 live) GZIP
Jul 21, 2020 22:36:07.965 [0xffff417fa1c0] ERROR - Error issuing curl_easy_perform(handle): 6
Jul 21, 2020 22:36:07.965 [0xffff417fa1c0] WARN - HTTP error requesting GET https://plex.tv/users/6743338b4f443125/avatar?c=1590969953&size=216 (6, Couldn't resolve host name) (getaddrinfo() thread failed to start
Jul 21, 2020 22:36:28.035 [0xffff677fe1c0] DEBUG - Content-Length is -1 (of total: -1).
Jul 21, 2020 22:36:28.035 [0xffff677fe1c0] DEBUG - Request: [192.168.86.150:40052 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (15 live) GZIP
Jul 21, 2020 22:36:48.104 [0xffff677fe1c0] DEBUG - Content-Length is -1 (of total: -1).
Jul 21, 2020 22:36:48.104 [0xffff677fe1c0] DEBUG - Request: [192.168.86.150:40052 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (15 live) GZIP
Jul 21, 2020 22:37:08.131 [0xffff677fe1c0] DEBUG - Content-Length is -1 (of total: -1).
Jul 21, 2020 22:37:08.131 [0xffff677fe1c0] DEBUG - Request: [192.168.86.150:40052 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (15 live) GZIP
Jul 21, 2020 22:37:28.158 [0xffff677fe1c0] DEBUG - Content-Length is -1 (of total: -1).
Jul 21, 2020 22:37:28.158 [0xffff677fe1c0] DEBUG - Request: [192.168.86.150:40052 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (15 live) GZIP
Jul 21, 2020 22:37:48.291 [0xffff677fe1c0] DEBUG - Content-Length is -1 (of total: -1).
Jul 21, 2020 22:37:48.291 [0xffff677fe1c0] DEBUG - Request: [192.168.86.150:40052 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (15 live) GZIP
Jul 21, 2020 22:38:08.383 [0xffff677fe1c0] DEBUG - Content-Length is -1 (of total: -1).
Jul 21, 2020 22:38:08.383 [0xffff677fe1c0] DEBUG - Request: [192.168.86.150:40052 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (15 live) GZIP
Jul 21, 2020 22:38:25.847 [0xfffef8ff91c0] DEBUG - HTTP simulating 408 after curl timeout
Jul 21, 2020 22:38:25.847 [0xfffef8ff91c0] DEBUG - [com.plexapp.system] HTTP reply status 408, with 0 bytes of content.
Jul 21, 2020 22:38:25.847 [0xfffef8ff91c0] ERROR - Error issuing curl_easy_perform(handle): 28
Jul 21, 2020 22:38:25.847 [0xfffefa7fc1c0] DEBUG - HTTP simulating 408 after curl timeout
Jul 21, 2020 22:38:25.847 [0xfffefa7fc1c0] DEBUG - [com.plexapp.system] HTTP reply status 408, with 0 bytes of content.
Jul 21, 2020 22:38:25.847 [0xfffefa7fc1c0] ERROR - Error issuing curl_easy_perform(handle): 28
Jul 21, 2020 22:38:25.847 [0xfffefaffd1c0] DEBUG - HTTP simulating 408 after curl timeout
Jul 21, 2020 22:38:25.847 [0xfffefaffd1c0] DEBUG - [com.plexapp.system] HTTP reply status 408, with 0 bytes of content.
Jul 21, 2020 22:38:25.847 [0xfffefaffd1c0] ERROR - Error issuing curl_easy_perform(handle): 28
Jul 21, 2020 22:38:25.847 [0xffff437fe1c0] DEBUG - HTTP simulating 408 after curl timeout
Jul 21, 2020 22:38:25.847 [0xffff437fe1c0] DEBUG - [com.plexapp.system] HTTP reply status 408, with 0 bytes of content.
Jul 21, 2020 22:38:25.847 [0xffff437fe1c0] ERROR - Error issuing curl_easy_perform(handle): 28
Jul 21, 2020 22:38:27.895 [0xfffef97fa1c0] DEBUG - HTTP simulating 408 after curl timeout
Jul 21, 2020 22:38:27.895 [0xfffef97fa1c0] DEBUG - [com.plexapp.system] HTTP reply status 408, with 0 bytes of content.
Jul 21, 2020 22:38:27.895 [0xfffef97fa1c0] ERROR - Error issuing curl_easy_perform(handle): 28
Jul 21, 2020 22:38:28.409 [0xffff437fe1c0] DEBUG - Content-Length is -1 (of total: -1).
Jul 21, 2020 22:38:28.409 [0xffff437fe1c0] DEBUG - Request: [192.168.86.150:40052 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (3 live) GZIP
Jul 21, 2020 22:38:48.088 [0xffff80f7c1c0] DEBUG - Updating the list of agents known by the system.
Jul 21, 2020 22:38:48.105 [0xffff1bfff1c0] DEBUG - Stopping [com.plexapp.agents.movieposterdb].
Jul 21, 2020 22:38:48.105 [0xffff1bfff1c0] DEBUG - Stopping [com.plexapp.agents.movieposterdb].
Jul 21, 2020 22:38:48.105 [0xffff1bfff1c0] DEBUG - [com.plexapp.agents.movieposterdb] Killing.
Jul 21, 2020 22:38:48.105 [0xffff1bfff1c0] ERROR - Error received reading configuration for com.plexapp.agents.movieposterdb
Jul 21, 2020 22:38:48.105 [0xffff1bfff1c0] ERROR - Timeout or error reading status line from plug-in pipe [com.plexapp.agents.movieposterdb], we're killing it.
Jul 21, 2020 22:38:48.106 [0xffff1bfff1c0] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/WebClient.bundle.
Jul 21, 2020 22:38:48.106 [0xffff7affd1c0] DEBUG - Updating the list of agents known by the system.
Jul 21, 2020 22:38:48.107 [0xffff1bfff1c0] DEBUG - Starting plug-in /usr/lib/plexmediaserver/Resources/Plug-ins-79e214ead/Musicbrainz.bundle.
Jul 21, 2020 22:38:48.109 [0xffff1bfff1c0] DEBUG - Starting file watcher for org.musicbrainz.agents.music
Jul 21, 2020 22:38:48.109 [0xffff1bfff1c0] DEBUG - [org.musicbrainz.agents.music] Agent not known by system, so we're starting for sure.
Jul 21, 2020 22:38:48.110 [0xffff1bfff1c0] DEBUG - Plugin: not setting user token for plugin
Jul 21, 2020 22:38:48.112 [0xffff1bfff1c0] DEBUG - Spawned plug-in org.musicbrainz.agents.music with PID 12743
Jul 21, 2020 22:38:48.112 [0xffff1bfff1c0] DEBUG - [org.musicbrainz.agents.music] Sending command: GET /:/prefixes
Jul 21, 2020 22:38:48.472 [0xffff437fe1c0] DEBUG - Content-Length is -1 (of total: -1).
Jul 21, 2020 22:38:48.472 [0xffff437fe1c0] DEBUG - Request: [192.168.86.150:40052 (Subnet)] GET /player/proxy/poll?deviceClass=pc&protocolVersion=3&protocolCapabilities=timeline%2Cplayback%2Cnavigation%2Cmirror%2Cplayqueues&timeout=1 (3 live) GZIP
2

Bad OS installation or bad network configuration.

DNS lookup is not working.

Please restart PMS,
Wait 2 minutes
Stop PMS
Create a tar.gz or ZIP of the Logs directory
Attach that log file tarball / ZIP

Can’t really debug from just a snippet but it does look like network config. Full logs will allow better diagnosis.

You probably don’t have the network gateway IP properly defined.

3

I know, it feels like a network problem doesn’t it? But I can resolve plex.tv through the local resolver and through 8.8.8.8. I can even curl this forums thread while Plex is failing to resolve plex.tv.

I had to let it run longer than 2 minutes to get the “thread failed to start” message.

logs.tar.gz (28.2 KB)

neale@sweetums:~ $ sudo rm -rf /var/lib/plexmediaserver/
neale@sweetums:~ $ echo deb https://downloads.plex.tv/repo/deb public main | sudo tee /etc/apt/sources.list.d/plexmediaserver.list
deb https://downloads.plex.tv/repo/deb public main
neale@sweetums:~ $ sudo apt-get -qq update
neale@sweetums:~ $ sudo apt-get -qq install plexmediaserver
Selecting previously unselected package plexmediaserver.
(Reading database ... 99541 files and directories currently installed.)
Preparing to unpack .../plexmediaserver_1.19.5.3112-b23ab3896_arm64.deb ...
PlexMediaServer install: Pre-installation Validation.
PlexMediaServer install: Pre-installation Validation complete.
Unpacking plexmediaserver (1.19.5.3112-b23ab3896) ...
Setting up plexmediaserver (1.19.5.3112-b23ab3896) ...
PlexMediaServer install: PlexMediaServer-1.19.5.3112-b23ab3896 - Installation starting.
PlexMediaServer install: 
PlexMediaServer install: Now installing based on:
PlexMediaServer install:   Installation Type:   New
PlexMediaServer install:   Process Control:     systemd
PlexMediaServer install:   Plex User:           plex
PlexMediaServer install:   Plex Group:          plex
PlexMediaServer install:   Video Group:         video
PlexMediaServer install:   Metadata Dir:        /var/lib/plexmediaserver/Library/Application Support
PlexMediaServer install:   Temp Directory:      /tmp 
PlexMediaServer install:   Lang Encoding:       en_US.UTF-8
PlexMediaServer install:   Transcoding HW:      Not found
PlexMediaServer install: 
PlexMediaServer install: Completing final configuration.
PlexMediaServer install: PlexMediaServer-1.19.5.3112-b23ab3896 - Installation successful.  Errors: 0, Warnings: 0
Processing triggers for mime-support (3.64ubuntu1) ...
neale@sweetums:~ $ date
Wed Jul 22 08:17:30 MDT 2020
neale@sweetums:~ $ ip -4 addr
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default qlen 1000
    inet 127.0.0.1/8 scope host lo
       valid_lft forever preferred_lft forever
2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP group default qlen 1000
    inet 192.168.86.2/24 brd 192.168.86.255 scope global dynamic eth0
       valid_lft 48128sec preferred_lft 48128sec
neale@sweetums:~ $ ip -4 route
default via 192.168.86.1 dev eth0 proto dhcp src 192.168.86.2 metric 1024 
192.168.86.0/24 dev eth0 proto kernel scope link src 192.168.86.2 
192.168.86.1 dev eth0 proto dhcp scope link src 192.168.86.2 metric 1024 
neale@sweetums:~ $ host plex.tv
plex.tv has address 99.80.242.242
plex.tv has address 99.80.231.223
plex.tv has address 99.81.213.165
plex.tv mail is handled by 20 alt1.aspmx.l.google.com.
plex.tv mail is handled by 10 aspmx.l.google.com.
plex.tv mail is handled by 20 alt2.aspmx.l.google.com.
plex.tv mail is handled by 30 aspmx3.googlemail.com.
plex.tv mail is handled by 30 aspmx2.googlemail.com.
neale@sweetums:~ $ traceroute plex.tv
traceroute to plex.tv (99.80.231.223), 64 hops max
  1   192.168.86.1  0.539ms  0.573ms  0.764ms 
  2   96.120.0.149  11.797ms  8.681ms  9.229ms 
  3   68.85.65.133  10.517ms  9.537ms  10.428ms 
  4   96.108.43.25  11.213ms  10.578ms  13.755ms 
  5   162.151.13.129  12.431ms  11.810ms  11.531ms 
  6   96.110.44.25  21.592ms  24.109ms  20.729ms 
  7   96.110.38.126  21.471ms  22.552ms  20.809ms 
  8   173.167.57.142  22.268ms  38.671ms  34.943ms 
  9   62.115.120.67  40.797ms  39.196ms  40.597ms 
 10   62.115.121.231  63.432ms  44.086ms  44.134ms 
 11   *  *  * 
 12   62.115.113.21  137.023ms  136.207ms  135.537ms 
^C
neale@sweetums:~ $ curl -s https://forums.plex.tv/t/pms-doesnt-start-getaddrinfo-thread-failed-to-start/615815/2 | grep '^.p.*the.network.gateway'
<p>You probably don’t have the network gateway IP properly defined.</p>
neale@sweetums:~ $ date
Wed Jul 22 08:21:11 MDT 2020
neale@sweetums:~ $ tar czf /tmp/logs.tar.gz /var/lib/plexmediaserver/Library/Application\ Support/Plex\ Media\ Server/Logs/
tar: Removing leading `/' from member names
neale@sweetums:~ $ ls -l /tmp/logs.tar.gz 
-rw-rw-r-- 1 neale neale 28865 Jul 22 08:21 /tmp/logs.tar.gz
neale@sweetums:~ $ md5sum /tmp/logs.tar.gz 
6297a48d21ee41bffd546ae4dfa5d6d2  /tmp/logs.tar.gz
4

FIXED

There was a Python service failing in a different log file. Tracing this down, it appears a component of Plex needs to communicate with itself.

A poorly configured firewall (iptables configuration) was the culprit: it was denying connections to localhost.

I don’t understand why this manifested as “thread failed to start”, but Plex Media Server is working fine now that I set the INPUT policy to ACCEPT:

sudo iptables -P INPUT ACCEPT
5

You are correct. Plex must be able to talk to itself on the loopback adapter.
Sub processes and the server itself use 127.0.0.1

6

This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.