[kchmon]

I just reformatted my MythTV backend/frontend + Kodi machine to a fresh Xubuntu 16.0.1 install. MythTV backend and frontend 0.28 work fine. The MythTV add-on for Kodi 16.1 functions fine for the channel list, programming guide, and recordings playback.

The problem is when I go to watch LiveTV, I select a program from the guide, it displays white text "Channel Unavailable" on a purple background.

I think I turned on all the debug logs I read about in looking for a solution to this problem. In the log below I select "Switch to Channel" just after the clock turned 21:51

I'm very eager to have my machine back up and running like normal, so any assistance is appreciated. Thanks in advance! Please let me know if I can provide any useful details.

21:51:00 T:140380210604352 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0
21:51:00 T:140380210604352 DEBUG: OnKey: return (0xf00d) pressed, action is Select
21:51:00 T:140378827048704 DEBUG: AddOnLog: MythTV PVR Client: RunHouseKeeping
21:51:00 T:140380210604352 DEBUG: ------ Window Deinit (DialogContextMenu.xml) ------
21:51:00 T:140380210604352 DEBUG: Loading settings for pvr://channels/tv/All channels/pvr.mythtv_1001.pvr
21:51:00 T:140380210604352 DEBUG: CPlayerCoreFactory::GetPlayers(pvr://channels/tv/All channels/pvr.mythtv_1001.pvr)
21:51:00 T:140380210604352 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
21:51:00 T:140380210604352 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
21:51:00 T:140380210604352 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
21:51:00 T:140380210604352 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
21:51:00 T:140380210604352 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
21:51:00 T:140380210604352 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
21:51:00 T:140380210604352 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
21:51:00 T:140380210604352 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
21:51:00 T:140380210604352 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
21:51:00 T:140380210604352 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
21:51:00 T:140380210604352 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
21:51:00 T:140380210604352 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
21:51:00 T:140380210604352 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
21:51:00 T:140380210604352 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
21:51:00 T:140380210604352 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
21:51:00 T:140380210604352 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
21:51:00 T:140380210604352 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
21:51:00 T:140380210604352 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
21:51:00 T:140380210604352 DEBUG: Radio UECP (RDS) Processor - new CDVDRadioRDSData
21:51:00 T:140380210604352 NOTICE: DVDPlayer: Opening: pvr://channels/tv/All channels/pvr.mythtv_1001.pvr
21:51:00 T:140380210604352 WARNING: CDVDMessageQueue(player):ut MSGQ_NOT_INITIALIZED
21:51:00 T:140380210604352 DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
21:51:00 T:140380210604352 DEBUG: LinuxRendererGL: Cleaning up GL resources
21:51:00 T:140380210604352 DEBUG: CLinuxRendererGL:reInit - precision of luminance 16 is 16
21:51:00 T:140379387369216 DEBUG: Thread DVDPlayer start, auto delete: false
21:51:00 T:140379387369216 NOTICE: Creating InputStream
21:51:00 T:140379387369216 DEBUG: PVRManager - OpenLiveStream - opening live stream on channel 'ABC'
21:51:00 T:140379387369216 DEBUG: opening live stream for channel 'ABC'
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: OpenLiveStream: channel uid: 1001, num: 1
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)SubscriptionHandlerThread: subscription is started (0x7faca4001ca0:3)
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)__connectAddr: connected to socket(0x7faca40011f8)
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)SendCommand: MYTH_PROTO_VERSION 88 XmasGift
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)RcvMessageLength: 13
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)OpenConnection: asked for version 88, got version 88
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)OpenConnection: agreed on version 88
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)SendCommand: ANN Monitor livingroomtv 0
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)RcvMessageLength: 2
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: Suspend
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: Suspend: Stopping Thread
21:51:00 T:140379208345344 DEBUG: AddOnLog: MythTV PVR Client: Process: FileOps Thread Stopped
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)SendCommand: GET_FREE_INPUT_INFO 0
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)RcvMessageLength: 607
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)FindTunableCardIds: [1] channel=WMAR-DT1(1001) card=15 input=MPEG2TS(15) mplex=0 source=1
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)FindTunableCardIds: [1] channel=WMAR-DT1(1001) card=16 input=MPEG2TS(16) mplex=0 source=1
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)FindTunableCardIds: [2] channel=WMAR-DT1(1001) card=13 input=MPEG2TS(13) mplex=0 source=1
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)FindTunableCardIds: [2] channel=WMAR-DT1(1001) card=14 input=MPEG2TS(14) mplex=0 source=1
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)SpawnLiveTV: trying recorder num (15) channum (1)
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)SendCommand: GET_RECORDER_FROM_NUM[]:[]15
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)RcvMessageLength: 22
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)GetRecorderFromNum75: open recorder 15 (192.168.1.123:6543)
21:51:00 T:140379387369216 ERROR: AddOnLog: MythTV PVR Client: (CPPMyth)__connectAddr: failed to connect (111)
21:51:00 T:140379387369216 ERROR: Previous line repeats 1 times.
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)SpawnLiveTV: limiting tune attempts to first tunable card
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)Stop: subscription thread (0x7faca4001ca0:3)
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)Stop: subscription thread (0x7faca4001ca0:3) stopped
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)SendCommand: DONE
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)Close: done
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: Resume
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: Resume: Resuming Thread
21:51:00 T:140379387369216 ERROR: AddOnLog: MythTV PVR Client: OpenLiveStream: Failed to open live stream
21:51:00 T:140379378976512 DEBUG: AddOnLog: MythTV PVR Client: Process: FileOps Thread Started
21:51:00 T:140379387369216 DEBUG: PVRFile - Open - playback has started on filename pvr://channels/tv/All channels/pvr.mythtv_1001.pvr
21:51:00 T:140379387369216 DEBUG: CDVDInputStreamPVRManager::Open - stream opened: pvr://channels/tv/All channels/pvr.mythtv_1001.pvr
21:51:00 T:140379387369216 NOTICE: Creating Demuxer
21:51:00 T:140379387369216 DEBUG: Open - probing detected format [mpegts]
21:51:00 T:140379387369216 INFO: ffmpeg[7FAC9F849700]: Input #0, mpegts, pvr://channels/tv/All channels/pvr.mythtv_1001.pv':
21:51:00 T:140379387369216 INFO: ffmpeg[7FAC9F849700]: Duration: N/A, bitrate: N/A
21:51:00 T:140379387369216 INFO: ffmpeg[7FAC9F849700]: Program 1
21:51:00 T:140379387369216 INFO: ffmpeg[7FAC9F849700]: Stream #0:0[0x1011]: Video: mpeg2video ([2][0][0][0] / 0x0002), none, 90k tbn
21:51:00 T:140379387369216 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: LengthLiveStream
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: LengthLiveStream: Done - duration: 502524
21:51:00 T:140379387369216 NOTICE: Opening stream: 0 source: 256
21:51:00 T:140379387369216 NOTICE: Creating video codec with codec id: 2
21:51:00 T:140379387369216 DEBUG: CDVDFactoryCodec: compiled in hardware support: AMCodec:no MediaCodec:no OpenMax:no libstagefright:no VDPAU:yes VAAPI:yes iMXVPU:no MMAL:no
21:51:00 T:140379387369216 DEBUG: FactoryCodec - Video: - Opening
21:51:00 T:140379387369216 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video
21:51:00 T:140379387369216 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened
21:51:00 T:140379387369216 NOTICE: Creating video thread
21:51:00 T:140379387369216 ERROR: Get - failed to get stream
21:51:00 T:140379387369216 DEBUG: CDVDPlayer::SetCaching - caching state 2
21:51:00 T:140379387369216 WARNING: CDVDMessageQueue(audio):ut MSGQ_NOT_INITIALIZED
21:51:00 T:140379208345344 DEBUG: Thread DVDPlayerVideo start, auto delete: false
21:51:00 T:140379387369216 DEBUG: OnPlayBackStarted: play state was 1, starting 1
21:51:00 T:140379208345344 NOTICE: running thread: video_thread
21:51:00 T:140380210604352 DEBUG: PlayFile: OpenFile succeed, play state 2
21:51:00 T:140380210604352 DEBUG: OnPlayBackStarted: play state was 2, starting 0
21:51:00 T:140379387369216 DEBUG: CDVDDemuxFFmpeg::Read() fetching extradata, extradata_size(22)
21:51:00 T:140379387369216 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
21:51:00 T:140379387369216 NOTICE: Opening stream: 0 source: 256
21:51:00 T:140379387369216 NOTICE: Creating video codec with codec id: 2
21:51:00 T:140379387369216 DEBUG: CDVDFactoryCodec: compiled in hardware support: AMCodec:no MediaCodec:no OpenMax:no libstagefright:no VDPAU:yes VAAPI:yes iMXVPU:no MMAL:no
21:51:00 T:140379387369216 DEBUG: FactoryCodec - Video: - Opening
21:51:00 T:140379387369216 NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: MPEG-2 video
21:51:00 T:140379387369216 DEBUG: FactoryCodec - Video: ff-mpeg2video - Opened
21:51:00 T:140379208345344 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(4199960000.000000, 1)
21:51:00 T:140379208345344 NOTICE: CDVDVideoCodecFFmpeg::GetFormat - Creating VDPAU(960x540)
21:51:00 T:140379208345344 NOTICE: (VDPAU) Close
21:51:00 T:140379387369216 DEBUG: set caching from pvr to done. audio (0) = 0. video (1) = 6
21:51:00 T:140379387369216 DEBUG: CDVDPlayer::SetCaching - caching state 0
21:51:00 T:140379387369216 DEBUG: CDVDPlayer::CheckContinuity - resync backward :2, prev:4203920000.000000, curr:4199960000.000000, diff:-3960000.000000
21:51:00 T:140379208345344 DEBUG: VAAPI - open decoder
21:51:00 T:140380210604352 DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/All channels/pvr.mythtv_1001.pvr)
21:51:00 T:140379208345344 DEBUG: VAAPI - initialize version 0.39
21:51:00 T:140379208345344 DEBUG: VAAPI - driver in use: Intel i965 driver for Intel® Haswell Mobile - 1.7.0
21:51:00 T:140379208345344 DEBUG: VAAPI - attrib 0 (get/set) min -100 max 100 value 0x0
21:51:00 T:140379208345344 DEBUG: VAAPI - attrib 1 (get/set) min 0 max 100 value 0x32
21:51:00 T:140379208345344 DEBUG: VAAPI - attrib 2 (get/set) min -180 max 180 value 0x0
21:51:00 T:140379208345344 DEBUG: VAAPI - attrib 3 (get/set) min 0 max 100 value 0x32
21:51:00 T:140379208345344 DEBUG: VAAPI - attrib 6 (get/set) min 0 max 3 value 0x0
21:51:00 T:140379208345344 DEBUG: VAAPI - profile 0
21:51:00 T:140379208345344 DEBUG: VAAPI - profile 1
21:51:00 T:140379208345344 DEBUG: VAAPI - profile 13
21:51:00 T:140379208345344 DEBUG: VAAPI - profile 6
21:51:00 T:140379208345344 DEBUG: VAAPI - profile 7
21:51:00 T:140379208345344 DEBUG: VAAPI - profile 15
21:51:00 T:140379208345344 DEBUG: VAAPI - profile 16
21:51:00 T:140379208345344 DEBUG: VAAPI - profile 8
21:51:00 T:140379208345344 DEBUG: VAAPI - profile 9
21:51:00 T:140379208345344 DEBUG: VAAPI - profile 10
21:51:00 T:140379208345344 DEBUG: VAAPI - profile -1
21:51:00 T:140379208345344 DEBUG: VAAPI - profile 12
21:51:00 T:140379370583808 DEBUG: Thread Vaapi-Output start, auto delete: false
21:51:00 T:140379370583808 NOTICE: COutput::OnStartup: Output Thread created
21:51:00 T:140379370583808 DEBUG: VAAPI::COutput::CreateGlxContext - created context
21:51:00 T:140379370583808 DEBUG: SECTION:LoadDLL(special://xbmcbin/system/libsse4-x86_64-linux.so)
21:51:00 T:140379370583808 DEBUG: Loading: /usr/lib/kodi/system/libsse4-x86_64-linux.so
21:51:00 T:140379370583808 DEBUG: VAAPI::COutput::InitBufferPool - Surfaces created
21:51:00 T:140379208345344 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
21:51:00 T:140379361142528 DEBUG: Previous line repeats 4 times.
21:51:00 T:140379361142528 DEBUG: Thread JobWorker start, auto delete: true
21:51:00 T:140379208345344 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -56.209390, Bufferlevel: 0, Gain: 0.080000
21:51:00 T:140379208345344 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
21:51:00 T:140380210604352 DEBUG: Previous line repeats 1 times.
21:51:00 T:140380210604352 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
21:51:00 T:140379387369216 DEBUG: CDVDPlayer::CheckContinuity - resync backward :2, prev:4207920000.000000, curr:4203960000.000000, diff:-3960000.000000
21:51:00 T:140380210604352 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
21:51:00 T:140380210604352 INFO: AddOnLog: MythTV PVR Client: Received announcement: Player, xbmc, OnPlay
21:51:00 T:140379208345344 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
21:51:00 T:140379208345344 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -56.209390, Bufferlevel: 0, Gain: 0.080000
21:51:00 T:140379208345344 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
21:51:00 T:140379208345344 DEBUG: Previous line repeats 2 times.
21:51:00 T:140379208345344 DEBUG: CDVDPlayerVideo::CalcDropRequirement - dropped in decoder, Sleeptime: -56.209390, Bufferlevel: 0, Gain: 0.079999
21:51:00 T:140379208345344 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
21:51:00 T:140379208345344 NOTICE: fps: 0.000000, pwidth: 960, pheight: 540, dwidth: 960, dheight: 540
21:51:00 T:140379208345344 DEBUG: OutputPicture - change configuration. 960x540. framerate: 0.00. format: VAAPI
21:51:00 T:140379208345344 DEBUG: CXBMCRenderManager::Configure - 4
21:51:00 T:140380210604352 NOTICE: Using GL_TEXTURE_2D
21:51:00 T:140380210604352 NOTICE: GL: Using VAAPI render method
21:51:00 T:140380210604352 NOTICE: GL: NPOT texture support detected
21:51:00 T:140380210604352 NOTICE: GL: Using GL_ARB_pixel_buffer_object
21:51:00 T:140379208345344 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
21:51:00 T:140379208345344 DEBUG: Previous line repeats 3 times.
21:51:00 T:140379208345344 DEBUG: CDVDClock:iscontinuity - CDVDPlayerVideo::OutputPicture - was:4199948813.127000, should be:4200006994.642000, error:58181.515000
21:51:00 T:140380210604352 DEBUG: Activating window ID: 12005
21:51:00 T:140380210604352 DEBUG: ------ Window Deinit (MyPVRGuide.xml) ------
21:51:00 T:140380210604352 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
21:51:00 T:140379387369216 DEBUG: CDVDPlayer::HandleMessages - player started 2
21:51:00 T:140380210604352 DEBUG: GL: Vertex Shader compilation log:
21:51:00 T:140380210604352 DEBUG: GL: Vertex Shader compiled successfully
21:51:00 T:140380210604352 DEBUG: GL: Pixel Shader compilation log:
21:51:00 T:140380210604352 DEBUG: GL: Fragment Shader compiled successfully
21:51:01 T:140378338465536 DEBUG: AddOnLog: MythTV PVR Client: SignalStatus
21:51:01 T:140378827048704 DEBUG: AddOnLog: MythTV PVR Client: RunHouseKeeping
21:51:02 T:140378338465536 DEBUG: AddOnLog: MythTV PVR Client: SignalStatus
21:51:02 T:140378827048704 DEBUG: AddOnLog: MythTV PVR Client: RunHouseKeeping
21:51:03 T:140380210604352 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0
21:51:03 T:140378338465536 DEBUG: AddOnLog: MythTV PVR Client: SignalStatus
21:51:03 T:140380210604352 DEBUG: OnKey: return (0xf00d) pressed, action is OSD
21:51:03 T:140380210604352 DEBUG: ------ Window Init (VideoOSD.xml) ------
21:51:03 T:140378827048704 DEBUG: AddOnLog: MythTV PVR Client: RunHouseKeeping
21:51:04 T:140378338465536 DEBUG: AddOnLog: MythTV PVR Client: SignalStatus
21:51:04 T:140380210604352 DEBUG: Keyboard: scancode: 0x24, sym: 0x000d, unicode: 0x000d, modifier: 0x0
21:51:04 T:140379387369216 DEBUG: CDVDPlayer::CheckContinuity - resync backward :2, prev:4211920000.000000, curr:4207960000.000000, diff:-3960000.000000
21:51:04 T:140380210604352 DEBUG: OnKey: return (0xf00d) pressed, action is Select
21:51:04 T:140380210604352 NOTICE: CDVDPlayer::CloseFile()
21:51:04 T:140380210604352 NOTICE: DVDPlayer: waiting for threads to exit
21:51:04 T:140379387369216 NOTICE: CDVDPlayer::OnExit()
21:51:04 T:140379387369216 NOTICE: Closing stream player 2
21:51:04 T:140379387369216 NOTICE: waiting for video thread to exit
21:51:04 T:140378827048704 DEBUG: AddOnLog: MythTV PVR Client: RunHouseKeeping
21:51:04 T:140379208345344 NOTICE: thread end: video_thread
21:51:04 T:140379208345344 DEBUG: Thread DVDPlayerVideo 140379208345344 terminating
21:51:04 T:140379387369216 NOTICE: deleting video codec
21:51:04 T:140379387369216 DEBUG: VAAPI::Release pre-cleanup
21:51:04 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: CloseLiveStream
21:51:04 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: Resume
21:51:04 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: CloseLiveStream: Done
21:51:04 T:140379361142528 DEBUG: DoWork - Saving file state for video item pvr://channels/tv/All channels/pvr.mythtv_1001.pvr
21:51:04 T:140379387369216 DEBUG: CDVDInputStreamPVRManager::Close - stream closed
21:51:04 T:140379387369216 DEBUG: OnPlayBackStopped: play state was 2, starting 0
21:51:04 T:140379387369216 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
21:51:04 T:140379387369216 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
21:51:04 T:140379387369216 INFO: AddOnLog: MythTV PVR Client: Received announcement: Player, xbmc, OnStop
21:51:04 T:140379387369216 DEBUG: Thread DVDPlayer 140379387369216 terminating
21:51:04 T:140380210604352 NOTICE: DVDPlayer: finished waiting
21:51:04 T:140380210604352 DEBUG: LinuxRendererGL: Cleaning up GL resources
21:51:04 T:140380210604352 NOTICE: VAAPI::Close
21:51:04 T:140379370583808 NOTICE: COutput::OnExit: Output Thread terminated
21:51:04 T:140379370583808 DEBUG: Thread Vaapi-Output 140379370583808 terminating
21:51:04 T:140380210604352 DEBUG: VAAPI::FiniVAAPIOutput destroying 4 video surfaces
21:51:04 T:140380210604352 NOTICE: VAAPI::Close - closing decoder context
21:51:04 T:140380210604352 DEBUG: CGUIWindowManager:reviousWindow: Deactivate
21:51:04 T:140380210604352 DEBUG: ------ Window Deinit (VideoOSD.xml) ------
21:51:04 T:140380210604352 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
21:51:04 T:140380210604352 DEBUG: CGUIWindowManager:reviousWindow: Activate new
21:51:04 T:140380210604352 DEBUG: ------ Window Init (MyPVRGuide.xml) ------
21:51:04 T:140380210604352 DEBUG: CGUIEPGGridContainer - SetStartEnd - start=2016-08-25 1:30:00 AM end=2016-09-03 1:30:00 AM
21:51:04 T:140380210604352 DEBUG: CGUIEPGGridContainer - UpdateItems completed successfully in 8 ms
21:51:05 T:140380210604352 NOTICE: CDVDPlayer::CloseFile()
21:51:05 T:140380210604352 NOTICE: DVDPlayer: waiting for threads to exit
21:51:05 T:140380210604352 NOTICE: DVDPlayer: finished waiting
21:51:05 T:140380210604352 DEBUG: LinuxRendererGL: Cleaning up GL resources
21:51:05 T:140380210604352 NOTICE: CDVDPlayer::CloseFile()
21:51:05 T:140380210604352 NOTICE: DVDPlayer: waiting for threads to exit
21:51:05 T:140380210604352 NOTICE: DVDPlayer: finished waiting
21:51:05 T:140380210604352 DEBUG: LinuxRendererGL: Cleaning up GL resources
21:51:05 T:140380210604352 DEBUG: Radio UECP (RDS) Processor - delete ~CDVDRadioRDSData
21:51:05 T:140378827048704 DEBUG: AddOnLog: MythTV PVR Client: RunHouseKeeping
21:51:06 T:140380210604352 DEBUG: Keyboard: scancode: 0x09, sym: 0x001b, unicode: 0x001b, modifier: 0x0
21:51:06 T:140380210604352 DEBUG: OnKey: escape (0xf01b) pressed, action is PreviousMenu

[MikeB2013]

From a quick look your problem is at :
21:51:00 T:140379387369216 DEBUG: AddOnLog: MythTV PVR Client: (CPPMyth)GetRecorderFromNum75: open recorder 15 (192.168.1.123:6543)
21:51:00 T:140379387369216 ERROR: AddOnLog: MythTV PVR Client: (CPPMyth)__connectAddr: failed to connect (111)

Can you confirm that in mythtv-setup (on the mythbackend) that in General screen:
a) Local backend has IPv4 address set to a valid IP
b) The Security Pin (required) is set to 0000 (same as in pvr.mythtv addon in Kodi)
c) Master Backend IP Address is set to a valid IP

