Plex no longer downloads original quality

Plex Players Desktop Players
1

Server Version#: 1.24.5.5173
Player Version#: 1.37.0.2706-5b2cfe50

I seem to have lost the ability to download in original quality after updating to the latest player. The original is 1080p and the downloaded version is 408p. I wanted to revert to the previous version of the player to confirm it is related to the update, but can’t find a download location for earlier versions.

As a temporary fix I have selected ‘Disable Video Stream Transcoding’ on the server, but that is not how it used to work. It seems the server is not respecting the player’s specification of ‘original quality’.

2

IIRC Disable Video Stream Transcoding has nothing to do with downloads. Check Settings > Plex for Mac > Downloads > Video Quality instead… if you want to download in original quality, this should be set to Original.

Edit:
Just tested downloading a 1080p movie and got a full size / quality result.

3

Thanks for responding so quickly. I didn’t explain myself clearly enough. Settings > Plex for Mac > Downloads > Video Quality IS set to Original . That’s what I meant when I said the server is not respecting the setting in the player. To recap the Player ‘Video Quality’ is set to ‘Original’ and yet a 1080p recording is being transcoded to 408p when I request its download.

4

Works like a charm for me…
Please create a new download and share the server logs and the logs from your Plex app (~/Library/Logs/Plex/)

5

Hmnn… This is tricky. After the download failed I watched the recording on my Smart TV (LG WebOS Player). I was able to watch it at full resolution.

When I go to create a new download it behaves as expected.

Possible clues. I restarted the server just before starting the download. I tend to do this because I seem to get a faster download after a server restart.

I have been through the server and player logs and below is the information relating to the first download initiated. I can see references to bandwidth limits and that the true bandwidth is different to the limit and selection of 720x404. The server upload speed setting is 5Mbps. Could it be that the download started with a default of 2Mbps before it had time to read the upload speed setting?

Anyhow, this is what’s in the logs:

Player:

