QTS Update to 4.4.3

Plex Media Server NAS & Devices
1

Dear Community,
I did an update of firmware QTS 4.4.3 on my qnap TS-853A. First it looks all good. But then I did also an update to Plex 1.20 and after that nothing was working anymore. I dowgraded to 1.16.0 and then via the Add On Manager of Qnap to 1.16.6 but still not working. No I downgraded to QTS version 4.3.6. but still Plex is not working.

Do you have any ideas how I can get back working my Plex Server on my Qnap?
Also funny, that after the Upgrade of firmware I get error messages on my Qnap with an IP address 154.28.188.105. It seems that my Qnap is trying to log on to this IP, but I dont know what side, service or what else this can be.

I really looking forward to your kind help, since I dont want to reinstall Plex completly new. I have a hell of a library in there.

Thanks for your reply in advance.
Timo

Server Version#:
Player Version#:

2

I need to see the Plex logs.

If you had 1.19.5 or higher installed, you’d be able to get them through FileStation.

Do you still have a PlexData share defined and , after giving your username/admin permission to R/W the share, can you see it in FileStation (FileStation needs close/reopen to make it visible after a change)?

If so, Navigate down into it and Right-Click “Logs” and “Compress” making the ZIP file.

Download and attach that ZIP file here please.

3

Hi,
it seems after a reboot it is working now. Strange.

Are you familiar with Qnap? May I ask you for another thing? I have after the firmware change problems, that it seems something wants to login somewhere and fails. I dont know what kind of app or service this can be. Didnt had that in QTS 4.3.5.

error
error1279×256 49.6 KB

BRGDS
Timo

4

Someone with an IP address in the Staatspark Karlsaue area has your IP or hostname, perhaps through the QNAP CloudLink.

It’s clear the web port is open and they are trying to sign in.
You need to secure your machine.

5

OK. I thought it is my Qnap trying to login somewhere not the other way around. Somweone wants to login to my qnap. As also the last digit of the IP is changing. So I was thinking of any service or other app the qnap is running. As well as I dont had this before. It appeared only after upgrading was done immeditalty.

6

Besides that, do you think I should try again to install the 1.20 version? Any benefit comparing to the 1.16.6 from Qnap store?

7

Here is also the log files. Maybe you can see, why an other version is not working. Logs.zip (5.5 MB)

8

The network interface (or LAN itself) is constantly changing.

