2017.01.18.2126 killing QNAP TS-431 overall function

Beta Community DVR Beta
#1

Everything had been working fine. I had recovered from the bad release and everything was working. I woke up today to a QNAP NAS not being reachable by any means. I powered off and it came back up but within 5 minutes was no longer reachable. I powered off a 2nd time and while it was coming up, I disabled the DVR using the stop command. NAS worked fine for hours. Ran check disk etc. and no errors.

Then I started up the DVR. I was able during the 3 minutes to get into the DVR web page and at first it worked but then locked up. The version is 2017.01.18.2126

Within 3 minutes, everything stopped working including DVR, disk sharing, QNAP console, etc.

#2

That’s quite strange.

Can you pull the DVR logs so we can see what it’s doing? Either via the web interface Log tab, or you can download the entire file from the NAS. It lives at:

/share/CACHEDEV1_DATA/.qpkg/ChannelsDVR/channels-dvr/data/channels-dvr.log

#3 
2017/01/18 10:54:18 [DVR] Fetching guide data for USA-PA66100-X @ 2017-01-31 9:30PM
2017/01/18 10:55:15 [DVR]   indexed 898 airings (134 channels, 561 skipped) [21s fetch, 35s index]
2017/01/18 10:55:15 [DVR] Fetching guide data for USA-PA66100-X @ 2017-02-01 3:30AM
2017/01/18 10:56:16 [DVR]   indexed 939 airings (134 channels, 561 skipped) [25s fetch, 35s index]
2017/01/18 10:56:16 [DVR] Fetching movie data for USA-PA66100-X @ 2017-01-31 9:30AM
2017/01/18 10:56:46 [DVR]   indexed 480 movies (1347 skipped) [20s fetch, 10s index]
2017/01/18 10:56:49 [IDX] Pruning replaced airings...
2017/01/18 10:56:49 [IDX] Finished pruning 0 airings.
2017/01/18 10:56:49 [IDX] Pruning expired groups...
2017/01/18 10:56:50 [IDX] Finished pruning 31 groups.
2017/01/18 10:56:50 [IDX] Pruning expired airings...
2017/01/18 10:56:52 [DVR] Rule 'The Mick' found 1 new airings
2017/01/18 10:56:52 [DVR]   queued recording job 1485912660-6
2017/01/18 10:56:55 [IDX] Finished pruning 174 airings.
2017/01/18 17:35:10 [SYS] Downloading new version v2017.01.18.2126
2017/01/18 17:35:22 [SYS] Update downloaded and verified successfully.
2017/01/18 17:35:22 [SYS] Shutting down for upgrade from v2017.01.18.0910 to v2017.01.18.2126
2017/01/18 17:35:22 [DVR] Recording engine stopped.
2017/01/18 17:35:24 [SYS] Starting Channels DVR v2017.01.18.2126 (linux-armv7l) in /share/CACHEDEV1_DATA/.qpkg/ChannelsDVR/channels-dvr/data
2017/01/18 17:35:25 [HDR] Found 1 devices
2017/01/18 17:35:25 [ERR] bonjour: Failed to bind to udp6 port: listen udp6 [ff02::]:5353: socket: address family not supported by protocol
2017/01/18 17:35:25 [SYS] Bonjour service running for dvr-nase3248a.local. [192.168.1.26]
2017/01/18 17:35:26 [SYS] Started HTTP Server
2017/01/18 17:35:36 [DVR] Recording engine started in /share/CACHEDEV1_DATA/Recordings
2017/01/18 17:35:36 [IDX] Pruning expired airings...
2017/01/18 17:35:36 [SYS] Removing old update 2017.01.14.0019
2017/01/18 17:35:37 [SYS] Created database snapshot: backup-20170118.173536
2017/01/18 17:35:51 [IDX] Finished pruning 1034 airings.
2017/01/19 00:36:07 [DVR] Waiting 3m52.451494232s until next job 1484804400-ch746
2017/01/19 00:36:13 [DVR] Waiting 3m46.312511296s until next job 1484804400-ch746
2017/01/19 00:40:00 [DVR] Starting job 1484804400-ch746 Slumdog Millionaire (2008) on ch=[746]
2017/01/19 00:40:01 [DVR] Recording for job 1484804400-ch746 from 13143640 ch746 into "Movies/Slumdog Millionaire (2008) 2017-01-19-0040.mpg" for 2h4m59.28494022s
2017/01/19 00:59:34 [DVR] Job 1484804400-ch746 ended prematurely: 1h45m25.583685348s
2017/01/19 00:59:34 [DVR] Starting job 1484804400-ch746 Slumdog Millionaire (2008) on ch=[746]
2017/01/19 00:59:39 [ERR] Failed to start stream on channel 746 via 13143640: got http response code 503
2017/01/19 00:59:39 [DVR] Error running job 1484804400-ch746: could not start stream on channels=[746]: got http response code 503
2017/01/19 01:00:09 [DVR] Starting job 1484804400-ch746 Slumdog Millionaire (2008) on ch=[746]
2017/01/19 01:00:14 [ERR] Failed to start stream on channel 746 via 13143640: got http response code 503
2017/01/19 01:00:14 [DVR] Error running job 1484804400-ch746: could not start stream on channels=[746]: got http response code 503
2017/01/19 01:00:44 [DVR] Starting job 1484804400-ch746 Slumdog Millionaire (2008) on ch=[746]
2017/01/19 01:00:47 [DVR] Recording for job 1484804400-ch746 from 13143640 ch746 into "Movies/Slumdog Millionaire (2008) 2017-01-19-0040.mpg" for 1h44m15.258242632s
2017/01/19 02:45:02 [DVR] Finished job 1484804400-ch746 Slumdog Millionaire (2008)
2017/01/19 02:45:03 [ERR] unexpected type <nil> for duration
2017/01/19 09:58:08 [SYS] Starting Channels DVR v2017.01.18.2126 (linux-armv7l) in /share/CACHEDEV1_DATA/.qpkg/ChannelsDVR/channels-dvr/data
2017/01/19 09:58:09 [HDR] Found 1 devices
2017/01/19 09:58:10 [ERR] bonjour: Failed to bind to udp6 port: listen udp6 [ff02::]:5353: socket: address family not supported by protocol
2017/01/19 09:58:10 [SYS] Bonjour service running for dvr-nase3248a.local. [192.168.1.26]
2017/01/19 09:58:12 [SYS] Started HTTP Server
2017/01/19 09:58:41 [DVR] Recording engine started in /share/CACHEDEV1_DATA/Recordings
2017/01/19 09:58:41 [IDX] Pruning expired airings...
2017/01/19 09:58:41 [DVR] Deleting expired job 1484820000-ch472
2017/01/19 09:58:44 [ERR] unexpected type <nil> for duration
2017/01/19 13:16:31 [SYS] Starting Channels DVR v2017.01.18.2126 (linux-armv7l) in /share/CACHEDEV1_DATA/.qpkg/ChannelsDVR/channels-dvr/data
2017/01/19 13:16:32 [HDR] Found 1 devices
2017/01/19 13:16:32 [ERR] bonjour: Failed to bind to udp6 port: listen udp6 [ff02::]:5353: socket: address family not supported by protocol
2017/01/19 13:16:32 [SYS] Bonjour service running for dvr-nase3248a.local. [192.168.1.26]
2017/01/19 13:16:33 [SYS] Started HTTP Server
2017/01/19 13:16:48 [IDX] Pruning expired airings...
2017/01/19 13:16:48 [DVR] Recording engine started in /share/CACHEDEV1_DATA/Recordings
2017/01/19 13:16:50 [ERR] unexpected type <nil> for duration
2017/01/19 13:16:53 [DVR] Error during search: cannot perform operation on empty alias
2017/01/19 13:16:53 [DVR] Error during search: cannot perform operation on empty alias
2017/01/19 13:16:53 [DVR] Error during search: cannot perform operation on empty alias
2017/01/19 13:16:53 [DVR] Error during search: cannot perform operation on empty alias
2017/01/19 13:16:53 [DVR] Error during search: cannot perform operation on empty alias
2017/01/19 13:16:53 [DVR] Error during search: cannot perform operation on empty alias
2017/01/19 13:16:53 [DVR] Recording engine stopped.
2017/01/19 13:16:53 [DVR] Error during search: index is closed
2017/01/19 13:16:53 [IDX] Finished pruning 1000 airings.
2017/01/19 13:17:11 [IDX] Pruning expired airings...
2017/01/19 13:17:11 [DVR] Recording engine started in /share/CACHEDEV1_DATA/Recordings
2017/01/19 13:17:14 [ERR] unexpected type <nil> for duration
#4

Hmm, nothing really sticks out in the log. It doesn’t look like the DVR was even making any recordings when you had issues.

Becoming completely unresponsive like that seems to indicate some sort of hardware failure, but it’s unclear why only having the DVR enabled would cause it.

When it locks up like that, are you able to ping it’s ip still? Can you ssh in maybe?

#5

DVR 2017.01.20.1732 is now available with a fix for this issue.

The TS-431 only has 512MB of RAM, and the recent change to recording thumbnail generation was causing a huge spike in RAM usage.

1 Like
#6

I did some recordings and the new version is working perfectly! Thanks for your quick response!

1 Like