Plex Media Server 1.5.5.3634 on FreeNAS exited on signal 11

server-freebsd

#1

I am trying to track down the cause of this error. I have been seeing it for the last few weeks and seemed to start when I tried adding photos onto the plea server. I have removed the photos but it still crashes, seems to be more prevalent when the library has any updates but generally it crashes at least once per day, more often with multiple updates.

Is there a log I can view to get more information? I am running FreeNAS-9.10.2-U5.

Thanks in advance.


#2

I saw today that there was an update to Plex - so have upgraded to Plex Media Server 1.7.5.4035.

Not sure if this has made it worse but it has crashed twice in the last hour.


#3

1.7.5 has been out for a while, might have recently been released to Public but the latest Plex Pass version is 1.7.6. Might want to check to make sure you have Plex Pass Downloads turned on.


#4

Thanks john - I have upgraded to 1.7.5.4035 but it has made no difference. Still crashing frequently.


#5

Plex Media Server 1.7.5.4035 on FreeNAS exited on signal 11 - are there any logs that could help track this down?


#6

Plex Media Server is the one to start with.


#7

The Plex Media Server.log didn't show any errors but I found these in the Plex Media Scanner.log they appear to be repeated a few times before it stops:
Jul 24, 2017 11:36:12.234 [0x80e49cc00] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=10413857&metadataType=2&state=5&metadataState=idle
Jul 24, 2017 11:36:12.234 [0x80e49cc00] ERROR - Error issuing curl_easy_perform(handle): 7
Jul 24, 2017 11:36:12.234 [0x80e49cc00] WARN - HTTP error requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=1&metadataItemID=10413857&metadataType=2&state=5&metadataState=idle (0, No error) (Failed connect to 127.0.0.1:32400; Connection refused)
Jul 24, 2017 11:36:12.237 [0x80e3cd400] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.237 [0x80e3cd400] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.237 [0x80e3cd400] ERROR - Error issuing curl_easy_perform(handle): 7
Jul 24, 2017 11:36:12.237 [0x80e3cd400] WARN - HTTP error requesting GET http://127.0.0.1:32400/library/changestamp (0, No error) (Failed connect to 127.0.0.1:32400; Connection refused)
Jul 24, 2017 11:36:12.237 [0x80e3cd400] ERROR - HTTP -7 downloading url http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.238 [0x80e3cd400] ERROR - Exception in worker thread: Unable to allocate a changestamp from the server
Jul 24, 2017 11:36:12.238 [0x80e3cd400] DEBUG - MediaItem::PerformBackgroundAnalysis on 8628427
Jul 24, 2017 11:36:12.241 [0x80e3cd400] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.241 [0x80e3cd400] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.242 [0x80e3cd400] ERROR - Error issuing curl_easy_perform(handle): 7
Jul 24, 2017 11:36:12.242 [0x80e3cd400] WARN - HTTP error requesting GET http://127.0.0.1:32400/library/changestamp (0, No error) (Failed connect to 127.0.0.1:32400; Connection refused)
Jul 24, 2017 11:36:12.242 [0x80e3cd400] ERROR - HTTP -7 downloading url http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.243 [0x80e3cd400] ERROR - Exception in worker thread: Unable to allocate a changestamp from the server
Jul 24, 2017 11:36:12.243 [0x80e3cd400] DEBUG - MediaItem::PerformBackgroundAnalysis on 8628428
Jul 24, 2017 11:36:12.246 [0x80e3cd400] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.246 [0x80e3cd400] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.246 [0x80e3cd400] ERROR - Error issuing curl_easy_perform(handle): 7
Jul 24, 2017 11:36:12.246 [0x80e3cd400] WARN - HTTP error requesting GET http://127.0.0.1:32400/library/changestamp (0, No error) (Failed connect to 127.0.0.1:32400; Connection refused)
Jul 24, 2017 11:36:12.247 [0x80e3cd400] ERROR - HTTP -7 downloading url http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.247 [0x80e3cd400] ERROR - Exception in worker thread: Unable to allocate a changestamp from the server
Jul 24, 2017 11:36:12.247 [0x80e3cd400] DEBUG - MediaItem::PerformBackgroundAnalysis on 8628429
Jul 24, 2017 11:36:12.250 [0x80e3cd400] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.251 [0x80e3cd400] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.251 [0x80e3cd400] ERROR - Error issuing curl_easy_perform(handle): 7
Jul 24, 2017 11:36:12.251 [0x80e3cd400] WARN - HTTP error requesting GET http://127.0.0.1:32400/library/changestamp (0, No error) (Failed connect to 127.0.0.1:32400; Connection refused)
Jul 24, 2017 11:36:12.251 [0x80e3cd400] ERROR - HTTP -7 downloading url http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.251 [0x80e3cd400] ERROR - Exception in worker thread: Unable to allocate a changestamp from the server
Jul 24, 2017 11:36:12.251 [0x80e3cd400] DEBUG - MediaItem::PerformBackgroundAnalysis on 8628430
Jul 24, 2017 11:36:12.254 [0x80e3cd400] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.255 [0x80e3cd400] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.255 [0x80e3cd400] ERROR - Error issuing curl_easy_perform(handle): 7
Jul 24, 2017 11:36:12.255 [0x80e3cd400] WARN - HTTP error requesting GET http://127.0.0.1:32400/library/changestamp (0, No error) (Failed connect to 127.0.0.1:32400; Connection refused)
Jul 24, 2017 11:36:12.255 [0x80e3cd400] ERROR - HTTP -7 downloading url http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.255 [0x80e3cd400] ERROR - Exception in worker thread: Unable to allocate a changestamp from the server
Jul 24, 2017 11:36:12.256 [0x80e3cd400] DEBUG - MediaItem::PerformBackgroundAnalysis on 8628431
Jul 24, 2017 11:36:12.258 [0x80e3cd400] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.259 [0x80e3cd400] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.259 [0x80e3cd400] ERROR - Error issuing curl_easy_perform(handle): 7
Jul 24, 2017 11:36:12.259 [0x80e3cd400] WARN - HTTP error requesting GET http://127.0.0.1:32400/library/changestamp (0, No error) (Failed connect to 127.0.0.1:32400; Connection refused)
Jul 24, 2017 11:36:12.259 [0x80e3cd400] ERROR - HTTP -7 downloading url http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.259 [0x80e3cd400] ERROR - Exception in worker thread: Unable to allocate a changestamp from the server
Jul 24, 2017 11:36:12.260 [0x80e3cd400] DEBUG - MediaItem::PerformBackgroundAnalysis on 8628432
Jul 24, 2017 11:36:12.262 [0x80e3cd400] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.263 [0x80e3cd400] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.263 [0x80e3cd400] ERROR - Error issuing curl_easy_perform(handle): 7
Jul 24, 2017 11:36:12.263 [0x80e3cd400] WARN - HTTP error requesting GET http://127.0.0.1:32400/library/changestamp (0, No error) (Failed connect to 127.0.0.1:32400; Connection refused)
Jul 24, 2017 11:36:12.264 [0x80e3cd400] ERROR - HTTP -7 downloading url http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.264 [0x80e3cd400] ERROR - Exception in worker thread: Unable to allocate a changestamp from the server
Jul 24, 2017 11:36:12.264 [0x80e3cd400] DEBUG - MediaItem::PerformBackgroundAnalysis on 8628433
Jul 24, 2017 11:36:12.267 [0x80e3cd400] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.267 [0x80e3cd400] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.267 [0x80e3cd400] ERROR - Error issuing curl_easy_perform(handle): 7
Jul 24, 2017 11:36:12.267 [0x80e3cd400] WARN - HTTP error requesting GET http://127.0.0.1:32400/library/changestamp (0, No error) (Failed connect to 127.0.0.1:32400; Connection refused)
Jul 24, 2017 11:36:12.268 [0x80e3cd400] ERROR - HTTP -7 downloading url http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.268 [0x80e3cd400] ERROR - Exception in worker thread: Unable to allocate a changestamp from the server
Jul 24, 2017 11:36:12.268 [0x80e3cd400] DEBUG - MediaItem::PerformBackgroundAnalysis on 8628434
Jul 24, 2017 11:36:12.271 [0x80e3cd400] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.271 [0x80e3cd400] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.272 [0x80e3cd400] ERROR - Error issuing curl_easy_perform(handle): 7
Jul 24, 2017 11:36:12.272 [0x80e3cd400] WARN - HTTP error requesting GET http://127.0.0.1:32400/library/changestamp (0, No error) (Failed connect to 127.0.0.1:32400; Connection refused)
Jul 24, 2017 11:36:12.272 [0x80e3cd400] ERROR - HTTP -7 downloading url http://127.0.0.1:32400/library/changestamp
Jul 24, 2017 11:36:12.272 [0x80e3cd400] ERROR - Exception in worker thread: Unable to allocate a changestamp from the server


#8

While it may not be your issue, the above logs indicate that the server cannot access itself via localhost. This is a requirement. You likely have it running in a jail without vimage which will result in no loopback interface.

Also, 1.5.5 is old.


#9

Thanks the VIMAGE made an improvement - the odd thing is that it was selected already in the jail, but I cleared the checkbox saved and then went back in and reselected. The exit on signal 11 was much less frequent but still occurred, I was reading another thread and there was one recommendation to upgrade FreeNas to V11 which stopped the exit on signal 11, but introduced another error. I will create a new thread for this.