Nov 14, 2021 08:03:43.655 [0x10db9ddc0] INFO - [Web] [Connections] Active connection to Mac mini is http://86.161.54.153:28415
Nov 14, 2021 08:03:43.656 [0x10db9ddc0] INFO - [Web] [Servers] Found all servers = Mac mini, Port Soller, plex.tv
Nov 14, 2021 08:03:43.685 [0x10db9ddc0] INFO - [Web] [MediaServerEventManager] Opening server event connection to Mac mini at ws://86.161.54.153:28415/:/websockets/notifications?X-Plex-Token=xxxxxxxx
Nov 14, 2021 08:03:43.702 [0x10db9ddc0] INFO - [Web] [Connections] Mac mini connected at https://86-161-54-153.953a4b08543f4c80921d82d2234e2ce5.plex.direct:28415/media/providers
Nov 14, 2021 08:03:43.706 [0x10db9ddc0] INFO - [Web] [Connections] Active connection to Mac mini is https://86-161-54-153.953a4b08543f4c80921d82d2234e2ce5.plex.direct:28415
Nov 14, 2021 08:03:43.785 [0x10db9ddc0] INFO - [Web] [MediaServerEventManager] Opened server event connection to Mac mini at ws://86.161.54.153:28415/:/websockets/notifications?X-Plex-Token=xxxxxxxx
Nov 14, 2021 08:03:43.815 [0x10db9ddc0] INFO - [Web] [Downloads] Update connection server://bb96d25b520dba5cc7fc2b4ae0dfc3867337fccd/com.plexapp.plugins.library PUT {
  "auth_token": "REDACTED",
  "connectionType": "remote",
  "url": "https://86-161-54-153.953a4b08543f4c80921d82d2234e2ce5.plex.direct:28415"
}
Nov 14, 2021 08:03:43.855 [0x10db9ddc0] INFO - [Web] [Metrics] Updating session last interaction time
Nov 14, 2021 08:03:44.243 [0x10db9ddc0] INFO - [Web] [Connections] Mac mini connected at https://88-80-190-127.953a4b08543f4c80921d82d2234e2ce5.plex.direct:8443/media/providers
Nov 14, 2021 08:03:50.733 [0x10db9ddc0] INFO - [Web] [Servers] Finding resources through plex.tv
Nov 14, 2021 08:03:51.009 [0x10db9ddc0] INFO - [Web] [Connections] Added connection https://178-79-176-52.953a4b08543f4c80921d82d2234e2ce5.plex.direct:8443 for Mac mini
Nov 14, 2021 08:03:51.011 [0x10db9ddc0] INFO - [Web] [Connections] Removed connection https://88-80-190-127.953a4b08543f4c80921d82d2234e2ce5.plex.direct:8443 from Mac mini
Nov 14, 2021 08:03:51.011 [0x10db9ddc0] INFO - [Web] [Commands] Executing abortServerConnectionTest
Nov 14, 2021 08:03:51.011 [0x10db9ddc0] INFO - [Web] [Servers] Waiting for additional resources from plex.tv
Nov 14, 2021 08:03:51.019 [0x10db9ddc0] INFO - [Web] [Servers] Found 6 resources through plex.tv
Nov 14, 2021 08:03:51.020 [0x10db9ddc0] INFO - [Web] [Servers] Found all servers = Mac mini, Port Soller, plex.tv
Nov 14, 2021 08:03:51.141 [0x10db9ddc0] INFO - [Web] [Companion] Opening long poll to Port Soller at http://127.0.0.1:32400/player/proxy/poll
Nov 14, 2021 08:03:51.384 [0x10db9ddc0] INFO - [Web] [Downloads] Creating decision: {
  "decisionID": "a47e525c2ed2f7b73d374400fe61acd5e4cb6f08",
  "source": "server://bb96d25b520dba5cc7fc2b4ae0dfc3867337fccd/com.plexapp.plugins.library",
  "key": "/library/metadata/12940",
  "sourceURI": "server://bb96d25b520dba5cc7fc2b4ae0dfc3867337fccd/com.plexapp.plugins.library/library/metadata/12940",
  "extraParams": {
    "context": "static",
    "offlineTranscode": "1",
    "session": "be7506a5138eb99fda970ab5a067aa13e54df128"
  }
}
Nov 14, 2021 08:03:51.478 [0x10db9ddc0] INFO - [Web] [MDE] Augmented profile: {
  "directPlay": {
    "3gpp": {
      "video": {
        "maxBitrate": 2000
      }
    },
    "asf": {
      "video": {
        "maxBitrate": 2000
      }
    },
    "avi": {
      "video": {
        "maxBitrate": 2000
      }
    },
    "mkv": {
      "video": {
        "maxBitrate": 2000
      }
    },
    "mov": {
      "video": {
        "maxBitrate": 2000
      }
    },
    "mp4": {
      "video": {
        "maxBitrate": 2000
      }
    },
    "mpeg": {
      "video": {
        "maxBitrate": 2000
      }
    },
    "mpegts": {
      "video": {
        "maxBitrate": 2000
      }
    },
    "rm": {
      "video": {
        "maxBitrate": 2000
      }
    },
    "wtv": {
      "video": {
        "maxBitrate": 2000
      }
    }
  },
  "directStream": {
    "video": {
      "maxBitrate": 2000
    }
  }
}
Nov 14, 2021 08:03:51.478 [0x10db9ddc0] INFO - [Web] [MDE] Starting analysis of 1080 (mpegts, h264, aac_latm, 40, high)
Nov 14, 2021 08:03:51.478 [0x10db9ddc0] INFO - [Web] [MDE] Analyzing direct play
Nov 14, 2021 08:03:51.479 [0x10db9ddc0] ERROR - [Web] [MDE] Invalid profile property; bitrate: 5772 > 2000
Nov 14, 2021 08:03:51.479 [0x10db9ddc0] INFO - [Web] [MDE] Cannot direct play: validateProfileProperty:maxBitrate
Nov 14, 2021 08:03:51.479 [0x10db9ddc0] INFO - [Web] [MDE] Analyzing video direct stream
Nov 14, 2021 08:03:51.479 [0x10db9ddc0] ERROR - [Web] [MDE] Invalid profile property; bitrate: 5772 > 2000
Nov 14, 2021 08:03:51.479 [0x10db9ddc0] INFO - [Web] [MDE] Analyzing audio direct stream
Nov 14, 2021 08:03:51.480 [0x10db9ddc0] INFO - [Web] [MDE] Analyzing playability
Nov 14, 2021 08:03:51.480 [0x10db9ddc0] INFO - [Web] [MDE] Finished analysis of: 1080 (mpegts, h264, aac_latm, 40, high) {
  "canPlay": true,
  "canDirectPlay": false,
  "canDirectStreamVideo": false,
  "canDirectStreamAudio": true,
  "bitrate": 2000,
  "videoResolution": 1080
}
Nov 14, 2021 08:03:51.481 [0x10db9ddc0] INFO - [Web] [PDE] Player decision: {
  "playerType": "html",
  "protocol": "http",
  "canDirectPlay": false
}
Nov 14, 2021 08:03:51.481 [0x10db9ddc0] INFO - [Web] [Transcoder] Video (start) options: {
  "hasMDE": 1,
  "path": "/library/metadata/12940",
  "mediaIndex": 0,
  "partIndex": 0,
  "protocol": "http",
  "fastSeek": 1,
  "directPlay": 0,
  "directStream": 1,
  "subtitleSize": 100,
  "audioBoost": 100,
  "location": "wan",
  "maxVideoBitrate": 2000,
  "addDebugOverlay": 0,
  "autoAdjustQuality": 0,
  "directStreamAudio": 1,
  "advancedSubtitles": "text",
  "X-Plex-Session-Identifier": "4xgaognlqt6ethxb24zmae6i",
  "session": "be7506a5138eb99fda970ab5a067aa13e54df128",
  "offlineTranscode": "1",
  "context": "static",
  "offset": 0,
  "subtitles": "auto",
  "copyts": 1,
  "X-Plex-Chunked": 1,
  "X-Plex-Incomplete-Segments": 1
}
Nov 14, 2021 08:03:51.483 [0x10db9ddc0] INFO - [Web] [Transcoder] Video (decision) options: {
  "hasMDE": 1,
  "path": "/library/metadata/12940",
  "mediaIndex": 0,
  "partIndex": 0,
  "protocol": "http",
  "fastSeek": 1,
  "directPlay": 0,
  "directStream": 1,
  "subtitleSize": 100,
  "audioBoost": 100,
  "location": "wan",
  "maxVideoBitrate": 2000,
  "addDebugOverlay": 0,
  "autoAdjustQuality": 0,
  "directStreamAudio": 1,
  "advancedSubtitles": "text",
  "X-Plex-Session-Identifier": "4xgaognlqt6ethxb24zmae6i",
  "session": "be7506a5138eb99fda970ab5a067aa13e54df128",
  "offlineTranscode": "1",
  "context": "static",
  "offset": 0,
  "subtitles": "auto",
  "copyts": 1,
  "X-Plex-Chunked": 1,
  "X-Plex-Incomplete-Segments": 1
}
Nov 14, 2021 08:03:52.080 [0x10db9ddc0] INFO - [Web] [Downloads] Sending decision to nano: {
  "decisionID": "a47e525c2ed2f7b73d374400fe61acd5e4cb6f08",
  "source": "server://bb96d25b520dba5cc7fc2b4ae0dfc3867337fccd/com.plexapp.plugins.library",
  "key": "/library/metadata/12940",
  "sourceURI": "server://bb96d25b520dba5cc7fc2b4ae0dfc3867337fccd/com.plexapp.plugins.library/library/metadata/12940",
  "extraParams": {
    "context": "static",
    "offlineTranscode": "1",
    "session": "be7506a5138eb99fda970ab5a067aa13e54df128"
  }
}

