[mythtv-users] 0.25 Changing input from hdhomerun to pvr150 fails

Nick Gorden miffedtv at yahoo.com
Sat Apr 21 13:56:05 UTC 2012



Hi, I have been having a whole bunch of stablity issues with 0.25. I upgraded from 0.24 which worked most of the time. Now with 0.25 it's less stable than 0.24.


Issue: Occasionally, when in LiveTV,  switching from HDHomerun source to pvr150 source, it will fail with "Error opening jump program file buffer". (this I believe is related to another issue that I'm having with the pvr150 not switching channels properly). But what's happening is after this happens, I'm unable to attempt to watch tv anymore. Restarting the mythbackend doesn't help. What's happening is that it thinks that the channel is being recorded already and somehow mythtv just doesn't know how to ignore that bad file???

I did not have this issue on 0.24.

Any ideas?


Setup:

Mythbuntu 11.04 with 0.25 fixes 20120420
1 x Master Backend server (2 pvr150)
1 x Frontend/Secondary Backend (for hdhomerun)
1 x Frontend only

Mythtv logs:

Apr 21 09:33:00 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:987 (TV) TV: Creating TV object
Apr 21 09:33:00 srv2 mythfrontend[24104]: N CoreContext mythmainwindow.cpp:2590 (PauseIdleTimer) Resuming idle timer
Apr 21 09:33:00 srv2 mythfrontend[24104]: N CoreContext mythmainwindow.cpp:2585 (PauseIdleTimer) Suspending idle timer
Apr 21 09:33:00 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:1206 (Init) TV: Created TvPlayWindow.
Apr 21 09:33:00 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:2121 (HandleStateChange) TV: Attempting to change from None to WatchingLiveTV
Apr 21 09:33:00 srv2 mythfrontend[24104]: I CoreContext mythcorecontext.cpp:371 (ConnectCommandSocket) MythCoreContext: Connecting to backend server: 192.168.1.3:6543 (try 1 of 1)
Apr 21 09:33:00 srv2 mythfrontend[24104]: I CoreContext mythcorecontext.cpp:1178 (CheckProtoVersion) Using protocol version 72
Apr 21 09:33:00 srv2 mythfrontend[24104]: I CoreContext mythcorecontext.cpp:371 (ConnectCommandSocket) MythCoreContext: Connecting to backend server: 192.168.1.5:6543 (try 1 of 1)
Apr 21 09:33:00 srv2 mythfrontend[24104]: I CoreContext mythcorecontext.cpp:1178 (CheckProtoVersion) Using protocol version 72
Apr 21 09:33:00 srv2 mythfrontend[24104]: N CoreContext tv_play.cpp:2188 (HandleStateChange) TV: Spawning LiveTV Recorder -- begin
Apr 21 09:33:00 srv2 mythfrontend[24104]: N CoreContext tv_play.cpp:2195 (HandleStateChange) TV: Spawning LiveTV Recorder -- end
Apr 21 09:33:00 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:2216 (HandleStateChange) TV: playbackURL(myth://192.168.1.5:6543/1038_20120421093300.mpg) cardtype(DUMMY)
Apr 21 09:33:01 srv2 mythfrontend[24104]: N CoreContext audioplayer.cpp:167 (ReinitAudio) AudioPlayer: Enabling Audio
Apr 21 09:33:01 srv2 mythfrontend[24104]: I CoreContext mythrender_vdpau.cpp:1675 (CreatePresentationSurfaces) VDPAU: Created 2 output surfaces.
Apr 21 09:33:01 srv2 mythfrontend[24104]: I CoreContext mythrender_vdpau.cpp:401 (Create) VDPAU: Created VDPAU render device 1280x720
Apr 21 09:33:01 srv2 mythfrontend[24104]: I CoreContext mythplayer.cpp:1738 (InitAVSync) Player(a): Video timing method: USleep with busy wait
Apr 21 09:33:01 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:5169 (StartPlayer) TV: Created player.
Apr 21 09:33:01 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:2360 (HandleStateChange) TV: Changing from None to WatchingLiveTV
Apr 21 09:33:01 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:2372 (HandleStateChange) TV: State is LiveTV & mctx == ctx
Apr 21 09:33:01 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:2374 (HandleStateChange) TV: UpdateOSDInput done
Apr 21 09:33:01 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:2376 (HandleStateChange) TV: UpdateLCD done
Apr 21 09:33:01 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:2378 (HandleStateChange) TV: ITVRestart done
Apr 21 09:33:01 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:2451 (HandleStateChange) TV: Main UI disabled.
Apr 21 09:33:01 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:378 (StartTV) TV: Entering main playback loop.
Apr 21 09:33:01 srv2 mythfrontend[24104]: I CoreContext mythcorecontext.cpp:1178 (CheckProtoVersion) Using protocol version 72
Apr 21 09:33:01 srv2 mythfrontend[24104]: I CoreContext mythcorecontext.cpp:1178 (CheckProtoVersion) Using protocol version 72
Apr 21 09:33:08 srv2 mythfrontend[24104]: E CoreContext mythsocket.cpp:534 (readStringList) MythSocket(7f6c7049f4b0:59): readStringList: Error, timed out after 7000 ms.
Apr 21 09:33:08 srv2 mythfrontend[24104]: E CoreContext remotefile.cpp:133 (openSocket) RemoteFile::openSocket(file data socket): Did not get proper response from 192.168.1.5:6543
Apr 21 09:33:08 srv2 mythfrontend[24104]: E CoreContext remotefile.cpp:172 (openSocket) RemoteFile::openSocket(file data socket): Failed to open socket, error was invalid response
Apr 21 09:33:08 srv2 mythfrontend[24104]: E CoreContext fileringbuffer.cpp:343 (OpenFile) FileRingBuf(myth://192.168.1.5:6543/1038_20120421093301.mpg): RingBuffer::RingBuffer(): Failed to open remote file (myth://192.168.1.5:6543/1038_20120421093301.mpg)
Apr 21 09:33:08 srv2 mythfrontend[24104]: E CoreContext mythplayer.cpp:2605 (JumpToProgram) Player(a): JumpToProgram's OpenFile failed (card type: MPEG).
Apr 21 09:33:08 srv2 mythfrontend[24104]: E CoreContext mythplayer.cpp:2606 (JumpToProgram) LiveTVChain has 2 entries#012   DUMMY: 1038 (09:33:00 to 09:33:00)#012*   MPEG: 1038 (09:33:01 to 10:00:00) discontinuous
Apr 21 09:33:08 srv2 mythfrontend[24104]: E CoreContext mythplayer.cpp:2817 (EventLoop) Player(a): Unknown recorder error, exiting decoder
Apr 21 09:33:08 srv2 mythfrontend[24104]: I CoreContext mythrender_vdpau.cpp:587 (CheckOutputSurfaces) VDPAU: Added 2 output surfaces (total 4, max 4)
Apr 21 09:33:08 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:2121 (HandleStateChange) TV: Attempting to change from WatchingLiveTV to None
Apr 21 09:33:08 srv2 mythfrontend[24104]: I CoreContext mythpainter_vdpau.cpp:111 (ClearCache) VDPAU Painter: Clearing VDPAU painter cache.
Apr 21 09:33:15 srv2 mythfrontend[24104]: E CoreContext mythsocket.cpp:534 (readStringList) MythSocket(7f6c81452220:41): readStringList: Error, timed out after 7000 ms.
Apr 21 09:33:15 srv2 mythfrontend[24104]: E CoreContext remoteencoder.cpp:99 (SendReceiveStringList) RemoteEncoder::SendReceiveStringList(): No response.
Apr 21 09:33:15 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:2360 (HandleStateChange) TV: Changing from WatchingLiveTV to None
Apr 21 09:33:15 srv2 mythfrontend[24104]: I CoreContext mythcorecontext.cpp:371 (ConnectCommandSocket) MythCoreContext: Connecting to backend server: 192.168.1.5:6543 (try 1 of 1)
Apr 21 09:33:16 srv2 mythfrontend[24104]: I CoreContext mythcorecontext.cpp:1178 (CheckProtoVersion) Using protocol version 72
Apr 21 09:33:16 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:380 (StartTV) TV: Exiting main playback loop.
Apr 21 09:33:16 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:2121 (HandleStateChange) TV: Attempting to change from None to WatchingLiveTV
Apr 21 09:33:16 srv2 mythfrontend[24104]: I CoreContext mythcorecontext.cpp:371 (ConnectCommandSocket) MythCoreContext: Connecting to backend server: 192.168.1.3:6543 (try 1 of 1)
Apr 21 09:33:16 srv2 mythfrontend[24104]: I CoreContext mythcorecontext.cpp:1178 (CheckProtoVersion) Using protocol version 72
Apr 21 09:33:16 srv2 mythfrontend[24104]: I CoreContext mythcorecontext.cpp:371 (ConnectCommandSocket) MythCoreContext: Connecting to backend server: 192.168.1.5:6543 (try 1 of 1)
Apr 21 09:33:16 srv2 mythfrontend[24104]: I CoreContext mythcorecontext.cpp:1178 (CheckProtoVersion) Using protocol version 72
Apr 21 09:33:16 srv2 mythfrontend[24104]: N CoreContext tv_play.cpp:2188 (HandleStateChange) TV: Spawning LiveTV Recorder -- begin
Apr 21 09:33:16 srv2 mythfrontend[24104]: N CoreContext tv_play.cpp:2195 (HandleStateChange) TV: Spawning LiveTV Recorder -- end
Apr 21 09:33:16 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:2216 (HandleStateChange) TV: playbackURL(myth://192.168.1.5:6543/1038_20120421093316.mpg) cardtype(DUMMY)
Apr 21 09:33:16 srv2 mythfrontend[24104]: E CoreContext tv_play.cpp:2233 (HandleStateChange) TV: LiveTV not successfully started
Apr 21 09:33:16 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:2451 (HandleStateChange) TV: Main UI disabled.
Apr 21 09:33:16 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:378 (StartTV) TV: Entering main playback loop.
Apr 21 09:33:16 srv2 mythfrontend[24104]: I CoreContext tv_play.cpp:380 (StartTV) TV: Exiting main playback loop.
Apr 21 09:33:22 srv2 mythfrontend[24104]: N CoreContext mythmainwindow.cpp:2590 (PauseIdleTimer) Resuming idle time




Apr 21 09:33:00 srv1 mythbackend[11295]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Playback
Apr 21 09:33:00 srv1 mythbackend[11295]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: srv2 as a client (events: 0)
Apr 21 09:33:00 srv1 mythbackend[11295]: I TVRecEvent tv_rec.cpp:1014 (HandleStateChange) TVRec(4): Changing from None to WatchingLiveTV
Apr 21 09:33:00 srv1 mythbackend[11295]: I TVRecEvent tv_rec.cpp:3456 (TuningCheckForHWChange) TVRec(4): HW Tuner: 4->4
Apr 21 09:33:00 srv1 mythbackend[11295]: I TVRecEvent v4lchannel.cpp:661 (SetInputAndFormat) V4LChannel(/dev/video1): SetInputAndFormat(4, NTSC) (v4l v2) input_switch: 0 mode_switch: 0
Apr 21 09:33:00 srv1 mythbackend[11295]: N CoreContext autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min
Apr 21 09:33:00 srv1 mythbackend[11295]: N CoreContext autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min
Apr 21 09:33:00 srv1 mythbackend[11295]: E TVRecEvent recordinginfo.cpp:966 (InsertProgram) RecordingInfo::InsertProgram(ProgramInfo(1038_20120421093300.mpg): channame(USA Network) startts(Sat Apr 21 09:00:00 2012) endts(Sat Apr 21 10:00:00 2012)#012             recstartts(Sat Apr 21 09:33:00 2012) recendts(Sat Apr 21 10:00:00 2012)#012             title(NCIS)): recording already exists...
Apr 21 09:33:01 srv1 mythbackend[11295]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Playback
Apr 21 09:33:01 srv1 mythbackend[11295]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: srv2 as a client (events: 0)
Apr 21 09:33:01 srv1 mythbackend[11295]: I ProcessRequest mainserver.cpp:1475 (HandleAnnounce) MainServer::HandleAnnounce FileTransfer
Apr 21 09:33:01 srv1 mythbackend[11295]: I ProcessRequest mainserver.cpp:1477 (HandleAnnounce) adding: srv2 as a remote file transfer
Apr 21 09:33:03 srv1 mythbackend[11295]: E DeviceReadBuffer DeviceReadBuffer.cpp:513 (Poll) DevRdB(/dev/video1): Poll giving up 2
Apr 21 09:33:03 srv1 mythbackend[11295]: E RecThread mpegrecorder.cpp:1010 (run) MPEGRec(/dev/video1): Device error detected
Apr 21 09:33:08 srv1 mythbackend[11295]: I TVRecEvent tv_rec.cpp:1014 (HandleStateChange) TVRec(4): Changing from WatchingLiveTV to None
Apr 21 09:33:08 srv1 mythbackend[11295]: E DeviceReadBuffer DeviceReadBuffer.cpp:513 (Poll) DevRdB(/dev/video1): Poll giving up 2
Apr 21 09:33:08 srv1 mythbackend[11295]: E RecThread mpegrecorder.cpp:1010 (run) MPEGRec(/dev/video1): Device error detected
Apr 21 09:33:11 srv1 mythbackend[11295]: E ProcessRequest fileringbuffer.cpp:289 (OpenFile) FileRingBuf(/storage/livetv/1038_20120421093301.mpg): OpenFile(): File too small (0B).
Apr 21 09:33:13 srv1 mythbackend[11295]: E DeviceReadBuffer DeviceReadBuffer.cpp:460 (Poll) DevRdB(/dev/video1): poll error
Apr 21 09:33:16 srv1 mythbackend[11295]: W ProcessRequest mainserver.cpp:5801 (connectionClosed) MainServer: Unknown socket closing MythSocket(0x8c89bd0)
Apr 21 09:33:16 srv1 mythbackend[11295]: E ProcessRequest mythsocket.cpp:389 (writeStringList) MythSocket(8c89bd0:-1): writeStringList: Error, No data written on writeBlock (1 errors)#012#011#011#011starts with: 15      OK[]:[]55[]:[]0
Apr 21 09:33:16 srv1 mythbackend[11295]: W ProcessRequest mainserver.cpp:5801 (connectionClosed) MainServer: Unknown socket closing MythSocket(0x8db86e0)
Apr 21 09:33:16 srv1 mythbackend[11295]: E ProcessRequest mythsocket.cpp:358 (writeStringList) MythSocket(8db86e0:-1): writeStringList: Error, socket went unconnected.#012#011#011#011We wrote 0 of 10 bytes with 1 errors#012#011#011#011starts with: 2       ok
Apr 21 09:33:16 srv1 mythbackend[11295]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Playback
Apr 21 09:33:16 srv1 mythbackend[11295]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: srv2 as a client (events: 0)
Apr 21 09:33:16 srv1 mythbackend[11295]: I ProcessRequest mainserver.cpp:1360 (HandleAnnounce) MainServer::ANN Playback
Apr 21 09:33:16 srv1 mythbackend[11295]: I ProcessRequest mainserver.cpp:1362 (HandleAnnounce) adding: srv2 as a client (events: 0)
Apr 21 09:33:16 srv1 mythbackend[11295]: I TVRecEvent tv_rec.cpp:1014 (HandleStateChange) TVRec(4): Changing from None to WatchingLiveTV
Apr 21 09:33:16 srv1 mythbackend[11295]: I TVRecEvent tv_rec.cpp:3456 (TuningCheckForHWChange) TVRec(4): HW Tuner: 4->4
Apr 21 09:33:16 srv1 mythbackend[11295]: I TVRecEvent v4lchannel.cpp:661 (SetInputAndFormat) V4LChannel(/dev/video1): SetInputAndFormat(4, NTSC) (v4l v2) input_switch: 0 mode_switch: 0
Apr 21 09:33:16 srv1 mythbackend[11295]: N CoreContext autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min
Apr 21 09:33:17 srv1 mythbackend[11295]: E TVRecEvent recordinginfo.cpp:966 (InsertProgram) RecordingInfo::InsertProgram(ProgramInfo(1038_20120421093316.mpg): channame(USA Network) startts(Sat Apr 21 09:00:00 2012) endts(Sat Apr 21 10:00:00 2012)#012             recstartts(Sat Apr 21 09:33:16 2012) recendts(Sat Apr 21 10:00:00 2012)#012             title(NCIS)): recording already exists...
Apr 21 09:33:17 srv1 mythbackend[11295]: I TVRecEvent tv_rec.cpp:1014 (HandleStateChange) TVRec(4): Changing from WatchingLiveTV to None
Apr 21 09:33:20 srv1 mythbackend[11295]: E DeviceReadBuffer DeviceReadBuffer.cpp:460 (Poll) DevRdB(/dev/video1): poll error
Apr 21 09:33:20 srv1 mythbackend[11295]: E RecThread mpegrecorder.cpp:1010 (run) MPEGRec(/dev/video1): Device error detected
Apr 21 09:33:22 srv1 mythbackend[11295]: E DeviceReadBuffer DeviceReadBuffer.cpp:460 (Poll) DevRdB(/dev/video1): poll error
Apr 21 09:33:22 srv1 mythbackend[11295]: N CoreContext autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min


More information about the mythtv-users mailing list