Jump to content


Photo

WAN limitations?

buffering wan

  • Please log in to reply
25 replies to this topic

#1 dodgen247

dodgen247

    Member

  • Members
  • PipPip
  • 46 posts

Posted 17 March 2013 - 07:25 PM

Original post in the forums started by someone else. My posts are below:

 

I am having the same issue.... 

I have rented a hosted machine.

2008R2

i3 2130 (3.4GHz dual core w/hyperthreading)

8GB of Ram

1TB Mirrored drives 170MB/s Read 110MB/s Write

100Mbit internet connection.

 

Everything I try to play buffers every few seconds.

Biggest thing I noticed on this server I get a CPU spike for 15 second then it drops to 5%. 

I have another plex server that has dual quad core processors and it will transcode for much longer than that!

 

The main purpose for purchasing a hosted server was for remote viewing without saturating my home connection. 

 

I have also tried the latest public release and the latest plexpass release both have yielded the same result. 

I also verified my home connection wasnt the limiting factor. I also noticed it wasnt really a stead data stream either; lots of spikes on my bandwidth monitor. 

 

I try to not bring up an issue until I have tried everything I can think of to not waste peoples time, but I would greatly appreciate any assistance in this

 

Plex Media Server Log:

 

Cleaning directory for session 981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C (C:\Users\root\AppData\Local\Temp\2\plex-transcode-981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C-e8a3cbb7-3ef7-45d5-b7f9-2527d8779269)
Mar 17, 2013 19:21:00:777 [3492] DEBUG - Starting a transcode session 981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C at offset 47.0 (stopped=1)
Mar 17, 2013 19:21:00:777 [3492] DEBUG - Setting up segmented session, quality=10
Mar 17, 2013 19:21:00:777 [3492] DEBUG - Item has a key, we're going to get information about it (http://127.0.0.1:324...ry/metadata/137).
Mar 17, 2013 19:21:00:785 [3492] DEBUG - It took 0.002000 sec to serialize a list with 1 elements.
Mar 17, 2013 19:21:00:785 [3492] DEBUG - Found the part, now looking for the selected streams.
Mar 17, 2013 19:21:00:785 [3492] DEBUG - Found selected audio stream 1 with language .
Mar 17, 2013 19:21:00:785 [3492] DEBUG - Computed display width of 7.8"
Mar 17, 2013 19:21:00:785 [3492] DEBUG - Audio Stream copy => ac:
Mar 17, 2013 19:21:00:786 [3492] DEBUG - Job running: C:\Program Files (x86)\Plex\Plex Media Server\PlexTranscoder.exe transcode "http://127.0.0.1:324...ts/106/file.mkv" "C:\Users\root\AppData\Local\Temp\2\plex-transcode-981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C-e8a3cbb7-3ef7-45d5-b7f9-2527d8779269\media" 10 1 47 1 nil 1.23000002 yes UTF-8 default "" "" 0.629999995 nil none "" "" "" 3355443 yes "" no "http://127.0.0.1:324...D0F36C/progress
Mar 17, 2013 19:21:00:786 [3492] DEBUG -  [FFMPEG] Resolution: 1280x720
Mar 17, 2013 19:21:00:786 [3492] DEBUG - Read line, and done: 0
Mar 17, 2013 19:21:01:144 [1028] DEBUG - [Transcoder] Starting transcoder v306 (t=1s, q=10)
Mar 17, 2013 19:21:01:145 [2932] DEBUG - [Transcoder] Registered components, about to parse arguments.
Mar 17, 2013 19:21:01:146 [1028] DEBUG - [Transcoder] Video caps: ca=0, cv=0, ac=libmp3lame, mp3=-1, aac=-1,0, ac3=-1, dts=-1, pcm=-1, vp=4d, vr=0, vl=0, mss=3355443, anam=1
Mar 17, 2013 19:21:01:157 [2932] DEBUG - [Transcoder] About to apply initial settings.
Mar 17, 2013 19:21:01:158 [1028] DEBUG - [Transcoder] Opening the input file.
Mar 17, 2013 19:21:01:170 [2932] DEBUG - Request: GET /library/parts/106/file.mkv [127.0.0.1:54542] (4 live)
Mar 17, 2013 19:21:01:176 [2932] DEBUG - Content-Length of C:\Media\Video.mkv is 986400664.
Mar 17, 2013 19:21:01:237 [1028] DEBUG - [Transcoder] Thread count: 4
Mar 17, 2013 19:21:01:240 [2932] DEBUG - Request: GET /library/parts/106/file.mkv [127.0.0.1:54544] (5 live)
Mar 17, 2013 19:21:01:240 [2932] DEBUG - Request range: 986365452 to 0
Mar 17, 2013 19:21:01:246 [2932] DEBUG - Content-Length of C:\Media\Video.mkv is 986400664.
Mar 17, 2013 19:21:01:250 [1028] DEBUG - Request: GET /library/parts/106/file.mkv [127.0.0.1:54545] (4 live)
Mar 17, 2013 19:21:01:250 [1028] DEBUG - Request range: 4836488 to 0
Mar 17, 2013 19:21:01:256 [1028] DEBUG - Content-Length of C:\Media\Video.mkv is 986400664.
Mar 17, 2013 19:21:01:258 [2932] DEBUG - Request: GET /library/parts/106/file.mkv [127.0.0.1:54546] (5 live)
Mar 17, 2013 19:21:01:258 [2932] DEBUG - Request range: 61917186 to 0
Mar 17, 2013 19:21:01:264 [2932] DEBUG - Content-Length of C:\Media\Video.mkv is 986400664.
Mar 17, 2013 19:21:01:266 [1028] DEBUG - [Transcoder] Input #0, matroska,webm, from 'http://127.0.0.1:324...ts/106/file.mkv':
Mar 17, 2013 19:21:01:268 [2932] DEBUG - [Transcoder]     Stream #0.0(eng), 43, 1/1000: Video: h264 (High), yuv420p, 1280x720, SAR 1:1 DAR 16:9
Mar 17, 2013 19:21:01:270 [1028] DEBUG - [Transcoder]     Stream #0.1, 61, 1/1000: Audio: ac3, 48000 Hz, stereo, s16, 384 kb/s
Mar 17, 2013 19:21:01:271 [2932] DEBUG - [Transcoder] Applying output settings.
Mar 17, 2013 19:21:01:272 [1028] DEBUG - [Transcoder] Frames per second is either 23.976025 or 23.976024.
Mar 17, 2013 19:21:01:273 [2932] DEBUG - [Transcoder] Final font scale: 1.000000 (0.630000) -> 0.936000
Mar 17, 2013 19:21:01:274 [1028] DEBUG - [Transcoder] Configuring output.
Mar 17, 2013 19:21:01:286 [3492] DEBUG -  [FFMPEG] Duration: 2626.88
Mar 17, 2013 19:21:01:286 [3492] DEBUG - Read line, and done: 1
Mar 17, 2013 19:21:01:299 [2932] DEBUG - [Transcoder] Output #0, mpegts, to 'C:\Users\root\AppData\Local\Temp\2\plex-transcode-981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C-e8a3cbb7-3ef7-45d5-b7f9-2527d8779269-tmp.ts':
Mar 17, 2013 19:21:01:301 [1028] DEBUG - [Transcoder]     Stream #0.0(eng), 0, 1/90000: Video: libx264, yuv420p, 1280x720 [SAR 1:1 DAR 16:9], q=10-51, 9437 kb/s
Mar 17, 2013 19:21:01:303 [2932] DEBUG - [Transcoder]     Stream #0.1, 0, 1/90000: Audio: libmp3lame, 48000 Hz, stereo, s16, 128 kb/s
Mar 17, 2013 19:21:01:304 [1028] DEBUG - [Transcoder] The verbose setting is 0 and PLEX_DEBUG is 0.
Mar 17, 2013 19:21:01:903 [3492] DEBUG - Returning segment 47 from session: C:\Users\root\AppData\Local\Temp\2\plex-transcode-981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C-e8a3cbb7-3ef7-45d5-b7f9-2527d8779269\media-00047.ts
Mar 17, 2013 19:21:01:903 [3492] DEBUG - Content-Length of C:\Users\root\AppData\Local\Temp\2\plex-transcode-981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C-e8a3cbb7-3ef7-45d5-b7f9-2527d8779269\media-00047.ts is 709700.
Mar 17, 2013 19:21:03:315 [2932] DEBUG - Request: PUT /video/:/transcode/session/981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C/progress?progress=0.2&size=0&speed=3.1&remaining=855 [127.0.0.1:54558] (5 live)
Mar 17, 2013 19:21:03:315 [2932] DEBUG -  * progress => 0.2
Mar 17, 2013 19:21:03:315 [2932] DEBUG -  * size => 0
Mar 17, 2013 19:21:03:315 [2932] DEBUG -  * speed => 3.1
Mar 17, 2013 19:21:03:315 [2932] DEBUG -  * remaining => 855
Mar 17, 2013 19:21:03:316 [2932] DEBUG - We want 60 segments ahead, last returned was 47 and max is 52.
Mar 17, 2013 19:21:03:316 [2932] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
Mar 17, 2013 19:21:04:927 [1028] DEBUG - Request: GET /video/:/transcode/segmented/session/981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C/10/00048.ts [***********:57760] (5 live)
Mar 17, 2013 19:21:04:928 [1028] DEBUG - Asked for segment 48 from session.
Mar 17, 2013 19:21:04:929 [1028] DEBUG - Returning segment 48 from session: C:\Users\root\AppData\Local\Temp\2\plex-transcode-981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C-e8a3cbb7-3ef7-45d5-b7f9-2527d8779269\media-00048.ts
Mar 17, 2013 19:21:04:929 [1028] DEBUG - Content-Length of C:\Users\root\AppData\Local\Temp\2\plex-transcode-981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C-e8a3cbb7-3ef7-45d5-b7f9-2527d8779269\media-00048.ts is 1400976.
Mar 17, 2013 19:21:05:312 [3492] DEBUG - Request: PUT /video/:/transcode/session/981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C/progress?progress=0.4&size=0&speed=2.8&remaining=888 [127.0.0.1:54559] (5 live)
Mar 17, 2013 19:21:05:312 [3492] DEBUG -  * progress => 0.4
Mar 17, 2013 19:21:05:312 [3492] DEBUG -  * size => 0
Mar 17, 2013 19:21:05:312 [3492] DEBUG -  * speed => 2.8
Mar 17, 2013 19:21:05:312 [3492] DEBUG -  * remaining => 888
Mar 17, 2013 19:21:05:313 [3492] DEBUG - We want 60 segments ahead, last returned was 48 and max is 58.
Mar 17, 2013 19:21:05:313 [3492] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
Mar 17, 2013 19:21:07:319 [2932] DEBUG - Request: PUT /video/:/transcode/session/981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C/progress?progress=0.7&size=0&speed=4.0&remaining=785 [127.0.0.1:54561] (6 live)
Mar 17, 2013 19:21:07:319 [2932] DEBUG -  * progress => 0.7
Mar 17, 2013 19:21:07:319 [2932] DEBUG -  * size => 0
Mar 17, 2013 19:21:07:319 [2932] DEBUG -  * speed => 4.0
Mar 17, 2013 19:21:07:319 [2932] DEBUG -  * remaining => 785
Mar 17, 2013 19:21:07:321 [2932] DEBUG - We want 60 segments ahead, last returned was 48 and max is 66.
Mar 17, 2013 19:21:07:321 [2932] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
Mar 17, 2013 19:21:09:320 [1028] DEBUG - Request: PUT /video/:/transcode/session/981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C/progress?progress=1.1&size=0&speed=4.3&remaining=629 [127.0.0.1:54562] (6 live)
Mar 17, 2013 19:21:09:320 [1028] DEBUG -  * progress => 1.1
Mar 17, 2013 19:21:09:320 [1028] DEBUG -  * size => 0
Mar 17, 2013 19:21:09:320 [1028] DEBUG -  * speed => 4.3
Mar 17, 2013 19:21:09:320 [1028] DEBUG -  * remaining => 629
Mar 17, 2013 19:21:09:322 [1028] DEBUG - We want 60 segments ahead, last returned was 48 and max is 74.
Mar 17, 2013 19:21:09:322 [1028] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
Mar 17, 2013 19:21:11:326 [3492] DEBUG - Request: PUT /video/:/transcode/session/981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C/progress?progress=1.3&size=0&speed=3.1&remaining=729 [127.0.0.1:54563] (6 live)
Mar 17, 2013 19:21:11:326 [3492] DEBUG -  * progress => 1.3
Mar 17, 2013 19:21:11:326 [3492] DEBUG -  * size => 0
Mar 17, 2013 19:21:11:326 [3492] DEBUG -  * speed => 3.1
Mar 17, 2013 19:21:11:326 [3492] DEBUG -  * remaining => 729
Mar 17, 2013 19:21:11:329 [3492] DEBUG - We want 60 segments ahead, last returned was 48 and max is 80.
Mar 17, 2013 19:21:11:329 [3492] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
Mar 17, 2013 19:21:13:326 [2932] DEBUG - Request: PUT /video/:/transcode/session/981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C/progress?progress=1.6&size=0&speed=3.4&remaining=801 [127.0.0.1:54564] (6 live)
Mar 17, 2013 19:21:13:326 [2932] DEBUG -  * progress => 1.6
Mar 17, 2013 19:21:13:326 [2932] DEBUG -  * size => 0
Mar 17, 2013 19:21:13:326 [2932] DEBUG -  * speed => 3.4
Mar 17, 2013 19:21:13:326 [2932] DEBUG -  * remaining => 801
Mar 17, 2013 19:21:13:329 [2932] DEBUG - We want 60 segments ahead, last returned was 48 and max is 87.
Mar 17, 2013 19:21:13:329 [2932] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
Mar 17, 2013 19:21:15:326 [1028] DEBUG - Request: PUT /video/:/transcode/session/981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C/progress?progress=1.8&size=0&speed=3.3&remaining=763 [127.0.0.1:54565] (6 live)
Mar 17, 2013 19:21:15:326 [1028] DEBUG -  * progress => 1.8
Mar 17, 2013 19:21:15:326 [1028] DEBUG -  * size => 0
Mar 17, 2013 19:21:15:326 [1028] DEBUG -  * speed => 3.3
Mar 17, 2013 19:21:15:326 [1028] DEBUG -  * remaining => 763
Mar 17, 2013 19:21:15:328 [1028] DEBUG - We want 60 segments ahead, last returned was 48 and max is 94.
Mar 17, 2013 19:21:15:328 [1028] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
Mar 17, 2013 19:21:17:327 [3492] DEBUG - Request: PUT /video/:/transcode/session/981C1E7A-696B-48AE-A1A7-D8DFC7D0F36C/progress?progress=2.1&size=0&speed=3.4&remaining=759 [127.0.0.1:54566] (5 live)
Mar 17, 2013 19:21:17:327 [3492] DEBUG -  * progress => 2.1
Mar 17, 2013 19:21:17:328 [3492] DEBUG -  * size => 0
Mar 17, 2013 19:21:17:328 [3492] DEBUG -  * speed => 3.4
Mar 17, 2013 19:21:17:328 [3492] DEBUG -  * remaining => 759
Mar 17, 2013 19:21:17:330 [3492] DEBUG - We want 60 segments ahead, last returned was 48 and max is 101.
Mar 17, 2013 19:21:17:330 [3492] DEBUG - It took 0.000000 sec to serialize a list with 0 elements.
 
 
This also doesnt seem correct. This graph shows a spike for the first few second then it drops to nothing. I would expect until the entire video was transcoded it wouldnt drop off...
 