Generally it is not a good idea to post part of a log file, post complete log file to xbmclogs or pastebin, with just the reference in the forum post.

Mike

[kchmon]

Can you confirm that in mythtv-setup (on the mythbackend) that in General screen:
a) Local backend has IPv4 address set to a valid IP
b) The Security Pin (required) is set to 0000 (same as in pvr.mythtv addon in Kodi)
c) Master Backend IP Address is set to a valid IP

a) Yes, backend has 192.168.1.123 as IPv4 address. This is a permanent lease set by my router, so it will never change.
b) Yes, Security Pin is set to 0000 and Ports match (6543 and 6544)
c) Master Backend is IP is set to 192.168.1.123. Backend and Kodi are on this same PC.

I generated a new log file: http://pastebin.com/y8ur6RGp

[MikeB2013]

For some reason pvr.mythtv cannot open a socket on the backend.
08:34:27 T:139913701869312 ERROR: AddOnLog: MythTV PVR Client: (CPPMyth)__connectAddr: failed to connect (111)

A few more things to check:

Mythbackend general screen
IPv6 address should be ::1
Listen on Link-Local addresses should be ticked

File /etc/mysql/mysql.conf.d/mythtv.cnf should have "bind-address=::"

If you have to change any of the above, I suggest a reboot of the mythbackend machine, in theory only msql restart and/or mythbackend restart should be necessary but I have been caught out before.

