DVR stops recording on WD MyCloud PR2100

DVR on WD MyCloud PR21000 - Firmware 2.30.193
Channels DVR Firmware - 2018.06.12.0218

For the past 2 months or so, the Channels DVR stops recording at inconsistent intervals; usually 2-3 days. I don’t need to restart the PR2100 to recover. I simply log into the PR2100 dashboard, go to Apps, click on Channels DVR, click to turn Channels DVR off, click again to turn it on, then it restarts immediately. I searched and see the problem presented itself in 2017. I wanted to start a new thread for 2018. Here’s the log for a 3-day stretch:
2018/06/14 09:46:47 [DVR] Waiting 3h13m12.570556907s until next job 1528995590-ch764 Food Network Star
2018/06/14 09:46:59 [IDX] Pruned 7391 expired airings from USA-MI21455-X in 14.641618073s.
2018/06/14 09:47:01 [IDX] Pruned 685 expired airings from USA-OTA48708 in 1.25881187s.
2018/06/14 10:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 11:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 12:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 12:36:57 [SYS] Created database snapshot: backup-20180614.123657
2018/06/14 12:36:57 [SYS] Removing old backup backup-20180519.060402
2018/06/14 13:00:00 [DVR] Starting job 1528995590-ch764 Food Network Star on ch=[764 54]
2018/06/14 13:00:00 [DVR] Waiting 59m49.995487021s until next job 1528999190-ch764 Food Network Star
2018/06/14 13:00:00 [TNR] Opened connection to 13261DD4 for ch764
2018/06/14 13:00:00 [DVR] Recording for job 1528995590-ch764 from 13261DD4 ch764 into “TV/Food Network Star/Food Network Star 2018-06-10 Comeback Kitchen One More Shot At Stardom 2018-06-14-1300.mpg” for 59m59.995439157s
2018/06/14 13:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 13:59:50 [DVR] Starting job 1528999190-ch764 Food Network Star on ch=[764 54]
2018/06/14 13:59:50 [TNR] Sharing existing connection to 13261DD4 for ch764 (clients=2, len=0)
2018/06/14 13:59:50 [DVR] Recording for job 1528999190-ch764 from 13261DD4 ch764 into “TV/Food Network Star/Food Network Star S14E01 2018-06-10 Are You A Main Attraction 2018-06-14-1359.mpg” for 1h1m9.937448317s
2018/06/14 14:00:00 [DVR] Finished job 1528995590-ch764 Food Network Star
2018/06/14 14:00:00 [DVR] Processing file-297: TV/Food Network Star/Food Network Star 2018-06-10 Comeback Kitchen One More Shot At Stardom 2018-06-14-1300.mpg
2018/06/14 14:00:01 [DVR] Running commercial detection on file 297 (TV/Food Network Star/Food Network Star 2018-06-10 Comeback Kitchen One More Shot At Stardom 2018-06-14-1300.mpg)
2018/06/14 14:10:09 [DVR] Commercial detection finished with 12 markers.
2018/06/14 14:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 15:01:00 [TNR] Closed connection to 13261DD4 for ch764
2018/06/14 15:01:00 [DVR] Finished job 1528999190-ch764 Food Network Star
2018/06/14 15:01:00 [DVR] Processing file-298: TV/Food Network Star/Food Network Star S14E01 2018-06-10 Are You A Main Attraction 2018-06-14-1359.mpg
2018/06/14 15:01:00 [DVR] Running commercial detection on file 298 (TV/Food Network Star/Food Network Star S14E01 2018-06-10 Are You A Main Attraction 2018-06-14-1359.mpg)
2018/06/14 15:11:08 [DVR] Commercial detection finished with 12 markers.
2018/06/14 15:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 16:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 17:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 17:53:15 http: TLS handshake error from 89.248.172.16:47590: tls: client offered an unsupported, maximum protocol version of 300
2018/06/14 17:53:30 http: TLS handshake error from 89.248.172.16:50046: tls: no cipher suite supported by both client and server
2018/06/14 18:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 19:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 20:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 21:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 22:03:39 [DVR] Deleting /shares/Public/DVR/TV/Food Network Star/Food Network Star 2018-06-10 Comeback Kitchen One More Shot At Stardom 2018-06-14-1300.mpg
2018/06/14 22:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 22:49:15 [DVR] Deleting /shares/Public/DVR/TV/Food Network Star/Food Network Star S14E01 2018-06-10 Are You A Main Attraction 2018-06-14-1359.mpg
2018/06/14 23:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/15 00:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/15 01:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/15 02:36:55 [NAT] Successfully mapped port 8089 using natpmp
2018/06/15 03:36:55 [NAT] Successfully mapped port 8089 using natpmp
2018/06/15 04:36:55 [NAT] Successfully mapped port 8089 using natpmp
2018/06/15 05:36:55 [NAT] Successfully mapped port 8089 using natpmp
2018/06/16 10:51:41 [SYS] Starting Channels DVR v2018.06.12.0218 (linux-x86_64) in /mnt/HD/HD_a2/Nas_Prog/ChannelsDVR/channels-dvr/data
2018/06/16 10:51:41 [HDR] Found 2 devices
2018/06/16 10:51:42 [SYS] Started HTTP Server
2018/06/16 10:52:03 [DVR] Recording engine started in /shares/Public/DVR
2018/06/16 10:52:03 [SYS] Bonjour service running for dvr-tubolard2.local. [192.168.0.11]
2018/06/16 10:52:03 [NAT] Successfully mapped port 8089 using natpmp
2018/06/16 10:52:03 [DVR] Deleting expired job 1529128490-27 Real Time With Bill Maher
2018/06/16 10:52:03 [SYS] Created database snapshot: backup-20180616.105203
2018/06/16 10:52:03 [SYS] Removing old backup backup-20180520.090400
2018/06/16 10:52:46 [IDX] Pruned 17422 expired airings from USA-MI21455-X in 33.623257983s.
2018/06/16 10:52:49 [IDX] Pruned 1638 expired airings from USA-OTA48708 in 3.177442532s.
2018/06/16 10:55:34 [DVR] Waiting 15h4m15.650745032s until next job 1529215190-ch503 Real Time With Bill Maher

