Hi all, I’ve done a completely fresh install of PMS 1.8.0.4109 on a completely fresh install of Ubuntu 16.04. My network is 10Gb and I’m pulling files from my UnRaid Server. For some reason, I keep getting a “your connection to the server is too slow” error message whether I’m watching locally or remotely. Processor is a Intel 4670K that is water cooled. I’m not seeing any packet drops on UnRaid or on my 10Gb switch that links the too. Is there something I’m missing? This is very frustrating. I’m trying to figure out where the breakdown is, but I need some direction.
My only thought is because you are pulling from your unRAID to your local computer where the Plex server is installed. If you have unRAID 6, then you should be able to run Plex server directly on your unRAID server. It’s worth a shot at least to run Plex server directly on your unRAID server.
More info here:
https://lime-technology.com/application-server/
@cagey215 said:
My only thought is because you are pulling from your unRAID to your local computer where the Plex server is installed. If you have unRAID 6, then you should be able to run Plex server directly on your unRAID server. It’s worth a shot at least to run Plex server directly on your unRAID server.More info here:
https://lime-technology.com/application-server/
I used to run Plex on UnRaid. I’ve built a dedicated machine for Plex as I’m running Emby on UnRaid. I didn’t have an issue before upgrading to 1.8.xxxxxx and this does not answer my question,
First things to check:
- Are Jumbo packets in use. If so, turn them off. They waste bandwidth on a home LAN and they cause far more problems than they are worth because they are intended for single-hop long haul communications
- You didn’t state which playback device is having the playback issue. How is it connected to the LAN? Remember, slowest leg is the gating element.
@ChuckPA said:
First things to check:
- Are Jumbo packets in use. If so, turn them off. They waste bandwidth on a home LAN and they cause far more problems than they are worth because they are intended for single-hop long haul communications
- You didn’t state which playback device is having the playback issue. How is it connected to the LAN? Remember, slowest leg is the gating element.
- Jumbo frames were off by default. I did test with them on and did not notice a change in performance. The Jumbo frames would only matter from my UnRaid server to Plex Server and back.
- Playback device was to the local server in a browser, to a Nexus Player remotely, cell phones, AFT inside the network. They all play then buffer, play, buffer, etc.
Just to note, it plays flawlessly when it’s direct playing but if it has to direct stream and transcode audio or transcode the video and audio, then it seems to buffer.
I’m about to go offline but would you create a test case, (using only Debug logging in Settings - Server - General), and then, after you experience the error, let it settle for 20 seconds and go to Settings - Server - Help - Download Logs, posting that ZIP file here for me to examine?
If you get it done before I’m offline, i’ll look tonight else look tomorrow
Log posted.
I see it and will need to get someone familiar to look at it. It is a problem with the Server & Player, not a transcode issue of any kind
I see playback start and end. It reports it’s played back for 969246 milliseconds in the 10 seconds elapsed time. Definitely not right.
I’m also seeing a bandwidth callout bandwidth=8765. You don’t by chance have any bandwidth limits set in Settings - Server - Network?
I am going offline now and will get someone when I wake.
Aug 13, 2017 02:03:03.696 [0x7f4b137ff700] DEBUG - Play progress on 10884 'Party Over Here, F... You Over There' - got played 959250 ms by account 1!
Aug 13, 2017 02:03:03.821 [0x7f4b137ff700] DEBUG - [Now] User is FredG89 (ID: 1)
Aug 13, 2017 02:03:03.821 [0x7f4b137ff700] DEBUG - [Now] Device is Android (Fire TV).
Aug 13, 2017 02:03:03.821 [0x7f4b137ff700] DEBUG - [Now] Profile is Android
Aug 13, 2017 02:03:03.821 [0x7f4b137ff700] DEBUG - [Now] Updated play state for /library/metadata/10884.
Aug 13, 2017 02:03:03.822 [0x7f4b137ff700] DEBUG - Statistics: (e90f02fb764ff0af-com-plexapp-android) Reporting active playback in state 0 of type 4 (scrobble: 0) for account 1
Aug 13, 2017 02:03:03.823 [0x7f4b3dbfe700] DEBUG - Completed: [10.10.1.25:58487] 200 GET /:/timeline?bandwidth=8765&bufferedTime=311&containerKey=%2FplayQueues%2F25&duration=1270392&guid=com.plexapp.agents.themoviedb%3A%2F%2F67761%2F2%2F8%3Flang%3Den&key=%2Flibrary%2Fmetadata%2F10884&machineIdentifier=9fd44111fa1f66a4237d624e2cb466987d721c6f&playQueueItemID=24&ratingKey=10884&state=playing&time=959250&token=xxxxxxxxxxxxxxxxxxxx (23 live) TLS GZIP 235ms 492 bytes (pipelined: 25)
Aug 13, 2017 02:03:10.919 [0x7f4b3e3ff700] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Aug 13, 2017 02:03:10.919 [0x7f4b3e3ff700] DEBUG - Auth: authenticated user 1 as FredG89
Aug 13, 2017 02:03:10.919 [0x7f4b3e3ff700] DEBUG - Auth: Came in with a super-token, authorization succeeded.
Aug 13, 2017 02:03:10.920 [0x7f4b2dff6700] DEBUG - Request: [10.10.1.15:40842 (Subnet)] GET /status/sessions (23 live) TLS Signed-in Token (FredG89)
Aug 13, 2017 02:03:10.920 [0x7f4b2dff6700] DEBUG - [Now] Adding 1 sessions.
Aug 13, 2017 02:03:10.920 [0x7f4b3dbfe700] DEBUG - Completed: [10.10.1.15:40842] 200 GET /status/sessions (23 live) TLS 0ms 2808 bytes (pipelined: 1)
Aug 13, 2017 02:03:10.921 [0x7f4b3e3ff700] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Aug 13, 2017 02:03:10.921 [0x7f4b3e3ff700] DEBUG - Auth: authenticated user 1 as FredG89
Aug 13, 2017 02:03:10.921 [0x7f4b3e3ff700] DEBUG - Auth: Came in with a super-token, authorization succeeded.
Aug 13, 2017 02:03:10.921 [0x7f4b137ff700] DEBUG - Request: [10.10.1.15:40844 (Subnet)] GET /status/sessions (23 live) TLS Signed-in Token (FredG89)
Aug 13, 2017 02:03:10.921 [0x7f4b137ff700] DEBUG - [Now] Adding 1 sessions.
Aug 13, 2017 02:03:10.922 [0x7f4b3dbfe700] DEBUG - Completed: [10.10.1.15:40844] 200 GET /status/sessions (23 live) TLS 0ms 2808 bytes (pipelined: 1)
Aug 13, 2017 02:03:13.589 [0x7f4b3dbfe700] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication.
Aug 13, 2017 02:03:13.589 [0x7f4b3dbfe700] DEBUG - Auth: authenticated user 1 as FredG89
Aug 13, 2017 02:03:13.589 [0x7f4b3dbfe700] DEBUG - Auth: Came in with a super-token, authorization succeeded.
Aug 13, 2017 02:03:13.590 [0x7f4b2dff6700] DEBUG - Request: [10.10.1.25:58487 (Subnet)] GET /:/timeline?bandwidth=8765&bufferedTime=301&containerKey=%2FplayQueues%2F25&duration=1270392&guid=com.plexapp.agents.themoviedb%3A%2F%2F67761%2F2%2F8%3Flang%3Den&key=%2Flibrary%2Fmetadata%2F10884&machineIdentifier=9fd44111fa1f66a4237d624e2cb466987d721c6f&playQueueItemID=24&ratingKey=10884&state=playing&time=969246&token=xxxxxxxxxxxxxxxxxxxx (14 live) TLS GZIP Signed-in Token (FredG89)
Aug 13, 2017 02:03:13.591 [0x7f4b2dff6700] DEBUG - Client [e90f02fb764ff0af-com-plexapp-android] reporting timeline state playing, progress of 969246/1270392ms for guid=com.plexapp.agents.themoviedb://67761/2/8?lang=en, ratingKey=10884 url=, key=/library/metadata/10884, containerKey=/playQueues/25, metadataId=10884
Aug 13, 2017 02:03:13.645 [0x7f4b2dff6700] DEBUG - Play progress on 10884 'Party Over Here, F... You Over There' - got played 969246 ms by account 1!
Aug 13, 2017 02:03:13.786 [0x7f4b2dff6700] DEBUG - [Now] User is FredG89 (ID: 1)
Aug 13, 2017 02:03:13.787 [0x7f4b2dff6700] DEBUG - [Now] Device is Android (Fire TV).
Aug 13, 2017 02:03:13.787 [0x7f4b2dff6700] DEBUG - [Now] Profile is Android
Aug 13, 2017 02:03:13.787 [0x7f4b2dff6700] DEBUG - [Now] Updated play state for /library/metadata/10884.
@ChuckPA said:
I see it and will need to get someone familiar to look at it. It is a problem with the Server & Player, not a transcode issue of any kind
I see playback start and end. It reports it’s played back for 969246 milliseconds in the 10 seconds elapsed time. Definitely not right.I’m also seeing a bandwidth callout
bandwidth=8765. You don’t by chance have any bandwidth limits set in Settings - Server - Network?I am going offline now and will get someone when I wake.
Aug 13, 2017 02:03:03.696 [0x7f4b137ff700] DEBUG - Play progress on 10884 'Party Over Here, F... You Over There' - got played 959250 ms by account 1! Aug 13, 2017 02:03:03.821 [0x7f4b137ff700] DEBUG - [Now] User is FredG89 (ID: 1) Aug 13, 2017 02:03:03.821 [0x7f4b137ff700] DEBUG - [Now] Device is Android (Fire TV). Aug 13, 2017 02:03:03.821 [0x7f4b137ff700] DEBUG - [Now] Profile is Android Aug 13, 2017 02:03:03.821 [0x7f4b137ff700] DEBUG - [Now] Updated play state for /library/metadata/10884. Aug 13, 2017 02:03:03.822 [0x7f4b137ff700] DEBUG - Statistics: (e90f02fb764ff0af-com-plexapp-android) Reporting active playback in state 0 of type 4 (scrobble: 0) for account 1 Aug 13, 2017 02:03:03.823 [0x7f4b3dbfe700] DEBUG - Completed: [10.10.1.25:58487] 200 GET /:/timeline?bandwidth=8765&bufferedTime=311&containerKey=%2FplayQueues%2F25&duration=1270392&guid=com.plexapp.agents.themoviedb%3A%2F%2F67761%2F2%2F8%3Flang%3Den&key=%2Flibrary%2Fmetadata%2F10884&machineIdentifier=9fd44111fa1f66a4237d624e2cb466987d721c6f&playQueueItemID=24&ratingKey=10884&state=playing&time=959250&token=xxxxxxxxxxxxxxxxxxxx (23 live) TLS GZIP 235ms 492 bytes (pipelined: 25) Aug 13, 2017 02:03:10.919 [0x7f4b3e3ff700] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication. Aug 13, 2017 02:03:10.919 [0x7f4b3e3ff700] DEBUG - Auth: authenticated user 1 as FredG89 Aug 13, 2017 02:03:10.919 [0x7f4b3e3ff700] DEBUG - Auth: Came in with a super-token, authorization succeeded. Aug 13, 2017 02:03:10.920 [0x7f4b2dff6700] DEBUG - Request: [10.10.1.15:40842 (Subnet)] GET /status/sessions (23 live) TLS Signed-in Token (FredG89) Aug 13, 2017 02:03:10.920 [0x7f4b2dff6700] DEBUG - [Now] Adding 1 sessions. Aug 13, 2017 02:03:10.920 [0x7f4b3dbfe700] DEBUG - Completed: [10.10.1.15:40842] 200 GET /status/sessions (23 live) TLS 0ms 2808 bytes (pipelined: 1) Aug 13, 2017 02:03:10.921 [0x7f4b3e3ff700] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication. Aug 13, 2017 02:03:10.921 [0x7f4b3e3ff700] DEBUG - Auth: authenticated user 1 as FredG89 Aug 13, 2017 02:03:10.921 [0x7f4b3e3ff700] DEBUG - Auth: Came in with a super-token, authorization succeeded. Aug 13, 2017 02:03:10.921 [0x7f4b137ff700] DEBUG - Request: [10.10.1.15:40844 (Subnet)] GET /status/sessions (23 live) TLS Signed-in Token (FredG89) Aug 13, 2017 02:03:10.921 [0x7f4b137ff700] DEBUG - [Now] Adding 1 sessions. Aug 13, 2017 02:03:10.922 [0x7f4b3dbfe700] DEBUG - Completed: [10.10.1.15:40844] 200 GET /status/sessions (23 live) TLS 0ms 2808 bytes (pipelined: 1) Aug 13, 2017 02:03:13.589 [0x7f4b3dbfe700] DEBUG - Auth: We found auth token (xxxxxxxxxxxxxxxxxxxx), enabling token-based authentication. Aug 13, 2017 02:03:13.589 [0x7f4b3dbfe700] DEBUG - Auth: authenticated user 1 as FredG89 Aug 13, 2017 02:03:13.589 [0x7f4b3dbfe700] DEBUG - Auth: Came in with a super-token, authorization succeeded. Aug 13, 2017 02:03:13.590 [0x7f4b2dff6700] DEBUG - Request: [10.10.1.25:58487 (Subnet)] GET /:/timeline?bandwidth=8765&bufferedTime=301&containerKey=%2FplayQueues%2F25&duration=1270392&guid=com.plexapp.agents.themoviedb%3A%2F%2F67761%2F2%2F8%3Flang%3Den&key=%2Flibrary%2Fmetadata%2F10884&machineIdentifier=9fd44111fa1f66a4237d624e2cb466987d721c6f&playQueueItemID=24&ratingKey=10884&state=playing&time=969246&token=xxxxxxxxxxxxxxxxxxxx (14 live) TLS GZIP Signed-in Token (FredG89) Aug 13, 2017 02:03:13.591 [0x7f4b2dff6700] DEBUG - Client [e90f02fb764ff0af-com-plexapp-android] reporting timeline state playing, progress of 969246/1270392ms for guid=com.plexapp.agents.themoviedb://67761/2/8?lang=en, ratingKey=10884 url=, key=/library/metadata/10884, containerKey=/playQueues/25, metadataId=10884 Aug 13, 2017 02:03:13.645 [0x7f4b2dff6700] DEBUG - Play progress on 10884 'Party Over Here, F... You Over There' - got played 969246 ms by account 1! Aug 13, 2017 02:03:13.786 [0x7f4b2dff6700] DEBUG - [Now] User is FredG89 (ID: 1) Aug 13, 2017 02:03:13.787 [0x7f4b2dff6700] DEBUG - [Now] Device is Android (Fire TV). Aug 13, 2017 02:03:13.787 [0x7f4b2dff6700] DEBUG - [Now] Profile is Android Aug 13, 2017 02:03:13.787 [0x7f4b2dff6700] DEBUG - [Now] Updated play state for /library/metadata/10884.
No, no network settings set. I’m not using the smart brain or whatever it’s called. Thanks for taking a look into this, I’ll be waiting for a response.
Would you get the XML for the movie(s) you were playing ? I do see a transcode session. I also see some things directly playing.
I am wondering if you’re simply maxing out your wifi
@ChuckPA said:
Would you get the XML for the movie(s) you were playing ? I do see a transcode session. I also see some things directly playing.I am wondering if you’re simply maxing out your wifi
Do file that was trasncoding was being played locally on the machine and the one that was direct playing was playing over Wifi. I have a Unifi AC-Lite so Wifi bandwidth shouldn’t be an issue.
if the file was being played locally on the same machine, bandwidth usage is zero and not a factor.
The question of wifi is 2.4 Ghz vs 5 Ghz and other traffic on the wifi at the time of playback.
2.4 Ghz is so vastly overloaded by other devices and neighbors (in urban areas) it is a ‘disaster’.
Your AC-lite will connect in A/B/G/N and AC modes. This said, take a look and see how the device is connecting and on what band, I’m not questioning the AP. I’m questioning what the device is capable of. This is limiting factor
@ChuckPA said:
if the file was being played locally on the same machine, bandwidth usage is zero and not a factor.
The question of wifi is 2.4 Ghz vs 5 Ghz and other traffic on the wifi at the time of playback.2.4 Ghz is so vastly overloaded by other devices and neighbors (in urban areas) it is a ‘disaster’.
Your AC-lite will connect in A/B/G/N and AC modes. This said, take a look and see how the device is connecting and on what band, I’m not questioning the AP. I’m questioning what the device is capable of. This is limiting factor
Chuck, the file that I was having issues with was being played locally and transcoding. The file being played over wifi was direct played. Bitrate of that file was 1.5Mb/s. The file that I’m having issues with was the one locally but to answer your questions…
I was on 5GHz and am the only 5GHz in the area. On the 2Ghz spectrum there are only two other networks and I have mine on the channels between those two, 4-8.
Again, we’re focusing on the wireless when I’m having issues transcoding and playing the file locally as well as remotely (out of network).
Fred,
I’m seeing two playback sessions concurrently. I see one for “Guardians” , which is transcoding via the WAN. I’m also seeing the other. I just want both clear and isolated.
I’m attempting to isolate this issue:
Aug 13, 2017 01:59:52.595 [0x7f4b28fff700] DEBUG - Selecting best audio stream for part ID 243 (autoselect: 1 language: en)
Aug 13, 2017 01:59:52.595 [0x7f4b28fff700] DEBUG - We're going to try to auto-select a subtitle.
Aug 13, 2017 01:59:52.595 [0x7f4b28fff700] DEBUG - Audio Stream: 6846, Subtitle Stream: -1
Aug 13, 2017 01:59:52.597 [0x7f4b28fff700] DEBUG - MDE: analyzing media item 243
Aug 13, 2017 01:59:52.597 [0x7f4b28fff700] DEBUG - MDE: Guardians of the Galaxy Vol. 2 (2017): Direct Play is disabled
Aug 13, 2017 01:59:52.597 [0x7f4b28fff700] DEBUG - MDE: Guardians of the Galaxy Vol. 2 (2017): no direct play video profile exists for http/mkv/h264
Aug 13, 2017 01:59:52.597 [0x7f4b28fff700] DEBUG - MDE: Guardians of the Galaxy Vol. 2 (2017): no direct play video profile exists for http/mkv/h264/dca
Aug 13, 2017 01:59:52.597 [0x7f4b28fff700] DEBUG - Guardians of the Galaxy Vol. 2 - audio.channels limitation applies: 8 > 6
->>>>>>>>>>> Aug 13, 2017 01:59:52.597 [0x7f4b28fff700] ERROR - get - invalid frameRate value: 23.976
Aug 13, 2017 01:59:52.597 [0x7f4b28fff700] DEBUG - Guardians of the Galaxy Vol. 2 - audio.channels limitation applies: 8 > 6
Aug 13, 2017 01:59:52.597 [0x7f4b28fff700] DEBUG - MDE: Guardians of the Galaxy Vol. 2 (2017): selected media 0 / 243
When I see an invalid frame rate and the valid indicated is valid, it is a very strong indicator of some type of file damage. In one location, PMS sees the framerate as one value then encounters 23.976 which it isn’t expecting. The only time this happens is when the timestamps in the file itself are damaged (bad conversion / capture)
If you don’t mind taking ‘Guardians’ and giving it a quick run through HandBrake to recompute the timestamps, keeping the original safe, this will satisfy PMS and the transcoder and in most cases does clear up the playback problems. To do this optimally, Look at the XML (Get Info is just as good) and use this value, rounded up to the nearest 1000, as the manually specified bit rate for a two-pass HandBrake encode on the ‘Video’ tab of HandBrake. Be certain to also pick up the desired audio track in the Audio tab.