e31DlFc.png


#2 dodgen247

dodgen247

    Member

  • Members
  • PipPip
  • 46 posts

Posted 17 March 2013 - 07:27 PM

I have also tried this with multiple devices: iPhone, iPad, rMBP, Windows PC, Web interface.

I have tried many settings:

Server set at 20,12,10,8,4 Mbps

Clients set at Direct Play down to 2Mbps



#3 lazybones

lazybones

    Rock Star

  • Members
  • PipPipPipPipPip
  • 710 posts

Posted 17 March 2013 - 09:40 PM

"Everything I play buffers for a few second" Is that the problem you are trying to solve? It is a little unclear to me from your post what is wrong and what your expectation is.

#4 dodgen247

dodgen247

    Member

  • Members
  • PipPip
  • 46 posts

Posted 17 March 2013 - 10:21 PM

I am sorry I wasnt more specific.

No matter what I change all videos buffer every couple of seconds. I wouldnt mind a short time in the beginning, but what I am experiencing makes videos unwatchable. 



#5 dodgen247

dodgen247

    Member

  • Members
  • PipPip
  • 46 posts

Posted 17 March 2013 - 10:54 PM

Did some more testing from my iPad. Match server and iOS App settings up to 4Mbps and everything seemed to work fine.

CPU usage looked different than what I posted before; same big spike, but little spike every few seconds while playing.

