Conversion failed. The transcoder exited due to an error. On Ubuntu

sbugzu · July 12, 2018, 5:14pm

Conversion failed. Unable to play any of my movies.

I have tried:
Upgraded to the newest beta version.
Deleted libraries.
Changed Transcoder directory.
Changed Transcoder quality.
Disabled/Enabled hardware acceleration.
Bought Plex Pass.

But that havn’t any helped.

Plex Media Server.log

 13, 2018 00:07:37.920 [0x7fb41ef73700] INFO - Plex Media Server v1.13.4.5251-2e6e8f841 - ubuntu PC x86_64 - build: linux-ubuntu-x86_64 ubuntu - GMT 08:00
Jul 13, 2018 00:07:37.920 [0x7fb41ef73700] INFO - Linux version: 4.10.0-041000rc8-generic (#201702121731 SMP Sun Feb 12 22:33:33 UTC 2017), language: en-US
Jul 13, 2018 00:07:37.921 [0x7fb41ef73700] INFO - Processor Intel(R) Core(TM) i5-4570T CPU @ 2.90GHz
Jul 13, 2018 00:07:37.921 [0x7fb41ef73700] INFO - /usr/lib/plexmediaserver/Plex Media Server
Jul 13, 2018 00:07:37.987 [0x7fb42b4d5800] INFO - OCSP: Successfully retrieved response from cache.
Jul 13, 2018 00:07:43.137 [0x7fb4187fe700] WARN - PublicAddressManager: WAN IP is a private IP address
Jul 13, 2018 00:07:43.269 [0x7fb4137fd700] WARN - Sync: Couldn't get sync lists without subscription.
Jul 13, 2018 00:07:46.984 [0x7fb41d7ff700] WARN - PublicAddressManager: WAN IP is a private IP address
Jul 13, 2018 00:07:49.256 [0x7fb41c7ff700] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (32dc14cb-0cd1-4997-b30a-98230b51d153, expected 6e47df21-ab31-4bf1-8b3b-38f76ac1e787)
Jul 13, 2018 00:07:49.256 [0x7fb41bffe700] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (32dc14cb-0cd1-4997-b30a-98230b51d153, expected 6e47df21-ab31-4bf1-8b3b-38f76ac1e787)
Jul 13, 2018 00:07:49.256 [0x7fb41c7ff700] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (32dc14cb-0cd1-4997-b30a-98230b51d153, expected )
Jul 13, 2018 00:07:49.256 [0x7fb41bffe700] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (32dc14cb-0cd1-4997-b30a-98230b51d153, expected )
Jul 13, 2018 00:07:50.805 [0x7fb40affa700] WARN - PublicAddressManager: WAN IP is a private IP address
Jul 13, 2018 00:07:53.035 [0x7fb41c7ff700] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (6e47df21-ab31-4bf1-8b3b-38f76ac1e787, expected 5cb33b17-b4cc-4c00-9149-69c7d0433816)
Jul 13, 2018 00:07:53.035 [0x7fb41bffe700] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (6e47df21-ab31-4bf1-8b3b-38f76ac1e787, expected 5cb33b17-b4cc-4c00-9149-69c7d0433816)
Jul 13, 2018 00:07:53.035 [0x7fb41bffe700] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (6e47df21-ab31-4bf1-8b3b-38f76ac1e787, expected )
Jul 13, 2018 00:07:53.035 [0x7fb41c7ff700] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (6e47df21-ab31-4bf1-8b3b-38f76ac1e787, expected )
Jul 13, 2018 00:07:53.390 [0x7fb41c7ff700] ERROR - MyPlex: mapping failed due to potential double NAT configuration
Jul 13, 2018 00:07:53.390 [0x7fb41c7ff700] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (5cb33b17-b4cc-4c00-9149-69c7d0433816, expected )
Jul 13, 2018 00:07:53.392 [0x7fb41bffe700] ERROR - MyPlex: mapping failed due to potential double NAT configuration
Jul 13, 2018 00:07:53.392 [0x7fb41bffe700] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (5cb33b17-b4cc-4c00-9149-69c7d0433816, expected )
Jul 13, 2018 00:07:55.249 [0x7fb4067f1700] WARN - PubSubManager: Connection to 45.33.73.209 failed: Connection timed out.
Jul 13, 2018 00:07:55.958 [0x7fb40cffe700] ERROR - Error issuing curl_easy_perform(handle): 28
Jul 13, 2018 00:07:56.022 [0x7fb40bffc700] ERROR - Error issuing curl_easy_perform(handle): 28
Jul 13, 2018 00:08:08.050 [0x7fb4047ff700] ERROR - Error issuing curl_easy_perform(handle): 28
Jul 13, 2018 00:08:09.283 [0x7fb41afff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Jul 13, 2018 00:08:10.035 [0x7fb40bffc700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Jul 13, 2018 00:08:10.248 [0x7fb41d7ff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Jul 13, 2018 00:08:29.411 [0x7fb40bffc700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Jul 13, 2018 00:08:29.720 [0x7fb41d7ff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Jul 13, 2018 00:08:30.378 [0x7fb41afff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Jul 13, 2018 00:09:12.739 [0x7fb4047ff700] ERROR - [Transcoder] Error writing trailer of media-%05d.ts: Invalid argument
Jul 13, 2018 00:09:12.771 [0x7fb41d7ff700] WARN - Transcode runner appears to have died.
Jul 13, 2018 00:09:12.781 [0x7fb41afff700] WARN - Transcode runner appears to have died.
Jul 13, 2018 00:09:12.794 [0x7fb40a7f9700] WARN - Transcode runner appears to have died.
Jul 13, 2018 00:09:29.969 [0x7fb419fff700] WARN - Got a transcode session ping without a session GUID (or with an invalid one).
Jul 13, 2018 00:09:30.233 [0x7fb41afff700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
Jul 13, 2018 00:09:30.265 [0x7fb40a7f9700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Jul 13, 2018 00:09:30.335 [0x7fb419fff700] ERROR - ClientProfileExtra: video transcode target already exists for  streaming hls
Jul 13, 2018 00:09:31.802 [0x7fb40cffe700] INFO - AutoUpdate: no updates available
Jul 13, 2018 00:09:50.582 [0x7fb4187fe700] WARN - Got a request to stop a transcode session without a session GUID (or with an invalid one).
Jul 13, 2018 00:10:00.422 [0x7fb42b4d5800] WARN - Timed out waiting for server to finish.

Plex Transcoder Statistics.log

<SessionReport version="2" startTime="0" startTimestamp="Jul 13, 2018 00:08:30.383" key="/library/metadata/19828" session="468C304D-CF1A-4DF1-AEFB-8613BE642150" transcode="60890678-7AC9-4155-BEB9-B732382ACDA0">
<User id="1" thumb="https://plex.tv/users/aa9662a3c177a65f/avatar?c=1531322367" title="sbugzu" />
<Player address="192.168.100.243" device="iPhone" machineIdentifier="Xxxxxxxxxxx" model="10,3" platform="iOS" platformVersion="11.3.1" product="Plex for iOS" remotePublicAddress="xx.xx.xx.xx" state="" title="iPhoneX" vendor="Apple" version="5.1" local="1" userID="1" />
<Variants>
<Variant id="cf0e34d6-986e-4f22-8568-cffbbc4b6b8a" targetBitrate="24959" context="streaming" sourceVideoCodec="hevc" sourceAudioCodec="ac3" videoDecision="transcode" audioDecision="transcode" subtitleDecision="burn" protocol="hls" container="mpegts" videoCodec="h264" audioCodec="aac" audioChannels="2" transcodeHwRequested="0" transcodeHwFullPipeline="0">
<Media id="19763" videoProfile="main 10" audioChannels="2" audioCodec="aac" bitrate="23770" container="mpegts" duration="7086080" height="800" protocol="hls" videoCodec="h264" videoFrameRate="24p" videoResolution="1080p" width="1920" selected="1">
<Part deepAnalysisVersion="2" id="19769" indexes="sd" requiredBandwidths="18966,17455,14992,12652,11237,10255,7498,7498" videoProfile="main 10" bitrate="23770" container="mpegts" duration="7086080" height="800" protocol="hls" width="1920" decision="transcode" selected="1">
<Stream bitrate="23512" codec="h264" colorPrimaries="bt709" default="1" displayTitle="Unknown (HEVC Main 10)" frameRate="23.975999999999999" height="800" id="1455" requiredBandwidths="18107,16695,14233,11925,10506,9490,6744,6744" streamType="1" width="1920" decision="transcode" location="segments-av" />
<Stream bitrate="258" bitrateMode="cbr" channels="2" codec="aac" default="1" displayTitle="English (AC3 5.1(side))" id="1456" language="English" languageCode="eng" requiredBandwidths="640,640,640,640,640,640,640,640" selected="1" streamType="2" decision="transcode" location="segments-av" />
<Stream burn="1" displayTitle="&#20013;&#25991; (PGS)" id="1458" language="&#20013;&#25991;" languageCode="chi" requiredBandwidths="44,44,44,44,44,44,44,44" selected="1" streamType="3" decision="burn" location="segments-av" />
</Part>
</Media>
</Variant>
</Variants>
<SegmentList>
<Segment duration="0" startOffset="-1" endOffset="-1">
<Timelines>
<Download startTime="15465" fileType="av" variant="cf0e34d6-986e-4f22-8568-cffbbc4b6b8a" />
<Download startTime="18758" fileType="av" variant="cf0e34d6-986e-4f22-8568-cffbbc4b6b8a" />
<Download startTime="28595" fileType="av" variant="cf0e34d6-986e-4f22-8568-cffbbc4b6b8a" />
</Timelines>
</Segment>
</SegmentList>
<PlaybackReports />
<StateReports />
<StateReports />
</SessionReport>

ChuckPa · July 12, 2018, 10:56pm

Would you please do the following.

Please verify DEBUG logging only.
Recreate the failure
Wait 20 seconds for the logs to get to disk
Settings - Server - Help - Download Logs
Attach the ZIP file it gives you here. I’ll look through the sequence. The codec should download with ease

sbugzu · July 13, 2018, 3:17am

I append the log file.

Plex Media Server Logs_2018-07-13_10-40-54.zip (36.6 KB)

ChuckPa · July 13, 2018, 3:38am

Thank you for that. I am seeing your network connection drop and then come back.

I see you have a router behind a router (Double NAT) configuration or a NAT VM which PMS will not be able to navigate reliably. It will not support Remote Access automatically in this configuration and Relay is also unlikely.

Jul 13, 2018 10:38:14.630 [0x7f71ae7ff700] DEBUG - NAT: UPnP, public address is 192.168.1.3
Jul 13, 2018 10:38:14.631 [0x7f71ae7ff700] DEBUG - PublicAddressManager: got WAN IP 192.168.1.3 from router
Jul 13, 2018 10:38:14.631 [0x7f71ae7ff700] WARN - PublicAddressManager: WAN IP is a private IP address
Jul 13, 2018 10:38:14.631 [0x7f71affff700] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
Jul 13, 2018 10:38:14.631 [0x7f71affff700] DEBUG - MyPlex: We appear to have regained Internet connectivity.

Can you elaborate please?

I see these adapters as well:

Jul 13, 2018 10:38:05.068 [0x7f71bea96800] DEBUG - Network interfaces:
Jul 13, 2018 10:38:05.068 [0x7f71bea96800] DEBUG -  * 1 lo (127.0.0.1) (loopback: 1)
Jul 13, 2018 10:38:05.068 [0x7f71bea96800] DEBUG -  * 4 wlp3s0 (192.168.100.3) (loopback: 0)
Jul 13, 2018 10:38:05.068 [0x7f71bea96800] DEBUG -  * 5 docker0 (172.17.0.1) (loopback: 0)
Jul 13, 2018 10:38:05.068 [0x7f71bea96800] DEBUG -  * 6 br-49782b0ce88a (172.18.0.1) (loopback: 0)
Jul 13, 2018 10:38:05.068 [0x7f71bea96800] DEBUG - Creating NetworkServices singleton.

It looks like wlp3s0 (a wireless adapter) is the server’s default adapter. If the Docker or Bridge adapters have gateway addresses, PMS will not be able to cope with this (yet. soon but not yet).

sbugzu · July 13, 2018, 6:51am

This is the structure of my home network。

   +--------+
   |        |
   | modem  |  192.168.1.1
   |        |
   +---+----+
       |
       |
       | DMZ 192.168.1.3
       |
       |
       | 192.168.1.3
+------v-------+
|              |
|   router     |
|              |
+------+-------+
       | 192.168.100.1
       |
       |
       |  Port forwarding: 15521->32400
       |
       |
       | 192.168.100.3(wlp3s0)
+------v-------+
|              |
|    PMS       |
|              |
+--------------+

I had changed some network settings, and received Fully accessible outside your network in Setting -> Remote Access.

I uploaded log file again.

Plex Media Server Logs_2018-07-13_14-20-26.zip (36.2 KB)

ChuckPa · July 13, 2018, 7:08am

That looks a lot better.

Jul 13, 2018 14:17:28.809 [0x7fb5227ff700] DEBUG - Completed: [127.0.0.1:43804] 200 GET / (6 live) GZIP 1ms 2308 bytes
Jul 13, 2018 14:17:29.328 [0x7fb51efff700] DEBUG - PubSubManager: Time to connect to 184.105.148.101 was 369 ms.
Jul 13, 2018 14:17:29.328 [0x7fb51efff700] DEBUG - PubSubManager: Time to connect to 109.237.24.233 was 446 ms.
Jul 13, 2018 14:17:29.328 [0x7fb51efff700] DEBUG - PubSubManager: Time to connect to 45.79.211.86 was 613 ms.
Jul 13, 2018 14:17:29.328 [0x7fb51efff700] DEBUG - PubSubManager: Time to connect to 45.79.11.43 was 626 ms.
Jul 13, 2018 14:17:29.328 [0x7fb51efff700] DEBUG - PubSubManager: Time to connect to 82.94.168.56 was 631 ms.
Jul 13, 2018 14:17:29.328 [0x7fb51efff700] DEBUG - PubSubManager: Time to connect to 172.104.218.101 was 716 ms.
Jul 13, 2018 14:17:29.328 [0x7fb51efff700] DEBUG - PubSubManager: Time to connect to 139.162.115.125 was 1191 ms.
Jul 13, 2018 14:17:29.328 [0x7fb51efff700] DEBUG - PubSubManager: Time to connect to 139.162.142.209 was 1425 ms.
Jul 13, 2018 14:17:29.328 [0x7fb51efff700] DEBUG - PubSubManager: Time to connect to 172.104.173.210 was 2085 ms.
Jul 13, 2018 14:17:29.329 [0x7fb51efff700] DEBUG - PubSubManager: Updating best ping time for 184.105.148.101 to 369 ms.

As long as you’re aware, any Remote Access will require you to setup port forwarding through the DMZ manually. PMS is expecting only one router/firewall on the LAN/WAN boundary it sees. If you have a DMZ, it must be transparent to PMS.

Your logs aren’t showing any transcoder error. The iOS app is commanding the playback to terminate. Are you doing that? What’s the app showing you?

system · October 11, 2018, 7:08am

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

Topic		Replies	Views
Playback often hangs / can't play videos after upgrade Desktops & Laptops server-truenas	13	862	December 21, 2019
Endless transcoding/buffering Desktops & Laptops server-linux	40	1554	June 14, 2020
Error - Conversion failed. Transcoder crashed or failed to start. PMS 1.10.1.4602 General Discussions	77	607	January 7, 2020
Transcoder is killed as idle when playing General Discussions server-linux	53	923	March 12, 2019
Transcoder Failure on Old media NAS & Devices subtitles , server-truenas	7	226	December 16, 2018

Conversion failed. The transcoder exited due to an error. On Ubuntu

Related topics