Sep 03, 2020 09:19:52.779 [0x7fbc9ae06700] DEBUG - Completed: [192.168.10.47:50364] 401 GET /media/providers (4 live) GZIP 0ms 357 bytes
Sep 03, 2020 09:19:52.782 [0x7fbc9b0f4700] DEBUG - Request: [192.168.10.47:50362 (Subnet)] GET / (4 live) GZIP Signed-in
Sep 03, 2020 09:19:52.783 [0x7fbc9ae06700] DEBUG - Completed: [192.168.10.47:50362] 401 GET / (4 live) GZIP 0ms 435 bytes
Sep 03, 2020 09:19:52.884 [0x7fbc5ba23700] DEBUG - Request: [192.168.10.47:50367 (Subnet)] GET /identity (3 live) GZIP Signed-in
Sep 03, 2020 09:20:26.746 [0x7fbc9ae06700] DEBUG - Completed: [192.168.10.47:50367] 200 GET /identity (2 live) GZIP 33862ms 479 bytes (pipelined: 2)
Sep 03, 2020 09:29:38.982 [0x7fbc98c7b700] DEBUG - NetworkInterface: received Netlink message len=88, type=RTM_GETADDR, flags=0x0
Sep 03, 2020 09:29:38.982 [0x7fbc98c7b700] DEBUG - NetworkInterface: Netlink address message family=2, index=14, flags=0x80
Sep 03, 2020 09:29:38.982 [0x7fbc98c7b700] DEBUG - Network change.
Sep 03, 2020 09:29:38.982 [0x7fbc98c7b700] DEBUG - NetworkInterface: Notified of network changed (force=0)
Sep 03, 2020 09:29:39.004 [0x7fbc98c7b700] DEBUG - Network change notification but nothing changed.
Sep 03, 2020 09:29:39.016 [0x7fbc98c7b700] DEBUG - NetworkInterface: received Netlink message len=1156, type=RTM_NEWLINK, flags=0x0
Sep 03, 2020 09:29:39.016 [0x7fbc98c7b700] DEBUG - NetworkInterface: Netlink information message family=0, type=65534, index=14, flags=0x1090, change=0x41
Sep 03, 2020 09:29:39.016 [0x7fbc98c7b700] DEBUG - Network change.
Sep 03, 2020 09:29:39.016 [0x7fbc98c7b700] DEBUG - NetworkInterface: Notified of network changed (force=0)
Sep 03, 2020 09:29:39.022 [0x7fbc98c7b700] DEBUG - Network change notification but nothing changed.
Sep 03, 2020 09:29:39.022 [0x7fbc98c7b700] DEBUG - NetworkInterface: received Netlink message len=472, type=RTM_DELLINK, flags=0x0
Sep 03, 2020 09:29:39.022 [0x7fbc98c7b700] DEBUG - NetworkInterface: Netlink information message family=0, type=65534, index=14, flags=0x1090, change=0xffffffff
Sep 03, 2020 09:29:53.958 [0x7fbc98c7b700] DEBUG - NetworkInterface: received Netlink message len=1152, type=RTM_NEWLINK, flags=0x0
Sep 03, 2020 09:29:53.958 [0x7fbc98c7b700] DEBUG - NetworkInterface: Netlink information message family=0, type=65534, index=15, flags=0x1090, change=0xffffffff
Sep 03, 2020 09:29:53.959 [0x7fbc98c7b700] DEBUG - Network change.
Sep 03, 2020 09:29:53.959 [0x7fbc98c7b700] DEBUG - NetworkInterface: Notified of network changed (force=0)
Sep 03, 2020 09:29:53.960 [0x7fbc98c7b700] DEBUG - Network change notification but nothing changed.
Sep 03, 2020 09:29:53.970 [0x7fbc98c7b700] DEBUG - NetworkInterface: received Netlink message len=80, type=RTM_DELADDR, flags=0x0
Sep 03, 2020 09:29:53.970 [0x7fbc98c7b700] DEBUG - NetworkInterface: Netlink address message family=2, index=15, flags=0x80
Sep 03, 2020 09:29:53.970 [0x7fbc98c7b700] DEBUG - Network change.
Sep 03, 2020 09:29:53.970 [0x7fbc98c7b700] DEBUG - NetworkInterface: Notified of network changed (force=0)
Sep 03, 2020 09:29:53.975 [0x7fbc98c7b700] DEBUG - Network change notification but nothing changed.
Sep 03, 2020 09:29:53.975 [0x7fbc98c7b700] DEBUG - NetworkInterface: received Netlink message len=1156, type=RTM_NEWLINK, flags=0x0
Sep 03, 2020 09:29:53.975 [0x7fbc98c7b700] DEBUG - NetworkInterface: Netlink information message family=0, type=65534, index=15, flags=0x110d1, change=0x1
Sep 03, 2020 09:29:53.975 [0x7fbc98c7b700] DEBUG - Network change.
Sep 03, 2020 09:29:53.976 [0x7fbc98c7b700] DEBUG - NetworkInterface: Notified of network changed (force=0)
Sep 03, 2020 09:29:53.982 [0x7fbc98c7b700] DEBUG - Network change notification but nothing changed.
Sep 03, 2020 09:29:53.983 [0x7fbc98c7b700] DEBUG - NetworkInterface: received Netlink message len=80, type=RTM_GETADDR, flags=0x0
Sep 03, 2020 09:29:53.983 [0x7fbc98c7b700] DEBUG - NetworkInterface: Netlink address message family=2, index=15, flags=0x80
Sep 03, 2020 09:29:53.983 [0x7fbc98c7b700] DEBUG - Network change.
Sep 03, 2020 09:29:53.983 [0x7fbc98c7b700] DEBUG - NetworkInterface: Notified of network changed (force=0)
Sep 03, 2020 09:29:54.000 [0x7fbc98c7b700] DEBUG - Network change notification but nothing changed.
Sep 03, 2020 09:29:54.000 [0x7fbc98c7b700] DEBUG - NetworkInterface: received Netlink message len=80, type=RTM_DELADDR, flags=0x0
Sep 03, 2020 09:29:54.000 [0x7fbc98c7b700] DEBUG - NetworkInterface: Netlink address message family=2, index=15, flags=0x80
Sep 03, 2020 09:29:54.000 [0x7fbc98c7b700] DEBUG - Network change.
Sep 03, 2020 09:29:54.000 [0x7fbc98c7b700] DEBUG - NetworkInterface: Notified of network changed (force=0)
Sep 03, 2020 09:29:54.001 [0x7fbc98c7b700] DEBUG - Network change notification but nothing changed.
Sep 03, 2020 09:29:54.001 [0x7fbc98c7b700] DEBUG - NetworkInterface: received Netlink message len=80, type=RTM_GETADDR, flags=0x0
Sep 03, 2020 09:29:54.001 [0x7fbc98c7b700] DEBUG - NetworkInterface: Netlink address message family=2, index=15, flags=0x80
Sep 03, 2020 09:29:54.001 [0x7fbc98c7b700] DEBUG - Network change.
Sep 03, 2020 09:29:54.001 [0x7fbc98c7b700] DEBUG - NetworkInterface: Notified of network changed (force=0)
Sep 03, 2020 09:29:54.003 [0x7fbc98c7b700] DEBUG - Network change notification but nothing changed.
Sep 03, 2020 09:29:54.003 [0x7fbc98c7b700] DEBUG - NetworkInterface: received Netlink message len=88, type=RTM_DELADDR, flags=0x0
Sep 03, 2020 09:29:54.003 [0x7fbc98c7b700] DEBUG - NetworkInterface: Netlink address message family=2, index=15, flags=0x80
Sep 03, 2020 09:29:54.003 [0x7fbc98c7b700] DEBUG - Network change.
Sep 03, 2020 09:29:54.003 [0x7fbc98c7b700] DEBUG - NetworkInterface: Notified of network changed (force=0)
Sep 03, 2020 09:29:54.004 [0x7fbc98c7b700] DEBUG - Network change notification but nothing changed.
Sep 03, 2020 09:29:55.139 [0x7fbc9b9be700] DEBUG - Shutting down with signal 15 (Terminated)
Sep 03, 2020 09:29:55.139 [0x7fbc9b9be700] DEBUG - Ordered to stop server.
Sep 03, 2020 09:29:55.139 [0x7fbc9b9be700] WARN - Timed out waiting for server to finish.
Sep 03, 2020 09:29:55.140 [0x7fbca5552740] DEBUG - Stopping server...
Sep 03, 2020 09:29:55.140 [0x7fbca5552740] DEBUG - HttpServer: Stopping server.
Sep 03, 2020 09:29:55.140 [0x7fbc9869f700] DEBUG - TranscodeJobManager: [Running] onServerShutdown
Sep 03, 2020 09:29:55.140 [0x7fbc9869f700] DEBUG - TranscodeJobManager: [Running] -> [Shutdown]
Sep 03, 2020 09:29:55.140 [0x7fbc9869f700] DEBUG - MetadataItemClusterRequestHandler: a shutdown event has been received
Sep 03, 2020 09:29:55.140 [0x7fbca5552740] DEBUG - Server stopped.
Sep 03, 2020 09:29:55.140 [0x7fbca5552740] DEBUG - NetworkService: Stopping advertisement.
Sep 03, 2020 09:29:55.140 [0x7fbca5552740] DEBUG - Killing plug-ins.
Sep 03, 2020 09:29:55.140 [0x7fbca5552740] DEBUG - [com.plexapp.agents.imdb] Killing.
Sep 03, 2020 09:29:55.140 [0x7fbc9898d700] ERROR - Network Service: Error in advertiser handle read: 125 (Operation canceled) socket=-1
Sep 03, 2020 09:29:55.140 [0x7fbca5552740] DEBUG - [com.plexapp.system] Killing.
Sep 03, 2020 09:29:55.141 [0x7fbca5552740] DEBUG - Waiting for Plex Tuner Service to exit.
Sep 03, 2020 09:29:55.147 [0x7fbc9b9be700] WARN - JobManager: Could not find job for handle 29275
Sep 03, 2020 09:29:55.149 [0x7fbc9b9be700] WARN - JobManager: Could not find job for handle 27947
Sep 03, 2020 09:29:55.175 [0x7fbc9b9be700] DEBUG - Jobs: '/share/CACHEDEV1_DATA/.qpkg/PlexMediaServer/Plex Tuner Service' exit code for process 29128 is 0 (success)
Sep 03, 2020 09:29:55.175 [0x7fbc983b1700] DEBUG - Plex Tuner Service exited.
Sep 03, 2020 09:29:55.188 [0x7fbca5552740] DEBUG - Database: Shutting down.
Sep 03, 2020 09:29:55.188 [0x7fbca5552740] DEBUG - Captured session 0.
Sep 03, 2020 09:29:55.188 [0x7fbca5552740] DEBUG - Captured session 1.