As soon as I selected 8Mbps I get what I posted above as far as CPU usage and the buffering comes back every couple seconds.



#6 dodgen247

dodgen247

    Member

  • Members
  • PipPip
  • 46 posts

Posted 17 March 2013 - 11:12 PM

Another update:

Still buffers every few seconds on the desktop client with the same settings which dont buffer on the iOS client...



#7 lazybones

lazybones

    Rock Star

  • Members
  • PipPipPipPipPip
  • 710 posts

Posted 18 March 2013 - 05:25 AM

What OS is the hosted server running? Have you attempted a direct download from the webui on the hosted server to test if you get anywhere a sustained greater than 4mbits throughput ?

#8 dodgen247

dodgen247

    Member

  • Members
  • PipPip
  • 46 posts

Posted 18 March 2013 - 01:34 PM

Server 2008R2. I just tested and was able to download the file through plex at my max local speed (12Mbit) I also tested an upload to a file hosting site and it hit the remote servers limit (100Mbit).



#9 lazybones

lazybones

    Rock Star

  • Members
  • PipPipPipPipPip
  • 710 posts

Posted 18 March 2013 - 02:24 PM

Over in the normal Plex server section of the forums it looks like some other users running the windows Plex server are having similar issues. It may be a bug.

#10 dodgen247