Nov 14, 2021 08:03:52.081 [0x10db9ddc0] ERROR - [Web] Sending POST request with Content-Type "text/xml". This can result in an unnecessary OPTIONS preflight request.
Nov 14, 2021 08:03:56.147 [0x10db9ddc0] INFO - [Web] [Companion] Poll connection successfully opened with Port Soller
Nov 14, 2021 08:04:05.133 [0x10db9ddc0] INFO - [Web] [Metrics] Updating session last interaction time
Nov 14, 2021 08:04:06.958 [0x10db9ddc0] ERROR - [Web] ResizeObserver loop limit exceeded
Nov 14, 2021 08:04:11.146 [0x10db9ddc0] INFO - [Web] [Companion] Opening long poll to Port Soller at http://127.0.0.1:32400/player/proxy/poll
Nov 14, 2021 08:04:16.152 [0x10db9ddc0] INFO - [Web] [Companion] Poll connection successfully opened with Port Soller
Nov 14, 2021 08:04:25.025 [0x10db9ddc0] ERROR - [Web] A cookie associated with a cross-site resource at http://86-161-54-153.953a4b08543f4c80921d82d2234e2ce5.plex.direct/ was set without the `SameSite` attribute. A future release of Chrome will only deliver cookies with cross-site requests if they are set with `SameSite=None` and `Secure`. You can review cookies in developer tools under Application>Storage>Cookies and see more details at https://www.chromestatus.com/feature/5088147346030592 and https://www.chromestatus.com/feature/5633521622188032.
Nov 14, 2021 08:04:25.083 [0x10db9ddc0] INFO - [Web] [Metrics] Updating session last interaction time
Nov 14, 2021 08:04:31.157 [0x10db9ddc0] INFO - [Web] [Companion] Opening long poll to Port Soller at http://127.0.0.1:32400/player/proxy/poll
Nov 14, 2021 08:04:34.715 [0x10db9ddc0] INFO - [Web] [Metrics] Updating session last interaction time
Nov 14, 2021 08:04:36.152 [0x10db9ddc0] INFO - [Web] [Companion] Poll connection successfully opened with Port Soller
Nov 14, 2021 08:04:47.056 [0x10db9ddc0] INFO - [Web] [Metrics] Updating session last interaction time
Nov 14, 2021 08:04:50.653 [0x10db9ddc0] INFO - [Web] [Metrics] Updating session last interaction time
Nov 14, 2021 08:04:51.163 [0x10db9ddc0] INFO - [Web] [Companion] Opening long poll to Port Soller at http://127.0.0.1:32400/player/proxy/poll
Nov 14, 2021 08:04:56.168 [0x10db9ddc0] INFO - [Web] [Companion] Poll connection successfully opened with Port Soller
Nov 14, 2021 08:05:11.174 [0x10db9ddc0] INFO - [Web] [Companion] Opening long poll to Port Soller at http://127.0.0.1:32400/player/proxy/poll
Nov 14, 2021 08:05:16.175 [0x10db9ddc0] INFO - [Web] [Companion] Poll connection successfully opened with Port Soller
Nov 14, 2021 08:05:29.015 [0x10db9ddc0] INFO - [Web] [Metrics] Updating session last interaction time

Server:

Nov 14, 2021 07:03:51.495 [0x30e8ef000] DEBUG - Request: [37.98.200.39:19814 (WAN)] GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F12940&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=wan&maxVideoBitrate=2000&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&advancedSubtitles=text&session=be7506a5138eb99fda970ab5a067aa13e54df128&offlineTranscode=1&context=static&offset=0&subtitles=auto&copyts=1&Accept-Language=en (14 live) TLS GZIP Signed-in Token (john@norman-baldor.org)
Nov 14, 2021 07:03:51.498 [0x30e8ef000] DEBUG - [Transcode] Found session GUID of be7506a5138eb99fda970ab5a067aa13e54df128 in session start.
Nov 14, 2021 07:03:51.500 [0x30e8ef000] DEBUG - [Transcode] TranscodeUniversalRequest: using profile Plex Desktop
Nov 14, 2021 07:03:51.500 [0x30e8ef000] DEBUG - [Transcode] Downloading document http://127.0.0.1:32400/library/metadata/12940?includeBandwidths=1&offset=0&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=4xgaognlqt6ethxb24zmae6i
Nov 14, 2021 07:03:51.513 [0x30e8ef000] DEBUG - [Transcode] We're going to try to auto-select an audio stream for account 1.
Nov 14, 2021 07:03:51.513 [0x30e8ef000] DEBUG - [Transcode] Selecting best audio stream for part ID 22770 (autoselect: 0 language: en)
Nov 14, 2021 07:03:51.513 [0x30e8ef000] DEBUG - [Transcode] Audio Stream: 52829, Subtitle Stream: -1
Nov 14, 2021 07:03:51.517 [0x30e8ef000] DEBUG - [Transcode] MDE: Selected protocol http; container: mkv
Nov 14, 2021 07:03:51.517 [0x30e8ef000] DEBUG - [Transcode] MDE: analyzing media item 22769
Nov 14, 2021 07:03:51.517 [0x30e8ef000] DEBUG - [Transcode] MDE: Formula 1 Sao Paulo Grand Prix Sprint Qualifying Highlights: Direct Play is disabled
Nov 14, 2021 07:03:51.517 [0x30e8ef000] DEBUG - [Transcode] MDE: Formula 1 Sao Paulo Grand Prix Sprint Qualifying Highlights: no direct play video profile exists for http/mpegts/h264
Nov 14, 2021 07:03:51.517 [0x30e8ef000] DEBUG - [Transcode] MDE: Formula 1 Sao Paulo Grand Prix Sprint Qualifying Highlights: no direct play video profile exists for http/mpegts/h264/aac_latm
Nov 14, 2021 07:03:51.517 [0x30e8ef000] DEBUG - [Transcode] MDE: Formula 1 Sao Paulo Grand Prix Sprint Qualifying Highlights: non-selected embedded subtitle stream 52830 cannot be direct-played
Nov 14, 2021 07:03:51.517 [0x30e8ef000] DEBUG - [Transcode] MDE: Formula 1 Motor Racing - Formula 1 Sao Paulo Grand Prix Sprint Qualifying Highlights: selected media 0 / 22769
Nov 14, 2021 07:03:51.517 [0x30e8ef000] DEBUG - [Transcode] Streaming Resource: Calculated bandwidth of 5930kbps exceeds bandwidth limit. Changing decision parameters provided by client to fit bandwidth limit of 2000kbps
Nov 14, 2021 07:03:51.517 [0x30e8ef000] DEBUG - [Transcode] Streaming Resource: Determining preferred transcode encoders through transcode only decision.
Nov 14, 2021 07:03:51.517 [0x30e8ef000] DEBUG - [Transcode] Scaled up video bitrate to 8658Kbps based on 1.500000x fudge factor.
Nov 14, 2021 07:03:51.518 [0x30e8ef000] DEBUG - [Transcode] Scaled up video bitrate to 8658Kbps based on 1.500000x fudge factor.
Nov 14, 2021 07:03:51.518 [0x30e8ef000] DEBUG - [Transcode] Streaming Resource: Reducing playback quality for 1904kbps stream bitrate: video resolution to 720x406, audio channels to 2, quality to 41, disable video DS as 8706kbps is > the 1724kbps available
Nov 14, 2021 07:03:51.519 [0x30e8ef000] DEBUG - [Transcode] Scaled up video bitrate to 8658Kbps based on 1.500000x fudge factor.
Nov 14, 2021 07:03:51.519 [0x30e8ef000] DEBUG - [Transcode] Scaled maximum bitrate for resolution reduction to 1214Kbps.
Nov 14, 2021 07:03:51.519 [0x30e8ef000] DEBUG - [Transcode] MDE: Selected protocol http; container: mkv
Nov 14, 2021 07:03:51.519 [0x30e8ef000] DEBUG - [Transcode] MDE: analyzing media item 22769
Nov 14, 2021 07:03:51.519 [0x30e8ef000] DEBUG - [Transcode] MDE: Formula 1 Sao Paulo Grand Prix Sprint Qualifying Highlights: Direct Play is disabled
Nov 14, 2021 07:03:51.519 [0x30e8ef000] DEBUG - [Transcode] MDE: Formula 1 Sao Paulo Grand Prix Sprint Qualifying Highlights: no direct play video profile exists for http/mpegts/h264
Nov 14, 2021 07:03:51.519 [0x30e8ef000] DEBUG - [Transcode] MDE: Formula 1 Sao Paulo Grand Prix Sprint Qualifying Highlights: no direct play video profile exists for http/mpegts/h264/aac_latm
Nov 14, 2021 07:03:51.519 [0x30e8ef000] DEBUG - [Transcode] MDE: Formula 1 Sao Paulo Grand Prix Sprint Qualifying Highlights: non-selected embedded subtitle stream 52830 cannot be direct-played
Nov 14, 2021 07:03:51.519 [0x30e8ef000] DEBUG - [Transcode] Formula 1 Sao Paulo Grand Prix Sprint Qualifying Highlights - video.width limitation applies: 1920 > 720
Nov 14, 2021 07:03:51.519 [0x30e8ef000] DEBUG - [Transcode] MDE: Formula 1 Sao Paulo Grand Prix Sprint Qualifying Highlights: Direct Streaming is disabled, so video stream will be transcoded
Nov 14, 2021 07:03:51.519 [0x30e8ef000] DEBUG - [Transcode] MDE: Formula 1 Sao Paulo Grand Prix Sprint Qualifying Highlights: no remuxable profile found, so video stream will be transcoded
Nov 14, 2021 07:03:51.519 [0x30e8ef000] DEBUG - [Transcode] MDE: Cannot direct stream video stream due to profile or setting limitations
Nov 14, 2021 07:03:51.519 [0x30e8ef000] DEBUG - [Transcode] Scaled up video bitrate to 8658Kbps based on 1.500000x fudge factor.
Nov 14, 2021 07:03:51.519 [0x30e8ef000] DEBUG - [Transcode] Scaled maximum bitrate for resolution reduction to 1214Kbps.
Nov 14, 2021 07:03:51.519 [0x30e8ef000] DEBUG - [Transcode] MDE: Formula 1 Motor Racing - Formula 1 Sao Paulo Grand Prix Sprint Qualifying Highlights: selected media 0 / 22769
Nov 14, 2021 07:03:51.519 [0x30e8ef000] DEBUG - [Transcode] Streaming Resource: Adding session 0x7ff9e1007938:4xgaognlqt6ethxb24zmae6i which is using 0kbps of WAN bandwidth.  Used is now 0kbps
Nov 14, 2021 07:03:51.519 [0x30e8ef000] DEBUG - [Transcode] Streaming Resource: Added session 0x7ff9e1007938:4xgaognlqt6ethxb24zmae6i
Nov 14, 2021 07:03:51.521 [0x30e8ef000] DEBUG - [Transcode] Cleaning directory for session be7506a5138eb99fda970ab5a067aa13e54df128 ()
Nov 14, 2021 07:03:51.521 [0x30e8ef000] DEBUG - [Transcode] Starting a transcode session be7506a5138eb99fda970ab5a067aa13e54df128 at offset -1.0 (state=3)
Nov 14, 2021 07:03:51.521 [0x30e8ef000] DEBUG - [Transcode] Streaming Resource: Added session 0x7ffa104f2588:be7506a5138eb99fda970ab5a067aa13e54df128
Nov 14, 2021 07:03:51.523 [0x30e8ef000] DEBUG - [Transcode] [Universal] Using local file path instead of URL: /Volumes/LaCie TV/TV Shows/Formula 1 Motor Racing (2011)/Season 2021/Formula 1 Motor Racing (2011) - 2021-11-13 12 00 00 - Formula 1 Sao Paulo Grand Prix Sprint Qualifying Highlights.ts
Nov 14, 2021 07:03:51.524 [0x30e8ef000] DEBUG - [Transcode/JobRunner] Job running: FFMPEG_EXTERNAL_LIBS='/Users/jrnorman/Library/Application\ Support/Plex\ Media\ Server/Codecs/be22e26-4019-darwin-x86_64/' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/Applications/Plex Media Server.app/Contents/MacOS/Plex Transcoder' '-codec:#0x100' 'h264' '-analyzeduration' '20000000' '-probesize' '20000000' '-i' '/Volumes/LaCie TV/TV Shows/Formula 1 Motor Racing (2011)/Season 2021/Formula 1 Motor Racing (2011) - 2021-11-13 12 00 00 - Formula 1 Sao Paulo Grand Prix Sprint Qualifying Highlights.ts' '-filter_complex' '[0:#0x100]scale=w=720:h=404[0];[0]format=pix_fmts=yuv420p|nv12[1]' '-map' '[1]' '-codec:0' 'libx264' '-crf:0' '21' '-maxrate:0' '1214k' '-bufsize:0' '2428k' '-r:0' '25' '-preset:0' 'medium' '-x264opts:0' 'subme=2:me_range=4:rc_lookahead=20:me=hex' '-map' '0:#0x101' '-metadata:s:1' 'language=eng' '-codec:1' 'copy' '-copypriorss:1' '0' '-map' '0:#0x102' '-metadata:s:2' 'language=eng' '-codec:2' 'copy' '-f' 'matroska' '-avoid_negative_ts' 'disabled' '-map_metadata' '-1' '-map_chapters' '-1' 'Output.mkv' '-start_at_zero' '-copyts' '-y' '-nostats' '-loglevel' 'quiet' '-loglevel_plex' 'error' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress'
Nov 14, 2021 07:03:51.525 [0x30e8ef000] DEBUG - [Transcode/JobRunner] Jobs: Starting child process with pid 29948
Nov 14, 2021 07:03:51.572 [0x30e86c000] DEBUG - Request: [127.0.0.1:65330 (Loopback)] PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress?status=startup (15 live) Signed-in Token (john@norman-baldor.org) (range: bytes=0-) 
Nov 14, 2021 07:03:51.572 [0x30e142000] DEBUG - Completed: [127.0.0.1:65330] 204 PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress?status=startup (15 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-) 
Nov 14, 2021 07:03:51.582 [0x30e972000] DEBUG - Request: [127.0.0.1:65330 (Loopback)] PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress?status=startup (15 live) Signed-in Token (john@norman-baldor.org) (range: bytes=0-) 
Nov 14, 2021 07:03:51.582 [0x30e1c5000] DEBUG - Completed: [127.0.0.1:65330] 204 PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress?status=startup (15 live) 0ms 203 bytes (pipelined: 2) (range: bytes=0-) 
Nov 14, 2021 07:03:51.583 [0x30e86c000] DEBUG - Request: [127.0.0.1:65330 (Loopback)] PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress?status=opening (15 live) Signed-in Token (john@norman-baldor.org) (range: bytes=0-) 
Nov 14, 2021 07:03:51.583 [0x30e142000] DEBUG - Completed: [127.0.0.1:65330] 204 PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress?status=opening (15 live) 0ms 203 bytes (pipelined: 3) (range: bytes=0-) 
Nov 14, 2021 07:03:51.625 [0x30e972000] DEBUG - Request: [127.0.0.1:65330 (Loopback)] PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress?status=opened (15 live) Signed-in Token (john@norman-baldor.org) (range: bytes=0-) 
Nov 14, 2021 07:03:51.625 [0x30e1c5000] DEBUG - Completed: [127.0.0.1:65330] 204 PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress?status=opened (15 live) 0ms 203 bytes (pipelined: 4) (range: bytes=0-) 
Nov 14, 2021 07:03:51.626 [0x30e86c000] DEBUG - Request: [127.0.0.1:65330 (Loopback)] PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress/stream?index=0&id=256&codec=h264&type=video (15 live) Signed-in Token (john@norman-baldor.org) (range: bytes=0-) 
Nov 14, 2021 07:03:51.626 [0x30e142000] DEBUG - Completed: [127.0.0.1:65330] 200 PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress/stream?index=0&id=256&codec=h264&type=video (15 live) 0ms 195 bytes (pipelined: 5) (range: bytes=0-) 
Nov 14, 2021 07:03:51.662 [0x30e972000] ERROR - [Transcoder] [h264 @ 0x7f93ec013400] mmco: unref short failure
Nov 14, 2021 07:03:51.710 [0x30e86c000] ERROR - [Transcoder] [h264 @ 0x7f93ec013400] mmco: unref short failure
Nov 14, 2021 07:03:52.020 [0x30e972000] DEBUG - Request: [127.0.0.1:65330 (Loopback)] PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress/stream?index=1&id=257&codec=aac_latm&type=audio&profile=LC (15 live) Signed-in Token (john@norman-baldor.org) (range: bytes=0-) 
Nov 14, 2021 07:03:52.020 [0x30e1c5000] DEBUG - Completed: [127.0.0.1:65330] 200 PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress/stream?index=1&id=257&codec=aac_latm&type=audio&profile=LC (15 live) 0ms 195 bytes (pipelined: 8) (range: bytes=0-) 
Nov 14, 2021 07:03:52.021 [0x30e86c000] DEBUG - Request: [127.0.0.1:65330 (Loopback)] PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress/streamDetail?index=0&id=256&codec=h264&type=video&profile=High&width=1920&height=1080&interlaced=0&sar=1:1&level=40&frameRate=25.000 (15 live) Signed-in Token (john@norman-baldor.org) (range: bytes=0-) 
Nov 14, 2021 07:03:52.021 [0x30e142000] DEBUG - Completed: [127.0.0.1:65330] 200 PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress/streamDetail?index=0&id=256&codec=h264&type=video&profile=High&width=1920&height=1080&interlaced=0&sar=1:1&level=40&frameRate=25.000 (15 live) 0ms 195 bytes (pipelined: 9) (range: bytes=0-) 
Nov 14, 2021 07:03:52.021 [0x30e972000] DEBUG - Request: [127.0.0.1:65330 (Loopback)] PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress/streamDetail?index=1&id=257&codec=aac_latm&type=audio&profile=LC&language=eng&channels=2&layout=stereo&sampleRate=48000 (15 live) Signed-in Token (john@norman-baldor.org) (range: bytes=0-) 
Nov 14, 2021 07:03:52.021 [0x30e1c5000] DEBUG - Completed: [127.0.0.1:65330] 200 PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress/streamDetail?index=1&id=257&codec=aac_latm&type=audio&profile=LC&language=eng&channels=2&layout=stereo&sampleRate=48000 (15 live) 0ms 195 bytes (pipelined: 10) (range: bytes=0-) 
Nov 14, 2021 07:03:52.022 [0x30e86c000] DEBUG - Request: [127.0.0.1:65330 (Loopback)] PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress/streamDetail?index=2&id=258&codec=dvb_subtitle&type=subtitle&language=eng (15 live) Signed-in Token (john@norman-baldor.org) (range: bytes=0-) 
Nov 14, 2021 07:03:52.022 [0x30e142000] DEBUG - Completed: [127.0.0.1:65330] 200 PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress/streamDetail?index=2&id=258&codec=dvb_subtitle&type=subtitle&language=eng (15 live) 0ms 195 bytes (pipelined: 11) (range: bytes=0-) 
Nov 14, 2021 07:03:52.024 [0x30e972000] DEBUG - Request: [127.0.0.1:65330 (Loopback)] PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress?duration=5396.602056 (15 live) Signed-in Token (john@norman-baldor.org) (range: bytes=0-) 
Nov 14, 2021 07:03:52.024 [0x30e1c5000] DEBUG - Completed: [127.0.0.1:65330] 204 PUT /video/:/transcode/session/be7506a5138eb99fda970ab5a067aa13e54df128/500e2347-7c19-49e8-9742-c6e44b24062d/progress?duration=5396.602056 (15 live) 0ms 203 bytes (pipelined: 12) (range: bytes=0-) 
Nov 14, 2021 07:03:52.024 [0x30e8ef000] DEBUG - [Transcode] Using default transcode duration of 120 minutes
Nov 14, 2021 07:03:52.024 [0x30e8ef000] DEBUG - [Transcode] Started session successfully: be7506a5138eb99fda970ab5a067aa13e54df128
Nov 14, 2021 07:03:52.025 [0x30e8ef000] DEBUG - [Transcode] Activity: registered new activity cad8ee89-4032-4616-9623-bb4f608ccee5 - ""
Nov 14, 2021 07:03:52.025 [0x30e8ef000] DEBUG - [Transcode] Activity: updated activity cad8ee89-4032-4616-9623-bb4f608ccee5 - completed -1.0% - Transcoding Media for john@norman-baldor.org
Nov 14, 2021 07:03:52.025 [0x30e8ef000] DEBUG - [Transcode] Streaming Resource: Reached Decision id=12940 codes=(General=1001,Direct play not available; Conversion OK. Direct Play=3000,App cannot direct play this item. Direct play is disabled. Transcode=1001,Direct play not available; Conversion OK.) media=(id=22769 part=(id=22770 decision=transcode container=mkv protocol=http streams=(Video=(id=52828 decision=transcode bitrate=1214 encoder=libx264 width=720 height=404) Audio=(id=52829 decision=copy channels=2 rate=48000))))
Nov 14, 2021 07:03:52.026 [0x30e8ef000] WARN - Caught exception trying to convert extra data attribute 'originallyAvailableAt' with type 1 and value '2021-11-13' ~ bad lexical cast: source type value could not be interpreted as target
Nov 14, 2021 07:03:52.028 [0x30e1c5000] DEBUG - Completed: [37.98.200.39:19814] 200 GET /video/:/transcode/universal/decision?hasMDE=1&path=%2Flibrary%2Fmetadata%2F12940&mediaIndex=0&partIndex=0&protocol=http&fastSeek=1&directPlay=0&directStream=1&subtitleSize=100&audioBoost=100&location=wan&maxVideoBitrate=2000&addDebugOverlay=0&autoAdjustQuality=0&directStreamAudio=1&advancedSubtitles=text&session=be7506a5138eb99fda970ab5a067aa13e54df128&offlineTranscode=1&context=static&offset=0&subtitles=auto&copyts=1&Accept-Language=en (15 live) TLS GZIP 532ms 1815 bytes (pipelined: 3)
Nov 14, 2021 07:03:52.032 [0x30e86c000] ERROR - [Transcoder] [h264 @ 0x7f93ec043a00] mmco: unref short failure
Nov 14, 2021 07:03:52.035 [0x30f32b000] ERROR - Error issuing curl_easy_perform(handle): 28
Nov 14, 2021 07:03:52.035 [0x30f32b000] DEBUG - HTTP simulating 408 after curl timeout
Nov 14, 2021 07:03:52.051 [0x30e972000] ERROR - [Transcoder] [h264 @ 0x7f93ec043400] mmco: unref short failure
Nov 14, 2021 07:03:52.070 [0x30e8ef000] DEBUG - Request: [37.98.200.39:19813 (WAN)] GET /status/sessions/background (15 live) TLS GZIP Signed-in Token (john@norman-baldor.org)
Nov 14, 2021 07:03:52.070 [0x30e142000] DEBUG - Completed: [37.98.200.39:19813] 200 GET /status/sessions/background (15 live) TLS GZIP 0ms 397 bytes (pipelined: 2)
Nov 14, 2021 07:03:52.079 [0x30e86c000] DEBUG - Request: [37.98.200.39:19815 (WAN)] GET /video/:/transcode/universal/start.mkv?session=be7506a5138eb99fda970ab5a067aa13e54df128 (15 live) TLS GZIP Signed-in Token (john@norman-baldor.org)
Nov 14, 2021 07:03:52.079 [0x30e86c000] DEBUG - [Transcode] Found session GUID of be7506a5138eb99fda970ab5a067aa13e54df128 in session start.
Nov 14, 2021 07:03:52.079 [0x30e86c000] DEBUG - [Transcode] Using existing transcode session.
Nov 14, 2021 07:03:52.079 [0x30e86c000] DEBUG - [Transcode] Activity: registered new activity a9577ef5-2065-4714-83f6-030fed054a47 - ""
Nov 14, 2021 07:03:52.080 [0x30e86c000] DEBUG - [Transcode] Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed -1.0% - Media download by john@norman-baldor.org
Nov 14, 2021 07:03:52.080 [0x30e86c000] DEBUG - Content-Length is -1 (of total: -1).
Nov 14, 2021 07:03:52.087 [0x30dc91000] DEBUG - [TranscodeOutputStream] Input processing thread started at offset 0 for -1 bytes.
Nov 14, 2021 07:03:52.188 [0x30dc91000] DEBUG - [TranscodeOutputStream] Input processed first bytes after 101 ms.
Nov 14, 2021 07:03:52.189 [0x30e1c5000] DEBUG - [TranscodeOutputStream] Sent back first bytes after 109 ms.
Nov 14, 2021 07:03:52.537 [0x30e972000] DEBUG - [Transcode] Streaming Resource: Unthrottling session 0x7ffa104f2588:be7506a5138eb99fda970ab5a067aa13e54df128 which is using static transcoder slot.  Used slots is now 1
Nov 14, 2021 07:03:52.537 [0x30e972000] DEBUG - [Transcode] Transcoder: session be7506a5138eb99fda970ab5a067aa13e54df128 indicated fallback to software decoding
Nov 14, 2021 07:03:52.621 [0x30e142000] DEBUG - Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed 0.1% - Media download by john@norman-baldor.org
Nov 14, 2021 07:03:52.749 [0x30e8ef000] DEBUG - Request: [37.98.200.39:19813 (WAN)] GET /status/sessions/background (15 live) TLS GZIP Signed-in Token (john@norman-baldor.org)
Nov 14, 2021 07:03:52.749 [0x30e1c5000] DEBUG - Completed: [37.98.200.39:19813] 200 GET /status/sessions/background (15 live) TLS GZIP 0ms 397 bytes (pipelined: 3)
Nov 14, 2021 07:03:52.792 [0x30e142000] DEBUG - Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed 0.1% - Media download by john@norman-baldor.org
Nov 14, 2021 07:03:52.920 [0x30e142000] DEBUG - Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed 0.2% - Media download by john@norman-baldor.org
Nov 14, 2021 07:03:53.039 [0x30e142000] DEBUG - Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed 0.2% - Media download by john@norman-baldor.org
Nov 14, 2021 07:03:53.204 [0x30e1c5000] DEBUG - Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed 0.1% - Media download by john@norman-baldor.org
Nov 14, 2021 07:03:53.321 [0x30e142000] DEBUG - Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed 0.1% - Media download by john@norman-baldor.org
Nov 14, 2021 07:03:53.440 [0x30e1c5000] DEBUG - Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed 0.1% - Media download by john@norman-baldor.org
Nov 14, 2021 07:03:53.594 [0x30e1c5000] DEBUG - Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed 0.2% - Media download by john@norman-baldor.org
Nov 14, 2021 07:03:53.722 [0x30e142000] DEBUG - Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed 0.2% - Media download by john@norman-baldor.org
Nov 14, 2021 07:03:53.842 [0x30e142000] DEBUG - Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed 0.2% - Media download by john@norman-baldor.org
Nov 14, 2021 07:03:53.972 [0x30e1c5000] DEBUG - Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed 0.2% - Media download by john@norman-baldor.org
Nov 14, 2021 07:03:54.157 [0x30e1c5000] DEBUG - Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed 0.3% - Media download by john@norman-baldor.org
Nov 14, 2021 07:03:54.288 [0x30e142000] DEBUG - Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed 0.3% - Media download by john@norman-baldor.org
Nov 14, 2021 07:03:54.389 [0x30e1c5000] DEBUG - Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed 0.3% - Media download by john@norman-baldor.org
Nov 14, 2021 07:03:54.541 [0x30e1c5000] DEBUG - Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed 0.3% - Media download by john@norman-baldor.org
Nov 14, 2021 07:03:54.750 [0x30e1c5000] DEBUG - Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed 0.3% - Media download by john@norman-baldor.org
Nov 14, 2021 07:03:54.872 [0x30e1c5000] DEBUG - Activity: updated activity a9577ef5-2065-4714-83f6-030fed054a47 - completed 0.3% - Media download by john@norman-baldor.org
6