I’m not very network tech savvy. Looking for resolution as I’m missing a lot of recordings. For now, I’m checking to see if Channels DVR is up nearly every day and restarting if it’s down.

Thanks, Carl

I don’t think the tls error is related.

The log you pasted doesn’t include any guide update messages. Did you remove those?

When you notice the DVR isn’t working- does it show any DVR info on your Apple TV? Are you able to reach the DVR web UI before you stop/start again from the NAS console?

Thanks for your rapid response! I’ll try to answer your questions as best I can. See new copy/paste of log below:

2018/06/14 17:53:15 http: TLS handshake error from 89.248.172.16:47590: tls: client offered an unsupported, maximum protocol version of 300
2018/06/14 17:53:30 http: TLS handshake error from 89.248.172.16:50046: tls: no cipher suite supported by both client and server
2018/06/14 18:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 19:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 20:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 21:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 22:03:39 [DVR] Deleting /shares/Public/DVR/TV/Food Network Star/Food Network Star 2018-06-10 Comeback Kitchen One More Shot At Stardom 2018-06-14-1300.mpg
2018/06/14 22:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/14 22:49:15 [DVR] Deleting /shares/Public/DVR/TV/Food Network Star/Food Network Star S14E01 2018-06-10 Are You A Main Attraction 2018-06-14-1359.mpg
2018/06/14 23:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/15 00:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/15 01:36:57 [NAT] Successfully mapped port 8089 using natpmp
2018/06/15 02:36:55 [NAT] Successfully mapped port 8089 using natpmp
2018/06/15 03:36:55 [NAT] Successfully mapped port 8089 using natpmp
2018/06/15 04:36:55 [NAT] Successfully mapped port 8089 using natpmp
2018/06/15 05:36:55 [NAT] Successfully mapped port 8089 using natpmp //DVR went down sometime after this log entry//
2018/06/16 10:51:41 [SYS] Starting Channels DVR v2018.06.12.0218 (linux-x86_64) in /mnt/HD/HD_a2/Nas_Prog/ChannelsDVR/channels-dvr/data
2018/06/16 10:51:41 [HDR] Found 2 devices
2018/06/16 10:51:42 [SYS] Started HTTP Server
2018/06/16 10:52:03 [DVR] Recording engine started in /shares/Public/DVR
2018/06/16 10:52:03 [SYS] Bonjour service running for dvr-tubolard2.local. [192.168.0.11]
2018/06/16 10:52:03 [NAT] Successfully mapped port 8089 using natpmp
2018/06/16 10:52:03 [DVR] Deleting expired job 1529128490-27 Real Time With Bill Maher
2018/06/16 10:52:03 [SYS] Created database snapshot: backup-20180616.105203
2018/06/16 10:52:03 [SYS] Removing old backup backup-20180520.090400
2018/06/16 10:52:46 [IDX] Pruned 17422 expired airings from USA-MI21455-X in 33.623257983s.
2018/06/16 10:52:49 [IDX] Pruned 1638 expired airings from USA-OTA48708 in 3.177442532s.
2018/06/16 10:55:34 [DVR] Waiting 15h4m15.650745032s until next job 1529215190-ch503 Real Time With Bill Maher
2018/06/16 11:52:03 [NAT] Successfully mapped port 8089 using natpmp
2018/06/16 12:52:03 [NAT] Successfully mapped port 8089 using natpmp
2018/06/16 13:52:03 [NAT] Successfully mapped port 8089 using natpmp
2018/06/16 10:51:41 [SYS] Starting Channels DVR v2018.06.12.0218 (linux-x86_64) in /mnt/HD/HD_a2/Nas_Prog/ChannelsDVR/channels-dvr/data
2018/06/16 10:51:41 [HDR] Found 2 devices
2018/06/16 10:51:42 [SYS] Started HTTP Server
2018/06/16 10:52:03 [DVR] Recording engine started in /shares/Public/DVR
2018/06/16 10:52:03 [SYS] Bonjour service running for dvr-tubolard2.local. [192.168.0.11]
2018/06/16 10:52:03 [NAT] Successfully mapped port 8089 using natpmp
2018/06/16 10:52:03 [DVR] Deleting expired job 1529128490-27 Real Time With Bill Maher
2018/06/16 10:52:03 [SYS] Created database snapshot: backup-20180616.105203
2018/06/16 10:52:03 [SYS] Removing old backup backup-20180520.090400
2018/06/16 10:52:46 [IDX] Pruned 17422 expired airings from USA-MI21455-X in 33.623257983s.
2018/06/16 10:52:49 [IDX] Pruned 1638 expired airings from USA-OTA48708 in 3.177442532s.
2018/06/16 10:55:34 [DVR] Waiting 15h4m15.650745032s until next job 1529215190-ch503 Real Time With Bill Maher
2018/06/16 11:52:03 [NAT] Successfully mapped port 8089 using natpmp
2018/06/16 12:52:03 [NAT] Successfully mapped port 8089 using natpmp
2018/06/16 13:52:03 [NAT] Successfully mapped port 8089 using natpmp

