I moved our DVR from a Windows laptop to a DS218+ yesterday. The DVR seems to be working fine, but three times now when turning on the family room TV I have seen a black screen with 'Lost Connection' in the middle. Turning everything off and then on again clears it. The ATV was idle for about 4 hours prior to this.
The ATV is connected via MoCa back to an ethernet port on the wifi router and has been operating with no issues for at least a year.
Any thoughts or things to check?
This is from the DVR log:
2019/04/02 11:52:13 [DVR] Cancelling stream 131B881C ch1009 after 6s read timeout
2019/04/02 11:52:13 [TNR] Closed connection to 131B881C for ch1009
2019/04/02 12:03:28 [NAT] Successfully mapped port 8089 using natpmp
2019/04/02 12:47:06 [TNR] Opened connection to 131B881C for ch1009 <-- When i turned it back on
And hereis the log from the AppleTV covering that time period:
2019-04-02 11:22:25.700 Notification: LTGuideDataUpdated
2019-04-02 11:52:09.170 read(32768) = 0 after 5000000
2019-04-02 11:52:13.738 read got (55) No buffer space available
2019-04-02 11:52:13.742 read got (55) No buffer space available
2019-04-02 11:52:13.747 read got (55) No buffer space available
2019-04-02 11:52:13.751 read got (55) No buffer space available
2019-04-02 11:52:13.755 read got (55) No buffer space available
2019-04-02 11:52:13.758 read got (55) No buffer space available
2019-04-02 11:52:13.760 read got (55) No buffer space available
2019-04-02 11:52:13.763 read got (55) No buffer space available
2019-04-02 11:52:13.767 read got (55) No buffer space available
2019-04-02 11:52:13.771 read got (55) No buffer space available
2019-04-02 11:52:13.775 read got (55) No buffer space available
2019-04-02 11:52:13.779 read got (55) No buffer space available
2019-04-02 11:52:13.782 read got (55) No buffer space available
2019-04-02 11:52:13.785 read got (55) No buffer space available
2019-04-02 11:52:13.788 read got (55) No buffer space available
2019-04-02 11:52:13.791 read got (55) No buffer space available
2019-04-02 11:52:13.795 read got (55) No buffer space available
2019-04-02 11:52:13.799 read got (55) No buffer space available
2019-04-02 11:52:13.803 read got (55) No buffer space available
2019-04-02 11:52:13.807 read got (55) No buffer space available
2019-04-02 11:52:13.810 streamer stopping after 17339300 packets and 20 timeouts (302529 waits)
2019-04-02 11:52:13.872 [lavf] v: EOF reached.
2019-04-02 11:52:14.043 [ffmpeg/audio] error: ac3: incomplete frame
2019-04-02 11:52:14.122 [af] v: filter input EOF
2019-04-02 11:52:14.131 [af] v: filter output EOF
2019-04-02 11:52:14.148 [cplayer] v: audio EOF reached
2019-04-02 11:52:14.733 [ffmpeg/video] error: mpeg2video: ac-tex damaged at 49 55
2019-04-02 11:52:14.740 [ffmpeg/video] error: mpeg2video: Warning MVs not available
2019-04-02 11:52:14.747 [ffmpeg/video] v: mpeg2video: concealing 1560 DC, 1560 AC, 1560 MV errors in P frame
2019-04-02 11:52:15.000 [ffmpeg/video] error: mpeg2video: invalid cbp -1 at 67 28
2019-04-02 11:52:15.005 [ffmpeg/video] error: mpeg2video: Warning MVs not available
2019-04-02 11:52:15.015 [ffmpeg/video] v: mpeg2video: concealing 4800 DC, 4800 AC, 4800 MV errors in B frame
2019-04-02 11:52:15.066 [vf] v: filter input EOF
2019-04-02 11:52:15.069 [vf] v: filter output EOF
2019-04-02 11:52:15.099 [cplayer] v: video EOF reached
2019-04-02 11:52:15.102 event: audio-reconfig
2019-04-02 11:52:15.112 event: video-reconfig
2019-04-02 11:52:15.118 event: tracks-changed
2019-04-02 11:52:15.121 event: end-file
2019-04-02 11:52:15.127 Updating playState from LTVideoPlayerPlayStatePlaying to LTVideoPlayerPlayStateError
2019-04-02 11:52:15.133 event: audio-reconfig
2019-04-02 11:52:15.135 event: video-reconfig
2019-04-02 11:52:15.139 [cplayer] v: EOF code: 1
2019-04-02 11:52:15.143 [ad] v: Uninit decoder.
2019-04-02 11:52:15.146 [vd] v: Uninit decoder.
2019-04-02 11:52:15.147 [cplayer] v: finished playback, success (reason 0)
2019-04-02 11:52:15.150 [cplayer] info:
2019-04-02 11:52:15.154 [cplayer] v: Set property: aid=1 -> 1
2019-04-02 11:52:15.158 [cplayer] v: Set property: audio=1 -> 1
2019-04-02 11:52:15.160 [cplayer] v: Set property: sid=0 -> 1
2019-04-02 11:52:15.164 [cplayer] v: Set property: sub=0 -> 1
2019-04-02 12:22:26.571 Notification: LTGuideDataUpdated
2019-04-02 12:45:14.965 App will resign active (0)
2019-04-02 12:45:21.136 App did become active (0)
2019-04-02 12:46:55.792 App will resign active (0)
2019-04-02 12:46:55.796 App did enter background (2)
2019-04-02 12:46:55.799 jsonpipe failed with Error Domain=NSURLErrorDomain Code=-999 "cancelled" UserInfo={NSErrorFailingURLStringKey=http://192.168.2.24:8089/dvr/events/subscribe, NSErrorFailingURLKey=http://192.168.2.24:8089/dvr/events/subscribe, _NSURLErrorRelatedURLSessionTaskErrorKey=(
"LocalDataTask .<2>"
), _NSURLErrorFailingURLSessionTaskErrorKey=LocalDataTask .<2>, NSLocalizedDescription=cancelled}
2019-04-02 12:46:55.806 App did enter background (2)
2019-04-02 12:46:55.809 [cplayer] v: Set property: pause=true -> 1
2019-04-02 12:46:55.813 Updating playState from LTVideoPlayerPlayStateError to LTVideoPlayerPlayStateStopped
2019-04-02 12:46:55.819 [cplayer] v: Set property: pause=false -> 1
2019-04-02 12:47:06.228 App will enter foreground (1)
2019-04-02 12:47:06.243 App will enter foreground (1)
2019-04-02 12:47:06.246 App did become active (0)
2019-04-02 12:47:06.249 [cplayer] v: Set property: pause=false -> 1
2019-04-02 12:47:06.252 Updating playState from LTVideoPlayerPlayStateStopped to LTVideoPlayerPlayStateStopped
2019-04-02 12:47:06.256 Updating playState from LTVideoPlayerPlayStateStopped to LTVideoPlayerPlayStateLoading
2019-04-02 12:47:06.260 set streaming buffer to 128 segments (free bytes: 18681643008)
2019-04-02 12:47:06.266 streamer sent request to DVR 192.168.2.24: device 131B881C, channel <HRChannel:0x280b0ebe0 number=1009 name=WGNDT (WGNDT)> [/devices/131B881C/channels/1009/stream.mpg?codec=copy]
2019-04-02 12:47:06.576 Notification: LTGuideDataUpdated
2019-04-02 12:47:06.615 Startup Tab: {
Tab = LTTVOnNowViewController;
}
2019-04-02 12:47:06.621 Device Found: {
DRM = false;
DRMChannels = 0;
DVB = false;
FirmwareUpToDate = true;
FirmwareVersion = 20180817;
FriendlyName = "HDHomeRun PRIME";
Legacy = false;
ModelNumber = "HDHR3-CC";
TunerCount = 3;
}
2019-04-02 12:47:06.627 Device Found: {
DRM = false;
DRMChannels = 0;
DVB = false;
FirmwareUpToDate = true;
FirmwareVersion = 20180817;
FriendlyName = "HDHomeRun PRIME";
Legacy = false;
ModelNumber = "HDHR3-CC";
TunerCount = 3;
}
2019-04-02 12:47:06.656 Found DVR server dvr-captainrondvr.local. @ (
"192.168.2.24:8089",
"[fe80::211:32ff:fea2:8bcb]:8089"
)
2019-04-02 12:47:06.795 event: start-file
2019-04-02 12:47:06.799 Updating playState from LTVideoPlayerPlayStateLoading to LTVideoPlayerPlayStateLoading
2019-04-02 12:47:06.804 [cplayer] info: Playing: cb://1554227226256
2019-04-02 12:47:06.808 [stream_callback] v: Opening cb://1554227226256
2019-04-02 12:47:06.812 [demux] v: Trying demuxers for level=force.
2019-04-02 12:47:07.063 streamer started receiving data
2019-04-02 12:47:07.069 [lavf] v: Found 'mpegts' at score=50 size=2048.
2019-04-02 12:47:07.072 [lavf] v: avformat_open_input() finished after 2048 bytes.
2019-04-02 12:47:07.076 event: tracks-changed
2019-04-02 12:47:07.083 event: tracks-changed
2019-04-02 12:47:07.086 event: tracks-changed
2019-04-02 12:47:07.090 event: metadata-update
2019-04-02 12:47:07.094 event: audio-reconfig
2019-04-02 12:47:07.098 event: audio-reconfig
2019-04-02 12:47:07.102 event: file-loaded
2019-04-02 12:47:07.105 [lavf] v: avformat_find_stream_info() skipped
2019-04-02 12:47:07.108 [demux] v: Detected file format: mpegts (libavformat)
2019-04-02 12:47:07.112 [cplayer] v: Opening done: cb://1554227226256
2019-04-02 12:47:07.116 [lavf] v: select track 0
2019-04-02 12:47:07.119 [lavf] v: select track 1
2019-04-02 12:47:07.124 [cplayer] info: (+) Video --vid=1 (mpeg2video)
2019-04-02 12:47:07.127 [cplayer] info: (+) Audio --aid=1 --alang=eng (ac3)
2019-04-02 12:47:07.129 [cplayer] info: Audio --aid=2 --alang=spa (ac3)
2019-04-02 12:47:07.132 [vd] v: Container reported FPS: 0.000000
2019-04-02 12:47:07.135 [vd] v: Codec list:
2019-04-02 12:47:07.139 [vd] v: mpeg2video - MPEG-2 video
2019-04-02 12:47:07.143 [vd] v: mpegvideo (mpeg2video) - MPEG-1 video
2019-04-02 12:47:07.147 [vd] v: Opening decoder mpeg2video
2019-04-02 12:47:07.151 [vd] v: Not trying to use hardware decoding: codec mpeg2video is not on whitelist.
2019-04-02 12:47:07.154 [vd] v: Using software decoding.
2019-04-02 12:47:07.158 [vd] v: Detected 3 logical cores.
2019-04-02 12:47:07.161 [vd] v: Requesting 4 threads for decoding.
2019-04-02 12:47:07.165 [vd] v: Selected codec: mpeg2video (MPEG-2 video)
2019-04-02 12:47:07.169 [vf] v: User filter list:
2019-04-02 12:47:07.173 [vf] v: lavfi (lavfi.00)
2019-04-02 12:47:07.178 [ad] v: Codec list:
2019-04-02 12:47:07.182 [ad] v: ac3 - ATSC A/52A (AC-3)
2019-04-02 12:47:07.185 [ad] v: ac3_fixed (ac3) - ATSC A/52A (AC-3)
2019-04-02 12:47:07.189 [ad] v: ac3_at (ac3) - ac3 (AudioToolbox)
2019-04-02 12:47:07.192 [ad] v: Opening decoder ac3
2019-04-02 12:47:07.196 [ad] v: Requesting 1 threads for decoding.
2019-04-02 12:47:07.200 [ad] v: Selected codec: ac3 (ATSC A/52A (AC-3))
2019-04-02 12:47:07.204 [af] v: User filter list:
2019-04-02 12:47:07.208 [af] v: (empty)
2019-04-02 12:47:07.212 [cplayer] v: Starting playback...
2019-04-02 12:47:07.216 [vd] warn: Error while decoding frame (-1094995529)!
2019-04-02 12:47:07.220 [vd] warn: Error while decoding frame (-1094995529)!
2019-04-02 12:47:07.229 [vd] warn: Error while decoding frame (-1094995529)!
2019-04-02 12:47:07.232 [vd] warn: Error while decoding frame (-1094995529)!
2019-04-02 12:47:07.235 [vd] warn: Error while decoding frame (-1094995529)!
2019-04-02 12:47:07.239 [vd] warn: Error while decoding frame (-1094995529)!
2019-04-02 12:47:07.301 [af] v: [in] 48000Hz 5.1(side) 6ch floatp
2019-04-02 12:47:07.306 [af] v: [userspeed] 48000Hz 5.1(side) 6ch floatp
2019-04-02 12:47:07.309 [af] v: [userspeed] (disabled)
2019-04-02 12:47:07.313 event: audio-reconfig
2019-04-02 12:47:07.317 [af] v: [convert] 48000Hz 5.1(side) 6ch floatp
2019-04-02 12:47:07.321 [ao] v: Trying audio driver 'audiounit'
2019-04-02 12:47:07.325 [ao/audiounit] v: requested format: 48000 Hz, 5.1(side) channels, floatp
2019-04-02 12:47:07.329 [ao/audiounit] v: using soft-buffer of 9600 samples.
2019-04-02 12:47:07.333 [cplayer] info: AO: [audiounit] 48000Hz stereo 2ch floatp
2019-04-02 12:47:07.337 [cplayer] v: AO: Description: AudioUnit (iOS)
2019-04-02 12:47:07.341 [autoconvert] v: inserting resampler
2019-04-02 12:47:07.344 [swresample] v: format change, reinitializing resampler
2019-04-02 12:47:07.347 [swresample] v: 48000Hz 5.1(side) floatp -> 48000Hz stereo floatp
2019-04-02 12:47:07.351 [swresample] v: Remix: 5.1(side) -> stereo
2019-04-02 12:47:07.355 [af] v: [out] 48000Hz stereo 2ch floatp
2019-04-02 12:47:07.412 [vd] warn: Error while decoding frame (-1094995529)!
2019-04-02 12:47:07.415 [vd] v: DR failed - disabling.
2019-04-02 12:47:07.468 [vd] v: Decoder format: 1920x1080 yuv420p bt.709/bt.709/bt.1886/limited/auto CL=mpeg2/4/h264 (auto 0.000000/0.000000/0.000000)
2019-04-02 12:47:07.470 [vf] v: [in] 1920x1080 yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
2019-04-02 12:47:07.474 [vf] v: [userdeint] 1920x1080 yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
2019-04-02 12:47:07.477 event: video-reconfig
2019-04-02 12:47:07.481 event: video-reconfig
2019-04-02 12:47:07.485 event: tracks-changed
2019-04-02 12:47:07.487 [vf] v: [userdeint] (disabled)
2019-04-02 12:47:07.491 [vf] v: [lavfi] 1920x1080 yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
2019-04-02 12:47:07.494 [vf] v: [autorotate] 1920x1080 yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
2019-04-02 12:47:07.497 [vf] v: [autorotate] (disabled)
2019-04-02 12:47:07.498 [vf] v: [convert] 1920x1080 yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
2019-04-02 12:47:07.501 [vf] v: [convert] (disabled)
2019-04-02 12:47:07.505 [vf] v: [out] 1920x1080 yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
2019-04-02 12:47:07.509 [cplayer] info: VO: [libmpv] 1920x1080 yuv420p
2019-04-02 12:47:07.511 [cplayer] v: VO: Description: render API for libmpv
2019-04-02 12:47:07.515 [vo/libmpv] v: reconfig to 1920x1080 yuv420p bt.709/bt.709/bt.1886/limited/display SP=1.000000 CL=mpeg2/4/h264
2019-04-02 12:47:07.518 [cplayer] v: first video frame after restart shown
2019-04-02 12:47:07.521 [cplayer] info: (+) Video --vid=1 (mpeg2video)
2019-04-02 12:47:07.522 [cplayer] info: (+) Audio --aid=1 --alang=eng (ac3)
2019-04-02 12:47:07.525 [cplayer] info: Audio --aid=2 --alang=spa (ac3)
2019-04-02 12:47:07.529 [cplayer] info: Subs --sid=1 (*) (eia_608)
2019-04-02 12:47:08.392 [cplayer] v: starting audio playback
2019-04-02 12:47:08.395 event: playback-restart
2019-04-02 12:47:08.398 Updating playState from LTVideoPlayerPlayStateLoading to LTVideoPlayerPlayStatePlaying
2019-04-02 12:47:08.402 [cplayer] v: playback restart complete
2019-04-02 13:02:23.619 read got (9) Bad file descriptor
2019-04-02 13:02:23.623 [cplayer] v: Set property: pause=false -> 1