PS. This is what the server log says now for the same recording being downloaded.

Nov 14, 2021 12:08:32.271 [0x30f844000] DEBUG - [Transcode] TranscodeUniversalRequest: using profile Plex Desktop
Nov 14, 2021 12:08:32.272 [0x30f844000] DEBUG - [Transcode] Downloading document http://127.0.0.1:32400/library/metadata/12940?includeBandwidths=1&offset=0&X-Plex-Incomplete-Segments=1&X-Plex-Session-Identifier=gp7shxlzrfhpzst5bclgiqab
Nov 14, 2021 12:08:32.289 [0x30f844000] DEBUG - [Transcode] We're going to try to auto-select an audio stream for account 1.
Nov 14, 2021 12:08:32.289 [0x30f844000] DEBUG - [Transcode] Selecting best audio stream for part ID 22770 (autoselect: 0 language: en)
Nov 14, 2021 12:08:32.289 [0x30f844000] DEBUG - [Transcode] Audio Stream: 52829, Subtitle Stream: -1
Nov 14, 2021 12:08:32.292 [0x30f844000] ERROR - [Transcode] get - invalid size value: 4341743628
Nov 14, 2021 12:08:32.292 [0x30f844000] DEBUG - [Transcode] Streaming Resource: Adding session 0x7fba5cf3d828:gp7shxlzrfhpzst5bclgiqab which is using 0kbps of WAN bandwidth.  Used is now 0kbps
Nov 14, 2021 12:08:32.292 [0x30f844000] DEBUG - [Transcode] Streaming Resource: Added session 0x7fba5cf3d828:gp7shxlzrfhpzst5bclgiqab
Nov 14, 2021 12:08:32.292 [0x30f844000] DEBUG - [Transcode] Streaming Resource: Reached Decision id=12940 codes=(MDE=1000,Direct play OK.) media=(id=22769 part=(id=22770 decision=direct play protocol=http streams=(Video=(id=52828 decision= width=1920 height=1080) Audio=(id=52829 decision= channels=0 rate=0))))