dodgen247

    Member

  • Members
  • PipPip
  • 46 posts

Posted 18 March 2013 - 03:50 PM

I saw the one with someone having an issue with 720p videos buffering. My local Plex server doesnt have any issues with anything I have tried, I will do some more testing on that to verify though. My issues seem to be directly related to my hosted streaming remotely... I saw some older topics from a couple of years ago and saw most people had switched to another streaming setup because it never seemed to gain any traction. I thoroughly love this program and have no desire to change to anything else. I would gladly pay more than the little I do for the plexpass. It is worth every penny and more to me!!!



#11 lazybones

lazybones

    Rock Star

  • Members
  • PipPipPipPipPip
  • 710 posts

Posted 18 March 2013 - 11:22 PM

In all honestly the bit rate and sustained bandwidth of the connection end to end is the most likely cause regardless of what it is rated. The download test shows the peak / average speed is there bit doesn't test for dropout or packet loss. There are some tests that you can run with iPerf to do a proper end to end test with more detailed stats. I can probably figure out some reasonable tests to run. I believe Plex only uses TCP bases streaming so jitter and latency could be factors that are not visible with the other tests end to end.

#12 dodgen247

dodgen247

    Member

  • Members
  • PipPip
  • 46 posts

Posted 19 March 2013 - 04:06 AM

