[mythtv-commits] Ticket #9177: "Error opening jump program file buffer" when changing channels in live TV

MythTV mythtv at cvs.mythtv.org
Wed Nov 3 22:26:13 UTC 2010 

#9177: "Error opening jump program file buffer" when changing channels in live TV
-----------------------------------------------------+----------------------
 Reporter:  Douglas Peale <Douglas_Peale@…>          |            Type:  defect          
   Status:  new                                      |        Priority:  minor           
Milestone:  unknown                                  |       Component:  MythTV - General
  Version:  0.24-fixes                               |        Severity:  medium          
 Keywords:                                           |   Ticket locked:  0               
-----------------------------------------------------+----------------------
 == Procedure to recreate bug
 If "Watch TV" starts on a SD channel the first time it is run in a
 session, changing channels to an HD channel will pause for a while then
 exit to the menu with "Error opening jump program file buffer" message
 box. Re-starting "Watch TV" will be back to the SD channel, and attempting
 the channel change will generate the same error again.

 This bug is 100% repeatable.

 == Work around
 Change to a different physical tuner than the default. Change to an HD
 channel on this tuner. You will get dumped back to the menu with the
 "Error opening jump program file buffer" error. Re-start "Watch TV" change
 to the same tuner you were on when you got the error. You will now viewing
 the HD channel without the error. For the rest of the session, you will be
 able to change tuners & channels without running into the "Error opening
 jump program file buffer" error.

 == Aditional data
 Myth Front End Log:
 {{{
 Starting mythfrontend.real..
 2010-11-03 15:12:12.088 mythfrontend version: branches/release-0-24-fixes
 [27085] www.mythtv.org
 2010-11-03 15:12:12.088 Using runtime prefix = /usr
 2010-11-03 15:12:12.088 Using configuration directory =
 /home/dpeale/.mythtv
 2010-11-03 15:12:12.089 ThreadPool:HTTP: Initial 1, Max 5, Timeout 60000
 2010-11-03 15:12:13.416 Empty LocalHostName.
 2010-11-03 15:12:13.416 Using localhost value of Compromise
 2010-11-03 15:12:13.422 New DB connection, total: 1
 2010-11-03 15:12:13.425 Connected to database 'mythconverg' at host:
 localhost
 2010-11-03 15:12:13.429 Closing DB connection named 'DBManager0'
 2010-11-03 15:12:13.430 Connected to database 'mythconverg' at host:
 localhost
 2010-11-03 15:12:13.431 Current locale en_US
 2010-11-03 15:12:13.431 Reading locale defaults from
 /usr/share/mythtv//locales/en_us.xml
 2010-11-03 15:12:13.640 ScreenSaverX11Private: Gnome screen saver support
 enabled
 2010-11-03 15:12:13.641 DPMS is active.
 2010-11-03 15:12:13.693 Desktop video mode: 2560x1600 59.860 Hz
 2010-11-03 15:12:13.916 Enabled verbose msgs:  important general
 2010-11-03 15:12:13.919 Loading en_us translation for module mythfrontend
 2010-11-03 15:12:13.924 LIRC, Error: Failed to connect to Unix socket
 '/var/run/lirc/lircd'
                         eno: No such file or directory (2)
 2010-11-03 15:12:13.924 JoystickMenuThread: Joystick disabled - Failed to
 read /home/dpeale/.mythtv/joystickmenurc
 2010-11-03 15:12:13.948 Using Frameless Window
 2010-11-03 15:12:14.025 Using the Qt painter
 2010-11-03 15:12:14.274 Current MythTV Schema Version (DBSchemaVer): 1264
 2010-11-03 15:12:14.574 ThemeInfo, Warning: Unable to open themeinfo.xml
 for /usr/share/mythtv/themes/BlackCurves-OSD/themeinfo.xml
 2010-11-03 15:12:14.574 ThemeInfo, Error: The theme
 (/usr/share/mythtv/themes/BlackCurves-OSD) is missing a themeinfo.xml
 file.
 2010-11-03 15:12:14.575 ThemeInfo, Warning: Unable to open themeinfo.xml
 for /usr/share/mythtv/themes/Gray-OSD/themeinfo.xml
 2010-11-03 15:12:14.575 ThemeInfo, Error: The theme
 (/usr/share/mythtv/themes/Gray-OSD) is missing a themeinfo.xml file.
 2010-11-03 15:12:14.805 Pulse: PulseAudio suspend OK
 2010-11-03 15:12:15.002 Pulse: PulseAudio resume OK
 2010-11-03 15:12:15.095 Registering Internal as a media playback plugin.
 2010-11-03 15:12:15.114 Loading en_us translation for module mytharchive
 2010-11-03 15:12:15.157 MediaMonitorUnix::AddDevice() - empty device path.
 2010-11-03 15:12:15.158 MediaMonitorUnix::AddDevice() - empty device path.
 2010-11-03 15:12:15.158 MediaMonitorUnix::AddDevice() - empty device path.
 2010-11-03 15:12:15.159 MediaMonitorUnix::AddDevice() - empty device path.
 2010-11-03 15:12:15.159 MediaMonitorUnix::AddDevice() - empty device path.
 2010-11-03 15:12:15.160 MediaMonitorUnix::AddDevice() - empty device path.
 2010-11-03 15:12:15.161 MediaMonitorUnix::AddDevice() - empty device path.
 2010-11-03 15:12:15.161 MediaMonitorUnix::AddDevice() - empty device path.
 2010-11-03 15:12:15.161 MediaMonitorUnix::AddDevice() - empty device path.
 2010-11-03 15:12:15.162 MonitorRegisterExtensions(0x100, gif,jpg,png)
 2010-11-03 15:12:15.162 Loading en_us translation for module mythgallery
 2010-11-03 15:12:15.168 Current MythVideo Schema Version
 (mythvideo.DBSchemaVer): 1038
 2010-11-03 15:12:15.184 Loading en_us translation for module mythvideo
 2010-11-03 15:12:15.188 Loading en_us translation for module mythweather
 2010-11-03 15:12:15.354 Found mainmenu.xml for theme 'MythCenter-wide'
 2010-11-03 15:12:15.378 MythCoreContext: Connecting to backend server:
 192.168.63.3:6543 (try 1 of 1)
 2010-11-03 15:12:15.379 Using protocol version 63
 2010-11-03 15:12:24.471 New DB connection, total: 2
 2010-11-03 15:12:24.472 Connected to database 'mythconverg' at host:
 localhost
 2010-11-03 15:12:24.517 TV: Attempting to change from None to
 WatchingLiveTV
 2010-11-03 15:12:24.518 MythCoreContext: Connecting to backend server:
 192.168.63.3:6543 (try 1 of 1)
 2010-11-03 15:12:24.518 Using protocol version 63
 2010-11-03 15:12:24.537 Spawning LiveTV Recorder -- begin
 2010-11-03 15:12:24.592 Spawning LiveTV Recorder -- end
 2010-11-03 15:12:24.608 We have a
 playbackURL(/var/lib/mythtv/recordings/1045_20101103151224.mpg) &
 cardtype(DUMMY)
 2010-11-03 15:12:24.608 We have a RingBuffer
 2010-11-03 15:12:24.638 AudioPlayer: Disabling Audio, params(0,2,44100)
 2010-11-03 15:12:24.718 Pulse: PulseAudio suspend OK
 2010-11-03 15:12:24.740 AudioOutput Error: Aborting Audio Reconfigure.
 Invalid audio parameters ch 2 fmt 0 @ 44100Hz
 2010-11-03 15:12:24.740 AudioPlayer: Disabling Audio, reason is: Aborting
 Audio Reconfigure. Invalid audio parameters ch 2 fmt 0 @ 44100Hz
 2010-11-03 15:12:24.740 playCtx, Error: Aborting Audio Reconfigure.
 Invalid audio parameters ch 2 fmt 0 @ 44100Hz
 2010-11-03 15:12:24.748 VideoOutputXv: XVideo Adaptor Name: 'Radeon
 Textured Video'
 2010-11-03 15:12:24.764 OSD: Base theme size: 1280x720
 2010-11-03 15:12:24.764 OSD: Scaling factors: 0.5625x0.8
 2010-11-03 15:12:24.860 OSD: Base theme size: 1280x720
 2010-11-03 15:12:24.860 OSD: Scaling factors: 0.5625x0.8
 2010-11-03 15:12:24.860 OSD: Base theme size: 1280x720
 2010-11-03 15:12:24.860 OSD: Scaling factors: 0.5625x0.8
 greedyhdeint: size changed from 0 x 0 -> 720 x 576
 2010-11-03 15:12:24.876 Player(0): Video timing method: DRM
 2010-11-03 15:12:24.892 TV: Changing from None to WatchingLiveTV
 2010-11-03 15:12:24.892 TV: State is LiveTV & mctx == ctx
 2010-11-03 15:12:24.894 TV: UpdateOSDInput done
 2010-11-03 15:12:24.894 TV: UpdateLCD done
 2010-11-03 15:12:24.894 TV: ITVRestart done
 2010-11-03 15:12:25.006 VideoOutput: Created YV12 OSD.
 2010-11-03 15:12:25.026 ScreenSaverX11Private: DPMS Deactivated 1
 greedyhdeint: size changed from 0 x 0 -> 720 x 576
 2010-11-03 15:12:25.590 Player(0): DecoderGetFrame() called with NULL
 decoder.
 2010-11-03 15:12:26.087
 RingBuf(/var/lib/mythtv/recordings/1045_20101103151225.mpg): Waited 0.2
 seconds for data
                         to become available... 9892 < 32768
 2010-11-03 15:12:26.087 Checking to see if there's a new livetv program to
 switch to..
 greedyhdeint: size changed from 0 x 0 -> 704 x 480
 2010-11-03 15:12:26.275 VideoOutputXv: XVideo Adaptor Name: 'Radeon
 Textured Video'
 2010-11-03 15:12:26.292 OSD: Base theme size: 1280x720
 2010-11-03 15:12:26.292 OSD: Scaling factors: 0.55x0.666667
 2010-11-03 15:12:26.366 OSD: Base theme size: 1280x720
 2010-11-03 15:12:26.366 OSD: Scaling factors: 0.55x0.666667
 2010-11-03 15:12:26.367 AFD: Opened codec 0x7f3ac845bed0, id(MPEG2VIDEO)
 type(Video)
 2010-11-03 15:12:26.367 AFD: codec AC3 has 2 channels
 2010-11-03 15:12:26.367 AFD: Opened codec 0x7f3ac84a9f80, id(AC3)
 type(Audio)
 2010-11-03 15:12:26.367 AFD: codec AC3 has 1 channels
 2010-11-03 15:12:26.367 AFD: Opened codec 0x7f3ac8492ac0, id(AC3)
 type(Audio)
 2010-11-03 15:12:26.520 Pulse: PulseAudio resume OK
 2010-11-03 15:12:26.620 Pulse: PulseAudio suspend OK
 2010-11-03 15:12:26.649 AO: Opening audio device
 'surround71:CARD=Intel,DEV=0' ch 6(2) sr 48000 sf signed 32 bit reenc 0
 2010-11-03 15:12:26.650 Opening ALSA audio device
 'surround71:CARD=Intel,DEV=0'.
 2010-11-03 15:12:26.828 AudioPlayer: Enabling Audio
 2010-11-03 15:12:27.153 VideoOutput: Created YV12 OSD.
 2010-11-03 15:12:33.090 BrowseDispInfo()
 2010-11-03 15:12:33.090 BrowseStart()
 2010-11-03 15:12:33.094 browsechanid: 0 -> 1021
 2010-11-03 15:12:35.911 BrowseEnd()
 greedyhdeint: size changed from 0 x 0 -> 704 x 480
 2010-11-03 15:12:39.362
 RingBuf(/var/lib/mythtv/recordings/1021_20101103151237.mpg) Error:
 OpenFile(): File too small (0B).
 2010-11-03 15:12:39.362 Player(0), Error: JumpToProgram's OpenFile failed
 (card type: HDHOMERUN).
 2010-11-03 15:12:39.362
 LiveTVChain has 4 entries
    DUMMY: 1045 (15:12:24 to 15:12:25)
   HDHOMERUN: 1045 (15:12:25 to 15:12:36) discontinuous
    DUMMY: 1021 (15:12:36 to 15:12:36) discontinuous
 * HDHOMERUN: 1021 (15:12:37 to 16:00:00) discontinuous

 2010-11-03 15:12:39.362 Player(0), Error: Unknown recorder error, exiting
 decoder
 2010-11-03 15:12:39.440 TV: Attempting to change from WatchingLiveTV to
 None
 2010-11-03 15:12:39.447 MythPainter: 18 images not yet de-allocated.
 2010-11-03 15:12:39.639 Pulse: PulseAudio resume OK
 2010-11-03 15:12:39.731 TV: Changing from WatchingLiveTV to None
 2010-11-03 15:12:39.752 ScreenSaverX11Private: DPMS Reactivated 1
 2010-11-03 15:12:39.752 TV: Attempting to change from None to None
 2010-11-03 15:12:39.798 TV: Attempting to change from None to
 WatchingLiveTV
 2010-11-03 15:12:39.798 MythCoreContext: Connecting to backend server:
 192.168.63.3:6543 (try 1 of 1)
 2010-11-03 15:12:39.799 Using protocol version 63
 2010-11-03 15:12:39.817 Spawning LiveTV Recorder -- begin
 2010-11-03 15:12:39.871 Spawning LiveTV Recorder -- end
 2010-11-03 15:12:39.891 We have a
 playbackURL(/var/lib/mythtv/recordings/1045_20101103151239.mpg) &
 cardtype(DUMMY)
 2010-11-03 15:12:39.894 We have a RingBuffer
 2010-11-03 15:12:39.895 playCtx, Error: Attempting to setup a player, but
 it already exists.
 2010-11-03 15:12:39.895 TV Error: LiveTV not successfully started
 2010-11-03 15:12:56.209 Pulse: Cleaning up PulseHandler
 2010-11-03 15:12:56.209 Deleting UPnP client...
 Error in my_thread_global_end(): 1 threads didn't exit
 }}}



 Excerpt from back end log:

 {{{
 2010-11-03 15:11:48.207 I'm idle now... shutdown will occur in 180
 seconds.
 2010-11-03 15:12:15.379 MainServer::ANN Playback
 2010-11-03 15:12:15.386 adding: Compromise as a client (events: 0)
 2010-11-03 15:12:15.393 MainServer::ANN Monitor
 2010-11-03 15:12:15.402 adding: Compromise as a client (events: 1)
 2010-11-03 15:12:24.518 MainServer::ANN Playback
 2010-11-03 15:12:24.523 adding: Compromise as a client (events: 0)
 2010-11-03 15:12:24.538 TVRec(1): Changing from None to WatchingLiveTV
 2010-11-03 15:12:24.555 TVRec(1): HW Tuner: 1->1
 2010-11-03 15:12:24.570 LoadFromScheduler(): Error, called from backend.
 2010-11-03 15:12:24.572 AutoExpire: CalcParams(): Max required Free Space:
 3.0 GB w/freq: 14 min
 2010-11-03 15:12:25.164 Finished recording Bosley: Hair Loss Answers:
 channel 1045
 2010-11-03 15:12:25.184 LoadFromScheduler(): Error, called from backend.
 2010-11-03 15:12:25.186 AutoExpire: CalcParams(): Max required Free Space:
 3.0 GB w/freq: 14 min
 2010-11-03 15:12:25.211 Finished recording Bosley: Hair Loss Answers:
 channel 1045
 2010-11-03 15:12:26.829 RecBase(1:1):
 GetKeyframePositions(28,9223372036854775807,#2) out of 6
 2010-11-03 15:12:27.070 RecBase(1:1):
 GetKeyframePositions(28,9223372036854775807,#2) out of 6
 2010-11-03 15:12:36.025 TVRec(1): HW Tuner: 1->1
 2010-11-03 15:12:36.039 LoadFromScheduler(): Error, called from backend.
 2010-11-03 15:12:36.041 AutoExpire: CalcParams(): Max required Free Space:
 3.0 GB w/freq: 14 min
 2010-11-03 15:12:36.062 Finished recording Bosley: Hair Loss Answers:
 channel 1045
 2010-11-03 15:12:36.205 MainServer::ANN Playback
 2010-11-03 15:12:36.215 adding: Compromise as a client (events: 0)
 2010-11-03 15:12:36.224 MainServer::ANN Monitor
 2010-11-03 15:12:36.234 adding: Compromise as a client (events: 1)
 2010-11-03 15:12:36.788 DTVSM(1016A87D-0) Error: Wrong PMT; pmt->pn(1)
 desired(2)
 2010-11-03 15:12:36.833 DTVSM(1016A87D-0) Error: Wrong PMT; pmt->pn(3)
 desired(2)
 2010-11-03 15:12:36.911 Finished recording The Dr. Oz Show "Secrets the
 Food Industry Doesn't Want You to Know": channel 1021
 2010-11-03 15:12:36.927 LoadFromScheduler(): Error, called from backend.
 2010-11-03 15:12:36.928 AutoExpire: CalcParams(): Max required Free Space:
 3.0 GB w/freq: 14 min
 2010-11-03 15:12:36.935 recording already exists...
 2010-11-03 15:12:36.980 Finished recording The Dr. Oz Show "Secrets the
 Food Industry Doesn't Want You to Know": channel 1021
 2010-11-03 15:12:39.640 TVRec(1): Changing from WatchingLiveTV to None
 2010-11-03 15:12:39.712 Finished recording The Dr. Oz Show "Secrets the
 Food Industry Doesn't Want You to Know": channel 1021
 2010-11-03 15:12:39.799 MainServer::ANN Playback
 2010-11-03 15:12:39.806 adding: Compromise as a client (events: 0)
 2010-11-03 15:12:39.819 TVRec(1): Changing from None to WatchingLiveTV
 2010-11-03 15:12:39.825 TVRec(1): HW Tuner: 1->1
 2010-11-03 15:12:39.853 LoadFromScheduler(): Error, called from backend.
 2010-11-03 15:12:39.854 AutoExpire: CalcParams(): Max required Free Space:
 3.0 GB w/freq: 14 min
 2010-11-03 15:12:39.896 TVRec(1): Changing from WatchingLiveTV to None
 2010-11-03 15:12:39.922 Finished recording Bosley: Hair Loss Answers:
 channel 1045
 2010-11-03 15:12:59.247 I'm idle now... shutdown will occur in 180
 seconds.
 2010-11-03 15:14:05.916 Expiring 0 MB for 1045 at 2010-11-03T15:12:24 =>
 "Bosley: Hair Loss Answers"
 2010-11-03 15:14:05.921 Expiring 3 MB for 1045 at 2010-11-03T15:12:25 =>
 "Bosley: Hair Loss Answers"
 2010-11-03 15:14:05.932 Expiring 0 MB for 1021 at 2010-11-03T15:12:37 =>
 "The Dr. Oz Show":"Secrets the Food Industry Doesn't Want You to Know"
 Someone is still logged in, don't shut down!
 }}}

-- 
Ticket URL: <http://svn.mythtv.org/trac/ticket/9177>
MythTV <http://www.mythtv.org/>
MythTV Media Center

More information about the mythtv-commits mailing list