I will check again with a virgin (unveiwed) recording, but I’m currently thinking this is a false alarm created by starting a download to soon after a server restart.

7

I have been able to reproduce the issue. If I initiate a download immediately after restarting the server, it restricts the resolution of the downloaded file. If I download the same file after a few minutes, I get full resolution.

Sorry to have taken up your time and I’m very grateful for the help in reaching a better understanding of how Plex works. This is a relatively easy problem to work around.

8

The new ‘Downloads’ option which replaced ’Sync’ is causing so many problems it is not usable. Please bring back ‘Sync’ where we can easily change quality based on size and time available for downloading.

All content download with ‘Sync’ will be deleted from my device “at the end of 2021”. Plex is becoming a joke.

1 Like
9

Totally agree PLEX is more annoying now, and never seems to work been a PLEX user for 8 years now and finally had enough switching to Emby

10

I guess everyone has a their own use case for Sync/Download. My use case is NOT for download onto a mobile device. Rather I am recording in one country and watching in another. The upload speed in the recording country is poor and variable so I sometimes download the content file and then move the file from the client download location to a local server library location to be ‘re-served’ at local network speed. As such, I am much more interested in max quality than download time. This won’t be true of everyone.

I had download quality set to ‘Maximum’ I think I will experiment to see if setting it to something lower allows the quality to be maintained i.e. Download Quality = “High (20Mbps, 1080p)”. It’s not clear what will happen on a 2-5Mbps upload connection, but I want the 1080p so I’ll try it. In any case I get original recording quality with Download Quality = “Maximum” at times other than immediately after a restart. But I’m curious to see if it fixes the progressively lower download bandwidth use reported by Dashboard. I’m thinking that must be a bug because each download seems to be slower and slower until it becomes unusable. Restarting the server seems to reset the download speed.

I don’t know if you can locate the place of storage on your device and move the files from the Sync location to the Download location, or simply re-download at a time when you are on the same local network as the server. I guess, that would depend on the device and how you are using Plex.

PS A ‘migrate content’ option might have been more friendly than a ‘content will be deleted’ message.

11

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