FreeNAS - Plex Freezing on Buffer, then Crashes (Local and Remote)

Plex Media Server NAS & Devices
1

Server Version#: 1.14.0.5468
Player Version#: Plex for Xbox 2.21.1.70, Windows Version 10.0.17763.3062

Hello. Recently, my Plex sessions (we notice most on local) have been running for a seemingly random amount of time, and then they freeze on Buffering. The session will not begin again. Looking at the Plex Server itself, it shows plenty of buffer available, and it should be playing, but it doesn’t. We mostly watch though the Xbox One playing over local connection, Wired Gigabit Ethernet.

Fast Forwarding or Rewinding sometimes frees the session up and it will play normally. Other times, we have to back out entirely, then restart the movie. I have attached my Server Logs from the latest incident, around 23:17 on Nov 25. I turned Verbose logging off.

I’ve noticed a bunch of this error: ERROR - Error issuing curl_easy_perform(handle): 28

At the time of failure, this is the snippit:

Nov 25, 2018 23:16:03.407 [0x80bd8d400] ERROR - Error issuing curl_easy_perform(handle): 28
Nov 25, 2018 23:16:05.611 [0x80bd8b800] INFO - [ui-edge-winvergo] [Companion] Opening long poll to Titan at https://192-168-1-250.4db1fceaeb0e4b82856c80fac1b775c3.plex.direct:32400/player/proxy/poll
Nov 25, 2018 23:16:10.626 [0x80bd8bc00] INFO - [ui-edge-winvergo] [Companion] Poll connection successfully opened with Titan
Nov 25, 2018 23:16:18.555 [0x80bd8d400] ERROR - Error issuing curl_easy_perform(handle): 28
Nov 25, 2018 23:16:25.644 [0x80bd8bc00] INFO - [ui-edge-winvergo] [Companion] Opening long poll to Titan at https://192-168-1-250.4db1fceaeb0e4b82856c80fac1b775c3.plex.direct:32400/player/proxy/poll
Nov 25, 2018 23:16:30.653 [0x80bd8bc00] INFO - [ui-edge-winvergo] [Companion] Poll connection successfully opened with Titan
Nov 25, 2018 23:16:33.657 [0x80bd8d400] ERROR - Error issuing curl_easy_perform(handle): 28
Nov 25, 2018 23:16:45.667 [0x80ccff400] INFO - [ui-edge-winvergo] [Companion] Opening long poll to Titan at https://192-168-1-250.4db1fceaeb0e4b82856c80fac1b775c3.plex.direct:32400/player/proxy/poll
Nov 25, 2018 23:16:48.936 [0x80bd8d400] ERROR - Error issuing curl_easy_perform(handle): 28
Nov 25, 2018 23:16:50.672 [0x80bd89c00] INFO - [ui-edge-winvergo] [Companion] Poll connection successfully opened with Titan
Nov 25, 2018 23:17:04.307 [0x80bd8d400] ERROR - Error issuing curl_easy_perform(handle): 28
Nov 25, 2018 23:17:05.706 [0x80ccff400] INFO - [ui-edge-winvergo] [Companion] Opening long poll to Titan at https://192-168-1-250.4db1fceaeb0e4b82856c80fac1b775c3.plex.direct:32400/player/proxy/poll
Nov 25, 2018 23:17:10.708 [0x80bd8bc00] INFO - [ui-edge-winvergo] [Companion] Poll connection successfully opened with Titan
Nov 25, 2018 23:17:19.627 [0x80bd8d400] ERROR - Error issuing curl_easy_perform(handle): 28
Nov 25, 2018 23:17:25.733 [0x80bd89800] INFO - [ui-edge-winvergo] [Companion] Opening long poll to Titan at https://192-168-1-250.4db1fceaeb0e4b82856c80fac1b775c3.plex.direct:32400/player/proxy/poll
Nov 25, 2018 23:17:30.738 [0x80bd89800] INFO - [ui-edge-winvergo] [Companion] Poll connection successfully opened with Titan
Nov 25, 2018 23:17:34.941 [0x80bd8d400] ERROR - Error issuing curl_easy_perform(handle): 28
Nov 25, 2018 23:17:45.777 [0x80ccff400] INFO - [ui-edge-winvergo] [Companion] Opening long poll to Titan at https://192-168-1-250.4db1fceaeb0e4b82856c80fac1b775c3.plex.direct:32400/player/proxy/poll
Nov 25, 2018 23:17:46.670 [0x80bd8bc00] WARN - [ui-edge-winvergo] [Player] Buffering detected, last position change was 517ms ago
Nov 25, 2018 23:17:50.622 [0x80bd8d400] ERROR - Error issuing curl_easy_perform(handle): 28
Nov 25, 2018 23:17:50.786 [0x80bd8bc00] INFO - [ui-edge-winvergo] [Companion] Poll connection successfully opened with Titan
Nov 25, 2018 23:18:05.802 [0x80bd8bc00] INFO - [ui-edge-winvergo] [Companion] Opening long poll to Titan at https://192-168-1-250.4db1fceaeb0e4b82856c80fac1b775c3.plex.direct:32400/player/proxy/poll
Nov 25, 2018 23:18:05.944 [0x80bd8d400] ERROR - Error issuing curl_easy_perform(handle): 28
Nov 25, 2018 23:18:10.801 [0x80bd8bc00] INFO - [ui-edge-winvergo] [Companion] Poll connection successfully opened with Titan
Nov 25, 2018 23:18:20.341 [0x80bd89c00] INFO - [ui-edge-winvergo] Successfully restored default display mode
Nov 25, 2018 23:18:20.341 [0x80bd89c00] WARN - [ui-edge-winvergo] No type was found for undefined
Nov 25, 2018 23:18:20.341 [0x80bd89c00] WARN - [ui-edge-winvergo] No type was found for undefined
Nov 25, 2018 23:18:20.341 [0x80bd89c00] INFO - [ui-edge-winvergo]   Type: Paused
Nov 25, 2018 23:18:20.341 [0x80bd89c00] INFO - [ui-edge-winvergo]   Status: 4
Nov 25, 2018 23:18:20.341 [0x80bd89c00] INFO - [ui-edge-winvergo] [WinvergoSystemMediaTransportControls] setSMTCPlaybackStatus
Nov 25, 2018 23:18:20.687 [0x80bd89800] WARN - [ui-edge-winvergo] [Player] Player was closed or a new playback session was started; ignoring callback
Nov 25, 2018 23:18:21.106 [0x80d0cf400] WARN - [ui-edge-winvergo] [State] Unable to process all updates within budget; 18 update(s) remaining
Nov 25, 2018 23:18:21.139 [0x80bd8d400] ERROR - Error issuing curl_easy_perform(handle): 28
Nov 25, 2018 23:18:21.200 [0x80bd89800] INFO - [ui-edge-winvergo] [Player] Can play AUTOMATIC? true
Nov 25, 2018 23:18:21.252 [0x80ccff400] INFO - [ui-edge-winvergo]   videoResolution: 1080
Nov 25, 2018 23:18:21.252 [0x80ccff400] INFO - [ui-edge-winvergo]   bitrate: 15508
Nov 25, 2018 23:18:21.252 [0x80ccff400] INFO - [ui-edge-winvergo]   canDirectStreamAudio: false
Nov 25, 2018 23:18:21.253 [0x80ccff400] INFO - [ui-edge-winvergo]   canDirectStreamVideo: true
Nov 25, 2018 23:18:21.253 [0x80ccff400] INFO - [ui-edge-winvergo]   canDirectPlay: false
Nov 25, 2018 23:18:21.253 [0x80ccff400] INFO - [ui-edge-winvergo]   canPlay: true
Nov 25, 2018 23:18:21.253 [0x80ccff400] INFO - [ui-edge-winvergo] [MDE] Finished analysis of 1080 (mkv, h264, dca, 41, high)
Nov 25, 2018 23:18:21.253 [0x80ccff400] INFO - [ui-edge-winvergo] [MDE] Starting analysis of 1080 (mkv, h264, dca, 41, high)
Nov 25, 2018 23:18:21.253 [0x80ccff400] INFO - [ui-edge-winvergo] [MDE] Augmented profile: { "directPlay": { "mp4": { "video": { "codecs": { "h264": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8, "maxLevel": 51 }, "hevc": { "maxWidth": 4096, "maxHeight": 2160, "maxBitDepth": 10 }, "mpeg4": { "maxWidth": 4096, "maxHeight": 2160, "maxBitDepth": 8 }, "vc1": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8 }, "wmv3": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8 } } }, "audio": { "codecs": { "aac": { "maxChannels": 6 }, "ac3": { "maxChannels": 6 }, "eac3": { "maxChannels": 6 }, "mp3": { "maxChannels": 2 } } } }, "mkv": { "video": { "codecs": { "h264": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8, "maxLevel": 51 }, "hevc": { "maxWidth": 4096, "maxHeight": 2160, "maxBitDepth": 10 }, "mpeg4": { "maxWidth": 4096, "maxHeight": 2160, "maxBitDepth": 8 }, "msmpeg4v2": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8 }, "msmpeg4v3": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8 }, "vc1": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8 }, "vp9": { "maxWidth": 4096, "maxHeight": 2160, "maxBitDepth": 10 }, "wmv3": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8 } } }, "audio": { "codecs": { "aac": { "maxChannels": 6 }, "ac3": { "maxChannels": 6 }, "eac3": { "maxChannels": 6 }, "alac": { "maxChannels": 6 }, "flac": { "maxChannels": 6 }, "mp3": { "maxChannels": 2 } } } }, "mpegts": { "video": { "codecs": { "h264": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8, "maxLevel": 51 }, "mpeg2video": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8 } } }, "audio": { "codecs": { "aac": { "maxChannels": 6 }, "ac3": { "maxChannels": 6 }, "mp2": { "maxChannels": 6 } } } }, "avi": { "video": { "codecs": { "mpeg4": { "maxWidth": 4096, "maxHeight": 2160, "maxBitDepth": 8 }, "msmpeg4": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8 }, "msmpeg4v2": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8 }, "msmpeg4v3": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8 }, "vc1": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8 }, "wmv3": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8 } } }, "audio": { "codecs": { "aac": { "maxChannels": 6 }, "ac3": { "maxChannels": 6 }, "mp3": { "maxChannels": 2 } } } }, "mov": { "video": { "codecs": { "h264": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8, "maxLevel": 51 } } }, "audio": { "codecs": { "aac": { "maxChannels": 6 } } } }, "asf": { "video": { "codecs": { "vc1": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8 }, "wmv2": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8 } } }, "audio": { "codecs": { "wmav2": { "maxChannels": 6 } } } } }, "directStream": { "video": { "codecs": { "h264": { "maxWidth": 1920, "maxHeight": 1080, "maxBitDepth": 8, "maxLevel": 51 }, "hevc": { "maxWidth": 4096, "maxHeight": 2160, "maxBitDepth": 10 } } }, "audio": { "codecs": { "aac": { "maxChannels": 6 }, "ac3": { "maxChannels": 6 }, "eac3": { "maxChannels": 6 } } } } }
Nov 25, 2018 23:18:21.253 [0x80ccff400] INFO - [ui-edge-winvergo] [Player] Selected transcode protocol: dash
Nov 25, 2018 23:18:21.253 [0x80ccff400] INFO - [ui-edge-winvergo] [Player] Can force direct play? false
Nov 25, 2018 23:18:21.253 [0x80ccff400] INFO - [ui-edge-winvergo] [Player] Can direct stream? true (isAllowed: true)
Nov 25, 2018 23:18:25.825 [0x80d0cf400] INFO - [ui-edge-winvergo] [Companion] Opening long poll to Titan at https://192-168-1-250.4db1fceaeb0e4b82856c80fac1b775c3.plex.direct:32400/player/proxy/poll
Nov 25, 2018 23:18:30.839 [0x80ccff400] INFO - [ui-edge-winvergo] [Companion] Poll connection successfully opened with Titan
Nov 25, 2018 23:18:36.376 [0x80bd8d400] ERROR - Error issuing curl_easy_perform(handle): 28
Nov 25, 2018 23:18:45.890 [0x80bd8a000] INFO - [ui-edge-winvergo] [Companion] Opening long poll to Titan at https://192-168-1-250.4db1fceaeb0e4b82856c80fac1b775c3.plex.direct:32400/player/proxy/poll
Nov 25, 2018 23:18:50.893 [0x80bd89c00] INFO - [ui-edge-winvergo] [Companion] Poll connection successfully opened with Titan
Nov 25, 2018 23:18:51.658 [0x80bd8d400] ERROR - Error issuing curl_easy_perform(handle): 28
Nov 25, 2018 23:19:05.994 [0x80bd8a000] INFO - [ui-edge-winvergo] [Companion] Opening long poll to Titan at https://192-168-1-250.4db1fceaeb0e4b82856c80fac1b775c3.plex.direct:32400/player/proxy/poll
Nov 25, 2018 23:19:06.989 [0x80bd8d400] ERROR - Error issuing curl_easy_perform(handle): 28
Nov 25, 2018 23:19:10.997 [0x80bd8bc00] INFO - [ui-edge-winvergo] [Companion] Poll connection successfully opened with Titan
Nov 25, 2018 23:19:19.727 [0x80bd8d400] ERROR - Error issuing curl_easy_perform(handle): 7
Nov 25, 2018 23:19:19.727 [0x80bd8d400] WARN - HTTP error requesting GET http://192.168.1.11:60006/upnp/desc/aios_device/aios_device.xml (0, No error) (Failed connect to 192.168.1.11:60006; Connection refused)
Nov 25, 2018 23:19:19.730 [0x80bd8d400] ERROR - Error issuing curl_easy_perform(handle): 7
Nov 25, 2018 23:19:19.730 [0x80bd8d400] WARN - HTTP error requesting GET http://192.168.1.11:60006/upnp/desc/aios_device/aios_device.xml (0, No error) (Failed connect to 192.168.1.11:60006; Connection refused)
Nov 25, 2018 23:19:19.733 [0x80bd8d400] ERROR - Error issuing curl_easy_perform(handle): 7

The Xbox is at 192.168.1.11.

I’ve restarted the FreeNAS Server (Which is up to date), the Plex Server, and the Xbox as well. I’m at a loss as to what the logs might suggest. I’m hoping more trained eyes can tell me where to look for further troubleshooting. I’ve attached the full logs to this post. Again, the most recent error occurred at 23:17 on Nov 25.

Many Thanks in Advance,
Edward

Plex Media Server Logs_2018-11-25_23-20-54.zip (3.1 MB)

2

I updated FreeNAS to 11.2-RC2 hoping that it might help, but no change. It still happens randomly. I double checked all my hard drives and none have experienced any errors. My server hardware is below in case it is relevant or helpful:

  • Lenovo TS440 Server
  • Intel Xeon CPU E3-1245 V3 @ 3.40Ghz
  • 32GB Crucial ECC Memory
  • Mobo - Not sure on Mobo but it came w/ Lenovo TS440 Server
  • 8x 5TB WD Red HDD in RaidZ2 (Roughly 40TB Raw, 27TB Accessible after Z2)
  • RAID Controller: RAID 500 (Discrete, 0/1/10) - AKA MegaRAID 9240-8i RAID Controller Card

I’m really hoping someone will chime in and help.

Thanks,
Edward

3

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