Is this DHCP? If so, check the Lease time / refresh interval.

9

Interessting. Nothing changed on Network settings. It is a proof that you should never change a running System.
Also VPN did disconnect after a while. So I guess with the update the network settings get confused including container and linux station.
Meanwhile I found my old QTS 4.3.5 install file and installed it.
Hopefully evrtything will now be back to normal. Seems like I cannot upgrade to a higher version of Plex even being published. So no new features but at least I can stream my Music and Videos.

10

VPNs, Containers, and VMs are all very important facts to know when diagnosing PMS.

On QNAP, being the package developer, my natural tendency is to think of the native package.

What was seen was a change in the networking at the QTS level and PMS reacted as it should.

11

Hi,
sorry for late reply. I have to say, it was looking a bit different as I was used from 4.3.5, but I cannot give you any detail. Sorry.
But it is still very confusing, that no PMS version beside the one being published in QNAP store is working on my Qnap NAS. Even after a reboot PMS is not reachable via web browser. Edge or Firefox, doesnt matter. In QNAP APP store, it shows PMS server running and after reboot also the service is starting up.
I dont really mind, as long as I dont have any trouble watching my media, but having the latest working version running, would be nice.

12

Let’s go back to basics.

  1. What’s the IP address of the computer you’re accessing the QNAP from ? (Local ? remote?) What you show above is a WAN IP and not an RFC-1918 (normal private LAN) address.
  2. What’s the LAN IP address of the QNAP?
  3. Are multiple ethernet adapters in use? If so, which is the system default?
  4. Lastly – most important – Is the QNAP directly on the internet or is the ISP’s modem/router in the circuit and creating the local LAN?

