Kodi Community Forum
pvr plugin gets no livetv from working mythtv backend - Printable Version

+- Kodi Community Forum (https://forum.kodi.tv)
+-- Forum: Support (https://forum.kodi.tv/forumdisplay.php?fid=33)
+--- Forum: PVR & Live TV Support (https://forum.kodi.tv/forumdisplay.php?fid=167)
+---- Forum: MythTV (https://forum.kodi.tv/forumdisplay.php?fid=170)
+---- Thread: pvr plugin gets no livetv from working mythtv backend (/showthread.php?tid=170163)

Pages: 1 2 3

pvr plugin gets no livetv from working mythtv backend - foshka - 2013-07-30

I am trying to watch live tv on a windows 7 xmbc, streaming over the lan from a mythbuntu backend. Could somebody help me out pretty please?

I cannot get xbmc to tune into channels, it says 'Working...' for a few seconds and then gives up. A Mythtv frontend runs fine on both computers, so I know the backend is working properly.

The backend is a mythbuntu fresh install, an updated 0.25 (LTS release), but I did try 0.26 and xmbc had the same problem with it. The tuner (though I don't see if that should matter since the backend handles it) is a hauppage pvr-150.

Here is the relevant part of the xmbc log file (similar gets added to the log file every time I try to tune a channel):
Code:
18:23:38 T:5684  NOTICE: DVDPlayer: Opening: pvr://channels/tv/All TV channels/15.pvr
18:23:38 T:5684 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
18:23:38 T:3124  NOTICE: Thread CDVDPlayer start, auto delete: false
18:23:38 T:3124  NOTICE: Creating InputStream
18:23:38 T:3124   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_conn_connect: asked for version 8, got version 72
18:23:38 T:3124   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_conn_connect: asked for version 72, got version 72
18:23:39 T:5992  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process - Event chain update: true
18:23:39 T:5528   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_proginfo_get_from_timeslot: didn't recieve OK as response
18:23:39 T:5528   ERROR: AddOnLog: MythTV cmyth PVR Client: PVRClientMythTV::EventUpdateRecordings - Add recording failed for 1019 1375151077
18:23:39 T:5992  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process - Event chain update: true
18:23:39 T:5528   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_proginfo_get_from_timeslot: didn't recieve OK as response
18:23:39 T:5528   ERROR: AddOnLog: MythTV cmyth PVR Client: PVRClientMythTV::EventUpdateRecordings - Add recording failed for 1019 1375151078
18:23:39 T:5992  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process - Event chain update: true
18:23:39 T:3124  NOTICE: Creating Demuxer
18:23:39 T:3124   ERROR: CDVDPlayer::OpenDemuxStream - Exception thrown when opening demuxer
18:23:39 T:3124  NOTICE: CDVDPlayer::OnExit()
18:23:39 T:3124  NOTICE: CDVDPlayer::OnExit() deleting input stream
18:23:42 T:5992  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process - Event chain update: No recorder
18:23:42 T:5992  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process: Event DONE_RECORDING: recorder 1 4 -1
18:23:42 T:5992  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process: Event DONE_RECORDING: No recorder
18:23:42 T:5684  NOTICE: CDVDPlayer::CloseFile()
18:23:42 T:5684 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
18:23:42 T:5684  NOTICE: DVDPlayer: waiting for threads to exit
18:23:42 T:5684  NOTICE: DVDPlayer: finished waiting


RE: pvr plugin gets no livetv from working mythtv backend - nickr - 2013-07-30

What does the backend log say?

RE: pvr plugin gets no livetv from working mythtv backend - foshka - 2013-07-30

Looks like I'll have to change my recording profile to DVD, but I don't know how to fix the protocol version problems. I'm sure the expiring stuff is the livetv leftover from the mythtv frontend I was checking it with.

Code:
Jul 29 22:39:53 butler mythbackend[28849]: C ProcessRequest mainserver.cpp:1259
(HandleVersion) MainServer::HandleVersion - Client speaks protocol version 8 but
we speak 72!
Jul 29 22:39:53 butler mythbackend[28849]: W ProcessRequest mainserver.cpp:5801
(connectionClosed) MainServer: Unknown socket closing MythSocket(0x20478d0)
Jul 29 22:39:53 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1360
(HandleAnnounce) MainServer::ANN Playback
Jul 29 22:39:53 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1362
(HandleAnnounce) adding: EARTH as a client (events: 0)
Jul 29 22:39:53 butler mythbackend[28849]: C ProcessRequest mainserver.cpp:1259
(HandleVersion) MainServer::HandleVersion - Client speaks protocol version 8 but
we speak 72!
Jul 29 22:39:53 butler mythbackend[28849]: W ProcessRequest mainserver.cpp:5801
(connectionClosed) MainServer: Unknown socket closing MythSocket(0x2047650)
Jul 29 22:39:53 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1360
(HandleAnnounce) MainServer::ANN Monitor
Jul 29 22:39:53 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: EARTH as a client (events: 1)
Jul 29 22:40:23 butler mythbackend[28849]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 0 MB for 1020 at 2013-07-29T22:38:07 => "The Last Word With Lawrence O'Donnell"
Jul 29 22:40:23 butler mythbackend[28849]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 4 MB for 1020 at 2013-07-29T22:38:08 => "The Last Word With Lawrence O'Donnell"
Jul 29 22:40:23 butler mythbackend[28849]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 0 MB for 1030 at 2013-07-29T22:38:15 => Cops:"Ho! Ho! Ho! Special Edition No. 4"
Jul 29 22:40:23 butler mythbackend[28849]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 4 MB for 1030 at 2013-07-29T22:38:16 => Cops:"Ho! Ho! Ho! Special Edition No. 4"
Jul 29 22:40:23 butler mythbackend[28849]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 0 MB for 1025 at 2013-07-29T22:38:25 => Avatar
Jul 29 22:40:23 butler mythbackend[28849]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 27 MB for 1025 at 2013-07-29T22:38:26 => Avatar
Jul 29 22:40:29 butler mythbackend[28849]: I Metadata_51 jobqueue.cpp:2151 (DoMetadataLookupThread) JobQueue: Metadata Lookup Starting for Cops:"Ho! Ho! Ho! Special Edition No. 4" recorded from channel 1030 at 2013-07-29T22:38:16
Jul 29 22:40:30 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Jul 29 22:40:30 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: butler as a client (events: 0)
Jul 29 22:40:30 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Jul 29 22:40:30 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: butler as a client (events: 1)
Jul 29 22:40:34 butler mythbackend[28849]: I Metadata_52 jobqueue.cpp:2151 (DoMetadataLookupThread) JobQueue: Metadata Lookup Starting for Avatar recorded from channel 1025 at 2013-07-29T22:38:25
Jul 29 22:40:35 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Jul 29 22:40:35 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: butler as a client (events: 0)
Jul 29 22:40:35 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Jul 29 22:40:35 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: butler as a client (events: 1)
Jul 29 22:40:56 butler mythbackend[28849]: C ProcessRequest mainserver.cpp:1259 (HandleVersion) MainServer::HandleVersion - Client speaks protocol version 8 but we speak 72!
Jul 29 22:40:56 butler mythbackend[28849]: W ProcessRequest mainserver.cpp:5801 (connectionClosed) MainServer: Unknown socket closing MythSocket(0x2044100)
Jul 29 22:40:56 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Playback
Jul 29 22:40:56 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: EARTH as a client (events: 0)
Jul 29 22:40:56 butler mythbackend[28849]: I TVRecEvent tv_rec.cpp:1029 (HandleStateChange) TVRec(1): Changing from None to WatchingLiveTV
Jul 29 22:40:56 butler mythbackend[28849]: I TVRecEvent tv_rec.cpp:3495 (TuningCheckForHWChange) TVRec(1): HW Tuner: 1->1
Jul 29 22:40:56 butler mythbackend[28849]: I TVRecEvent v4lchannel.cpp:661 (SetInputAndFormat) V4LChannel(/dev/video0): SetInputAndFormat(1, NTSC) (v4l v2) input_switch: 0 mode_switch: 0
Jul 29 22:40:56 butler mythbackend[28849]: N CoreContext autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 11.0 GB w/freq: 15 min
Jul 29 22:40:56 butler mythbackend[28849]: N CoreContext autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 11.0 GB w/freq: 15 min
Jul 29 22:40:56 butler mythbackend[28849]: E TVRecEvent recordinginfo.cpp:966 (InsertProgram) RecordingInfo::InsertProgram(ProgramInfo(1016_20130729224056.mpg): channame(15 KINC) startts(Mon Jul 29 22:00:00 2013) endts(Mon Jul 29 23:00:00 2013)#012             recstartts(Mon Jul 29 22:40:56 2013) recendts(Mon Jul 29 23:00:00 2013)#012             title(Qué Bonito Amor)): recording already exists...
Jul 29 22:40:56 butler mythbackend[28849]: W RecThread mpegrecorder.cpp:618 (GetFilteredStreamType) MPEGRec(/dev/video0): Stream type 'DVD-Special 2'#012#011#011#011is not supported by ivtv driver, using 'DVD' instead.
Jul 29 22:40:57 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1475 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
Jul 29 22:40:57 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1477 (HandleAnnounce) adding: EARTH as a remote file transfer
Jul 29 22:40:57 butler mythbackend[28849]: I TVRecEvent tv_rec.cpp:1029 (HandleStateChange) TVRec(1): Changing from WatchingLiveTV to None
Jul 29 22:40:58 butler mythbackend[28849]: E DeviceReadBuffer DeviceReadBuffer.cpp:460 (Poll) DevRdB(/dev/video0): poll error
Jul 29 22:40:58 butler mythbackend[28849]: E RecThread mpegrecorder.cpp:1010 (run) MPEGRec(/dev/video0): Device error detected
Jul 29 22:41:00 butler mythbackend[28849]: E DeviceReadBuffer DeviceReadBuffer.cpp:460 (Poll) DevRdB(/dev/video0): poll error
Jul 29 22:41:23 butler mythbackend[28849]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 10.0 GB w/freq: 15 min
Jul 29 22:41:39 butler mythbackend[28849]: I Metadata_54 jobqueue.cpp:2151 (DoMetadataLookupThread) JobQueue: Metadata Lookup Starting for "Qué Bonito Amor" recorded from channel 1016 at 2013-07-29T22:40:56
Jul 29 22:41:40 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Jul 29 22:41:40 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: butler as a client (events: 0)
Jul 29 22:41:40 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Jul 29 22:41:40 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: butler as a client (events: 1)
Jul 29 22:41:44 butler mythbackend[28849]: I Metadata_55 jobqueue.cpp:2151 (DoMetadataLookupThread) JobQueue: Metadata Lookup Starting for "Qué Bonito Amor" recorded from channel 1016 at 2013-07-29T22:40:57
Jul 29 22:41:45 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Jul 29 22:41:45 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: butler as a client (events: 0)
Jul 29 22:41:45 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Jul 29 22:41:45 butler mythbackend[28849]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: butler as a client (events: 1)


RE: pvr plugin gets no livetv from working mythtv backend - fetzerch - 2013-07-30

(2013-07-30, 03:50)foshka Wrote: I am trying to watch live tv on a windows 7 xmbc, streaming over the lan from a mythbuntu backend. Could somebody help me out pretty please?

I cannot get xbmc to tune into channels, it says 'Working...' for a few seconds and then gives up. A Mythtv frontend runs fine on both computers, so I know the backend is working properly.

The backend is a mythbuntu fresh install, an updated 0.25 (LTS release), but I did try 0.26 and xmbc had the same problem with it. The tuner (though I don't see if that should matter since the backend handles it) is a hauppage pvr-150.
Strange. Can you play recordings? Or is that broken as well?

(2013-07-30, 03:50)foshka Wrote: Here is the relevant part of the xmbc log file (similar gets added to the log file every time I try to tune a channel):

That log snippet is not enough to help. See: http://wiki.xbmc.org/index.php?title=PVR/Backend/MythTV#Troubleshooting
I'd need a full debug log with the addon setting Include more debug information in the log file enabled.

Code:
(HandleVersion) MainServer::HandleVersion - Client speaks protocol version 8 but we speak 72!

This is unfortunately normal. There is no way to ask the backend for its version without triggering that error.
I'm more concerned about the 'Unknown socket closing'. Do you have only one master backend? Or do you have slave backends as well?

If you want, you could try compiling the current dev version (frodo branch) yourself: http://wiki.xbmc.org/index.php?title=PVR/Backend/MythTV/BuildFromSource
At the moment, the addon releases are coupled to XBMC releases and the version shipped with Frodo (12.2) is quite old.

RE: pvr plugin gets no livetv from working mythtv backend - nickr - 2013-07-30

(2013-07-30, 08:42)cfetzer Wrote:
Code:
(HandleVersion) MainServer::HandleVersion - Client speaks protocol version 8 but we speak 72!

This is unfortunately normal.

Not even unfortunately. It is by design, the procedure is basically

frontend: i want to connect using [completely unlikely version]

backend: sorry we only do [my version]

frontend: yeah OK I do [my version]

backend: cool, go ahead

RE: pvr plugin gets no livetv from working mythtv backend - foshka - 2013-07-30

I am coming back to linux after years away, so I am new to ideas like repositories. So on my linux side, I just have the no-addons version that came from doing 'sudo apt-get install xmbc' in mythbuntu. So I can't use that one to access mythtv, I am using the xmbc on windows.

I am going to shutdown my mythtv backend, and throw away the old log files, then start it and xmbc up again, try to changing to a few channels, and then post those log files in my next post. I just want to get rid of the megs of mythtv backend info that has built up.

To answer an earlier question: Yes, using mythtv front end on windows (a prebuilt binary some kind soul made available fixes/0.25.2-3-gf0e2ad8), i can watch live tv and recordings and videos that stream from my linux back end (a fully updated version that came with mythbuntu 0.25.2-15-g46cab93). I just want to get the windows xmbc to work so I can watch tv and recordings from my laptop and desktop etc.

RE: pvr plugin gets no livetv from working mythtv backend - foshka - 2013-07-30

Here is the xmbc log:
Code:
07:19:32 T:7916  NOTICE: -----------------------------------------------------------------------
07:19:32 T:7916  NOTICE: Starting XBMC (12.2 Git:20130502-32b1a5e), Platform: Windows 7 SP1 64-bit, build 7601. Built on May  2 2013 (compiler 1600)
07:19:32 T:7916  NOTICE: Intel(R) Core(TM)2 Duo CPU     E8400  @ 3.00GHz
07:19:32 T:7916  NOTICE: Desktop Resolution: 1920x1080 32Bit at 60Hz
07:19:32 T:7916  NOTICE: Running with restricted rights
07:19:32 T:7916  NOTICE: Aero is enabled
07:19:32 T:7916  NOTICE: special://xbmc/ is mapped to: C:\Program Files (x86)\XBMC
07:19:32 T:7916  NOTICE: special://xbmcbin/ is mapped to: C:\Program Files (x86)\XBMC
07:19:32 T:7916  NOTICE: special://masterprofile/ is mapped to: C:\Users\tree\AppData\Roaming\XBMC\userdata
07:19:32 T:7916  NOTICE: special://home/ is mapped to: C:\Users\tree\AppData\Roaming\XBMC\
07:19:32 T:7916  NOTICE: special://temp/ is mapped to: C:\Users\tree\AppData\Roaming\XBMC\cache
07:19:32 T:7916  NOTICE: The executable running is: C:\Program Files (x86)\XBMC\XBMC.exe
07:19:32 T:7916  NOTICE: Local hostname: EARTH
07:19:32 T:7916  NOTICE: Log File is located: C:\Users\tree\AppData\Roaming\XBMC\xbmc.log
07:19:32 T:7916  NOTICE: -----------------------------------------------------------------------
07:19:32 T:7916  NOTICE: Found 2 Lists of Devices
07:19:32 T:7916  NOTICE: Enumerated DirectSound devices:
07:19:32 T:7916  NOTICE:     Device 1
07:19:32 T:7916  NOTICE:         m_deviceName      : {F6E43FF9-2B29-4457-979E-15CD1384ADE1}
07:19:32 T:7916  NOTICE:         m_displayName     : Speakers - Speakers (Realtek High Definition Audio)
07:19:32 T:7916  NOTICE:         m_displayNameExtra: DirectSound: Speakers (Realtek High Definition Audio)
07:19:32 T:7916  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
07:19:32 T:7916  NOTICE:         m_channels        : FL,FR
07:19:32 T:7916  NOTICE:         m_sampleRates     : 48000
07:19:32 T:7916  NOTICE:         m_dataFormats     : AE_FMT_FLOAT,AE_FMT_AC3
07:19:32 T:7916  NOTICE: Enumerated WASAPI devices:
07:19:32 T:7916  NOTICE:     Device 1
07:19:32 T:7916  NOTICE:         m_deviceName      : {F6E43FF9-2B29-4457-979E-15CD1384ADE1}
07:19:32 T:7916  NOTICE:         m_displayName     : Speakers - Speakers (Realtek High Definition Audio)
07:19:32 T:7916  NOTICE:         m_displayNameExtra: WASAPI: Speakers (Realtek High Definition Audio)
07:19:32 T:7916  NOTICE:         m_deviceType      : AE_DEVTYPE_PCM
07:19:32 T:7916  NOTICE:         m_channels        : FL,FR,FC,LFE,SL,SR,BL,BR
07:19:32 T:7916  NOTICE:         m_sampleRates     : 192000,96000,48000,44100
07:19:32 T:7916  NOTICE:         m_dataFormats     : AE_FMT_S24NE4,AE_FMT_S24LE4,AE_FMT_S24BE4,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_S16BE,AE_FMT_LPCM
07:19:32 T:7916  NOTICE: load settings...
07:19:32 T:7916  NOTICE: special://profile/ is mapped to: special://masterprofile/
07:19:32 T:7916  NOTICE: loading special://masterprofile/guisettings.xml
07:19:32 T:7916  NOTICE: Getting hardware information now...
07:19:32 T:7916  NOTICE: Loading player core factory settings from special://xbmc/system/playercorefactory.xml.
07:19:32 T:7916  NOTICE: Loaded playercorefactory configuration
07:19:32 T:7916  NOTICE: Loading player core factory settings from special://masterprofile/playercorefactory.xml.
07:19:32 T:7916  NOTICE: special://masterprofile/playercorefactory.xml does not exist. Skipping.
07:19:32 T:7916  NOTICE: No settings file to load (special://xbmc/system/advancedsettings.xml)
07:19:32 T:7916  NOTICE: No settings file to load (special://masterprofile/advancedsettings.xml)
07:19:32 T:7916  NOTICE: Default DVD Player: dvdplayer
07:19:32 T:7916  NOTICE: Default Video Player: dvdplayer
07:19:32 T:7916  NOTICE: Default Audio Player: paplayer
07:19:32 T:7916  NOTICE: Disabled debug logging due to GUI setting. Level 0.
07:19:32 T:7916  NOTICE: Log level changed to 0
07:19:32 T:7916  NOTICE: Loading media sources from special://masterprofile/sources.xml
07:19:32 T:1644  NOTICE: Thread CSoftAE start, auto delete: false
07:19:32 T:7916  NOTICE: Running database version Addons15
07:19:32 T:8136  NOTICE: Thread XBMC Peripherals start, auto delete: false
07:19:32 T:6832  NOTICE: Thread CRemoteControl start, auto delete: false
07:19:32 T:3320  NOTICE: Thread XBMC Peripherals start, auto delete: false
07:19:32 T:2880  NOTICE: Thread Jobworker start, auto delete: true
07:19:32 T:7916  NOTICE: Found screen: Generic PnP Monitor on ATI Radeon HD 4800 Series, adapter 0.
07:19:32 T:7916  NOTICE: Found screen: SyncMaster 940BW/199BW/941BW, SyncMaster Magic CX915BW(Digital) on ATI Radeon HD 4800 Series, adapter 1.
07:19:32 T:7916  NOTICE: Primary mode: 1920x1080 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Secondary mode: 1440x900 @ 60.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Additional mode: 640x480 @ 59.94 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 640x480 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 640x480 @ 73.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 640x480 @ 75.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 720x480 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 720x576 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 800x600 @ 56.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 800x600 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 800x600 @ 72.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 800x600 @ 75.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 848x480 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1024x768 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 1024x768 @ 70.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 1024x768 @ 75.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 1152x864 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1152x864 @ 75.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 1280x720 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1280x768 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1280x800 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1280x960 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 1280x1024 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 1280x1024 @ 75.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 1360x768 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1360x1024 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1366x768 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1400x1050 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1440x900 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 1600x900 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1600x1200 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 1680x1050 @ 59.94 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 1680x1050 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 1776x1000 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 1920x1080 @ 60.00 - Full Screen
07:19:32 T:7916  NOTICE: Additional mode: 640x480 @ 59.94 - Full Screen #2
07:19:32 T:7916  NOTICE: Additional mode: 640x480 @ 60.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Additional mode: 640x480 @ 73.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Additional mode: 640x480 @ 75.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Additional mode: 720x480 @ 60.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 720x576 @ 60.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 800x600 @ 56.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Additional mode: 800x600 @ 60.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Additional mode: 800x600 @ 72.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Additional mode: 800x600 @ 75.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Additional mode: 848x480 @ 60.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1024x768 @ 60.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Additional mode: 1024x768 @ 70.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Additional mode: 1024x768 @ 75.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Additional mode: 1152x648 @ 60.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1152x864 @ 60.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1152x864 @ 75.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Additional mode: 1280x720 @ 60.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1280x768 @ 60.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1280x800 @ 60.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1280x960 @ 60.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Additional mode: 1280x1024 @ 60.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Additional mode: 1280x1024 @ 75.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Additional mode: 1360x768 @ 60.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1366x768 @ 60.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Previous line repeats 2 times.
07:19:32 T:7916  NOTICE: Additional mode: 1440x900 @ 60.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Additional mode: 1440x900 @ 75.00 - Full Screen #2
07:19:32 T:7916  NOTICE: Checking resolution 15
07:19:33 T:7916  NOTICE: Running database version Addons15
07:19:33 T:7916  NOTICE: Running database version ViewModes4
07:19:33 T:7916  NOTICE: Running database version Textures13
07:19:33 T:7916  NOTICE: Running database version MyMusic32
07:19:33 T:7916  NOTICE: Running database version MyVideos75
07:19:33 T:7916  NOTICE: Running database version TV22
07:19:33 T:7916  NOTICE: Running database version Epg7
07:19:33 T:7916  NOTICE: initializing playlistplayer
07:19:33 T:7916  NOTICE: DONE initializing playlistplayer
07:19:33 T:4996  NOTICE: Thread Jobworker start, auto delete: true
07:19:33 T:7916  NOTICE: initialize done
07:19:33 T:7916  NOTICE: Running the application...
07:19:33 T:7916  NOTICE: ES: Starting event server
07:19:33 T:4728  NOTICE: Thread CEventServer start, auto delete: false
07:19:33 T:4728  NOTICE: ES: Starting UDP Event server on 0.0.0.0:9777
07:19:33 T:4728  NOTICE: UDP: Listening on port 9777
07:19:33 T:7744  NOTICE: Thread Jobworker start, auto delete: true
07:19:33 T:7640  NOTICE: Thread CTCPServer start, auto delete: false
07:19:33 T:5084  NOTICE: Thread CRssReader start, auto delete: false
07:19:33 T:4996  NOTICE: EPG::CEpgContainer::Start - EPG thread started
07:19:33 T:4996  NOTICE: PVRManager - starting up
07:19:33 T:6988  NOTICE: Thread EPG updater start, auto delete: false
07:19:33 T:4904  NOTICE: Thread PVR manager start, auto delete: false
07:19:33 T:2412  NOTICE: Thread PVR add-on updater start, auto delete: false
07:19:33 T:2412   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_conn_connect: asked for version 8, got version 72
07:19:33 T:2412   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_conn_connect: asked for version 72, got version 72
07:19:33 T:2412   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_conn_connect: asked for version 8, got version 72
07:19:33 T:2412   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_conn_connect: asked for version 72, got version 72
07:19:34 T:7204  NOTICE: Thread PVR GUI info updater start, auto delete: false
07:19:44 T:1292  NOTICE: Thread PVR Channel Window start, auto delete: false
07:19:50 T:7916  NOTICE: DVDPlayer: Opening: pvr://channels/tv/All TV channels/3.pvr
07:19:50 T:7916 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
07:19:50 T:4676  NOTICE: Thread CDVDPlayer start, auto delete: false
07:19:50 T:4676  NOTICE: Creating InputStream
07:19:50 T:4676   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_conn_connect: asked for version 8, got version 72
07:19:50 T:4676   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_conn_connect: asked for version 72, got version 72
07:19:51 T:4904   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_proginfo_get_from_timeslot: didn't recieve OK as response
07:19:51 T:4904   ERROR: AddOnLog: MythTV cmyth PVR Client: PVRClientMythTV::EventUpdateRecordings - Add recording failed for 1007 1375197650
07:19:52 T:7752  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process - Event chain update: true
07:19:52 T:4676  NOTICE: Previous line repeats 2 times.
07:19:52 T:4676  NOTICE: Creating Demuxer
07:19:52 T:4676   ERROR: CDVDPlayer::OpenDemuxStream - Exception thrown when opening demuxer
07:19:52 T:4676  NOTICE: CDVDPlayer::OnExit()
07:19:52 T:4676  NOTICE: CDVDPlayer::OnExit() deleting input stream
07:19:55 T:7752  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process - Event chain update: No recorder
07:19:55 T:7752  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process: Event DONE_RECORDING: recorder 1 4 -1
07:19:55 T:7752  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process: Event DONE_RECORDING: No recorder
07:19:55 T:4904   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_proginfo_get_from_timeslot: didn't recieve OK as response
07:19:55 T:4904   ERROR: AddOnLog: MythTV cmyth PVR Client: PVRClientMythTV::EventUpdateRecordings - Add recording failed for 1007 1375197651
07:19:55 T:7916  NOTICE: CDVDPlayer::CloseFile()
07:19:55 T:7916 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
07:19:55 T:7916  NOTICE: DVDPlayer: waiting for threads to exit
07:19:55 T:7916  NOTICE: DVDPlayer: finished waiting
07:20:36 T:7916  NOTICE: DVDPlayer: Opening: pvr://channels/tv/All TV channels/7.pvr
07:20:36 T:7916 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
07:20:36 T:4572  NOTICE: Thread CDVDPlayer start, auto delete: false
07:20:36 T:4572  NOTICE: Creating InputStream
07:20:36 T:4572   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_conn_connect: asked for version 8, got version 72
07:20:36 T:4572   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_conn_connect: asked for version 72, got version 72
07:20:37 T:7752  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process - Event chain update: true
07:20:37 T:4904   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_proginfo_get_from_timeslot: didn't recieve OK as response
07:20:37 T:4904   ERROR: AddOnLog: MythTV cmyth PVR Client: PVRClientMythTV::EventUpdateRecordings - Add recording failed for 1011 1375197696
07:20:37 T:7752  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process - Event chain update: true
07:20:37 T:4572  NOTICE: Previous line repeats 1 times.
07:20:37 T:4572  NOTICE: Creating Demuxer
07:20:37 T:4572   ERROR: CDVDPlayer::OpenDemuxStream - Exception thrown when opening demuxer
07:20:37 T:4572  NOTICE: CDVDPlayer::OnExit()
07:20:37 T:4572  NOTICE: CDVDPlayer::OnExit() deleting input stream
07:20:40 T:7752  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process - Event chain update: No recorder
07:20:40 T:7752  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process: Event DONE_RECORDING: recorder 1 3 -1
07:20:40 T:7752  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process: Event DONE_RECORDING: No recorder
07:20:40 T:4904   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_proginfo_get_from_timeslot: didn't recieve OK as response
07:20:40 T:4904   ERROR: AddOnLog: MythTV cmyth PVR Client: PVRClientMythTV::EventUpdateRecordings - Add recording failed for 1011 1375197697
07:20:40 T:7916  NOTICE: CDVDPlayer::CloseFile()
07:20:40 T:7916 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
07:20:40 T:7916  NOTICE: DVDPlayer: waiting for threads to exit
07:20:40 T:7916  NOTICE: DVDPlayer: finished waiting
07:21:32 T:7916  NOTICE: DVDPlayer: Opening: pvr://channels/tv/All TV channels/53.pvr
07:21:32 T:7916 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
07:21:32 T:2296  NOTICE: Thread CDVDPlayer start, auto delete: false
07:21:32 T:2296  NOTICE: Creating InputStream
07:21:32 T:2296   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_conn_connect: asked for version 8, got version 72
07:21:32 T:2296   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_conn_connect: asked for version 72, got version 72
07:21:33 T:7752  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process - Event chain update: true
07:21:33 T:4904   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_proginfo_get_from_timeslot: didn't recieve OK as response
07:21:33 T:4904   ERROR: AddOnLog: MythTV cmyth PVR Client: PVRClientMythTV::EventUpdateRecordings - Add recording failed for 1057 1375197751
07:21:33 T:7752  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process - Event chain update: true
07:21:33 T:4904   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_proginfo_get_from_timeslot: didn't recieve OK as response
07:21:33 T:4904   ERROR: AddOnLog: MythTV cmyth PVR Client: PVRClientMythTV::EventUpdateRecordings - Add recording failed for 1057 1375197752
07:21:33 T:7752  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process - Event chain update: true
07:21:33 T:2296  NOTICE: Creating Demuxer
07:21:33 T:2296   ERROR: CDVDPlayer::OpenDemuxStream - Exception thrown when opening demuxer
07:21:33 T:2296  NOTICE: CDVDPlayer::OnExit()
07:21:33 T:2296  NOTICE: CDVDPlayer::OnExit() deleting input stream
07:21:36 T:7752  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process - Event chain update: No recorder
07:21:36 T:7752  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process: Event DONE_RECORDING: recorder 1 4 -1
07:21:36 T:7752  NOTICE: AddOnLog: MythTV cmyth PVR Client: MythEventHandler::MythEventHandlerPrivate::Process: Event DONE_RECORDING: No recorder
07:21:36 T:4904   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_proginfo_get_from_timeslot: didn't recieve OK as response
07:21:36 T:7916  NOTICE: CDVDPlayer::CloseFile()
07:21:36 T:7916 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
07:21:36 T:7916  NOTICE: DVDPlayer: waiting for threads to exit
07:21:36 T:7916  NOTICE: DVDPlayer: finished waiting
07:21:41 T:4904   ERROR: AddOnLog: MythTV cmyth PVR Client: LibCMyth: (cmyth)cmyth_proginfo_get_from_timeslot: didn't recieve OK as response
07:22:17 T:7916  NOTICE: Storing total System Uptime
07:22:17 T:7916  NOTICE: Saving settings
07:22:17 T:7916  NOTICE: stop all
07:22:17 T:7916  NOTICE: PVRManager - stopping
07:22:18 T:7916  NOTICE: ES: Stopping event server
07:22:18 T:7916  NOTICE: stopping zeroconf publishing
07:22:18 T:4728  NOTICE: ES: UDP Event server stopped
07:22:18 T:7916  NOTICE: stop sap announcement listener
07:22:18 T:7916  NOTICE: clean cached files!
07:22:18 T:7916  NOTICE: unload skin
07:22:18 T:7916  NOTICE: stop python
07:22:18 T:7916  NOTICE: stopped
07:22:18 T:7916  NOTICE: destroy
07:22:18 T:7916  NOTICE: closing down remote control service
07:22:18 T:7916  NOTICE: unload sections
07:22:18 T:7916  NOTICE: destroy
07:22:18 T:7916 WARNING: Attempted to remove window 10013 from the window manager when it didn't exist
07:22:18 T:7916 WARNING: Attempted to remove window 10014 from the window manager when it didn't exist
07:22:18 T:7916 WARNING: Attempted to remove window 10015 from the window manager when it didn't exist
07:22:18 T:7916 WARNING: Attempted to remove window 10016 from the window manager when it didn't exist
07:22:18 T:7916 WARNING: Attempted to remove window 10017 from the window manager when it didn't exist
07:22:18 T:7916 WARNING: Attempted to remove window 10018 from the window manager when it didn't exist
07:22:18 T:7916 WARNING: Attempted to remove window 10019 from the window manager when it didn't exist
07:22:18 T:7916 WARNING: Attempted to remove window 10021 from the window manager when it didn't exist
07:22:18 T:7916 WARNING: Attempted to remove window 10107 from the window manager when it didn't exist
07:22:18 T:7916 WARNING: Attempted to remove window 10115 from the window manager when it didn't exist
07:22:18 T:7916 WARNING: Attempted to remove window 10104 from the window manager when it didn't exist
07:22:18 T:7916  NOTICE: closing down remote control service
07:22:18 T:7916  NOTICE: unload sections
07:22:18 T:7916  NOTICE: application stopped...
And here is the mythbackend log:
Code:
Jul 30 07:18:59 butler mythbackend[1464]: C thread_unknown mythcommandlineparser.cpp:2534 (ConfigureLogging) mythbackend version: fixes/0.25 [v0.25.2-15-g46cab93] www.mythtv.org
Jul 30 07:18:59 butler mythbackend[1464]: C thread_unknown mythcommandlineparser.cpp:2536 (ConfigureLogging) Qt version: compile: 4.8.1, runtime: 4.8.1
Jul 30 07:18:59 butler mythbackend[1464]: N thread_unknown mythcommandlineparser.cpp:2538 (ConfigureLogging) Enabled verbose msgs:  general
Jul 30 07:18:59 butler mythbackend[1464]: N thread_unknown logging.cpp:1176 (logStart) Setting Log Level to LOG_INFO
Jul 30 07:18:59 butler mythbackend[1464]: I thread_unknown logging.cpp:229 (FileLogger) Added logging to the console
Jul 30 07:18:59 butler mythbackend[1464]: I thread_unknown logging.cpp:369 (SyslogLogger) Added syslogging to facility local7
Jul 30 07:18:59 butler mythbackend[1464]: I thread_unknown logging.cpp:425 (DatabaseLogger) Added database logging to table logging
Jul 30 07:18:59 butler mythbackend[1464]: N thread_unknown logging.cpp:1215 (logStart) Setting up SIGHUP handler
Jul 30 07:18:59 butler mythbackend[1464]: N thread_unknown mythdirs.cpp:51 (InitializeMythDirs) Using runtime prefix = /usr
Jul 30 07:18:59 butler mythbackend[1464]: N thread_unknown mythdirs.cpp:64 (InitializeMythDirs) Using configuration directory = /home/mythtv/.mythtv
Jul 30 07:19:00 butler mythbackend[1464]: I CoreContext mythcorecontext.cpp:227 (Init) Assumed character encoding: en_US.UTF-8
Jul 30 07:19:00 butler mythbackend[1464]: N CoreContext mythcontext.cpp:477 (LoadDatabaseSettings) Empty LocalHostName.
Jul 30 07:19:00 butler mythbackend[1464]: I CoreContext mythcontext.cpp:481 (LoadDatabaseSettings) Using localhost value of butler
Jul 30 07:19:01 butler mythbackend[1464]: N CoreContext mythcorecontext.cpp:1270 (InitLocale) Setting QT default locale to EN_US
Jul 30 07:19:01 butler mythbackend[1464]: I CoreContext mythcorecontext.cpp:1303 (SaveLocaleDefaults) Current locale EN_US
Jul 30 07:19:01 butler mythbackend[1464]: N CoreContext mythlocale.cpp:121 (LoadDefaultsFromXML) Reading locale defaults from /usr/share/mythtv//locales/en_us.xml
Jul 30 07:19:01 butler mythbackend[1464]: I CoreContext schemawizard.cpp:117 (Compare) Current MythTV Schema Version (DBSchemaVer): 1299
Jul 30 07:19:01 butler mythbackend[1464]: I CoreContext mythtranslation.cpp:66 (load) Loading en_us translation for module mythfrontend
Jul 30 07:19:01 butler mythbackend[1464]: N CoreContext main_helpers.cpp:556 (run_backend) MythBackend: Starting up as the master server.
Jul 30 07:19:03 butler mythbackend[1464]: I CoreContext v4lchannel.cpp:661 (SetInputAndFormat) V4LChannel(/dev/video0): SetInputAndFormat(1, NTSC) (v4l v2) input_switch: 0 mode_switch: 1
Jul 30 07:19:03 butler mythbackend[1464]: I CoreContext v4lchannel.cpp:661 (SetInputAndFormat) V4LChannel(/dev/video0): SetInputAndFormat(1, NTSC) (v4l v2) input_switch: 0 mode_switch: 0
Jul 30 07:19:17 butler mythbackend[1464]: I CoreContext programinfo.cpp:2052 (CheckProgramIDAuthorities) Found 326 distinct programid authorities
Jul 30 07:19:17 butler mythbackend[1464]: I Scheduler mythdbcon.cpp:422 (getStaticCon) New static DB connectionSchedCon
Jul 30 07:19:17 butler mythbackend[1464]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP 127.0.0.1:6544
Jul 30 07:19:17 butler mythbackend[1464]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP 192.168.1.198:6544
Jul 30 07:19:17 butler mythbackend[1464]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP [::1]:6544
Jul 30 07:19:17 butler mythbackend[1464]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP [fe80::21f:c6ff:feed:1280%eth1]:6544
Jul 30 07:19:18 butler mythbackend[1464]: I CoreContext main_helpers.cpp:626 (run_backend) Main::Registering HttpStatus Extension
Jul 30 07:19:18 butler mythbackend[1464]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP 127.0.0.1:6543
Jul 30 07:19:18 butler mythbackend[1464]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP 192.168.1.198:6543
Jul 30 07:19:18 butler mythbackend[1464]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP [::1]:6543
Jul 30 07:19:18 butler mythbackend[1464]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP [fe80::21f:c6ff:feed:1280%eth1]:6543
Jul 30 07:19:18 butler mythbackend[1464]: N CoreContext autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 10.0 GB w/freq: 15 min
Jul 30 07:19:19 butler mythbackend[1464]: I CoreContext bonjourregister.cpp:103 (BonjourCallback) Bonjour: Service registration complete: name 'Mythbackend on butler' type '_mythbackend-master._tcp.' domain: 'local.'
Jul 30 07:19:20 butler mythbackend[1464]: I Scheduler scheduler.cpp:2033 (HandleReschedule) Reschedule requested for id -1.
Jul 30 07:19:20 butler mythbackend[1464]: I Scheduler scheduler.cpp:2093 (HandleReschedule) Scheduled 63 items in 0.6 = 0.53 match + 0.09 place
Jul 30 07:19:20 butler mythbackend[1464]: I Scheduler scheduler.cpp:2160 (HandleRunSchedulerStartup) Scheduler: Seem to be woken up by USER
Jul 30 07:19:27 butler mythbackend[1464]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
Jul 30 07:20:33 butler mythbackend[1464]: C ProcessRequest mainserver.cpp:1259 (HandleVersion) MainServer::HandleVersion - Client speaks protocol version 8 but we speak 72!
Jul 30 07:20:33 butler mythbackend[1464]: W ProcessRequest mainserver.cpp:5801 (connectionClosed) MainServer: Unknown socket closing MythSocket(0x282bfb0)
Jul 30 07:20:33 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Playback
Jul 30 07:20:33 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: EARTH as a client (events: 0)
Jul 30 07:20:33 butler mythbackend[1464]: C ProcessRequest mainserver.cpp:1259 (HandleVersion) MainServer::HandleVersion - Client speaks protocol version 8 but we speak 72!
Jul 30 07:20:33 butler mythbackend[1464]: W ProcessRequest mainserver.cpp:5801 (connectionClosed) MainServer: Unknown socket closing MythSocket(0x282bc20)
Jul 30 07:20:33 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Jul 30 07:20:33 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: EARTH as a client (events: 1)
Jul 30 07:20:34 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1475 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
Jul 30 07:20:34 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1477 (HandleAnnounce) adding: EARTH as a remote file transfer
Jul 30 07:20:34 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1475 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
Jul 30 07:20:34 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1477 (HandleAnnounce) adding: EARTH as a remote file transfer
Jul 30 07:20:36 butler mythbackend[1464]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 10.0 GB w/freq: 15 min
Jul 30 07:20:50 butler mythbackend[1464]: C ProcessRequest mainserver.cpp:1259 (HandleVersion) MainServer::HandleVersion - Client speaks protocol version 8 but we speak 72!
Jul 30 07:20:50 butler mythbackend[1464]: W ProcessRequest mainserver.cpp:5801 (connectionClosed) MainServer: Unknown socket closing MythSocket(0x2833960)
Jul 30 07:20:50 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Playback
Jul 30 07:20:50 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: EARTH as a client (events: 0)
Jul 30 07:20:50 butler mythbackend[1464]: I TVRecEvent tv_rec.cpp:1029 (HandleStateChange) TVRec(1): Changing from None to WatchingLiveTV
Jul 30 07:20:50 butler mythbackend[1464]: I TVRecEvent tv_rec.cpp:3495 (TuningCheckForHWChange) TVRec(1): HW Tuner: 1->1
Jul 30 07:20:50 butler mythbackend[1464]: I TVRecEvent v4lchannel.cpp:661 (SetInputAndFormat) V4LChannel(/dev/video0): SetInputAndFormat(1, NTSC) (v4l v2) input_switch: 0 mode_switch: 0
Jul 30 07:20:50 butler mythbackend[1464]: N CoreContext autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 11.0 GB w/freq: 15 min
Jul 30 07:20:51 butler mythbackend[1464]: N CoreContext autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 11.0 GB w/freq: 15 min
Jul 30 07:20:51 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1475 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
Jul 30 07:20:51 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1477 (HandleAnnounce) adding: EARTH as a remote file transfer
Jul 30 07:20:52 butler mythbackend[1464]: I TVRecEvent tv_rec.cpp:1029 (HandleStateChange) TVRec(1): Changing from WatchingLiveTV to None
Jul 30 07:20:52 butler mythbackend[1464]: E DeviceReadBuffer DeviceReadBuffer.cpp:460 (Poll) DevRdB(/dev/video0): poll error
Jul 30 07:20:52 butler mythbackend[1464]: E RecThread mpegrecorder.cpp:1010 (run) MPEGRec(/dev/video0): Device error detected
Jul 30 07:20:55 butler mythbackend[1464]: E DeviceReadBuffer DeviceReadBuffer.cpp:460 (Poll) DevRdB(/dev/video0): poll error
Jul 30 07:21:27 butler mythbackend[1464]: I Metadata_62 jobqueue.cpp:2151 (DoMetadataLookupThread) JobQueue: Metadata Lookup Starting for "Jack Hanna's Animal Adventures":"The Tropics Bird Refuge" recorded from channel 1007 at 2013-07-30T07:20:50
Jul 30 07:21:27 butler mythbackend[1464]: I SystemManager system-unix.cpp:263 (run) Starting process manager
Jul 30 07:21:27 butler mythbackend[1464]: I SystemIOHandlerW system-unix.cpp:90 (run) Starting IO manager (write)
Jul 30 07:21:27 butler mythbackend[1464]: I SystemIOHandlerR system-unix.cpp:90 (run) Starting IO manager (read)
Jul 30 07:21:27 butler mythbackend[1464]: I SystemSignalManager system-unix.cpp:485 (run) Starting process signal handler
Jul 30 07:21:29 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Jul 30 07:21:29 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: butler as a client (events: 0)
Jul 30 07:21:29 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Jul 30 07:21:29 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: butler as a client (events: 1)
Jul 30 07:21:35 butler mythbackend[1464]: C ProcessRequest mainserver.cpp:1259 (HandleVersion) MainServer::HandleVersion - Client speaks protocol version 8 but we speak 72!
Jul 30 07:21:35 butler mythbackend[1464]: W ProcessRequest mainserver.cpp:5801 (connectionClosed) MainServer: Unknown socket closing MythSocket(0x27ebd50)
Jul 30 07:21:35 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Playback
Jul 30 07:21:35 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: EARTH as a client (events: 0)
Jul 30 07:21:35 butler mythbackend[1464]: I TVRecEvent tv_rec.cpp:1029 (HandleStateChange) TVRec(1): Changing from None to WatchingLiveTV
Jul 30 07:21:36 butler mythbackend[1464]: I TVRecEvent tv_rec.cpp:3495 (TuningCheckForHWChange) TVRec(1): HW Tuner: 1->1
Jul 30 07:21:36 butler mythbackend[1464]: I TVRecEvent v4lchannel.cpp:661 (SetInputAndFormat) V4LChannel(/dev/video0): SetInputAndFormat(1, NTSC) (v4l v2) input_switch: 0 mode_switch: 0
Jul 30 07:21:36 butler mythbackend[1464]: N CoreContext autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 11.0 GB w/freq: 15 min
Jul 30 07:21:36 butler mythbackend[1464]: N CoreContext autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 11.0 GB w/freq: 15 min
Jul 30 07:21:36 butler mythbackend[1464]: E TVRecEvent recordinginfo.cpp:966 (InsertProgram) RecordingInfo::InsertProgram(ProgramInfo(1011_20130730072136.mpg): channame(10 KLVX) startts(Tue Jul 30 07:00:00 2013) endts(Tue Jul 30 07:30:00 2013)#012             recstartts(Tue Jul 30 07:21:36 2013) recendts(Tue Jul 30 07:30:00 2013)#012             title(Curious George)): recording already exists...
Jul 30 07:21:36 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1475 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
Jul 30 07:21:36 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1477 (HandleAnnounce) adding: EARTH as a remote file transfer
Jul 30 07:21:37 butler mythbackend[1464]: I TVRecEvent tv_rec.cpp:1029 (HandleStateChange) TVRec(1): Changing from WatchingLiveTV to None
Jul 30 07:21:37 butler mythbackend[1464]: E DeviceReadBuffer DeviceReadBuffer.cpp:460 (Poll) DevRdB(/dev/video0): poll error
Jul 30 07:21:37 butler mythbackend[1464]: E RecThread mpegrecorder.cpp:1010 (run) MPEGRec(/dev/video0): Device error detected
Jul 30 07:21:40 butler mythbackend[1464]: E DeviceReadBuffer DeviceReadBuffer.cpp:460 (Poll) DevRdB(/dev/video0): poll error
Jul 30 07:22:31 butler mythbackend[1464]: C ProcessRequest mainserver.cpp:1259 (HandleVersion) MainServer::HandleVersion - Client speaks protocol version 8 but we speak 72!
Jul 30 07:22:31 butler mythbackend[1464]: W ProcessRequest mainserver.cpp:5801 (connectionClosed) MainServer: Unknown socket closing MythSocket(0x2836bb0)
Jul 30 07:22:31 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Playback
Jul 30 07:22:31 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: EARTH as a client (events: 0)
Jul 30 07:22:31 butler mythbackend[1464]: I TVRecEvent tv_rec.cpp:1029 (HandleStateChange) TVRec(1): Changing from None to WatchingLiveTV
Jul 30 07:22:31 butler mythbackend[1464]: I TVRecEvent tv_rec.cpp:3495 (TuningCheckForHWChange) TVRec(1): HW Tuner: 1->1
Jul 30 07:22:31 butler mythbackend[1464]: I TVRecEvent v4lchannel.cpp:661 (SetInputAndFormat) V4LChannel(/dev/video0): SetInputAndFormat(1, NTSC) (v4l v2) input_switch: 0 mode_switch: 0
Jul 30 07:22:31 butler mythbackend[1464]: N CoreContext autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 11.0 GB w/freq: 15 min
Jul 30 07:22:32 butler mythbackend[1464]: N CoreContext autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 11.0 GB w/freq: 15 min
Jul 30 07:22:32 butler mythbackend[1464]: I Metadata_63 jobqueue.cpp:2151 (DoMetadataLookupThread) JobQueue: Metadata Lookup Starting for "Jack Hanna's Animal Adventures":"The Tropics Bird Refuge" recorded from channel 1007 at 2013-07-30T07:20:51
Jul 30 07:22:33 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Jul 30 07:22:33 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: butler as a client (events: 0)
Jul 30 07:22:33 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Jul 30 07:22:33 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: butler as a client (events: 1)
Jul 30 07:22:33 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1475 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
Jul 30 07:22:33 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1477 (HandleAnnounce) adding: EARTH as a remote file transfer
Jul 30 07:22:33 butler mythbackend[1464]: I TVRecEvent tv_rec.cpp:1029 (HandleStateChange) TVRec(1): Changing from WatchingLiveTV to None
Jul 30 07:22:34 butler mythbackend[1464]: E DeviceReadBuffer DeviceReadBuffer.cpp:460 (Poll) DevRdB(/dev/video0): poll error
Jul 30 07:22:34 butler mythbackend[1464]: E RecThread mpegrecorder.cpp:1010 (run) MPEGRec(/dev/video0): Device error detected
Jul 30 07:22:35 butler mythbackend[1464]: N Expire autoexpire.cpp:640 (SendDeleteMessages) Expiring 0 MB for 1007 at 2013-07-30T07:20:50 => "Jack Hanna's Animal Adventures":"The Tropics Bird Refuge"
Jul 30 07:22:36 butler mythbackend[1464]: E DeviceReadBuffer DeviceReadBuffer.cpp:460 (Poll) DevRdB(/dev/video0): poll error
Jul 30 07:22:37 butler mythbackend[1464]: I Metadata_64 jobqueue.cpp:2151 (DoMetadataLookupThread) JobQueue: Metadata Lookup Starting for "Curious George":"George Digs Worms; Everything Old Is New Again" recorded from channel 1011 at 2013-07-30T07:21:36
Jul 30 07:22:38 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Jul 30 07:22:38 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: butler as a client (events: 0)
Jul 30 07:22:38 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Jul 30 07:22:38 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: butler as a client (events: 1)
Jul 30 07:23:42 butler mythbackend[1464]: I Metadata_65 jobqueue.cpp:2151 (DoMetadataLookupThread) JobQueue: Metadata Lookup Starting for "Curious George":"George Digs Worms; Everything Old Is New Again" recorded from channel 1011 at 2013-07-30T07:21:37
Jul 30 07:23:43 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Jul 30 07:23:43 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: butler as a client (events: 0)
Jul 30 07:23:43 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Monitor
Jul 30 07:23:43 butler mythbackend[1464]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: butler as a client (events: 1)


RE: pvr plugin gets no livetv from working mythtv backend - fetzerch - 2013-07-30

(2013-07-30, 09:33)nickr Wrote:
(2013-07-30, 08:42)cfetzer Wrote:
Code:
(HandleVersion) MainServer::HandleVersion - Client speaks protocol version 8 but we speak 72!

This is unfortunately normal.

Not even unfortunately. It is by design, the procedure is basically

frontend: i want to connect using [completely unlikely version]

backend: sorry we only do [my version]

frontend: yeah OK I do [my version]

backend: cool, go ahead

To be more specific, the design is unfortunately how it is Wink
For our addon users it's not obvious that this is not an error, but the normal way of determining the version that we have to use.

@foshka: Your XBMC log doesn't have debug entries. To enable them: Settings → System → Debugging
There's an addon that helps you uploading the XBMC log, if you want: http://wiki.xbmc.org/index.php?title=Log_file/Easy

This line indicates that there might be something wrong on the backend, but since MythFrontend works, it's very strange.
I hope to get more info from a debug log.
Code:
Jul 30 07:20:52 butler mythbackend[1464]: E DeviceReadBuffer DeviceReadBuffer.cpp:460 (Poll) DevRdB(/dev/video0): poll error


RE: pvr plugin gets no livetv from working mythtv backend - foshka - 2013-07-31

Okay we are making progress. cfetzer sent me version 1.6.11 of the addon, and that let me tune channels!

Now, the issues are the stuttering audio, and video artifacts, and dragging the xmbc window to another screen crashed it and created 260 files (crashlog and stacktrace dumps).

Going to try and upload the log file (my first try to use the log addon still hasn't sent me the email)..

RE: pvr plugin gets no livetv from working mythtv backend - nickr - 2013-07-31

Make sure you put the log on an external site like xbmclogs.com or pastebin.

RE: pvr plugin gets no livetv from working mythtv backend - foshka - 2013-07-31

Okay, the log file is 6.1 megs, no wonder the addon freaked. So I put a copy in my dropbox folder, here is the public link:

https://dl.dropboxusercontent.com/u/4026941/xbmc.log

RE: pvr plugin gets no livetv from working mythtv backend - nickr - 2013-07-31

Read my lips. xbmclogs.com or pastebin!

RE: pvr plugin gets no livetv from working mythtv backend - foshka - 2013-07-31

I couldn't paste 6+ megs into a field in pastebin. Chrome hated me for trying.

RE: pvr plugin gets no livetv from working mythtv backend - fetzerch - 2013-08-01

I've checked the logs. It seems that the addon has problems to get the video stream fast enough from the backend.

Unfortunately we have a very simple algorithm: We ask for 32k. If we get less, we ask for 16k and so on. If we get what we request, we increase it again until we reach 32k.
MythFrontend is handling this a lot better, but its also a big effort to implement their algorithm into the addon (and the 32k seem to work very well for almost all users).

Now lets see if we find the reason why we have troubles to get the requested data:
- Disable the addon setting 'include more debug information in the log' for the moment (extensive logging might slow things down as well)
- If you're using wifi, check if it's the network and try a cable
- Make sure that you don't have too high cpu load (neither on front, nor backend pc)

Just to be sure that I'm not on the wrong track:
- Try different audio and audio clock settings in XBMC (if I remember correctly, there was wasapi and directsound on win)
- Try disabling de-interlacing

Let me know your findings. If this all doesn't help, it could be that your backend itself has troubles to get the data fast enough from the tuner.
In that case, I can build an addon version with a setting that lets you specify the requested data block size.

Cheers,
Christian

RE: pvr plugin gets no livetv from working mythtv backend - foshka - 2013-08-03

Okay I tried those things and I still get this issue. I could not find a deinterlace setting (though I thought the mpeg stream from the hardware encoder meant that was not necessary to begin with)

I tried with a different ethernet adapter, and even on two computers (one a netbook one a desktop)

When I tune in a channel, I get 5 seconds or so of beauty, then it starts adding artifacts to the video and the audio starts breaking up in spurts every second or so. Could the adjustment algorithm itself be causing this?