Logs ZIP please ? Let me see it in context.
When DSM is re-installed (re-initialized),
- The volume isn’t erased unless you perform a full factory reset
- You can export (backup) your DSM configuration which
- You can reload after reinstallation of DSM (if needed)
The procedure recommended (safe) is: Mode 2 - (Step 3 of their guide)
https://www.synology.com/en-us/knowledgebase/DSM/tutorial/General_Setup/How_to_reset_my_Synology_NAS
The key point here is to allow the three beeps to finish but do NOT let it beep a fourth time. (this will be obvious as you start the 3 beep sequence. There is plenty of time to let go)
We can do this if/as needed.
@ChuckPa so PMS got stuck. PMS Web-UI is offline:
PMS on Synology’s Package Center looks stopped:
however the service on Synology’s Resource monitor is active with a 13% CPU usage:
Here are the logs, retrieved by following your instructions:
DS-3615xs, PMS Stuck, Logs.zip (7.5 MB)
I hope this will provide some insight why the crash.
@ChuckPa have you take n a look on this latest logs with stuck PMS? Could i have you feedback please?
I’m sorry about the delay. It has been VERY busy for me.
Why do you have multiple adapters per LAN?
May 11, 2020 04:55:35.772 [0x7f1953735700] DEBUG - Jobs: Starting child process with pid 14755
May 11, 2020 04:55:35.824 [0x7f19b7447700] DEBUG - NetworkInterface: received Netlink message len=80, type=RTM_GETADDR, flags=0x0
May 11, 2020 04:55:35.824 [0x7f19b7447700] DEBUG - NetworkInterface: Netlink address message family=2, index=7, flags=0x80
May 11, 2020 04:55:35.824 [0x7f19b7447700] DEBUG - Network change.
May 11, 2020 04:55:35.824 [0x7f19b7447700] DEBUG - NetworkInterface: Notified of network changed (force=0)
May 11, 2020 04:55:35.824 [0x7f19b7447700] DEBUG - Detected primary interface: 192.168.124.12
May 11, 2020 04:55:35.824 [0x7f19b7447700] DEBUG - Network interfaces:
May 11, 2020 04:55:35.824 [0x7f19b7447700] DEBUG - * 1 lo (127.0.0.1) (loopback: 1)
May 11, 2020 04:55:35.824 [0x7f19b7447700] DEBUG - * 3 eth0 (192.168.123.2) (loopback: 0)
May 11, 2020 04:55:35.824 [0x7f19b7447700] DEBUG - * 4 eth1 (192.168.124.2) (loopback: 0)
May 11, 2020 04:55:35.824 [0x7f19b7447700] DEBUG - * 5 eth2 (169.254.70.68) (loopback: 0)
May 11, 2020 04:55:35.824 [0x7f19b7447700] DEBUG - * 6 eth3 (169.254.250.162) (loopback: 0)
May 11, 2020 04:55:35.824 [0x7f19b7447700] DEBUG - * 8 eth5 (192.168.124.12) (loopback: 0)
May 11, 2020 04:55:35.824 [0x7f19b7447700] DEBUG - NetworkService: Got notification of changed network (first change: 0)
Now, notice how soon it is again notified / changed.
May 11, 2020 04:55:45.793 [0x7f1953735700] DEBUG - Jobs: Starting child process with pid 20059
May 11, 2020 04:55:45.839 [0x7f19b7447700] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
May 11, 2020 04:55:45.840 [0x7f19b7d11700] DEBUG - PublicAddressManager: Obtaining public IP.
May 11, 2020 04:55:45.840 [0x7f19b7447700] DEBUG - NetworkInterface: received Netlink message len=80, type=RTM_DELADDR, flags=0x0
May 11, 2020 04:55:45.840 [0x7f19b7447700] DEBUG - NetworkInterface: Netlink address message family=2, index=7, flags=0x80
May 11, 2020 04:55:45.840 [0x7f19b7447700] DEBUG - Network change.
May 11, 2020 04:55:45.840 [0x7f19b7447700] DEBUG - NetworkInterface: Notified of network changed (force=0)
May 11, 2020 04:55:45.840 [0x7f19b540d700] DEBUG - NAT: UPnP, attempting port mapping.
May 11, 2020 04:55:45.840 [0x7f19b7d11700] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
May 11, 2020 04:55:45.840 [0x7f19b7447700] DEBUG - Detected primary interface: 192.168.123.12
May 11, 2020 04:55:45.840 [0x7f19b7447700] DEBUG - Network interfaces:
May 11, 2020 04:55:45.840 [0x7f19b7447700] DEBUG - * 1 lo (127.0.0.1) (loopback: 1)
May 11, 2020 04:55:45.840 [0x7f19b7447700] DEBUG - * 3 eth0 (192.168.123.2) (loopback: 0)
May 11, 2020 04:55:45.840 [0x7f19b7447700] DEBUG - * 4 eth1 (192.168.124.2) (loopback: 0)
May 11, 2020 04:55:45.840 [0x7f19b7447700] DEBUG - * 5 eth2 (169.254.70.68) (loopback: 0)
May 11, 2020 04:55:45.840 [0x7f19b7447700] DEBUG - * 6 eth3 (169.254.250.162) (loopback: 0)
May 11, 2020 04:55:45.840 [0x7f19b7447700] DEBUG - * 7 eth4 (192.168.123.12) (loopback: 0)
May 11, 2020 04:55:45.840 [0x7f19b7447700] DEBUG - * 8 eth5 (192.168.124.12) (loopback: 0)
May 11, 2020 04:55:45.840 [0x7f19b7447700] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 11, 2020 04:55:45.840 [0x7f19b7447700] DEBUG - NetworkService: Dispatch network change after two second delay.
May 11, 2020 04:55:45.840 [0x7f19b7447700] DEBUG - NetworkService: Got notification of changed network (first change: 0)
It changes yet again.
May 11, 2020 04:56:52.764 [0x7f1953735700] DEBUG - Jobs: Starting child process with pid 26053
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - NetworkInterface: received Netlink message len=80, type=RTM_GETADDR, flags=0x0
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - NetworkInterface: Netlink address message family=2, index=7, flags=0x80
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - Network change.
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - NetworkInterface: Notified of network changed (force=0)
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - Detected primary interface: 0.0.0.0
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - Network interfaces:
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - * 1 lo (127.0.0.1) (loopback: 1)
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - * 3 eth0 (192.168.123.2) (loopback: 0)
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - * 4 eth1 (192.168.124.2) (loopback: 0)
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - * 5 eth2 (169.254.70.68) (loopback: 0)
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - * 6 eth3 (169.254.250.162) (loopback: 0)
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - * 8 eth5 (192.168.124.12) (loopback: 0)
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - NetworkService: Dispatch network change after two second delay.
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - NetworkService: Dispatch network change after two second delay.
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - NetworkService: Dispatch network change after two second delay.
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - NetworkService: Dispatch network change after two second delay.
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - NetworkService: Dispatch network change after two second delay.
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 11, 2020 04:56:52.809 [0x7f19b7447700] DEBUG - NetworkService: Dispatch network change after two second delay.
May 11, 2020 04:56:52.809 [0x7f19b7447700] ERROR - getaddrinfo(192-168-123-2.abcdefghijklmnopqrstuvwxyz012345.plex.direct) f
Are these static or DHCP?
If DHCP, you need to check the Lease time. This is too aggressive. 86400 seconds = 1 day which is more than adequate
A synology NAS should have a STATIC IP
These latest logs show me the network configuration / wiring / or switch (?) has a major problem.
Please resolve this first. PMS will never settle down until the LAN is stable
@ChuckPa definetely a network screw-up… The funny part is that all four Ethernet adapters (eth0-3) are NOT connected. Eth4 is specific assigned IP via DHCP (via MAC) and eth5 is static IP. I think that i will have to re setup DSM, as you suggested, and then PMS freshly again.
If you only do the 3 beep reset, you reinstall DSM but the volumes remain.
Saves a LOT of time.
PMS will find the Plex share.
@ChuckPa done. DSM reinstalled and I have NOT done configuration restore to avoid carrying on any older “garbage”. Still, PMS crushed after less then 24h of operation, agian being stucj on 13% CPU usage.
For the record, I have tried to discover my Synology via the 10Gbit card (eth-4) but no luck. I had to plug eth-0 (LAN-1 of Synology motherboard) and only then I was able to see the machine on Synology Assistant and do the re-installation of DSM. Currently PMS shows eth-0 (LAN-1 used for DSM install) plus the two (2) 10Gbit eth-4 and eth-5. The network status is:
- eth-0: UNPLUGGED, visible on PMS, static IP 192.168.123.2
- eth-1: not visible on PMS, DHCP, internal “dummy” IP, 169.254.x.x
- eth-2: not visible on PMS, DHCP, internal “dummy” IP, 169.254.x.x
- eth-3: not visible on PMS, DHCP, internal “dummy” IP, 169.254.x.x
- eth-4: 10Gbit Intel card, DHCP (static via MAC), 192.168.123.12, DEFAULT GATEWAY
- eth-5: 10Gbit Intel card, Static, 192.168.124.14, connected directly to another Synology (NOT to the LAN switch)
Is it Network, again?!
Logs.zip (7.0 MB)
All was fine for many years and also fine for 2+ years with the installed 10Gbit Intel card (no hardware network changes during this period). Any suspicions on why the hiccups the last period?!
Your logs didn’t make it.
You hit reply too soon 
@ChuckPa …my bad. Now they have been attached.
Thanks.
When was the server restored?
I am seeing network errors from 15-May-2020 06:49 local time.
Also see this.
May 15, 2020 13:43:41.300 [0x7f842860d700] DEBUG - NetworkService: Dispatch network change after two second delay.
May 15, 2020 13:43:41.300 [0x7f842860d700] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 15, 2020 13:43:41.300 [0x7f842860d700] DEBUG - NetworkService: Dispatch network change after two second delay.
May 15, 2020 13:43:41.300 [0x7f842860d700] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 15, 2020 13:43:41.300 [0x7f842860d700] DEBUG - NetworkService: Dispatch network change after two second delay.
May 15, 2020 13:43:41.300 [0x7f842860d700] ERROR - getaddrinfo(192-168-123-2.abcdefghijklmnopqrstuvwxyz012345.plex.direct) failed: -3
May 15, 2020 13:43:41.300 [0x7f842860d700] DEBUG - Network: 192-168-123-2.abcdefghijklmnopqrstuvwxyz012345.plex.direct failed to resolve to 192.168.123.2 but instead yielded ""
May 15, 2020 13:43:41.300 [0x7f842860d700] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
May 15, 2020 13:43:41.301 [0x7f841940d700] DEBUG - PublicAddressManager: Obtaining public IP.
May 15, 2020 13:43:41.301 [0x7f84199e9700] DEBUG - NAT: UPnP, attempting port mapping.
May 15, 2020 13:43:41.301 [0x7f841940d700] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
May 15, 2020 13:43:41.301 [0x7f841940d700] ERROR - Error issuing curl_easy_perform(handle): 6
May 15, 2020 13:43:41.301 [0x7f841940d700] WARN - HTTP error requesting GET http://plex.tv/pms/:/ip (6, Couldn't resolve host name) (Could not resolve host: plex.tv)
May 15, 2020 13:43:41.302 [0x7f841940d700] ERROR - PublicAddressManager: Unable to get public IP adddress from myPlex (httpCode=-6):
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - NetworkInterface: received Netlink message len=80, type=RTM_DELADDR, flags=0x0
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - NetworkInterface: Netlink address message family=2, index=7, flags=0x80
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - Network change.
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - NetworkInterface: Notified of network changed (force=0)
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - Detected primary interface: 0.0.0.0
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - Network interfaces:
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - * 1 lo (127.0.0.1) (loopback: 1)
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - * 3 eth0 (192.168.123.2) (loopback: 0)
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - * 4 eth1 (169.254.156.249) (loopback: 0)
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - * 5 eth2 (169.254.70.68) (loopback: 0)
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - * 6 eth3 (169.254.250.162) (loopback: 0)
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - * 7 eth4 (192.168.123.12) (loopback: 0)
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - * 8 eth5 (192.168.124.12) (loopback: 0)
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - NetworkService: Dispatch network change after two second delay.
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - NetworkService: Dispatch network change after two second delay.
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - NetworkService: Dispatch network change after two second delay.
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - NetworkService: Dispatch network change after two second delay.
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - NetworkService: Dispatch network change after two second delay.
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - NetworkService: Got notification of changed network (first change: 0)
May 15, 2020 13:43:41.302 [0x7f842860d700] DEBUG - NetworkService: Dispatch network change after two second delay.
May 15, 2020 13:43:41.303 [0x7f841940d700] DEBUG - MyPlex: Updating device connections (from timer: 0)
May 15, 2020 13:43:41.303 [0x7f841940d700] DEBUG - HTTP requesting PUT https://plex.tv/devices/15c7be7e4c416c710e027af1a0075b2afd54bc58?Connection[][uri]=http://192.168.123.12:32400&httpsEnabled=1&httpsRequired=0&dnsRebindingProtection=1&natLoopbackSupported=0&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx
May 15, 2020 13:43:43.364 [0x7f842860d700] DEBUG - PublicAddressManager: Obtaining public address and mapping port.
May 15, 2020 13:43:43.364 [0x7f84196fb700] DEBUG - PublicAddressManager: Obtaining public IP.
May 15, 2020 13:43:43.364 [0x7f84196fb700] DEBUG - HTTP requesting GET http://plex.tv/pms/:/ip
Followup.
What is this plugged into?
Quality of cabling & connectors?
Something “stupid” is not right here.
the DSM re-installation was done on 14 MAY, 19:02:48 (i made a full PMS back-up and have the zipped file’s timestamp)
1Gbit interfaces are on prefabricated CAT-6 electrical cables, although DS3612xs is UNPLUGGED
10Gbit interfaces are prefabricated optic-fiber SFP-SFP cables
Port-A: to LAN switch
Port-B: to DS-3612xs (direct-cable, not via LAN)
LAN switch is S3300-28X ProSAFE 24-Port Gigabit Stackable Smart Switch with 4 10G up-links, on latest firmware
Router is Mikrotik, on latest firmware
What do you make out of the logs?
What I am seeing is a bunch of network problems.
If you have a single connection, from the Synology to the switch, and you’re still having these problems…
The choices are:
- Bad / noisy cable
- Bad / loose / noisy switch port
- Bad / loose Synology port.
Cat 6 + cabling?
@ChuckPa it was a network configuration on the Router that was causing the whole mess. Some firewall forwarding routing that was messing up the 2 class-c networks used by Synology’s 10Gbit adapters (one connected to LAN and one to a 2nd Synology). Long story, short, reconfigured the class-c topology and PMS came back to normal! Strange that all was functioning for a very long time until recently (…probably with some conflicts/errors though)
@ChuckPa thank you you for your time, patience and valuable feedback!
@ChuckPa you proved 100% right, was a “Bad / loose / noisy switch port” problems solved when switching to another switch port. Thanks!
This topic was automatically closed 90 days after the last reply. New replies are no longer allowed.