You’ll see the TLS handshake warnings followed by mapping statements, deletion of 2 recorded shows I watched and deleted, more mapping statements, then nothing until I restarted the DVR after noticing it was down at 2018/06/16 10:51:41. Again, I restart by logging into my PR2100 dashboard, going to Apps, turning Channels DVR off, then on.

I cannot reach the DVR when it’s down through Apple TV (DVR and Recordings are removed from the Channels App menu), nor the DVR web UI. I’ve tried the web UI with both IP address and URL.

Since first reporting this error, I downloaded the DVR app from your website, reinstalled it, and updated it. I also port forwarded port 8089 from my Airport Extreme thinking that may help. I’m reaching for anything at this point.

Guide data is usually fetched around 09:00. I didn’t include it in my log attachment, but shortly after I restarted the DVR, guide data began to download and completed normally. If you want that part of the log, please let me know.

One note, I have 2 HDHomeRun devices on my network, DUO and PRIME, using the DUO for OTA broadcasts.

Thanks, again, for all your help.
Carl

This indicates that the DVR software may be crashing. I would expect the crash log messages to show in the log so I’m surprised there’s nothing there.

Okay good to know, just wanted to make sure it wasn’t the guide download causing the crash.

Are you able to ssh into the NAS and check some more logs? I’m curious if the kernel log shows anything about channels-dvr. You can see it by running this command:

dmesg

First of all, an apology for the long period without responding. I’ve been in the summer vacation mode. I think I’ve found the problem. I was running WD’s suggested anti-virus app on the PR2100. I decided to uninstall it. Since doing so, I’ve had no problems with the DVR. This is something to file away, I presume. It may present itself again with another Channels DVR user.

2 Likes