Plex crashed randomly a few times a week on mac

Plex Media Server Plex Pass Betas
1

Hi,

on my mac mini MacOS 10.11.6 the PMS crashed a few times a week. Nothing anyone starts remotely something and the server crashed sometimes. PMS is v1.11.3.4793 but this happens for a while now with many older versions, too.

This are my last entries in the log.

Please help. Thats very annoying .

    Feb 26, 2018 10:21:00.470 [0x700000a4d000] WARN - NAT: PMP, got an error: Not Supported by gateway.
    Feb 26, 2018 11:21:00.539 [0x700000a4d000] WARN - NAT: PMP, got an error: Not Supported by gateway.
    Feb 26, 2018 12:21:00.028 [0x7000009ca000] WARN - NAT: PMP, got an error: Not Supported by gateway.
    Feb 26, 2018 13:21:00.464 [0x7000009ca000] WARN - NAT: PMP, got an error: Not Supported by gateway.
    Feb 26, 2018 14:21:00.507 [0x700000b53000] WARN - NAT: PMP, got an error: Not Supported by gateway.
    Feb 26, 2018 15:21:00.504 [0x7000009ca000] WARN - NAT: PMP, got an error: Not Supported by gateway.
    Feb 26, 2018 16:21:00.542 [0x7000009ca000] WARN - NAT: PMP, got an error: Not Supported by gateway.
    Feb 26, 2018 16:54:31.488 [0x700001695000] ERROR - Extras: Preroll file didn't exist or couldn't be read: ''.
    Feb 26, 2018 16:54:45.866 [0x700001718000] ERROR - [Transcoder] [h264 @ 0x7f87ea89d600] videotoolbox: invalid state when returning frame
    Feb 26, 2018 16:54:45.867 [0x7000002a0000] ERROR - [Transcoder] Error while decoding stream #0:0: Generic error in an external library
    Feb 26, 2018 16:54:45.882 [0x700001695000] ERROR - [Transcoder] [h264 @ 0x7f87ea89d600] videotoolbox: invalid state when returning frame
    Feb 26, 2018 16:54:45.883 [0x700001718000] ERROR - [Transcoder] Error while decoding stream #0:0: Generic error in an external library
    Feb 26, 2018 16:57:43.978 [0x7000006b8000] ERROR - Exception getting remote address: remote_endpoint: Invalid argument

Edit:

Again Crash. One user is watching remotely with Firefox 58.0.2

    Feb 26, 2018 17:11:22.172 [0x70000020a000] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (6378f594-66a3-4481-8478-9e7be9632689, expected 86b52d82-a379-487b-a511-8241752d7ead)
    Feb 26, 2018 17:11:22.173 [0x70000020a000] WARN - PubSub: Received notifyConnectivity event with incorrect async identifier (40ad5729-aea6-43a3-9ada-e795dfba2a21, expected 86b52d82-a379-487b-a511-8241752d7ead)
    Feb 26, 2018 17:23:05.814 [0x7000003a6000] ERROR - [Transcoder] [h264 @ 0x7fd53b805200] videotoolbox: invalid state when returning frame
    Feb 26, 2018 17:23:05.815 [0x7000008c4000] ERROR - [Transcoder] Error while decoding stream #0:0: Generic error in an external library
    Feb 26, 2018 17:23:05.845 [0x700000635000] ERROR - [Transcoder] [h264 @ 0x7fd53b805200] videotoolbox: invalid state when returning frame
    Feb 26, 2018 17:23:05.847 [0x700000323000] ERROR - [Transcoder] Error while decoding stream #0:0: Generic error in an external library
    Feb 26, 2018 17:23:22.372 [0x7000008c4000] ERROR - [Transcoder] [h264 @ 0x7fe4fb826c00] videotoolbox: invalid state when returning frame
    Feb 26, 2018 17:23:22.374 [0x7000011fa000] ERROR - [Transcoder] Error while decoding stream #0:0: Generic error in an external library
    Feb 26, 2018 17:23:22.393 [0x7000008c4000] ERROR - [Transcoder] [h264 @ 0x7fe4fb826c00] videotoolbox: invalid state when returning frame
    Feb 26, 2018 17:23:22.395 [0x7000011fa000] ERROR - [Transcoder] Error while decoding stream #0:0: Generic error in an external library
    Feb 26, 2018 17:45:59.156 [0x70000073b000] ERROR - Exception getting remote address: remote_endpoint: Invalid argument