The topology should be:

Internet -> (WAN address) ISP modem/router (LAN address) -> switch -> QNAP.

13

Hi,
the ip is in my network 192.168.10.53 (Desktop Computer)
The NAS is 192.168.10.22
My IP (WAN) is a static IP due to my internet connection.
Latest I connected the Qnap to VPN service via QVPN and routing my FireTV through that Gateway.
From Office or Car I connect through my mobile via the Plex App. This is working fine.

THe Qnap is connected to with link aggregation (2 Ethernet Ports are used together) to a switch and of course behind a router. The router also riuns the DHCP which porivdes the IP for Qnap and the open ports for INternetconnection. Therefore the Qnap has a fixed IP in my LAN as well.

The above provided log file was running with QTS 4.3.6. Container and Linux Station. I think they also creating any virtual Ethernet connection… And I also received some access requests to my Qnap as mentioned above. Now being back on QTS 4.3.5 all is OK.

But again, as soon if I update to a new Plex Server version. Plex is not working anymore. That was actually the inital reason why I upgraded my QTS version, as I thought maybe Plex is using something which is not provided on QTS 4.3.5. But I guess I was wrong.

14

VPNs and Plex have always been problematic at best.

  1. Plex can see the actual WAN IP of your modem/router.
  2. When it connects to Plex.tv, those packets, which have the IP address of the VPN gateway exit aperture, are different and Plex.tv goes nuts. It sees what appears to be a Man-in-the-middle (MITM) attack and will refuse it.