Mike

[lovemachine]

I just went through a similar rebuild to 16.04 and had the same problem. One more thing to check, make sure the folder you have set for Storage Directories in the Backend for LiveTV and Default both have permissions set correctly (see https://www.linux.com/learn/how-manage-f...ions-linux ). I had to redo them on the attached USB hard drive (for some reason even had to mount it at boot with fstab) to get things streaming correctly.

[kchmon]

Mythbackend general screen
IPv6 address should be ::1
Listen on Link-Local addresses should be ticked

Yes, IPv6 is "::1" and Link-Local address is checked.

File /etc/mysql/mysql.conf.d/mythtv.cnf should have "bind-address=::"

My mythtv.cnf file is a linked file. I don't know the ins and outs of that, but the contents are:

[mysqld]
#bind-address=::
max_connections=100

Should the "bind-address" be commented out like that? (edit: I took out the "#" and rebooted. Doesn't seem to have fixed anything)

make sure the folder you have set for Storage Directories in the Backend for LiveTV and Default both have permissions set correctly

MythTV Frontend plays and records videos fine on this same machine. Also, I am able to play videos from the MythTV PVR Recordings and LiveTV categories. Should I check permissions on any directories in addition to LiveTV and Recordings?

[lovemachine]

make sure the folder you have set for Storage Directories in the Backend for LiveTV and Default both have permissions set correctly

MythTV Frontend plays and records videos fine on this same machine. Also, I am able to play videos from the MythTV PVR Recordings and LiveTV categories. Should I check permissions on any directories in addition to LiveTV and Recordings?

Nevermind, then. Before I changed ownership of the external drive, I had the same problem with kodi, but Myth Frontend also didn't work because the backend could write to the folder (funny, though, because the backend didn't throw an error message). Sounds like your issue is not related to storage.

[MikeB2013]

MythTV Frontend plays and records videos fine on this same machine. Also, I am able to play videos from the MythTV PVR Recordings and LiveTV categories. Should I check permissions on any directories in addition to LiveTV and Recordings?

Can you confirm that mythtv frontend is using the real IP address and not localhost or 127.0.0.1 ?

Here are my directory permissions for reference:

Code:

mike@myth-server-2:~$ ls -lh /var/lib/mythtv
total 88K
drwxrwsr-x   2 mythtv mythtv 4.0K Aug 27 10:28 banners
drwxrwsr-x   2 mythtv mythtv 4.0K Sep 14  2015 bare-client
drwxrwsr-x   2 mythtv mythtv  12K Aug 27 10:28 coverart
drwxrwsr-x   2 mythtv mythtv 4.0K Aug 27 10:28 db_backups
drwxrwsr-x   2 mythtv mythtv  12K Aug 27 10:28 fanart
drwxrwsr-x   2 mythtv mythtv 4.0K Aug 27 12:00 livetv
drwxrwsr-x 136 mythtv mythtv  12K Aug 27 10:28 music
drwxrwsr-x   2 mythtv mythtv  20K Aug 27 10:28 recordings
drwxrwsr-x   2 mythtv mythtv 4.0K Aug 27 10:28 screenshots
drwxrwsr-x   2 mythtv mythtv 4.0K Apr  7 02:05 streaming
drwxrwsr-x   2 mythtv mythtv 4.0K Aug 27 10:28 trailers
drwxrwsr-x   3 mythtv mythtv 4.0K Aug 27 10:28 videos

Also you should check the storagegroups in mythtv backend are correctly set.
From a terminal do :

mysql -uroot -p mythconverg

and then
select * from storagegroup;

you should get something like :

Code:

mysql> select * from storagegroup;
+----+-------------+---------------+------------------------------+
| id | groupname   | hostname      | dirname                      |
+----+-------------+---------------+------------------------------+
|  1 | LiveTV      | myth-server-2 | /var/lib/mythtv/livetv/      |
|  2 | Videos      | myth-server-2 | /var/lib/mythtv/videos/      |
|  3 | Default     | myth-server-2 | /var/lib/mythtv/recordings/  |
|  4 | Music       | myth-server-2 | /var/lib/mythtv/music/       |
|  5 | Fanart      | myth-server-2 | /var/lib/mythtv/fanart/      |
|  6 | Trailers    | myth-server-2 | /var/lib/mythtv/trailers/    |
|  7 | Coverart    | myth-server-2 | /var/lib/mythtv/coverart/    |
|  8 | DB Backups  | myth-server-2 | /var/lib/mythtv/db_backups/  |
|  9 | Screenshots | myth-server-2 | /var/lib/mythtv/screenshots/ |
| 10 | Banners     | myth-server-2 | /var/lib/mythtv/banners/     |
| 11 | MusicArt    | myth-server-2 | /var/lib/mythtv/music/       |
+----+-------------+---------------+------------------------------+
11 rows in set (0.00 sec)

LiveTV and Default are the most important

Mike

[kchmon]

Thank you for keeping up with my troubleshooting Mike. So first, I thought you meant to change the Backend address in the PVR add-on from 127.0.0.1 to it's actual address, 192.168.1.123. I did that, rebooted, and when I opened Kodi there is an error that said it could not communicate with the backend, so it wouldn't load the PVR add-on. I switched it back to 127.0.0.1.

Then I re-read what you actually typed and changed the Backend address setting in MythTV Frontend from localhost to 192.168.1.123 and rebooted. Now MythTV Frontend crashes when it opens and I can't get into the settings to change it back. How can I set this back to "localhost" if I can't get back in? (edit: I did "sudo apt-get purge mythtv-frontend" and did a "sudo apt-get update". There were some upgrades available for MythTV, so ran them all. Rebooted. Reinstalled mythtv-frontend and was able to put in the regular values. MythTV Frontend works like before.)

Also that did not fix the "Channel Unavailable" problem.

Here's my folder permissions:

total 44K
drwxrwsr-x 2 mythtv mythtv 4.0K Aug 27 13:09 banners
drwxrwsr-x 2 mythtv mythtv 4.0K Aug 22 20:34 bare-client
drwxrwsr-x 2 mythtv mythtv 4.0K Aug 27 13:09 coverart
drwxrwsr-x 2 mythtv mythtv 4.0K Aug 27 13:09 db_backups
drwxrwsr-x 2 mythtv mythtv 4.0K Aug 27 13:09 fanart
drwxrwsr-x 2 mythtv mythtv 4.0K Aug 27 13:09 livetv
drwxrwsr-x 2 mythtv mythtv 4.0K Apr 18 19:27 recordings
drwxrwsr-x 2 mythtv mythtv 4.0K Aug 27 13:09 screenshots
drwxrwsr-x 2 mythtv mythtv 4.0K Aug 27 13:09 streaming
drwxrwsr-x 2 mythtv mythtv 4.0K Aug 27 13:09 trailers
drwxrwsr-x 2 mythtv mythtv 4.0K Aug 27 13:09 videos

And in MySql:

mysql> select * from storagegroup;
+----+-------------+--------------+-------------------------------------+
| id | groupname | hostname | dirname |
+----+-------------+--------------+-------------------------------------+
| 2 | Videos | livingroomtv | /var/lib/mythtv/videos/ |
| 3 | Fanart | livingroomtv | /var/lib/mythtv/fanart/ |
| 4 | Trailers | livingroomtv | /var/lib/mythtv/trailers/ |
| 5 | Coverart | livingroomtv | /var/lib/mythtv/coverart/ |
| 7 | Screenshots | livingroomtv | /var/lib/mythtv/screenshots/ |
| 8 | Banners | livingroomtv | /var/lib/mythtv/banners/ |
| 9 | DB Backups | livingroomtv | /var/lib/mythtv/db_backups/ |
| 11 | Streaming | livingroomtv | /var/lib/mythtv/streaming/ |
| 23 | Default | livingroomtv | /home/maintv/smb_recordings/mythtv/ |
| 28 | LiveTV | livingroomtv | /var/lib/mythtv/livetv/ |
+----+-------------+--------------+-------------------------------------+
10 rows in set (0.00 sec)

My longterm storage is on a NAS, hence the "smb_recordings/mythtv/" location. The PVR add-on will play recorded shows from there and "recordings" from LiveTV, so I think permissions are OK.

What would be preventing the Add-on from getting thru to the 192.168.1.123 or localhost stream, but it loads the guide data fine and recordings play fine?

[MikeB2013]

The mythfrontend settings are stored in a file named config.xml which is stored in local directory .mythtv (note . character infront of mythtv) of the logged in user

So open a terminal, do "cd .mythtv" and edit the config.xml file:

Just change the line <Host>192.168.1.123</Host> which has your IP back to localhost i.e. it now reads
<Host>localhost</Host>

The guide data, recording information etc is loaded into kodi via pvr.mythtv addon using a different connection method to that used when viewing LiveTV. The fact that the mythtv frontend crashes when using a real IP but not when using localhost does indicate a configuration problem with mysql and possibly mythbackend.

Mike

[lovemachine]

To change the IP in the backend, run sudo dpkg-reconfigure mythtv-common then sudo dpkg-reconfigure database , the first allows you to change the IP of the backend (and doesn't destroy any other configuration info to my knowledge). Also check /etc/mysql/mysql.conf.d/mysqld.cnf and check the line with bind address (about 2/3rds of the way down), and change it to 192.168.1.123. You shouldn't need to change any other files in mysql, but then check the config.xml files in /etc/mythtv and in the home folder (possibly hidden) of the user (something like /home/username/.mythtv). Mythtv is surprising difficult to get the database to sync with everything else. I seem to recall running the dpkg lines, editing the files, then running dpkg again with a reboot at the end to get everything to talk nicely.

[kchmon]

I have MythTV Frontend working OK again. I'll be able to fix it from terminal now if I screw it up again.

Hmm, well I restored the database from a backup of the old system. Are there any settings, ID's or something that might need to be updated? The only configuration change I can think is I have a larger SSD now, so LiveTV is stored on my OS drive. Before it was on a secondary drive.

edit: Does mythtv.pvr talk to mythtv-frontend or directly to mythtv-backend?

[MikeB2013]

The database restore from backup should be ok, providing IP address is the same AND host name (i.e. pc name) are the same see https://www.mythtv.org/wiki/Database_Backup_and_Restore

If any of the storage directories have changed use mythtv-setup to adjust them.

With the dpkg-reconfigure mythtv-database make sure you answer "Yes" to the question about "Will other computers run MythTV?" This is the part that allows access via IP address instead of localhost.

Mike

[kchmon]

I ran "dpkg-reconfigure mythtv-database" and selected "Yes". Myth-frontend does not crash anymore while <Host> is set to 192.168.1.123. That's good I guess.

The IP address and host name have stayed the same between the old setup and new. Is there anything left to try? Any additional logs that might pinpoint a problem?

[MikeB2013]

It is good news that mythtv frontend now works with a real IP.

In kodi pvr.mythtv:
Set IP address to 192.168.1.123
Increase the "Tuning delay (sec)" in Advanced tab to 30 (this timeout is cancelled if tuning works, so normal operation is not affected).
Restart kodi and see if it works, if not a new debug log will be needed.

Mike