2

Does this happen with a particular video or with all? Can you reproduce this error? I know very little, but it looks to me like the Transcoder is crashing because of a bad file, so I wonder whether this happens with something specific.

3

This happend for month now. With different files. It happends mostly when an user using Firefox and plex.tv remotly.

4

Debug Logging has been disabled - please enable it and attach full logs zip after restart so if there is a crash report it can be located from its reference id

As It appears to be to do with probably request syntax, please also enable verbose logging on the server to see all the request parameters and header content

Settings / Server / General / Show Advanced
Enable: Debug Logging, Verbose Logging and Crash reporting
and save changes

Restart the server after this

And on next crash, restart and download the logs zip and attach
Settings / Server / Help / Download Logs

5

Hi, thx for reply. Today I had the next crash after enabling Debug Logging.

6

The crash reports are failing to get uploaded

Whilst the server is able to communicate with plex.tv, all attempts to communicate with https://crashreport.plexapp.com are failing

Have you by any chance blocked access to this domain?
I have had the system checked out and it was operational throughout the periods when your server failed to get to it to upload crash reports

Feb 26, 2018 17:11:01.554 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Feb 26, 2018 17:11:01.554 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)
Feb 26, 2018 17:11:01.557 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Feb 26, 2018 17:11:01.557 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)

Feb 26, 2018 17:45:59.732 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Feb 26, 2018 17:45:59.732 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)
Feb 26, 2018 17:45:59.734 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Feb 26, 2018 17:45:59.734 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)
Feb 26, 2018 17:45:59.736 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Feb 26, 2018 17:45:59.736 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)

Feb 26, 2018 17:48:06.580 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Feb 26, 2018 17:48:06.580 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)
Feb 26, 2018 17:48:06.583 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Feb 26, 2018 17:48:06.583 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)
Feb 26, 2018 17:48:06.586 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Feb 26, 2018 17:48:06.586 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)

Feb 28, 2018 19:33:37.655 [0x7fff77080000] DEBUG - HTTP requesting POST https://crashreport.plexapp.com
Feb 28, 2018 19:33:37.971 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Feb 28, 2018 19:33:37.971 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)
Feb 28, 2018 19:33:37.972 [0x7fff77080000] DEBUG - HTTP requesting POST https://crashreport.plexapp.com
Feb 28, 2018 19:33:37.973 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Feb 28, 2018 19:33:37.973 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)
Feb 28, 2018 19:33:37.974 [0x7fff77080000] DEBUG - HTTP requesting POST https://crashreport.plexapp.com
Feb 28, 2018 19:33:37.975 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Feb 28, 2018 19:33:37.975 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)

Mar 01, 2018 18:26:47.471 [0x7fff77080000] DEBUG - HTTP requesting POST https://crashreport.plexapp.com
Mar 01, 2018 18:26:47.499 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Mar 01, 2018 18:26:47.499 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)
Mar 01, 2018 18:26:47.500 [0x7fff77080000] DEBUG - HTTP requesting POST https://crashreport.plexapp.com
Mar 01, 2018 18:26:47.502 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Mar 01, 2018 18:26:47.502 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)
Mar 01, 2018 18:26:47.503 [0x7fff77080000] DEBUG - HTTP requesting POST https://crashreport.plexapp.com
Mar 01, 2018 18:26:47.505 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Mar 01, 2018 18:26:47.505 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)
Mar 01, 2018 18:26:47.506 [0x7fff77080000] DEBUG - HTTP requesting POST https://crashreport.ple xapp.com
Mar 01, 2018 18:26:47.508 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Mar 01, 2018 18:26:47.508 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)

