PMS v1.2.2.2857 (QNAP) crashes randomly/daily

General Discussions
1

Hi,

The last month my PMS (v1.2.2.2857-d34b464) has almost crashed every day. When i check the logs (attached) there a no errors.
The only way to get i running again, is to restart the service/app. I have never had this problem before. PMS has just worked previously.

Sometimes the PMS service is running after crashing. Today the service was stopped after the crash.

QNAP FW: 4.2.2 Build 20161021 (latest official release)

Logs (before restart) is attached.

EDIT: Looks like PMS is killing itself (Plex Media Server.log)? The NAS restarts daily at 7:00 am. NAS wasn’t stopped/restarted at 07:55. The log timestamp are correct - have checked.
Oct 24, 2016 07:55:05.487 [0xf1511b90] DEBUG - WebSocket: client initiated close Oct 24, 2016 07:55:05.487 [0xf1511b90] DEBUG - WebSocket: processed 0 frame(s) Oct 24, 2016 07:55:05.487 [0xf1511b90] DEBUG - NotificationStream: Removing because of close Oct 24, 2016 07:55:05.489 [0xf17ffb90] ERROR - handle_stream_read error 335544539 short read Oct 24, 2016 07:55:35.131 [0xf3e16740] DEBUG - Shutting down with signal 15 Oct 24, 2016 07:55:35.131 [0xf3e16740] DEBUG - Ordered to stop server. Oct 24, 2016 07:55:35.131 [0xf3e16740] WARN - Timed out waiting for server to finish. Oct 24, 2016 07:55:35.131 [0xf3e16740] DEBUG - Stopping server... Oct 24, 2016 07:55:35.132 [0xed3ffb90] DEBUG - HTTP requesting POST http://www.google-analytics.com/collect Oct 24, 2016 07:55:35.175 [0xed3ffb90] DEBUG - HTTP 200 response from POST http://www.google-analytics.com/collect Oct 24, 2016 07:55:35.176 [0xed3ffb90] DEBUG - Sent analytics request: 200 Oct 24, 2016 07:55:35.176 [0xed3ffb90] DEBUG - TranscodeJobManager: [Running] onServerShutdown Oct 24, 2016 07:55:35.176 [0xed3ffb90] DEBUG - TranscodeJobManager: [Running] -> [Shutdown] Oct 24, 2016 07:55:35.177 [0xf3e16740] DEBUG - Server stopped. Oct 24, 2016 07:55:35.177 [0xf3e16740] DEBUG - NetworkService: Stopping advertisement. Oct 24, 2016 07:55:35.177 [0xf3e16740] DEBUG - Killing plug-ins. Oct 24, 2016 07:55:35.177 [0xee3ffb90] ERROR - Network Service: Error in advertiser handle read: 125 (Operation canceled) socket=67 Oct 24, 2016 07:55:35.177 [0xf3e16740] DEBUG - [com.plexapp.system] Killing. Oct 24, 2016 07:55:35.187 [0xf3e16740] DEBUG - Database: Shutting down. Oct 24, 2016 07:55:35.277 [0xee3ffb90] INFO - Network Service: Abandoning advertise socket, it was closed.

2

Looks like QNAP (system) is stopping the PMS service (huh!). Below is QNAP System Event Logs. The timestamp is one sec prior to when i logged in and emptied the recycle bin (daily routine). Hmmmm …

From the QNAP Connection Logs i logged in at 07:55:18.

5uh58pes4y01.png1297×276 38.1 KB

I get the same Event logs items, when i manually stop the PMS service. So i MUST have stopped it manually. No reason for it … hmmm …

3

Does it do it every time you log in, as in crash 1 sec before?

4

@christopher.dooks said:
Does it do it every time you log in, as in crash 1 sec before?

No, it usually crashed completely (random times - morning, evening, midnight etc) but service is still running on QNAP (have to restart it). Today the service was stopped.

5

I’ll close this thread - Since it wasn’t an actual crash today, and the logs doesn’t show anything else. I’ll get back when it crashes.