Is there a way for the local client/device to cache more locally in order to compensate for the irregularities? 



#13 dwanthny

dwanthny

    Dedicated Member

  • Members
  • PipPipPip
  • 257 posts

Posted 19 March 2013 - 04:14 AM

I'm not at home right now so my directions are iffy, but have you gone into the advanced preferences and tried reverting to the previous "Stable" transcoder to see if that makes a difference?

#14 JimPhreak

JimPhreak

    Dedicated Member

  • Members
  • PipPipPip
  • 200 posts

Posted 19 March 2013 - 04:53 AM

How many different kinds of video files have you tested this with?  I had an issue where 2-3 files (of course the 2-3 I wanted to watch at that time) gave me the same exact issue and it turned out to be an issue with the files themselves.  Probably bad indexes.  I replaced the files with different versions (via torrent) and it completely fixed the issue.  Anytime I'v had an issue like that with constant buffering I've replaced the file and it fixes it.  I don't how many of your files you've tried or what formats they are but you may want to look into it.



#15 dodgen247

dodgen247

    Member

  • Members
  • PipPip
  • 46 posts

Posted 19 March 2013 - 05:59 AM

I could not find that option...

I have tried quite a few different videos. Ironically I have better luck with direct play.

I have been watching the vq and aq during the videos and even at the 4mbps option they cant keep up and once they hit 0% they buffer. 