My suggested first action is to turn off the VPN and verify it works, in a clean basic mode, without it.

PMS 1.15.2 (IIRC) through current all use the same authentication and claiming sequence with Plex.tv so version will not matter.

15

OK. I will try. Uninstall Linux and Container station and will deactivate QVPN. Shall I also uninstall that? Then restart and then updating to new PMS version. But I bet it doesnt matter. It wont work.
I will report.

16

I have ContainerStation installed.
It does not conflict unless that’s where your VPN is rooted.
LinuxStation is an unknown.

If you deactivate QVPN and then let plex restart after restarting the NAS, please get the log files for me (tar.gz or ZIP of the Logs directory) and I will read through them.

17

Hi, here is the log file with Linux and Container Station removed. Restarted and PMS 1.15.6. from Qnap Store running perfectly.
With PMS 1.15.6 from Qnap Store running - Logs.zip (4.9 MB)

18

And here are the logs after update PMS to 1.20.1 not running. Logs.zip (4.1 MB)

19

After I downgraded to 1.16.6 I had the an error while installing 1.16.1 (the only version I have left, and 1.16.6 will be automatically updated from QNAP Store) Now I have 2 Plex in my Qnap Store. 1 with an error and 1 regular.
Will it has any impact if I now delete the one with an error? Please see screenshot

Capture
Capture1044×479 62.3 KB

20

That dangling 1.16.1 will cause problems with QTS and App Center.
As far as installing on top of it, it shouldn’t cause any problems.

I can tell you how to remove it if you want but will require you to remove both then start over.

  1. Uninstall PMS 1.16.6
  2. SSH into the unit.
  3. cd /etc/config
  4. vi qpkg.conf
  5. cursor down, looking for [PlexMediaServer]
  6. when you find the block (looks like this)
[DownloadStation]
Name = DownloadStation
Class = null
Status = complete
Display_Name = Download Station
Version = 5.6.3.157
Build = 20200422
Author = QNAP Systems, Inc.
QPKG_File = DownloadStation.qpkg
Date = 2020-05-31
Shell = /share/CACHEDEV1_DATA/.qpkg/DownloadStation/qpkg_ds.sh
Volume_Select = 3
Install_Path = /share/CACHEDEV1_DATA/.qpkg/DownloadStation
WebUI = /downloadstation/
Web_Port = -1
RC_Number = 100
Sys_App = 1
App_ID = A236
Desktop = 1
Timeout = 180,180
App_Route = 1
Enable = TRUE

[PlexMediaServer]
Status = complete
Name = PlexMediaServer
Build = 20200827
Display_Name = Plex Media Server
Version = 1.20.1
Author = Plex Build Team
QPKG_File = PlexMediaServer.qpkg
Date = 2020-08-30
Shell = /share/CACHEDEV3_DATA/.qpkg/PlexMediaServer/plex.sh
Volume_Select = 3
Install_Path = /share/CACHEDEV3_DATA/.qpkg/PlexMediaServer
WebUI = /web
Web_Port = 32400
Web_SSL_Port = 32400
RC_Number = 101
Timeout = 10,30
Enable = TRUE
FW_Ver_Min = 4.3.0
FW_Ver_Max = 4.9.9

[ContainerStation]
  1. delete the header [PlexMediaServer]
  2. delete the other lines in that block until you get to either the next blank line or end-of-file (whichever comes first).
  3. Save and exit the editor
  4. Close and re-open App Center
  5. The stray entry should be gone.

If you prefer, QNAP often fixes these problems with Teamviewer sessions.
It should not happen but is easily corrected when you know how to do it.
If you prefer, you can submit a ticket or telephone them.