1080p works at 20Mbps, breaks at 40Mbps

glougloumouth · July 3, 2013, 1:11am

Hi,

I downloaded PlexConnect this morning. I did wipe my previous PlexConnect version & all config files, and re-did setup with this version.

I run PMS 0.9.8.1 on RHEL/CentOS 6.4, clean install I re-did yesterday.

I am using an MKV file,1080p full HD (http://pastebin.com/nTEXQGwe) with average rate 27000Kbps.

I cannot play on my aTV v3 firmware 5.3 with transcoding option set to 1080p @ 40MBps but I can if I set PlexConnect to 1080p @ 20Mbps.

When I look at my PMS, if set to 40 Mbps, I see the transcoder process kick in, but it never start using much CPU, stick around 30% (of one core) then drops in CPU usage. Meanwhile the aTV has either green screen, or very choppy image (better say: still images with 30s refresh).

When set at 20Mbps, playback is smooth, I can see the transcoder process kick in, eat up 580% CPU (so almost 6 cores) to buffer then lower its use while the movie goes on. I suspected a CPU issue (lack of horse power) but no, when at 40Mbps, the CPU never kicks in nor reach max CPU level.

I would tend to say this is a bug, as I had better success using 40Mbps last week with my old installation of PMS 0.9.8.1 & the PlexConnect I has downloaded last Thursday.

Any one else seing this?

Thank you for all the hard work & the help I got from this community.

Gl.

PS:

View from "top" in 40MBps:

PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND

29285 plex 20 0 76272 19m 4156 S 34.9 0.1 0:02.87 Plex New Transc

25546 plex 20 0 2213m 41m 11m S 7.3 0.1 0:26.42 Plex Media Serv

I can barely get 40% usage.

View when at 20MBps:

PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND

965 plex 20 0 1338m 374m 4968 S 582.3 1.2 1:03.31 Plex New Transc

25546 plex 20 0 2213m 42m 11m S 3.6 0.1 0:29.67 Plex Media Serv

Intense buffering at 20Mbps.

I see no error in the PMS server's logs.

jmcgeejr · July 3, 2013, 2:13am

Hi,

I downloaded PlexConnect this morning. I did wipe my previous PlexConnect version & all config files, and re-did setup with this version.

I run PMS 0.9.8.1 on RHEL/CentOS 6.4, clean install I re-did yesterday.

I am using an MKV file,1080p full HD (http://pastebin.com/nTEXQGwe) with average rate 27000Kbps.

I cannot play on my aTV v3 firmware 5.3 with transcoding option set to 1080p @ 40MBps but I can if I set PlexConnect to 1080p @ 20Mbps.

When I look at my PMS, if set to 40 Mbps, I see the transcoder process kick in, but it never start using much CPU, stick around 30% (of one core) then drops in CPU usage. Meanwhile the aTV has either green screen, or very choppy image (better say: still images with 30s refresh).

When set at 20Mbps, playback is smooth, I can see the transcoder process kick in, eat up 580% CPU (so almost 6 cores) to buffer then lower its use while the movie goes on. I suspected a CPU issue (lack of horse power) but no, when at 40Mbps, the CPU never kicks in nor reach max CPU level.

I would tend to say this is a bug, as I had better success using 40Mbps last week with my old installation of PMS 0.9.8.1 & the PlexConnect I has downloaded last Thursday.

Any one else seing this?

Thank you for all the hard work & the help I got from this community.

Gl.

PS:

View from "top" in 40MBps:

PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND

29285 plex 20 0 76272 19m 4156 S 34.9 0.1 0:02.87 Plex New Transc

25546 plex 20 0 2213m 41m 11m S 7.3 0.1 0:26.42 Plex Media Serv

I can barely get 40% usage.

View when at 20MBps:

PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND

965 plex 20 0 1338m 374m 4968 S 582.3 1.2 1:03.31 Plex New Transc

25546 plex 20 0 2213m 42m 11m S 3.6 0.1 0:29.67 Plex Media Serv

Intense buffering at 20Mbps.

I see no error in the PMS server's logs.

I am going to guess the lower cpu usage at 40Mbps is because the video doesnt need to be dropped down in quality since you said it's a 27000 Bitrate file, so your plex is just doing a container change to mp4, whereas the 20Mbps setting requires plex to transcode the file down to a max of 20Mbps bitrate. However I am not sure why you are seeing a green screen, maybe try an older version of the plexconnect app?

glougloumouth · July 3, 2013, 2:28am

Thank you for the reply.

From what I see, it feels like when the transcoder would lack horsepower to just work properly: dropped frames, still images, blocky screen...

But I see it does not uses CPU. Even if now it is just a container change, I should not see lost frames & blocky images, it should just flow.

Gl.

baa · July 3, 2013, 5:30am

I can't believe this should be caused by PlexConnect - we have NOTHING to do with the actual replay. All is taked care of by aTV & PMS at that point.

I go with Monsieur Latte and would add the following thought:

Your MKV file might be somewhat of the specs - so far, that aTV can not handle the original video stream (h264?). When set to 20Mbps PMS transcoder (ffmpeg) seems to be handle it fine, decoding and re-encoding the video, in fact creating a new, clean video stream.

For debugging: How does the file replay on other iOS devices? Are you able to easily remux it to m4v? How does it play then - either with Plex or even with aTV/iTunes?

pecinko · July 3, 2013, 8:10am

Does not look as out of spec file judging by sent specifications. What's happening here, IMHO, is that stream in that MKV is "borked" at least as iOS player is concerned.

Little a little heads up as I spent last week mainly testing play capabilities of ATV:

I have mostly files that are in line with specification - profile 4.0, bitrate < 9000, AAC audio. However, several of them won't play via PlexConnect. When I tried to play them via iTunes I've bumped to a wall as well. Now, if I force transcoding PMS makes compatible stream and the movie plays fine. Still, I have a few of them that refuse to play even when transcoding is forced as even PMS is not capable of handling the file.

All of those play fine on my Mac Mini because player is less picky (or more forgiving if you want) than iOS player or PMS. Maybe I should start making a PlexConnect like skin for PHT :)

glougloumouth · July 4, 2013, 2:01am

Thank you for your answer.

The file is an ACVHD format, indeed, and I want PMS to transcode it on the fly to the Apple TV. I am just wondering what is going on so that it does work at 20Mbps and not once set at 40.

If I transcode the MKV video back to H264, then it plays fine, any setting (but obviously there is no transcoding to do).

I have tried another 4 MKVs, and all of them show the same issue: do not play at 40Mbps, does play at 20. All of them AVCHD, requiring transcoding.

I have cross posted on the PMS forum, I'll see if someone can explain what is going on here, around transcoding.

Tested the iPad Plex app, I can watch the movie no issue in 1080p @ 20Mbps. I wish I could test 40, but the highest you can send to an iPad is 20...

Gl.

f00b4r · July 4, 2013, 7:52am

Yeah it is either:

The video is too high a spec aTV (i would be more inclined to say it is this if you had an aTV2)
Your PMS does not have enough horsepower
There is an issue with the file
PMS has a bug

As you have an aTV3 and it plays fine when remuxed ( are you sure you remuxed rather than reencoded?), i would think either 2 (you did not give PMS specs) or 4.

I would be interested in what the PMS guys come back with.

Currently PlexConnect uses the generic iOS profile on the PMS but this should eventually contain individual profiles for the aTV2 and aTV3 so information on this sort of thing is useful.

pecinko · July 4, 2013, 9:46am

Thank you for your answer.

The file is an ACVHD format, indeed, and I want PMS to transcode it on the fly to the Apple TV. I am just wondering what is going on so that it does work at 20Mbps and not once set at 40.

If I transcode the MKV video back to H264, then it plays fine, any setting (but obviously there is no transcoding to do).

I have tried another 4 MKVs, and all of them show the same issue: do not play at 40Mbps, does play at 20. All of them AVCHD, requiring transcoding.

I have cross posted on the PMS forum, I'll see if someone can explain what is going on here, around transcoding.

Tested the iPad Plex app, I can watch the movie no issue in 1080p @ 20Mbps. I wish I could test 40, but the highest you can send to an iPad is 20...

Gl.

Hmm This seems only logical to me:

1080 @ 40 was introduced so that PlexConnect can play without transcoding. Your file is 27, which is lower than 40 and file analysis reports H.264 stream, which should be iOS compatible, so PMS is not transcoding it.

When you set 1080 @ 20 your file is above set bit rate so it gets transcoded. This takes care of incompatible AVCHD stream (that is analysed and reported as H.264). Same thing happens on iPhone/Pad as 1080 @ 20 is top limit there. Even if you would be able to set it to 1080 @ 40 it would not play as it will bump to a WiFi speed wall in that case.

As I see it you either leave file as is and keep 1080 @ 20 which will make PMS transcode it to a playable stream OR you convert video stream yourself to a iOS compatible format. I believe both PMS and PlexConnect are working as they should here as I'm afraid PMS can not really tell the difference between AVCHD and H.264.

glougloumouth · July 4, 2013, 11:40am

If I set PlexConnect parameters to Force Encoding True, would that force transencoding? I tested it and it is not better (just a black screen, PMS transcoder process sitting at 30% CPU).

I will ■■■■■ my MKV HD library to see what works fine & what breaks. Might be an issue with the file, although it plays fine from the Web Client, natively with VLC & MPlayerX, and on my old WD Live TV.

Horse power is not an issue as the transcoder process never jumps over 30-40% when failing to deliver 1080p@40Mbps. When transcoding at 20Mbps, CPU jumps close to 600% for the same process, yes I have margin here.

Thank you all for your answers. I am learning a lot.

baa · July 4, 2013, 12:12pm

"force_transcoding" invokes the PMS transcoder... but it might also decide to just do "direct streaming" (keeping the streams/codecs, only changing the container). Check the PMS logs, it should give you details about that - ie. "copying audio / copying video", something along those lines.

pecinko · July 4, 2013, 2:38pm

Horse power is not an issue as the transcoder process never jumps over 30-40% when failing to deliver 1080p@40Mbps. When transcoding at 20Mbps, CPU jumps close to 600% for the same process, yes I have margin here.

========================

As Baa said you better check PMS logs - my guess is file doesn't get transcoded at 1080@40 but rather remuxed - video stream is kept as-is and gets remuxed from MKV container to MP4. And since you have incompatible video stream - it won't play. On iOS (or web client) you have 1080@20 which means transcoder will kick in regardless of video stream type because your file has bit rate over 20.

If you have the same type of video - AVCHD in MKV, but with a bit rate lower that 20 - test that on iOS and web client.

glougloumouth · July 5, 2013, 3:18am

Testing another MKV from the aTV3, PMS log gives (1080p @ 40Mbps):

Jul 04, 2013 23:17:16 [0x7f7e3f63b700] DEBUG - NetworkService: Have an old server at index 1 and address 192.168.3.221 (we just got packet from 127.0.0.1, index 0)

Jul 04, 2013 23:17:16 [0x7f7e3f63b700] DEBUG - NetworkServiceBrowser: SERVER updated: 127.0.0.1

Jul 04, 2013 23:17:16 [0x7f7e3f63b700] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=127.0.0.1

Jul 04, 2013 23:17:16 [0x7f7e3f63b700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=127.0.0.1

Jul 04, 2013 23:17:16 [0x7f7e3f63b700] DEBUG - HTTP requesting to: http://127.0.0.1:48890/system/notify/serverUpdated?host=127.0.0.1

Jul 04, 2013 23:17:16 [0x7f7e03fff700] DEBUG - Request: GET /servers [127.0.0.1:55218] (9 live)

Jul 04, 2013 23:17:16 [0x7f7e03fff700] DEBUG - It took 0.000000 sec to serialize a list with 1 elements.

Jul 04, 2013 23:17:16 [0x7f7e23fff700] DEBUG - Request: GET /library/sections [127.0.0.1:55219] (9 live)

Jul 04, 2013 23:17:16 [0x7f7e23fff700] DEBUG - It took 0.000000 sec to serialize a list with 1 elements.

Jul 04, 2013 23:17:16 [0x7f7e558ef700] DEBUG - Request: GET /channels/all [127.0.0.1:55220] (9 live)

Jul 04, 2013 23:17:16 [0x7f7e558ef700] DEBUG - It took 0.000000 sec to serialize a list with 4 elements.

Jul 04, 2013 23:17:16 [0x7f7e3f63b700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.

Jul 04, 2013 23:17:16 [0x7f7e3f63b700] DEBUG - NetworkService: Have an old server at index 0 and address 127.0.0.1 (we just got packet from 192.168.3.221, index 1)

Jul 04, 2013 23:17:16 [0x7f7e3f63b700] DEBUG - NetworkServiceBrowser: SERVER updated: 192.168.3.221

Jul 04, 2013 23:17:16 [0x7f7e3f63b700] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=192.168.3.221

Jul 04, 2013 23:17:16 [0x7f7e3f63b700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=192.168.3.221

Jul 04, 2013 23:17:16 [0x7f7e3f63b700] DEBUG - HTTP requesting to: http://127.0.0.1:48890/system/notify/serverUpdated?host=192.168.3.221

Jul 04, 2013 23:17:16 [0x7f7e3cf2b700] DEBUG - Request: GET /servers [127.0.0.1:55222] (9 live)

Jul 04, 2013 23:17:16 [0x7f7e3cf2b700] DEBUG - It took 0.000000 sec to serialize a list with 1 elements.

Jul 04, 2013 23:17:16 [0x7f7e22c77700] DEBUG - Request: GET /library/sections [192.168.3.221:40635] (9 live)

Jul 04, 2013 23:17:16 [0x7f7e22c77700] DEBUG - It took 0.000000 sec to serialize a list with 1 elements.

Jul 04, 2013 23:17:16 [0x7f7e2363b700] DEBUG - Request: GET /channels/all [192.168.3.221:40636] (9 live)

Jul 04, 2013 23:17:16 [0x7f7e2363b700] DEBUG - It took 0.000000 sec to serialize a list with 4 elements.

Jul 04, 2013 23:17:16 [0x7f7e3f63b700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - Request: GET /video/:/transcode/universal/start.m3u8?maxVideoBitrate=40000&videoQuality=100&directPlay=0&subtitleSize=100&fastSeek=1&session=DY3KLKEVFF54&audioBoost=100&protocol=hls&path=%2Flibrary%2Fmetadata%2F3&videoResolution=1920x1080&directStream=1&X-Plex-Platform=iOS&X-Plex-Model=3%2C1&X-Plex-Platform-Version=5.3&X-Plex-Client-Platform=iOS&X-Plex-Device=AppleTV&X-Plex-Product=Plex+Connect&X-Plex-Device-Name=Apple+TV&X-Plex-Version= [192.168.3.201:49846] (9 live)

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * maxVideoBitrate => 40000

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * videoQuality => 100

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * directPlay => 0

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * subtitleSize => 100

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * fastSeek => 1

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * session => DY3KLKEVFF54

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * audioBoost => 100

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * protocol => hls

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * path => /library/metadata/3

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * videoResolution => 1920x1080

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * directStream => 1

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * X-Plex-Platform => iOS

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * X-Plex-Model => 3,1

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * X-Plex-Platform-Version => 5.3

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * X-Plex-Client-Platform => iOS

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * X-Plex-Device => AppleTV

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * X-Plex-Product => Plex Connect

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * X-Plex-Device-Name => Apple TV

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - * X-Plex-Version =>

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - Request range: 0 to 1

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - Using profile Apple TV (2nd generation)

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/3

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - Cache hit for document http://127.0.0.1:32400/library/metadata/3

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - It took 0.000000 sec to serialize a list with 1 elements.

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - Remuxing to hls/mpegts/h264/aac

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - Found session GUID of DY3KLKEVFF54 in session start.

Jul 04, 2013 23:17:17 [0x7f7e20f2b700] DEBUG - Short-circuiting silly request.

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - Request: GET /video/:/transcode/universal/start.m3u8?maxVideoBitrate=40000&videoQuality=100&directPlay=0&subtitleSize=100&fastSeek=1&session=DY3KLKEVFF54&audioBoost=100&protocol=hls&path=%2Flibrary%2Fmetadata%2F3&videoResolution=1920x1080&directStream=1&X-Plex-Platform=iOS&X-Plex-Model=3%2C1&X-Plex-Platform-Version=5.3&X-Plex-Client-Platform=iOS&X-Plex-Device=AppleTV&X-Plex-Product=Plex+Connect&X-Plex-Device-Name=Apple+TV&X-Plex-Version= [192.168.3.201:49847] (9 live)

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * maxVideoBitrate => 40000

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * videoQuality => 100

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * directPlay => 0

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * subtitleSize => 100

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * fastSeek => 1

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * session => DY3KLKEVFF54

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * audioBoost => 100

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * protocol => hls

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * path => /library/metadata/3

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * videoResolution => 1920x1080

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * directStream => 1

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * X-Plex-Platform => iOS

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * X-Plex-Model => 3,1

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * X-Plex-Platform-Version => 5.3

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * X-Plex-Client-Platform => iOS

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * X-Plex-Device => AppleTV

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * X-Plex-Product => Plex Connect

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * X-Plex-Device-Name => Apple TV

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - * X-Plex-Version =>

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - Using profile Apple TV (2nd generation)

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - Downloading document http://127.0.0.1:32400/library/metadata/3

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - Cache hit for document http://127.0.0.1:32400/library/metadata/3

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - It took 0.000000 sec to serialize a list with 1 elements.

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - Remuxing to hls/mpegts/h264/aac

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - Found session GUID of DY3KLKEVFF54 in session start.

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - Using session GUID DY3KLKEVFF54 for new transcode session.

Jul 04, 2013 23:17:17 [0x7f7e03fff700] DEBUG - Cleaning directory for session DY3KLKEVFF54 (/tmp/plex-transcode-DY3KLKEVFF54-b2edacf5-1d6d-4752-a1d0-cc7ff3055d91)

Jul 04, 2013 23:17:17 [0x7f7e23fff700] DEBUG - Request: GET /video/:/transcode/universal/session/DY3KLKEVFF54/base/index.m3u8 [192.168.3.201:49848] (9 live)

Jul 04, 2013 23:17:17 [0x7f7e23fff700] DEBUG - Remuxing to hls/mpegts/h264/aac

Jul 04, 2013 23:17:17 [0x7f7e23fff700] DEBUG - Starting a transcode session DY3KLKEVFF54 at offset -1.0 (stopped=1)

Jul 04, 2013 23:17:17 [0x7f7e23fff700] DEBUG - Job running: /usr/lib/plexmediaserver/Resources/Plex New Transcoder -i http://127.0.0.1:32400/library/parts/3/file.mkv -threads 0 -y -segment_format mpegts -f segment -loglevel quiet -loglevel_plex error -map_metadata -1 -progressurl http://127.0.0.1:32400/video/:/transcode/session/DY3KLKEVFF54/progress -vcodec copy -flags -global_header -segment_time 10 -segment_start_number 0 -bsf h264_mp4toannexb -map 0:0 -sn -acodec aac -strict -2 -cutoff 15000 -ac 2 -ab 193k -map 0:1 media-%05d.ts

Jul 04, 2013 23:17:17 [0x7f7e558ef700] DEBUG - Request: GET /library/parts/3/file.mkv [127.0.0.1:55225] (9 live)

Jul 04, 2013 23:17:17 [0x7f7e558ef700] DEBUG - Content-Length of /mnt/plexmedia/HPUSB2TBa/MKV/Arthur Christmas.mkv is 21371823609.

Jul 04, 2013 23:17:18 [0x7f7e3cf2b700] INFO - [Transcoder] Input #0, matroska,webm, from 'http://127.0.0.1:32400/library/parts/3/file.mkv':

Jul 04, 2013 23:17:18 [0x7f7e22c77700] INFO - [Transcoder] Stream #0:0(eng): Video: h264, yuv420p, 1920x1080 [SAR 1:1 DAR 16:9], 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc

Jul 04, 2013 23:17:18 [0x7f7e2363b700] INFO - [Transcoder] Stream #0:1(eng): Audio: dts, 48000 Hz, 5.1(side), fltp, 1536 kb/s (default)

Jul 04, 2013 23:17:18 [0x7f7e20f2b700] INFO - [Transcoder] Stream #0:2(eng): Audio: ac3, 48000 Hz, 5.1(side), fltp, 640 kb/s

Jul 04, 2013 23:17:18 [0x7f7e03fff700] INFO - [Transcoder] Stream #0:3(fra): Audio: dts, 48000 Hz, 5.1(side), fltp, 1536 kb/s

Jul 04, 2013 23:17:18 [0x7f7e558ef700] INFO - [Transcoder] Stream #0:4(spa): Audio: ac3, 48000 Hz, 5.1(side), fltp, 640 kb/s

Jul 04, 2013 23:17:18 [0x7f7e3cf2b700] INFO - [Transcoder] Stream #0:5(eng): Subtitle: hdmv_pgs_subtitle (default)

Jul 04, 2013 23:17:18 [0x7f7e22c77700] INFO - [Transcoder] Stream #0:6(eng): Subtitle: hdmv_pgs_subtitle

Jul 04, 2013 23:17:18 [0x7f7e2363b700] INFO - [Transcoder] Stream #0:7(fra): Subtitle: hdmv_pgs_subtitle

Jul 04, 2013 23:17:18 [0x7f7e20f2b700] INFO - [Transcoder] Stream #0:8(fra): Subtitle: hdmv_pgs_subtitle

Jul 04, 2013 23:17:18 [0x7f7e03fff700] INFO - [Transcoder] Stream #0:9(spa): Subtitle: hdmv_pgs_subtitle

Jul 04, 2013 23:17:18 [0x7f7e558ef700] INFO - [Transcoder] Stream #0:10(spa): Subtitle: hdmv_pgs_subtitle

Jul 04, 2013 23:17:18 [0x7f7e3cf2b700] INFO - [Transcoder] Stream #0:11(fra): Subtitle: hdmv_pgs_subtitle

Jul 04, 2013 23:17:18 [0x7f7e22c77700] INFO - [Transcoder] Stream #0:12(fra): Subtitle: hdmv_pgs_subtitle

Jul 04, 2013 23:17:18 [0x7f7e2363b700] DEBUG - [Transcoder] Average FPS ~ 24 fps, Frame rate ~ 24 fps.

Jul 04, 2013 23:17:18 [0x7f7e23fff700] DEBUG - [FFMPEG] Duration: 5852

Jul 04, 2013 23:17:18 [0x7f7e23fff700] DEBUG - Read line, and done: 1

Jul 04, 2013 23:17:18 [0x7f7e23fff700] DEBUG - Started session successfully: DY3KLKEVFF54

Jul 04, 2013 23:17:18 [0x7f7e23fff700] DEBUG - HLS: Building an M3U8 for 5852 total seconds with 10 seconds/segment, target duration of 10.

Jul 04, 2013 23:17:18 [0x7f7e20f2b700] INFO - [Transcoder] Output #0, segment, to 'media-%05d.ts':

Jul 04, 2013 23:17:18 [0x7f7e03fff700] INFO - [Transcoder] Stream #0:0: Video: h264, yuv420p, 1920x1080 [SAR 1:1 DAR 16:9], q=2-31, 23.98 fps, 90k tbn, 23.98 tbc

Jul 04, 2013 23:17:18 [0x7f7e558ef700] INFO - [Transcoder] Stream #0:1: Audio: aac, 48000 Hz, stereo, fltp, 193 kb/s (default)

Jul 04, 2013 23:17:18 [0x7f7e3cf2b700] DEBUG - Request: PUT /video/:/transcode/session/DY3KLKEVFF54/progress?width=1920&height=1080 [127.0.0.1:55244] (10 live)

Jul 04, 2013 23:17:18 [0x7f7e3cf2b700] DEBUG - * width => 1920

Jul 04, 2013 23:17:18 [0x7f7e3cf2b700] DEBUG - * height => 1080

Jul 04, 2013 23:17:18 [0x7f7e3cf2b700] DEBUG - We want 9 segments ahead, last returned was -1 and max is -1.

Jul 04, 2013 23:17:18 [0x7f7e3cf2b700] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.

Jul 04, 2013 23:17:18 [0x7f7e22c77700] DEBUG - Request: GET /video/:/transcode/universal/session/DY3KLKEVFF54/base/00101.ts [192.168.3.201:49849] (10 live)

Jul 04, 2013 23:17:18 [0x7f7e22c77700] DEBUG - Asked for segment 101 from session.

So is it transcoding or not?

Playback is just awful, still images, green images, blocks.

Log from PMS using 1080p @ 20Mbps:

Jul 04, 2013 23:14:50 [0x7f7e03fff700] DEBUG - Starting a transcode session DY3KLKEVFF54 at offset 914.0 (stopped=1)

Jul 04, 2013 23:14:50 [0x7f7e03fff700] DEBUG - Job running: /usr/lib/plexmediaserver/Resources/Plex New Transcoder -ss 914 -i http://127.0.0.1:32400/library/parts/3/file.mkv -threads 0 -y -segment_format mpegts -f segment -loglevel quiet -loglevel_plex error -map_metadata -1 -progressurl http://127.0.0.1:32400/video/:/transcode/session/DY3KLKEVFF54/progress -vcodec libx264 -crf 18 -maxrate 20000k -bufsize 40000k -vsi 0x0 -preset veryfast -x264opts cabac=0:me=dia:subme=0:me_range=4:no_chroma_me:8x8dct=0:bframes=0:rc_lookahead=1:qcomp=0.50:qpmin=10:qpmax=51:qpstep=4:partitions=none:bframes=3:cabac=1 -flags -global_header -segment_time 1 -segment_start_number 914 -force_key_frames expr:gte(t,n_forced*1) -map 0:0 -sn -acodec aac -strict -2 -cutoff 15000 -ac 2 -ab 193k -map 0:1 media-%05d.ts

Jul 04, 2013 23:14:50 [0x7f7e558ef700] DEBUG - Request: GET /library/parts/3/file.mkv [127.0.0.1:54864] (9 live)

Jul 04, 2013 23:14:50 [0x7f7e558ef700] DEBUG - Content-Length of /mnt/plexmedia/HPUSB2TBa/MKV/Arthur Christmas.mkv is 21371823609.

Jul 04, 2013 23:14:50 [0x7f7e22c77700] DEBUG - Request: GET /library/parts/3/file.mkv [127.0.0.1:54865] (9 live)

Jul 04, 2013 23:14:50 [0x7f7e22c77700] DEBUG - Request range: 21371679886 to 0

Jul 04, 2013 23:14:50 [0x7f7e22c77700] DEBUG - Content-Length of /mnt/plexmedia/HPUSB2TBa/MKV/Arthur Christmas.mkv is 21371823609.

Jul 04, 2013 23:14:50 [0x7f7e23fff700] DEBUG - Request: GET /library/parts/3/file.mkv [127.0.0.1:54866] (9 live)

Jul 04, 2013 23:14:50 [0x7f7e23fff700] DEBUG - Request range: 5150851 to 0

Jul 04, 2013 23:14:50 [0x7f7e23fff700] DEBUG - Content-Length of /mnt/plexmedia/HPUSB2TBa/MKV/Arthur Christmas.mkv is 21371823609.

Jul 04, 2013 23:14:50 [0x7f7e3cf2b700] DEBUG - Request: GET /library/parts/3/file.mkv [127.0.0.1:54867] (9 live)

Jul 04, 2013 23:14:50 [0x7f7e3cf2b700] DEBUG - Request range: 3347942270 to 0

Jul 04, 2013 23:14:50 [0x7f7e3cf2b700] DEBUG - Content-Length of /mnt/plexmedia/HPUSB2TBa/MKV/Arthur Christmas.mkv is 21371823609.

Jul 04, 2013 23:14:50 [0x7f7e20f2b700] INFO - [Transcoder] Input #0, matroska,webm, from 'http://127.0.0.1:32400/library/parts/3/file.mkv':

Jul 04, 2013 23:14:50 [0x7f7e2363b700] INFO - [Transcoder] Stream #0:0(eng): Video: h264, yuv420p, 1920x1080 [SAR 1:1 DAR 16:9], 23.98 fps, 23.98 tbr, 1k tbn, 47.95 tbc

Jul 04, 2013 23:14:50 [0x7f7e558ef700] INFO - [Transcoder] Stream #0:1(eng): Audio: dts, 48000 Hz, 5.1(side), fltp, 1536 kb/s (default)

Jul 04, 2013 23:14:50 [0x7f7e22c77700] INFO - [Transcoder] Stream #0:2(eng): Audio: ac3, 48000 Hz, 5.1(side), fltp, 640 kb/s

Jul 04, 2013 23:14:50 [0x7f7e23fff700] INFO - [Transcoder] Stream #0:3(fra): Audio: dts, 48000 Hz, 5.1(side), fltp, 1536 kb/s

Jul 04, 2013 23:14:50 [0x7f7e20f2b700] INFO - [Transcoder] Stream #0:4(spa): Audio: ac3, 48000 Hz, 5.1(side), fltp, 640 kb/s

Jul 04, 2013 23:14:50 [0x7f7e2363b700] INFO - [Transcoder] Stream #0:5(eng): Subtitle: hdmv_pgs_subtitle (default)

Jul 04, 2013 23:14:50 [0x7f7e558ef700] INFO - [Transcoder] Stream #0:6(eng): Subtitle: hdmv_pgs_subtitle

Jul 04, 2013 23:14:50 [0x7f7e22c77700] INFO - [Transcoder] Stream #0:7(fra): Subtitle: hdmv_pgs_subtitle

Jul 04, 2013 23:14:50 [0x7f7e23fff700] INFO - [Transcoder] Stream #0:8(fra): Subtitle: hdmv_pgs_subtitle

Jul 04, 2013 23:14:50 [0x7f7e3cf2b700] INFO - [Transcoder] Stream #0:9(spa): Subtitle: hdmv_pgs_subtitle

Jul 04, 2013 23:14:50 [0x7f7e20f2b700] INFO - [Transcoder] Stream #0:10(spa): Subtitle: hdmv_pgs_subtitle

Jul 04, 2013 23:14:50 [0x7f7e2363b700] INFO - [Transcoder] Stream #0:11(fra): Subtitle: hdmv_pgs_subtitle

Jul 04, 2013 23:14:50 [0x7f7e558ef700] INFO - [Transcoder] Stream #0:12(fra): Subtitle: hdmv_pgs_subtitle

Jul 04, 2013 23:14:50 [0x7f7e22c77700] DEBUG - [Transcoder] Average FPS ~ 24 fps, Frame rate ~ 24 fps.

Jul 04, 2013 23:14:50 [0x7f7e03fff700] DEBUG - [FFMPEG] Duration: 5852

Jul 04, 2013 23:14:50 [0x7f7e03fff700] DEBUG - Read line, and done: 1

Jul 04, 2013 23:14:50 [0x7f7e03fff700] DEBUG - Started session successfully: DY3KLKEVFF54

Jul 04, 2013 23:14:50 [0x7f7e23fff700] INFO - [Transcoder] Output #0, segment, to 'media-%05d.ts':

Jul 04, 2013 23:14:50 [0x7f7e3cf2b700] INFO - [Transcoder] Stream #0:0: Video: h264, yuv420p, 1920x1080 [SAR 1:1 DAR 16:9], q=-1--1, 90k tbn, 23.98 tbc

Jul 04, 2013 23:14:50 [0x7f7e20f2b700] INFO - [Transcoder] Stream #0:1: Audio: aac, 48000 Hz, stereo, fltp, 193 kb/s (default)

Jul 04, 2013 23:14:50 [0x7f7e2363b700] DEBUG - Request: PUT /video/:/transcode/session/DY3KLKEVFF54/progress?width=1920&height=1080 [127.0.0.1:54886] (10 live)

Jul 04, 2013 23:14:50 [0x7f7e2363b700] DEBUG - * width => 1920

Jul 04, 2013 23:14:50 [0x7f7e2363b700] DEBUG - * height => 1080

Jul 04, 2013 23:14:50 [0x7f7e2363b700] DEBUG - We want 90 segments ahead, last returned was -1 and max is -1.

Jul 04, 2013 23:14:50 [0x7f7e2363b700] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.

Jul 04, 2013 23:14:50 [0x7f7e558ef700] ERROR - [Transcoder] [h264 @ 0x5c5a2a0] Missing reference picture, default is 0

Jul 04, 2013 23:14:50 [0x7f7e22c77700] ERROR - [Transcoder] [h264 @ 0x5c5a2a0] decode_slice_header error

Jul 04, 2013 23:14:50 [0x7f7e23fff700] ERROR - [Transcoder] [h264 @ 0x5c5a2a0] Missing reference picture, default is 0

Jul 04, 2013 23:14:50 [0x7f7e3cf2b700] ERROR - [Transcoder] [h264 @ 0x5c5a2a0] decode_slice_header error

Jul 04, 2013 23:14:50 [0x7f7e20f2b700] ERROR - [Transcoder] [h264 @ 0x5c5a2a0] Missing reference picture, default is 0

Jul 04, 2013 23:14:50 [0x7f7e2363b700] ERROR - [Transcoder] [h264 @ 0x5c5a2a0] decode_slice_header error

Jul 04, 2013 23:14:50 [0x7f7e558ef700] ERROR - [Transcoder] [h264 @ 0x5c5a2a0] Missing reference picture, default is 0

Jul 04, 2013 23:14:50 [0x7f7e22c77700] ERROR - [Transcoder] [h264 @ 0x5c5a2a0] decode_slice_header error

Jul 04, 2013 23:14:50 [0x7f7e23fff700] ERROR - [Transcoder] [matroska,webm @ 0x2f38780] File is broken, keyframes not correctly marked!

Jul 04, 2013 23:14:51 [0x7f7e3f63b700] DEBUG - NetworkService: Have an old server at index 1 and address 192.168.3.221 (we just got packet from 127.0.0.1, index 0)

Jul 04, 2013 23:14:51 [0x7f7e3f63b700] DEBUG - NetworkServiceBrowser: SERVER updated: 127.0.0.1

Jul 04, 2013 23:14:51 [0x7f7e3f63b700] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=127.0.0.1

Jul 04, 2013 23:14:51 [0x7f7e3f63b700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=127.0.0.1

Jul 04, 2013 23:14:51 [0x7f7e3f63b700] DEBUG - HTTP requesting to: http://127.0.0.1:48890/system/notify/serverUpdated?host=127.0.0.1

Jul 04, 2013 23:14:51 [0x7f7e3cf2b700] DEBUG - Request: GET /servers [127.0.0.1:54897] (10 live)

Jul 04, 2013 23:14:51 [0x7f7e3cf2b700] DEBUG - It took 0.000000 sec to serialize a list with 1 elements.

Jul 04, 2013 23:14:51 [0x7f7e20f2b700] DEBUG - Request: GET /library/sections [127.0.0.1:54898] (10 live)

Jul 04, 2013 23:14:51 [0x7f7e20f2b700] DEBUG - It took 0.000000 sec to serialize a list with 1 elements.

Jul 04, 2013 23:14:51 [0x7f7e2363b700] DEBUG - Request: GET /channels/all [127.0.0.1:54899] (10 live)

Jul 04, 2013 23:14:51 [0x7f7e2363b700] DEBUG - It took 0.000000 sec to serialize a list with 4 elements.

Jul 04, 2013 23:14:51 [0x7f7e3f63b700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.

Jul 04, 2013 23:14:51 [0x7f7e3f63b700] DEBUG - NetworkService: Have an old server at index 0 and address 127.0.0.1 (we just got packet from 192.168.3.221, index 1)

Jul 04, 2013 23:14:51 [0x7f7e3f63b700] DEBUG - NetworkServiceBrowser: SERVER updated: 192.168.3.221

Jul 04, 2013 23:14:51 [0x7f7e3f63b700] DEBUG - Notifying the system bundle an update to server /system/notify/serverUpdated?host=192.168.3.221

Jul 04, 2013 23:14:51 [0x7f7e3f63b700] DEBUG - [com.plexapp.system] Sending command over HTTP (GET): /system/notify/serverUpdated?host=192.168.3.221

Jul 04, 2013 23:14:51 [0x7f7e3f63b700] DEBUG - HTTP requesting to: http://127.0.0.1:48890/system/notify/serverUpdated?host=192.168.3.221

Jul 04, 2013 23:14:51 [0x7f7e558ef700] DEBUG - Request: GET /servers [127.0.0.1:54901] (10 live)

Jul 04, 2013 23:14:51 [0x7f7e558ef700] DEBUG - It took 0.000000 sec to serialize a list with 1 elements.

Jul 04, 2013 23:14:51 [0x7f7e22c77700] DEBUG - Request: GET /library/sections [192.168.3.221:40314] (10 live)

Jul 04, 2013 23:14:51 [0x7f7e22c77700] DEBUG - It took 0.000000 sec to serialize a list with 1 elements.

Jul 04, 2013 23:14:51 [0x7f7e23fff700] DEBUG - Request: GET /channels/all [192.168.3.221:40315] (10 live)

Jul 04, 2013 23:14:51 [0x7f7e23fff700] DEBUG - It took 0.000000 sec to serialize a list with 4 elements.

Jul 04, 2013 23:14:51 [0x7f7e3f63b700] DEBUG - [com.plexapp.system] HTTP reply status 200, with 0 bytes of content.

Jul 04, 2013 23:14:51 [0x7f7e03fff700] DEBUG - Returning segment 914 from session: /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00914.ts

Jul 04, 2013 23:14:51 [0x7f7e03fff700] DEBUG - Content-Length of /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00914.ts is 654804.

Jul 04, 2013 23:14:51 [0x7f7e3cf2b700] DEBUG - Request: GET /video/:/transcode/universal/session/DY3KLKEVFF54/base/00915.ts [192.168.3.201:49606] (10 live)

Jul 04, 2013 23:14:51 [0x7f7e3cf2b700] DEBUG - Asked for segment 915 from session.

Jul 04, 2013 23:14:51 [0x7f7e3cf2b700] DEBUG - Returning segment 915 from session: /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00915.ts

Jul 04, 2013 23:14:51 [0x7f7e3cf2b700] DEBUG - Content-Length of /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00915.ts is 823816.

Jul 04, 2013 23:14:51 [0x7f7e20f2b700] DEBUG - Request: GET /:/timeline?ratingKey=3&duration=5852864&key=%2Flibrary%2Fmetadata%2F3&state=play&time=914293&report=1&X-Plex-Client-Identifier=DY3KLKEVFF54&X-Plex-Device-Name=Apple%20TV [192.168.3.201:49607] (10 live)

Jul 04, 2013 23:14:51 [0x7f7e20f2b700] DEBUG - * ratingKey => 3

Jul 04, 2013 23:14:51 [0x7f7e20f2b700] DEBUG - * duration => 5852864

Jul 04, 2013 23:14:51 [0x7f7e20f2b700] DEBUG - * key => /library/metadata/3

Jul 04, 2013 23:14:51 [0x7f7e20f2b700] DEBUG - * state => play

Jul 04, 2013 23:14:51 [0x7f7e20f2b700] DEBUG - * time => 914293

Jul 04, 2013 23:14:51 [0x7f7e20f2b700] DEBUG - * report => 1

Jul 04, 2013 23:14:51 [0x7f7e20f2b700] DEBUG - * X-Plex-Client-Identifier => DY3KLKEVFF54

Jul 04, 2013 23:14:51 [0x7f7e20f2b700] DEBUG - * X-Plex-Device-Name => Apple TV

Jul 04, 2013 23:14:51 [0x7f7e20f2b700] DEBUG - Client [DY3KLKEVFF54] reporting timeline state play, progress of 914293/5852864ms for guid=, ratingKey=3 url=, key=/library/metadata/3, containerKey=, metadataId=3

Jul 04, 2013 23:14:52 [0x7f7e20f2b700] DEBUG - Play progress on 3 'Arthur Christmas' - got played 914293 ms by account 1!

Jul 04, 2013 23:14:52 [0x7f7e20f2b700] DEBUG - [Now] Device is (Apple TV).

Jul 04, 2013 23:14:52 [0x7f7e20f2b700] DEBUG - [Now] Updated play state for /library/metadata/3.

Jul 04, 2013 23:14:52 [0x7f7e2363b700] DEBUG - Request: GET /video/:/transcode/universal/session/DY3KLKEVFF54/base/00916.ts [192.168.3.201:49609] (10 live)

Jul 04, 2013 23:14:52 [0x7f7e2363b700] DEBUG - Asked for segment 916 from session.

Jul 04, 2013 23:14:52 [0x7f7e2363b700] DEBUG - Returning segment 916 from session: /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00916.ts

Jul 04, 2013 23:14:52 [0x7f7e2363b700] DEBUG - Content-Length of /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00916.ts is 520760.

Jul 04, 2013 23:14:52 [0x7f7e558ef700] DEBUG - Request: GET /video/:/transcode/universal/session/DY3KLKEVFF54/base/00917.ts [192.168.3.201:49610] (10 live)

Jul 04, 2013 23:14:52 [0x7f7e558ef700] DEBUG - Asked for segment 917 from session.

Jul 04, 2013 23:14:52 [0x7f7e558ef700] DEBUG - Returning segment 917 from session: /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00917.ts

Jul 04, 2013 23:14:52 [0x7f7e558ef700] DEBUG - Content-Length of /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00917.ts is 581108.

Jul 04, 2013 23:14:52 [0x7f7e22c77700] DEBUG - Request: GET /video/:/transcode/universal/session/DY3KLKEVFF54/base/00918.ts [192.168.3.201:49611] (10 live)

Jul 04, 2013 23:14:52 [0x7f7e22c77700] DEBUG - Asked for segment 918 from session.

Jul 04, 2013 23:14:52 [0x7f7e22c77700] DEBUG - Returning segment 918 from session: /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00918.ts

Jul 04, 2013 23:14:52 [0x7f7e22c77700] DEBUG - Content-Length of /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00918.ts is 392920.

Jul 04, 2013 23:14:52 [0x7f7e23fff700] DEBUG - Request: GET /video/:/transcode/universal/session/DY3KLKEVFF54/base/00919.ts [192.168.3.201:49612] (10 live)

Jul 04, 2013 23:14:52 [0x7f7e23fff700] DEBUG - Asked for segment 919 from session.

Jul 04, 2013 23:14:52 [0x7f7e23fff700] DEBUG - Returning segment 919 from session: /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00919.ts

Jul 04, 2013 23:14:52 [0x7f7e23fff700] DEBUG - Content-Length of /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00919.ts is 448380.

Jul 04, 2013 23:14:52 [0x7f7e03fff700] DEBUG - Request: PUT /video/:/transcode/session/DY3KLKEVFF54/progress?progress=0.1&size=-22&speed=3.9&remaining=1499 [127.0.0.1:54906] (10 live)

Jul 04, 2013 23:14:52 [0x7f7e03fff700] DEBUG - * progress => 0.1

Jul 04, 2013 23:14:52 [0x7f7e03fff700] DEBUG - * size => -22

Jul 04, 2013 23:14:52 [0x7f7e03fff700] DEBUG - * speed => 3.9

Jul 04, 2013 23:14:52 [0x7f7e03fff700] DEBUG - * remaining => 1499

Jul 04, 2013 23:14:52 [0x7f7e03fff700] DEBUG - We want 90 segments ahead, last returned was 919 and max is 919.

Jul 04, 2013 23:14:52 [0x7f7e03fff700] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.

Jul 04, 2013 23:14:52 [0x7f7e3cf2b700] DEBUG - Request: GET /video/:/transcode/universal/session/DY3KLKEVFF54/base/00920.ts [192.168.3.201:49613] (10 live)

Jul 04, 2013 23:14:52 [0x7f7e3cf2b700] DEBUG - Asked for segment 920 from session.

Jul 04, 2013 23:14:52 [0x7f7e3cf2b700] DEBUG - Returning segment 920 from session: /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00920.ts

Jul 04, 2013 23:14:52 [0x7f7e3cf2b700] DEBUG - Content-Length of /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00920.ts is 974028.

Jul 04, 2013 23:14:52 [0x7f7e20f2b700] DEBUG - Request: GET /video/:/transcode/universal/session/DY3KLKEVFF54/base/00921.ts [192.168.3.201:49614] (10 live)

Jul 04, 2013 23:14:52 [0x7f7e20f2b700] DEBUG - Asked for segment 921 from session.

Jul 04, 2013 23:14:52 [0x7f7e20f2b700] DEBUG - Returning segment 921 from session: /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00921.ts

Jul 04, 2013 23:14:52 [0x7f7e20f2b700] DEBUG - Content-Length of /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00921.ts is 1073856.

Jul 04, 2013 23:14:53 [0x7f7e2363b700] DEBUG - Request: GET /video/:/transcode/universal/session/DY3KLKEVFF54/base/00922.ts [192.168.3.201:49615] (10 live)

Jul 04, 2013 23:14:53 [0x7f7e2363b700] DEBUG - Asked for segment 922 from session.

Jul 04, 2013 23:14:53 [0x7f7e2363b700] DEBUG - Returning segment 922 from session: /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00922.ts

Jul 04, 2013 23:14:53 [0x7f7e2363b700] DEBUG - Content-Length of /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00922.ts is 958236.

Jul 04, 2013 23:14:53 [0x7f7e558ef700] DEBUG - Request: GET /video/:/transcode/universal/session/DY3KLKEVFF54/base/00923.ts [192.168.3.201:49616] (10 live)

Jul 04, 2013 23:14:53 [0x7f7e558ef700] DEBUG - Asked for segment 923 from session.

Jul 04, 2013 23:14:53 [0x7f7e558ef700] DEBUG - Returning segment 923 from session: /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00923.ts

Jul 04, 2013 23:14:53 [0x7f7e558ef700] DEBUG - Content-Length of /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00923.ts is 1292124.

Jul 04, 2013 23:14:53 [0x7f7e22c77700] DEBUG - Request: GET /video/:/transcode/universal/session/DY3KLKEVFF54/base/00924.ts [192.168.3.201:49617] (10 live)

Jul 04, 2013 23:14:53 [0x7f7e22c77700] DEBUG - Asked for segment 924 from session.

Jul 04, 2013 23:14:53 [0x7f7e22c77700] DEBUG - Returning segment 924 from session: /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00924.ts

Jul 04, 2013 23:14:53 [0x7f7e22c77700] DEBUG - Content-Length of /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00924.ts is 1448164.

Jul 04, 2013 23:14:54 [0x7f7e23fff700] DEBUG - Request: GET /video/:/transcode/universal/session/DY3KLKEVFF54/base/00925.ts [192.168.3.201:49618] (10 live)

Jul 04, 2013 23:14:54 [0x7f7e23fff700] DEBUG - Asked for segment 925 from session.

Jul 04, 2013 23:14:54 [0x7f7e23fff700] DEBUG - Returning segment 925 from session: /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00925.ts

Jul 04, 2013 23:14:54 [0x7f7e23fff700] DEBUG - Content-Length of /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00925.ts is 937932.

Jul 04, 2013 23:14:54 [0x7f7e03fff700] DEBUG - Request: GET /video/:/transcode/universal/session/DY3KLKEVFF54/base/00926.ts [192.168.3.201:49619] (10 live)

Jul 04, 2013 23:14:54 [0x7f7e03fff700] DEBUG - Asked for segment 926 from session.

Jul 04, 2013 23:14:54 [0x7f7e03fff700] DEBUG - Returning segment 926 from session: /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00926.ts

Jul 04, 2013 23:14:54 [0x7f7e03fff700] DEBUG - Content-Length of /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00926.ts is 2124588.

Jul 04, 2013 23:14:54 [0x7f7e3cf2b700] DEBUG - Request: PUT /video/:/transcode/session/DY3KLKEVFF54/progress?progress=0.2&size=-22&speed=3.4&remaining=1606 [127.0.0.1:54909] (10 live)

Jul 04, 2013 23:14:54 [0x7f7e3cf2b700] DEBUG - * progress => 0.2

Jul 04, 2013 23:14:54 [0x7f7e3cf2b700] DEBUG - * size => -22

Jul 04, 2013 23:14:54 [0x7f7e3cf2b700] DEBUG - * speed => 3.4

Jul 04, 2013 23:14:54 [0x7f7e3cf2b700] DEBUG - * remaining => 1606

Jul 04, 2013 23:14:54 [0x7f7e3cf2b700] DEBUG - We want 90 segments ahead, last returned was 926 and max is 926.

Jul 04, 2013 23:14:54 [0x7f7e3cf2b700] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.

Jul 04, 2013 23:14:54 [0x7f7e20f2b700] DEBUG - Request: GET /video/:/transcode/universal/session/DY3KLKEVFF54/base/00927.ts [192.168.3.201:49620] (10 live)

Jul 04, 2013 23:14:54 [0x7f7e20f2b700] DEBUG - Asked for segment 927 from session.

Jul 04, 2013 23:14:54 [0x7f7e20f2b700] DEBUG - Returning segment 927 from session: /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00927.ts

Jul 04, 2013 23:14:54 [0x7f7e20f2b700] DEBUG - Content-Length of /tmp/plex-transcode-DY3KLKEVFF54-68afdb3a-bda5-47d4-9cc3-ffc9c8f57273/media-00927.ts is 2423696.

I would understand it asks for trans

glougloumouth · July 5, 2013, 3:25am

Mmm, and I don't like cross posting, can you please move your answers to the PMS linux forum:

Transcoding AVCHD to 1080p @ 40Mbps bug?

http://forums.plexapp.com/index.php/topic/73294-transcoding-avchd-to-1080p-40mbps-bug/

I understand the 40Mbps profile was created to support PlexConnect & the aTV, but as it looks like the issue is either with my files of with PMS, better move the discussion over there.

Thank you.

Gl.

elmerfreak · September 14, 2013, 3:38am

Same here... My file is a 1:1 bluray remux, btw when I set subtitles it run nice, any solution?

glougloumouth · September 26, 2013, 3:18pm

OK,

I agree the issue is still present.

Did a clean CentOS 6.4 x64 installation with PMS 0.9.8.6.175 x64. Using Sept 26 2013 download of Plex Connect. Using aTV v3 with OS 6.0 (6646.65) & created the fake certificate.

Everything works fine, but I still see the same issue with Plex Connect set to 40Mbps.

I really want to thank elmerfreak for finding that activating subtitles does indeed lead to a working stream (including the worse case I had with just green screen and artifacts).

At least we have a work around for now.

baa · September 26, 2013, 6:33pm

Mmm, and I don't like cross posting, can you please move your answers to the PMS linux forum:

Transcoding AVCHD to 1080p @ 40Mbps bug?

http://forums.plexapp.com/index.php/topic/73294-transcoding-avchd-to-1080p-40mbps-bug/

I understand the 40Mbps profile was created to support PlexConnect & the aTV, but as it looks like the issue is either with my files of with PMS, better move the discussion over there.

Thank you.

Gl.

...and I don't like copy-pasting huge logs into the posts. By the way, font is hard to read...

Have you even looked at that stuff yourself?