#16 JimPhreak

JimPhreak

    Dedicated Member

  • Members
  • PipPipPip
  • 200 posts

Posted 19 March 2013 - 02:58 PM

I could not find that option...

I have tried quite a few different videos. Ironically I have better luck with direct play.

I have been watching the vq and aq during the videos and even at the 4mbps option they cant keep up and once they hit 0% they buffer. 

 

Well the fact that direct play works better makes more sense since those files don't have to be transcoded.  Files (especially if you've gotten them through torrents) can be very hit and miss as to the quality of their indexing.  I would try at least 15-20 videos before you can say it's a problem with your PMS or connection.



#17 dodgen247

dodgen247

    Member

  • Members
  • PipPip
  • 46 posts

Posted 20 March 2013 - 01:28 AM

Well I just saturated a 40mbit connection with downloads from my server. One thing I noticed is that each download leveled out at 1.5M/s which makes no sense. Anyone have an idea of why?!

 

Will try more videos, but I have tested some of the videos on my local server even forcing transcoding does not cause the issues I am experiencing.



#18 JimPhreak

JimPhreak

    Dedicated Member

  • Members
  • PipPipPip
  • 200 posts

Posted 20 March 2013 - 01:48 AM

Well I just saturated a 40mbit connection with downloads from my server. One thing I noticed is that each download leveled out at 1.5M/s which makes no sense. Anyone have an idea of why?!

 

Will try more videos, but I have tested some of the videos on my local server even forcing transcoding does not cause the issues I am experiencing.

 

1.5MB/s (I assume that's what you meant) is equivalent to about 12mbit per second connection so if anything you exceeded your bandwith with 4 of downloads running at 1.5MB/s (4x12 = 48mbit).



#19 lazybones

lazybones

    Rock Star

  • Members
  • PipPipPipPipPip
  • 710 posts

Posted 20 March 2013 - 02:24 AM

What is your ping to the server? This is why I was going to get back to you about testing with iPerf.. TCP sessions rapidly degrade to a maximum of about 1Mbit per second once ping / latency reaches a specific point because each packet requires confirmation.

#20 dodgen247

dodgen247

    Member

  • Members
  • PipPip
  • 46 posts

Posted 20 March 2013 - 02:33 AM

Yes, only used M/s because that's what wget displays, also I did not want to confuse anyone who doesn't know the difference between Mbps and MB/s. I was using it to test bandwidth of the server by snatching the raw mkv files.

 

I know that I exceeded the bandwidth with all of the files downloading. The problem is that I had to start multiple download sessions to do so; when downloading a single file it was limited to 1.5MB/s. I am trying to figure out what is limiting it to that....







Also tagged with one or more of these keywords: buffering, wan

0 user(s) are reading this topic

0 members, 0 guests, 0 anonymous users