Mar 01, 2018 20:21:31.478 [0x7fff77080000] DEBUG - HTTP requesting POST https://crashreport.plexapp.com
Mar 01, 2018 20:21:31.504 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Mar 01, 2018 20:21:31.504 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)
Mar 01, 2018 20:21:31.505 [0x7fff77080000] DEBUG - HTTP requesting POST https://crashreport.plexapp.com
Mar 01, 2018 20:21:31.506 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Mar 01, 2018 20:21:31.506 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)
Mar 01, 2018 20:21:31.507 [0x7fff77080000] DEBUG - HTTP requesting POST https://crashreport.plexapp.com
Mar 01, 2018 20:21:31.508 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Mar 01, 2018 20:21:31.508 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)
Mar 01, 2018 20:21:31.509 [0x7fff77080000] DEBUG - HTTP requesting POST https://crashreport.plexapp.com
Mar 01, 2018 20:21:31.511 [0x7fff77080000] ERROR - Error issuing curl_easy_perform(handle): 7
Mar 01, 2018 20:21:31.511 [0x7fff77080000] WARN - HTTP error requesting POST https://crashreport.plexapp.com (0, No error) (Failed to connect to 184.105.148.89: Host is down)

Curl error 7 is

CURLE_COULDNT_CONNECT (7)  - Failed to connect() to host or proxy.

The host was not down and there were uploads from other servers. So I suspect an issue at your end and your firewall / network

I have no dumps to get looked at

The only info I have is this error message logged before crashing

Mar 01, 2018 18:26:47.114 [0x70000073b000] ERROR - Exception getting remote address: remote_endpoint: Invalid argument

This error is the same as seen in another user’s log on this forum topic


which was followed by a crash to due with some exception when dealing with an SSL Connection

May be this SSL issue is also why the connection to crashreport.plexapp.com is failing

Have you installed some SSL software ?
Are you using a VPN ?

I do see some extra network interfaces in addition to the local subnet

Mar 01, 2018 20:21:32.434 [0x70000052f000] DEBUG - Network interfaces:
Mar 01, 2018 20:21:32.434 [0x70000052f000] DEBUG -  * 4 en0 (192.168.2.27) (loopback: 0)
Mar 01, 2018 20:21:32.434 [0x70000052f000] DEBUG -  * 11 vnic0 (10.211.55.2) (loopback: 0)
Mar 01, 2018 20:21:32.434 [0x70000052f000] DEBUG -  * 12 vnic1 (10.37.129.2) (loopback: 0)

With regards to the connection SSL crash that the other user had and may well be the same crash for your server, a fix is being produced to stop the crash

7

I’m not using any SSL Software or VPN on my Plex Server.
But I’m using Pi-Hole. Maybe thats the problem. I try to disable pi-hole on my Server and see if the crashes are gone.

Thanks a lot for your answer!

Edit:
No I was wrong. Pi-hole is already disables on my server, but my Firewall blocked https://crashreport.plexapp.com

What I have to do now? Waiting for ne next crash and post it here or waiting for an PMS Update?

8

again crash with 1.12.0.4829

9

@kolossboss said:
again crash with 1.12.0.4829

Thanks for the logs. Similar crash as before processing an SSL connection
I sent you by Private Message a link to a pre-release version of next 1.2.1 beta release - some changes were made that may fix this crash. The pre-release build identifier is 1.12.1.4860-8eb9dc33c

Please try it and report back - with fresh logs if the problem is not fixed

Thanks

10

No crashes so far with latest PMS Version 1.12.1.4885.
This issues seems fixed.

11

Would love to see what we are now logging instead of crashing - not sure how quickly your logs are recycling and how often you had the crashes before