[mythtv-commits] Ticket #10743: After upgrade to 0.25 from 0.24.xx channel change is awfully slow

MythTV noreply at mythtv.org
Thu May 24 20:59:20 UTC 2012


#10743: After upgrade to 0.25 from 0.24.xx channel change is awfully slow
----------------------------------+--------------------------------
 Reporter:  xyz@…                 |          Owner:
     Type:  Bug Report - General  |         Status:  infoneeded_new
 Priority:  trivial               |      Milestone:  unknown
Component:  MythTV - General      |        Version:  0.25-fixes
 Severity:  low                   |     Resolution:
 Keywords:                        |  Ticket locked:  0
----------------------------------+--------------------------------

Comment (by xyz@…):

 Frontend:

 2012-05-24 22:18:15.976789 I  TV: Creating TV object
 2012-05-24 22:18:15.987593 I  TV: Finished creating TV object
 2012-05-24 22:18:15.987636 I  TV: StartTV() -- begin
 2012-05-24 22:18:15.987818 N  Resuming idle timer
 2012-05-24 22:18:15.987844 N  Suspending idle timer
 2012-05-24 22:18:15.987862 I  TV: Init -- begin
 2012-05-24 22:18:15.992591 I  TV: Created TvPlayWindow.
 2012-05-24 22:18:15.992958 I  TV: DrawUnusedRects() -- begin
 2012-05-24 22:18:15.992983 I  TV: DrawUnusedRects() -- end
 2012-05-24 22:18:15.997418 I  TV: Init -- end
 2012-05-24 22:18:16.000757 I  TV: tv->LiveTV() -- begin
 2012-05-24 22:18:16.005581 I  TV: HandleStateChange(0) -- begin
 2012-05-24 22:18:16.005617 I  TV: Attempting to change from None to
 WatchingLiveTV
 2012-05-24 22:18:16.005667 I  MythCoreContext: Connecting to backend
 server: ::1:6543 (try 1 of 1)
 2012-05-24 22:18:23.008349 E  MythSocket(1b6df00:58): readStringList:
 Error, timed out after 7000 ms.
 2012-05-24 22:18:23.008408 C  Protocol version check failure.
                         The response to MYTH_PROTO_VERSION was empty.
                         This happens when the backend is too busy to
 respond,
                         or has deadlocked in due to bugs or hardware
 failure.
 2012-05-24 22:18:23.008438 E  RemoteEncoder::Setup(): Failed to connect to
 backend
 2012-05-24 22:18:23.011738 I  RemoteRequestFreeRecorderList returned { 3
 5}
 2012-05-24 22:18:23.012963 I  TV: IsTunableOn(18501) yes { 5 }
 2012-05-24 22:18:23.013276 I  MythCoreContext: Connecting to backend
 server: ::1:6543 (try 1 of 1)







 2012-05-24 22:18:30.017285 E  MythSocket(1fcbfd0:58): readStringList:
 Error, timed out after 7000 ms.
 2012-05-24 22:18:30.017368 C  Protocol version check failure.
                         The response to MYTH_PROTO_VERSION was empty.
                         This happens when the backend is too busy to
 respond,
                         or has deadlocked in due to bugs or hardware
 failure.
 2012-05-24 22:18:30.017399 E  RemoteEncoder::Setup(): Failed to connect to
 backend
 2012-05-24 22:18:30.017409 E  RemoteEncoder::SendReceiveStringList():
 Failed to reconnect with backend.
 2012-05-24 22:18:30.017508 N  TV: Spawning LiveTV Recorder -- begin
 2012-05-24 22:18:30.017569 I  MythCoreContext: Connecting to backend
 server: ::1:6543 (try 1 of 1)
 2012-05-24 22:18:32.454186 I  Using protocol version 72
 2012-05-24 22:18:33.264664 N  TV: Spawning LiveTV Recorder -- end
 2012-05-24 22:18:33.265191 I  LiveTVChain(live-
 mythtv-2012-05-24T22:18:16): ReloadAll(): Added new recording
 2012-05-24 22:18:33.265244 I  LiveTVChain(live-
 mythtv-2012-05-24T22:18:16): LiveTVChain has 1 entries
 *  DUMMY: 18501 (22:18:32 to 22:30:00)

 2012-05-24 22:18:33.268352 I  TV: playbackURL(/MEDIA/video/live-
 tv/18501_20120524221832.mpg) cardtype(DUMMY)
 2012-05-24 22:18:33.268715 I  TV: StartRecorder(): took 0 ms to start
 recorder.
 2012-05-24 22:18:33.822496 I  TV: StartPlayer(0, WatchingLiveTV, main) --
 begin
 2012-05-24 22:18:33.822530 I  TV: Elapsed time since TV constructor was
 called: 17846 ms
 2012-05-24 22:18:33.835865 I  playCtx: Output filters for this channel
 are: ''
 2012-05-24 22:18:34.204103 E  ALSA: snd_pcm_info_get_card: Die Operation
 ist nicht erlaubt
 2012-05-24 22:18:34.243771 N  AudioPlayer: Enabling Audio
 2012-05-24 22:18:34.243832 I  Player(0): SetFrameInterval ps:1 scan:3
 2012-05-24 22:18:34.243857 I  Player(0): detectInterlace(Ignore Scan,
 Interlaced Scan, 25, 576) ->Interlaced Scan
 2012-05-24 22:18:34.246342 I  Player(0): Stretch Factor 1, allow passthru
 2012-05-24 22:18:34.251413 I  VideoOutput: Allowed renderers: xv-
 blit,xshm,xlib,opengl,opengl-lite,vdpau,openglvaapi
 2012-05-24 22:18:34.251446 I  VideoOutput: Allowed renderers (filt:
 dummy): xlib,xshm,xv-blit,opengl,opengl-lite,vdpau,openglvaapi
 2012-05-24 22:18:34.569469 I  VDP: Accepting: cmp(> 0 0) dec(vdpau)
 cpus(2) skiploop(disabled) rend(vdpau) osd(vdpau) osdfade(enabled)
 deint(vdpauadvanced,vdpauonefield) filt(vdpaucolorspace=auto)
 2012-05-24 22:18:34.569500 I  VDP: LoadBestPreferences(2048x2048, 0)
 2012-05-24 22:18:34.569528 I  VDP: LoadBestPreferences(2048x2048, 60)
 2012-05-24 22:18:34.569546 I  VDP: LoadBestPreferences(720x576, 60)
 2012-05-24 22:18:34.569573 I  VideoOutput: Preferred renderer: vdpau
 2012-05-24 22:18:34.569590 I  VideoOutput: Trying video renderer: 'vdpau'
 2012-05-24 22:18:34.576765 I  VDP: Accepting: cmp(> 0 0) dec(vdpau)
 cpus(2) skiploop(disabled) rend(vdpau) osd(vdpau) osdfade(enabled)
 deint(vdpauadvanced,vdpauonefield) filt(vdpaucolorspace=auto)
 2012-05-24 22:18:34.576781 I  VDP: LoadBestPreferences(2048x2048, 0)
 2012-05-24 22:18:34.576804 I  VDP: LoadBestPreferences(2048x2048, 60)
 2012-05-24 22:18:34.576842 I  VideoOutWindow::SetPIPState. pip_state: 0]
 2012-05-24 22:18:34.576851 I  VDP: LoadBestPreferences(2048x2048, 25)
 2012-05-24 22:18:34.576871 I  Clearing OpenGL painter cache.
 2012-05-24 22:18:34.582645 I  Display Rect  left: 0, top: 0, width: 1920,
 height: 1080, aspect: 1.33333
 2012-05-24 22:18:34.582666 I  Video Rect    left: 0, top: 0, width: 720,
 height: 576, aspect: 1.33333
 2012-05-24 22:18:34.582678 I  VDP: LoadBestPreferences(720x576, 25)
 2012-05-24 22:18:34.582709 I  Display Rect  left: 0, top: 0, width: 1920,
 height: 1080, aspect: 1.33333
 2012-05-24 22:18:34.582721 I  Video Rect    left: 0, top: 0, width: 720,
 height: 576, aspect: 1.33333
 2012-05-24 22:18:34.582733 I  VDP: SetVideoRenderer(vdpau)
 2012-05-24 22:18:34.582742 I  VDP: SetVideoRender(vdpau) ==
 GetVideoRenderer()
 2012-05-24 22:18:34.595787 I  VideoOutput: Pixel dimensions: Screen
 1920x1080, window 1920x1080
 2012-05-24 22:18:34.595820 I  VideoOutput: Actual display dimensions:
 483x272 mm  Aspect: 1.77574
 2012-05-24 22:18:34.595838 I  VideoOutput: Estimated window dimensions:
 483x272 mm  Aspect: 1.77574
 2012-05-24 22:18:34.653520 I  VDPAU: Created 2 output surfaces.
 2012-05-24 22:18:34.653543 I  VDPAU: Set colorkey to 0x20202
 2012-05-24 22:18:34.653559 I  VDPAU: Version 1
 2012-05-24 22:18:34.653570 I  VDPAU: Information NVIDIA VDPAU Driver
 Shared Library  295.49  Tue May  1 00:13:48 PDT 2012
 2012-05-24 22:18:34.660731 I  VDPAU: HQ scaling level 1 of 9 available.
 2012-05-24 22:18:34.660743 I  VDPAU: MPEG4 hardware acceleration
 supported.
 2012-05-24 22:18:34.660751 I  VDPAU: Created VDPAU render device 1920x1080
 2012-05-24 22:18:34.660771 I  VidOutVDPAU: Created VDPAU osd (1920x1080)
 2012-05-24 22:18:34.677582 I  ColourSpace: PictureAttributes: Brightness,
 Contrast, Colour, Hue, Studio Levels,
 2012-05-24 22:18:34.677602 I  VidOutVDPAU: Using ITU BT.601 colorspace
 2012-05-24 22:18:34.677643 I  Display Rect  left: 240, top: 0, width:
 1440, height: 1080, aspect: 1.77778
 2012-05-24 22:18:34.677657 I  Video Rect    left: 0, top: 0, width: 720,
 height: 576, aspect: 1.33333
 2012-05-24 22:18:34.677663 I  VidOutVDPAU: Created VDPAU context (software
 decode)
 2012-05-24 22:18:34.678653 I  Over/underscan. V: 0, H: 0
 2012-05-24 22:18:34.678669 I  Display Rect  left: 240, top: 0, width:
 1440, height: 1080, aspect: 1.77778
 2012-05-24 22:18:34.678680 I  Video Rect    left: 0, top: 0, width: 720,
 height: 576, aspect: 1.33333
 2012-05-24 22:18:34.678720 I  FilterManager: GetFilterInfo(convert)
 returning: 0x0
 2012-05-24 22:18:34.678739 I  Player(0):
 LoadFilters('vdpaucolorspace=auto'..) -> 0x0
 2012-05-24 22:18:34.679651 I  Player(0): Decoder thread starting.
 2012-05-24 22:18:34.686744 I  OSD: Loaded window osd_message
 2012-05-24 22:18:34.693533 I  OSD: Loaded window osd_input
 2012-05-24 22:18:34.705210 E  MythUIHelper:
 LoadScaleImage(images/shared/osd/audiocodec/eac3.png)Unable to find image
 file
 2012-05-24 22:18:34.717435 I  OSD: Loaded window program_info
 2012-05-24 22:18:34.729055 I  OSD: Loaded window browse_info
 2012-05-24 22:18:34.742707 I  OSD: Loaded window osd_status
 2012-05-24 22:18:34.756665 I  OSD: Loaded window osd_program_editor
 2012-05-24 22:18:34.770045 I  OSD: Loaded window osd_debug
 2012-05-24 22:18:34.770077 I  OSD: Loaded OSD: size 1920x1080 offset 0+0
 2012-05-24 22:18:34.771515 I  OSD: Created window aa_OSD_SUBTITLES
 2012-05-24 22:18:34.772104 I  Player(0): ClearAfterSeek(0)
 2012-05-24 22:18:34.772901 I  VDP: GetFilteredDeint() : vdpau ->
 'vdpauadvanced'
 2012-05-24 22:18:34.773460 I  VidOutVDPAU: Enabled deinterlacing.
 2012-05-24 22:18:34.773505 I  VSYNC: DRMVideoSync: Could not open device
 /dev/dri/card0, Datei oder Verzeichnis nicht gefunden
 2012-05-24 22:18:34.773538 E  VSYNC: RTCVideoSync: Could not open
 /dev/rtc:
                         eno: Keine Berechtigung (13)
 2012-05-24 22:18:34.774054 I  Player(0): Video timing method: USleep with
 busy wait
 2012-05-24 22:18:34.774072 I  Player(0): Display Refresh Rate: 59.999
 Video Frame Rate: 25.000
 2012-05-24 22:18:34.774082 I  Player(0): SetFrameInterval ps:1 scan:1
 2012-05-24 22:18:34.774277 I  playCtx: StartPlaying(): took 0 ms to start
 player.
 2012-05-24 22:18:34.774304 I  TV: Created player.
 2012-05-24 22:18:34.774333 I  TV: StartPlayer(0, WatchingLiveTV, main) --
 end ok
 2012-05-24 22:18:34.774363 I  TV: Changing from None to WatchingLiveTV
 2012-05-24 22:18:34.774376 I  TV: State is LiveTV & mctx == ctx
 2012-05-24 22:18:34.775173 I  TV: UpdateOSDInput done
 2012-05-24 22:18:34.775186 I  TV: UpdateLCD done
 2012-05-24 22:18:34.775644 I  TV: ITVRestart done
 2012-05-24 22:18:34.787521 I  TV: DrawUnusedRects() -- begin
 2012-05-24 22:18:34.787542 I  TV: DrawUnusedRects() -- end
 2012-05-24 22:18:34.787639 I  TV: Main UI disabled.
 2012-05-24 22:18:34.787650 I  TV: HandleStateChange(0) -- end
 2012-05-24 22:18:34.788181 I  TV: Pausing player: 0
 2012-05-24 22:18:34.830821 I  Display Rect  left: 119, top: 760, width:
 338, height: 255, aspect: 1.77778
 2012-05-24 22:18:34.830881 I  Video Rect    left: 0, top: 0, width: 720,
 height: 576, aspect: 1.33333
 2012-05-24 22:18:34.830924 I  Display Rect  left: 119, top: 760, width:
 338, height: 255, aspect: 1.77778
 2012-05-24 22:18:34.830936 I  Video Rect    left: 0, top: 0, width: 720,
 height: 576, aspect: 1.33333
 2012-05-24 22:18:34.833040 I  TV: DrawUnusedRects() -- begin
 2012-05-24 22:18:34.833057 I  TV: DrawUnusedRects() -- end
 2012-05-24 22:18:34.843734 I  TV: tv->LiveTV() -- end
 2012-05-24 22:18:34.843748 I  TV: Entering main playback loop.
 2012-05-24 22:18:34.901785 I  ScreenSaverX11Private: DPMS Deactivated 1
 2012-05-24 22:18:34.901826 I  TV: DrawUnusedRects() -- begin
 2012-05-24 22:18:34.901835 I  TV: DrawUnusedRects() -- end
 2012-05-24 22:18:34.903774 I  TV: DrawUnusedRects() -- begin
 2012-05-24 22:18:34.903787 I  TV: DrawUnusedRects() -- end
 2012-05-24 22:18:35.104502 I  TV: ClearTunableCache()
 2012-05-24 22:18:35.108541 I  RemoteRequestFreeRecorderList returned { 3
 5}
 2012-05-24 22:18:35.111027 I  TV: IsTunableOn(18500) yes { 5 }
 2012-05-24 22:18:35.115902 I  RemoteRequestFreeRecorderList returned { 3
 5}
 2012-05-24 22:18:35.116378 I  TV: IsTunableOn(18502) yes { 5 }
 2012-05-24 22:18:35.121151 I  RemoteRequestFreeRecorderList returned { 3
 5}
 2012-05-24 22:18:35.121553 I  TV: IsTunableOn(18501) yes { 5 }
 2012-05-24 22:18:35.127978 I  RemoteRequestFreeRecorderList returned { 3
 5}
 2012-05-24 22:18:35.128450 I  TV: IsTunableOn(13060) yes { 5 }
 2012-05-24 22:18:35.133107 I  RemoteRequestFreeRecorderList returned { 3
 5}
 2012-05-24 22:18:35.133507 I  TV: IsTunableOn(18504) yes { 5 }
 2012-05-24 22:18:35.140622 I  LiveTVChain(live-
 mythtv-2012-05-24T22:18:16): ReloadAll(): Added new recording
 2012-05-24 22:18:35.140670 I  LiveTVChain(live-
 mythtv-2012-05-24T22:18:16): LiveTVChain has 2 entries
 *  DUMMY: 18501 (22:18:32 to 22:18:34)
      DVB: 18501 (22:18:34 to 22:30:00) discontinuous

 2012-05-24 22:18:35.147645 I  TV: DrawUnusedRects() -- begin
 2012-05-24 22:18:35.147662 I  TV: DrawUnusedRects() -- end
 2012-05-24 22:18:35.186772 I  LiveTVChain(live-
 mythtv-2012-05-24T22:18:16): SwitchTo(1)
 2012-05-24 22:18:35.186794 I  Player(0): JumpToProgram - start
 2012-05-24 22:18:35.189833 I  playCtx: Output filters for this channel
 are: ''
 2012-05-24 22:18:35.189856 I  FilterManager: GetFilterInfo(convert)
 returning: 0x0
 2012-05-24 22:18:35.189873 I  Player(0):
 LoadFilters('vdpaucolorspace=auto'..) -> 0x0
 2012-05-24 22:18:35.190209 I  Player(0): Play speed: rate: 25 speed: 0
 skip: 0 => new interval 40000
 2012-05-24 22:18:35.191065 I  FileRingBuf(/MEDIA/video/live-
 tv/18501_20120524221832.mpg): OpenFile(/MEDIA/video/live-
 tv/18501_20120524221834.mpg, 10000 ms)
 2012-05-24 22:18:35.439927 I  Player(0): Stretch Factor 1, allow passthru
 2012-05-24 22:18:35.440333 I  AFD: Buffer size: 32768, streamed 1
 2012-05-24 22:18:36.322668 I  AFD: Stream #0, has id 0x21 codec id
 DVB_TELETEXT, type Subtitle, bitrate 0 at 0x252c120
 2012-05-24 22:18:36.322746 I  AFD: Teletext stream #0 (Menu) is in the
 German language on page 1 0.
 2012-05-24 22:18:36.322776 I  AFD: subtitle codec (Subtitle)
 2012-05-24 22:18:36.322804 I  AFD: Stream #1, has id 0x1ff codec id
 MPEG2VIDEO, type Video, bitrate 15000000 at 0x43cc7c0
 2012-05-24 22:18:36.325928 I  VDP: Accepting: cmp(> 0 0) dec(vdpau)
 cpus(2) skiploop(disabled) rend(vdpau) osd(vdpau) osdfade(enabled)
 deint(vdpauadvanced,vdpauonefield) filt(vdpaucolorspace=auto)
 2012-05-24 22:18:36.325954 I  VDP: LoadBestPreferences(2048x2048, 0)
 2012-05-24 22:18:36.325984 I  VDP: LoadBestPreferences(2048x2048, 60)
 2012-05-24 22:18:36.326002 I  VDP: LoadBestPreferences(720x576, 60)
 2012-05-24 22:18:36.326061 I  AFD: Using 1 CPUs for decoding
 2012-05-24 22:18:36.326076 I  AFD: InitVideoCodec() 0x2636ff0
 id(MPEG2VIDEO) type (Video).
 2012-05-24 22:18:36.326154 I  AFD: Selected FPS is 25 (avg 47.5 codec 25
 container 90000 estimated 25)
 2012-05-24 22:18:36.326169 I  Player(0): SetFrameInterval ps:0 scan:3
 2012-05-24 22:18:36.326197 I  VidOutVDPAU: InputChanged(720,576,1.33333)
 'None'->'MPEG2 VDPAU'
 2012-05-24 22:18:36.326218 I  Display Rect  left: 240, top: 0, width:
 1440, height: 1080, aspect: 1.77778
 2012-05-24 22:18:36.326232 I  Video Rect    left: 0, top: 0, width: 720,
 height: 576, aspect: 1.33333
 2012-05-24 22:18:36.326245 I  Display Rect  left: 240, top: 0, width:
 1440, height: 1080, aspect: 1.77778
 2012-05-24 22:18:36.326257 I  Video Rect    left: 0, top: 0, width: 720,
 height: 576, aspect: 1.33333
 2012-05-24 22:18:36.326812 I  VidOutVDPAU: DiscardFrames(1)
 2012-05-24 22:18:36.326946 I  VideoBuffers::DiscardFrames(1):
 AAAAAAAAAAAAAAAAAAAAAAAAAAAA
 2012-05-24 22:18:36.326988 I  VideoBuffers::DiscardFrames(1):
 AAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done
 2012-05-24 22:18:36.327016 I  VidOutVDPAU: DiscardFrames() 3:
 AAAAAAAAAAAAAAAAAAAAAAAAAAAA -- done()
 2012-05-24 22:18:36.327822 I  VDPAU Painter: Clearing VDPAU painter cache.
 2012-05-24 22:18:36.327861 W  MythPainter: 24 images not yet de-allocated.
 2012-05-24 22:18:36.339240 I  Clearing OpenGL painter cache.
 2012-05-24 22:18:36.351822 I  Display Rect  left: 240, top: 0, width:
 1440, height: 1080, aspect: 1.77778
 2012-05-24 22:18:36.351844 I  Video Rect    left: 0, top: 0, width: 720,
 height: 576, aspect: 1.33333
 2012-05-24 22:18:36.351858 I  Display Rect  left: 240, top: 0, width:
 1440, height: 1080, aspect: 1.77778
 2012-05-24 22:18:36.351871 I  Video Rect    left: 0, top: 0, width: 720,
 height: 576, aspect: 1.33333
 2012-05-24 22:18:36.351878 I  VDP: SetVideoRenderer(vdpau)
 2012-05-24 22:18:36.351889 I  VDP: SetVideoRender(vdpau) ==
 GetVideoRenderer()
 2012-05-24 22:18:36.352584 I  VideoOutput: Pixel dimensions: Screen
 1920x1080, window 1920x1080
 2012-05-24 22:18:36.352602 I  VideoOutput: Actual display dimensions:
 483x272 mm  Aspect: 1.77574
 2012-05-24 22:18:36.352613 I  VideoOutput: Estimated window dimensions:
 483x272 mm  Aspect: 1.77574
 2012-05-24 22:18:36.387323 I  VDPAU: Created 2 output surfaces.
 2012-05-24 22:18:36.387340 I  VDPAU: Set colorkey to 0x20202
 2012-05-24 22:18:36.387352 I  VDPAU: Created VDPAU render device 1920x1080
 2012-05-24 22:18:36.387365 I  VidOutVDPAU: Created VDPAU osd (1920x1080)
 2012-05-24 22:18:36.399080 I  ColourSpace: PictureAttributes: Brightness,
 Contrast, Colour, Hue, Studio Levels,
 2012-05-24 22:18:36.399094 I  VidOutVDPAU: Using ITU BT.601 colorspace
 2012-05-24 22:18:36.399122 I  Display Rect  left: 240, top: 0, width:
 1440, height: 1080, aspect: 1.77778
 2012-05-24 22:18:36.399137 I  Video Rect    left: 0, top: 0, width: 720,
 height: 576, aspect: 1.33333
 2012-05-24 22:18:36.399143 I  VidOutVDPAU: Created VDPAU context (GPU
 decode)
 2012-05-24 22:18:36.399158 I  Display Rect  left: 119, top: 760, width:
 338, height: 255, aspect: 1.77778
 2012-05-24 22:18:36.399170 I  Video Rect    left: 0, top: 0, width: 720,
 height: 576, aspect: 1.33333
 2012-05-24 22:18:36.399182 I  Display Rect  left: 119, top: 760, width:
 338, height: 255, aspect: 1.77778
 2012-05-24 22:18:36.399194 I  Video Rect    left: 0, top: 0, width: 720,
 height: 576, aspect: 1.33333
 2012-05-24 22:18:36.399209 I  VDP: GetFilteredDeint() : vdpau ->
 'vdpauadvanced'
 2012-05-24 22:18:36.399659 I  VidOutVDPAU: Enabled deinterlacing.
 2012-05-24 22:18:36.400415 N  Player(0): Forcing decode extra audio option
 on (Video method requires it).
 2012-05-24 22:18:36.400426 I  Player(0): ClearAfterSeek(1)
 2012-05-24 22:18:36.400432 I  VidOutVDPAU: ClearAfterSeek()
 2012-05-24 22:18:36.400438 I  VidOutVDPAU: DiscardFrames(0)
 2012-05-24 22:18:36.400461 I  VideoBuffers::DiscardFrames(0):
 AAAAAAAAAAAAAA
 2012-05-24 22:18:36.400479 I  VideoBuffers::DiscardFrames(0):
 AAAAAAAAAAAAAA -- done
 2012-05-24 22:18:36.400496 I  VidOutVDPAU: DiscardFrames() 3:
 AAAAAAAAAAAAAA -- done()
 2012-05-24 22:18:36.400521 I  FilterManager: GetFilterInfo(convert)
 returning: 0x0
 2012-05-24 22:18:36.400540 I  Player(0):
 LoadFilters('vdpaucolorspace=auto'..) -> 0x0
 2012-05-24 22:18:36.400559 I  Player(0): detectInterlace(Detect Scan,
 Interlaced Scan, 25, 576) ->Interlaced Scan
 2012-05-24 22:18:36.400569 I  Player(0): Enabled deinterlacing
 2012-05-24 22:18:36.400611 I  AFD: Using vdpau for video decoding
 2012-05-24 22:18:36.400618 I  AFD: Looking for decoder for MPEG2VIDEO
 2012-05-24 22:18:36.400663 I  AFD: Opened codec 0x2636ff0, id(MPEG2VIDEO)
 type(Video)
 2012-05-24 22:18:36.400679 I  AFD: Stream #2, has id 0x200 codec id MP2,
 type Audio, bitrate 192000 at 0x252f510
 2012-05-24 22:18:36.400686 I  AFD: codec MP2 has 2 channels
 2012-05-24 22:18:36.400692 I  AFD: Looking for decoder for MP2
 2012-05-24 22:18:36.400723 I  AFD: Opened codec 0x23fdab0, id(MP2)
 type(Audio)
 2012-05-24 22:18:36.400749 I  AFD: Stream #3, has id 0x203 codec id AC3,
 type Audio, bitrate 384000 at 0x2637480
 2012-05-24 22:18:36.400756 I  AFD: codec AC3 has 2 channels
 2012-05-24 22:18:36.400761 I  AFD: Looking for decoder for AC3
 2012-05-24 22:18:36.401135 I  AFD: Opened codec 0x2552ed0, id(AC3)
 type(Audio)
 2012-05-24 22:18:36.573406 I  AO: Opening audio device 'default' ch 2(2)
 sr 48000 sf signed 16 bit reenc 0
 2012-05-24 22:18:36.580824 I  Dec: Selected track #1 in the Unknown
 language(0)
 2012-05-24 22:18:36.580847 I  Dec: Selected track #1 in the German
 language(6776178)
 2012-05-24 22:18:36.580862 I  Dec: Resyncing position map. posmapStarted =
 0 livetv(1) watchingRec(0)
 2012-05-24 22:18:36.588262 I  Dec: Position map filled from DB to: 12
 2012-05-24 22:18:36.588276 I  Dec: SyncPositionMap watchingrecording, from
 DB: 2 entries
 2012-05-24 22:18:36.588306 I  Player(0): Filling position map from 13 to
 end
 2012-05-24 22:18:36.588668 I  Dec: Position map filled from Encoder to: 24
 2012-05-24 22:18:36.588678 I  Dec: SyncPositionMap watchingrecording
 total: 3 entries
 2012-05-24 22:18:36.588694 I  Dec: SyncPositionMap, new totframes: 24, new
 length: 0, posMap size: 3
 2012-05-24 22:18:36.589855 I  AFD: Partial position map found
 2012-05-24 22:18:36.589869 I  AFD: Successfully opened decoder for file:
 "/MEDIA/video/live-tv/18501_20120524221834.mpg". novideo(0)
 2012-05-24 22:18:36.595389 N  AFD: Resetting byte context eof (livetv 1
 was eof 0)
 2012-05-24 22:18:36.639032 I  Player(0): Play(  1.0, normal 1, unpause
 audio 1)
 2012-05-24 22:18:36.639689 I  Dec: Resyncing position map. posmapStarted =
 0 livetv(1) watchingRec(1)
 2012-05-24 22:18:36.639721 I  Player(0): Play speed: rate: 25 speed: 1
 skip: 1 => new interval 40000
 2012-05-24 22:18:36.640338 I  VDP: GetFilteredDeint() : vdpau ->
 'vdpauadvanced'
 2012-05-24 22:18:36.640831 I  VidOutVDPAU: Enabled deinterlacing.
 2012-05-24 22:18:36.640857 I  Player(0): Stretch Factor 1, allow passthru
 2012-05-24 22:18:36.640866 I  Player(0): JumpToProgram - end
 2012-05-24 22:18:36.640885 I  Player(0): Waiting for video buffers...
 2012-05-24 22:18:36.641007 I  Dec: Position map filled from DB to: 12
 2012-05-24 22:18:36.641016 I  Dec: SyncPositionMap watchingrecording, from
 DB: 2 entries
 2012-05-24 22:18:36.641026 I  Player(0): Filling position map from 13 to
 end
 2012-05-24 22:18:36.641313 I  Dec: Position map filled from Encoder to: 36
 2012-05-24 22:18:36.641321 I  Dec: SyncPositionMap watchingrecording
 total: 4 entries
 2012-05-24 22:18:36.641335 I  Dec: SyncPositionMap, new totframes: 36, new
 length: 1, posMap size: 4
 2012-05-24 22:18:36.650060 I  VidOutVDPAU: Created VDPAU decoder (2 ref
 frames)
 2012-05-24 22:18:36.651233 I  TV: DrawUnusedRects() -- begin
 2012-05-24 22:18:36.651244 I  TV: DrawUnusedRects() -- end
 2012-05-24 22:18:36.804391 I  Player(0): Video Aspect ratio changed from
 1.33333 to 1.77778
 2012-05-24 22:18:36.804414 I  Display Rect  left: 0, top: 0, width: 1920,
 height: 1080, aspect: 1.77778
 2012-05-24 22:18:36.804427 I  Video Rect    left: 0, top: 0, width: 720,
 height: 576, aspect: 1.77778
 2012-05-24 22:18:36.804466 I  OSD: Created window bb_OSD_INTERACTIVE
 2012-05-24 22:18:36.808436 I  VDPAU: Added 2 output surfaces (total 4, max
 4)
 2012-05-24 22:18:39.118018 E  AFD: Unknown audio decoding error
 2012-05-24 22:18:40.721674 I  Player(0): FPS:   25.58 Mean: 39099 Std.Dev:
 6995 CPUs: 100% 100%
 2012-05-24 22:18:43.754422 I  Player(0): Waiting for video buffers...
 2012-05-24 22:18:44.080648 I  Player(0): Waiting for video buffers...
 2012-05-24 22:18:44.591579 I  Player(0): FPS:   25.85 Mean: 38686 Std.Dev:
 7910 CPUs: 20% 27%
 2012-05-24 22:18:44.844197 I  Player(0): Waiting for video buffers...
 2012-05-24 22:18:44.947189 N  Player(0): Waited 103ms for video buffers
 LAAAAAAAAADddA
 2012-05-24 22:18:45.073351 N  Event socket closed.  No connection to the
 backend.
 2012-05-24 22:18:45.602156 I  Player(0): Waiting for video buffers...
 2012-05-24 22:18:45.612585 I  MythCoreContext: Connecting to backend
 server: localhost:6543 (try 1 of 1)
 2012-05-24 22:18:45.612649 E  No address defined for host: localhost
 2012-05-24 22:18:45.613117 E  Connection to master server timed out.
                         Either the server is down or the master server
 settings
                         in mythtv-settings does not contain the proper IP
 address

 2012-05-24 22:18:45.656513 I  RingBuf(/MEDIA/video/live-
 tv/18501_20120524221834.mpg): Waited 0.2 seconds for data
                         to become available... 22788 < 32768
 2012-05-24 22:18:45.711889 I  RingBuf(/MEDIA/video/live-
 tv/18501_20120524221834.mpg): Waited 0.2 seconds for data
                         to become available... 22788 < 32768
 2012-05-24 22:18:45.787225 N  Player(0): Waited 185ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:45.890630 N  Player(0): Waited 288ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:45.962020 I  RingBuf(/MEDIA/video/live-
 tv/18501_20120524221834.mpg): Waited 0.5 seconds for data
                         to become available... 22788 < 32768
 2012-05-24 22:18:45.993669 N  Player(0): Waited 391ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:46.096918 N  Player(0): Waited 494ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:46.199779 N  Player(0): Waited 597ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:46.267402 I  RingBuf(/MEDIA/video/live-
 tv/18501_20120524221834.mpg): Waited 1.0 seconds for data
                         to become available... 22788 < 32768
 2012-05-24 22:18:46.302633 N  Player(0): Waited 700ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:46.322762 I  RingBuf(/MEDIA/video/live-
 tv/18501_20120524221834.mpg): Waited 1.0 seconds for data
                         to become available... 22788 < 32768
 2012-05-24 22:18:46.405755 N  Player(0): Waited 803ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:46.508636 N  Player(0): Waited 906ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:46.612245 N  Player(0): Waited 1010ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:46.715061 N  Player(0): Waited 1112ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:46.817779 N  Player(0): Waited 1215ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:46.921231 N  Player(0): Waited 1319ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:47.024507 N  Player(0): Waited 1422ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:47.127713 N  Player(0): Waited 1525ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:47.232181 N  Player(0): Waited 1630ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:47.239144 I  RingBuf(/MEDIA/video/live-
 tv/18501_20120524221834.mpg): Waited 2.0 seconds for data
                         to become available... 22788 < 32768
 2012-05-24 22:18:47.335663 N  Player(0): Waited 1733ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:47.439669 N  Player(0): Waited 1837ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:47.542983 N  Player(0): Waited 1940ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:47.646182 N  Player(0): Waited 2044ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:47.749673 N  Player(0): Waited 2147ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:47.853106 N  Player(0): Waited 2250ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:47.956954 N  Player(0): Waited 2354ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:48.060807 N  Player(0): Waited 2458ms for video buffers
 DAdAAAAAAAdAAL
 2012-05-24 22:18:48.164517 N  Player(0): Waited 2562ms for video buffers
 DAdAAAAAAAdAAL

-- 
Ticket URL: <http://code.mythtv.org/trac/ticket/10743#comment:3>
MythTV <http://code.mythtv.org/trac>
MythTV Media Center


More information about the mythtv-commits mailing list