[mythtv-commits] Ticket #11686: Frontend OOMs at end of watching a recording

MythTV noreply at mythtv.org
Mon Jul 15 08:49:40 UTC 2013


#11686: Frontend OOMs at end of watching a recording
-------------------------------+--------------------------------------
 Reporter:  voltaire1983red@…  |           Type:  Bug Report - General
   Status:  new                |       Priority:  minor
Milestone:  unknown            |      Component:  MythTV - General
  Version:  Unspecified        |       Severity:  medium
 Keywords:                     |  Ticket locked:  0
-------------------------------+--------------------------------------
 I am using master updated on Saturday.  I have now had two instances of
 the frontend gobbling all my ram when a recording comes to the end.  The
 playback freezes on the last frame and memory usage grows until it
 depletes all available memory.  I update about once or twice a month and
 have not seen this previously.  I was able to reproduce using the same
 recording.

 The recordings were both from an HDHomeRun Prime, playback with VDPAU and
 timestretch at 1.3.  Audio is hdmi passthrough.  It is a remote frontend
 playing directly from an NFS share, not streaming from the backend.  The
 frontend is Ubuntu 12.04 with the 3.8 kernel and has 16GB ram with no swap
 file.

 I have watched several other recordings from the same source without this
 issue.

 Below is console output captured by jumping to near the end of the
 recording to reproduce the issue.

 {{{
 2013-07-15 01:05:20.004766 I  Pulse: Created PulseHandler object
 2013-07-15 01:05:20.005475 I  Pulse: Callback: State changed
 Unconnected->Connecting
 2013-07-15 01:05:20.005517 I  Pulse: Callback: State changed
 Connecting->Authorizing
 2013-07-15 01:05:20.056196 I  Pulse: Callback: State changed
 Authorizing->Setting Name
 2013-07-15 01:05:20.096574 I  Pulse: Callback: State changed Setting
 Name->Ready!
 2013-07-15 01:05:20.106663 I  Pulse: Initialised handler
 2013-07-15 01:05:20.137094 I  Pulse: Operation: success 1 remaining 1
 2013-07-15 01:05:20.157324 I  Pulse: Operation: success 1 remaining 0
 2013-07-15 01:05:20.167415 I  Pulse: PulseAudio suspend OK
 2013-07-15 01:05:20.174431 I  ALSA: OpenDevice plughw:CARD=NVidia,DEV=9
 2013-07-15 01:05:20.174878 I  AOS: Sample rate 32000 is supported
 2013-07-15 01:05:20.174906 I  AOS: Sample rate 44100 is supported
 2013-07-15 01:05:20.174930 I  AOS: Sample rate 48000 is supported
 2013-07-15 01:05:20.174951 I  AOS: Format signed 16 bit is supported
 2013-07-15 01:05:20.174993 I  AOS: 2 channel(s) are supported
 2013-07-15 01:05:20.175226 I  ALSA: Successfully retrieved ELD data
 2013-07-15 01:05:20.175265 I  ELDUTILS: Detected monitor TX-NR709 at
 connection type HDMI
 2013-07-15 01:05:20.175286 I  ELDUTILS: available speakers: FL/FR LFE FC
 RL/RR RC FLC/FRC RLC/RRC FLW/FRW FLH/FRH TC FCH
 2013-07-15 01:05:20.175295 I  ELDUTILS: max LPCM channels = 2
 2013-07-15 01:05:20.175303 I  ELDUTILS: max channels = 2
 2013-07-15 01:05:20.175310 I  ELDUTILS: supported codecs = LPCM
 2013-07-15 01:05:20.175332 I  ELDUTILS: supports coding type LPCM:
 channels = 2, rates = 32000 44100 48000, bits = 16
 2013-07-15 01:05:20.195561 I  AOS: may be AC3 or DTS capable
 2013-07-15 01:05:20.195571 I  AOS: 6 channel(s) are supported
 2013-07-15 01:05:20.204657 N  AudioPlayer: Enabling Audio
 2013-07-15 01:05:20.220703 I  Player(0): Stretch Factor 1, allow passthru
 2013-07-15 01:05:20.220919 I  AFD: Buffer size: 32768, streamed 0
 2013-07-15 01:05:20.275450 I  AFD: Stream #0, has id 0x7c0 codec id
 MPEG2VIDEO, type Video, bitrate 0 at 0x94dc360
 2013-07-15 01:05:20.275477 I  AFD: Stream #1, has id 0x7c1 codec id AC3,
 type Audio, bitrate 448000 at 0x95e58c0
 2013-07-15 01:05:20.275486 I  AFD: codec AC3 has 6 channels
 2013-07-15 01:05:20.275492 I  AFD: Looking for decoder for AC3
 2013-07-15 01:05:20.275837 I  AFD: Opened codec 0x95ed660, id(AC3)
 type(Audio)
 2013-07-15 01:05:20.276135 I  AFD: Audio Track #1, of type (Normal) is A/V
 stream #1 (id=0x7c1) and has 6 channels in the English language(6647399).
 2013-07-15 01:05:20.276152 I  AFD: Stream #2, has id 0x7c2 codec id AC3,
 type Audio, bitrate 192000 at 0x95edb20
 2013-07-15 01:05:20.276160 I  AFD: codec AC3 has 2 channels
 2013-07-15 01:05:20.276166 I  AFD: Looking for decoder for AC3
 2013-07-15 01:05:20.276479 I  AFD: Opened codec 0x95e1140, id(AC3)
 type(Audio)
 2013-07-15 01:05:20.276557 I  AFD: Audio Track #2, of type (Normal) is A/V
 stream #2 (id=0x7c2) and has 2 channels in the Spanish; Castilian
 language(7565409).
 2013-07-15 01:05:20.276565 I  AFD: Trying to select best video track
 2013-07-15 01:05:20.276593 I  AFD: Selected track #0 (id 0x7c0 codec id
 MPEG2VIDEO, type Video, bitrate 500000 at 0x94dc360)
 2013-07-15 01:05:20.283482 I  VDP: Accepting: cmp(> 0 0) dec(vdpau)
 cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
 deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto)
 2013-07-15 01:05:20.283510 I  VDP: LoadBestPreferences(2048x2048, 0)
 2013-07-15 01:05:20.283539 I  VDP: LoadBestPreferences(2048x2048, 60)
 2013-07-15 01:05:20.283568 I  VDP: LoadBestPreferences(1920x1080, 60)
 2013-07-15 01:05:20.283617 I  AFD: Using 1 CPUs for decoding
 2013-07-15 01:05:20.283631 I  AFD: InitVideoCodec() 0x95e5460
 id(MPEG2VIDEO) type (Video).
 2013-07-15 01:05:20.283667 I  AFD: Selected FPS is 29.97 (avg 29.97 codec
 29.97 container 90000 estimated 29.97)
 2013-07-15 01:05:20.283694 I  Player(0): SetFrameInterval ps:1 scan:3
 2013-07-15 01:05:20.283720 I  Player(0): detectInterlace(Detect Scan,
 Interlaced Scan, 29.97, 1080) ->Interlaced Scan
 2013-07-15 01:05:20.283836 I  AFD: EIA-608 caption service #3 is in the
 Unknown language.
 2013-07-15 01:05:20.283851 I  AFD: EIA-708 caption service #1 is in the
 Unknown language.
 2013-07-15 01:05:20.283864 I  AFD: EIA-608 caption service #1 is in the
 Unknown language.
 2013-07-15 01:05:20.283878 I  AFD: EIA-608 caption service #3 is in the
 Unknown language.
 2013-07-15 01:05:20.283888 I  AFD: EIA-708 caption service #2 is in the
 Unknown language.
 2013-07-15 01:05:20.283897 I  AFD: EIA-608 caption service #1 is in the
 Unknown language.
 2013-07-15 01:05:20.283911 I  AFD: EIA-608 caption service #3 is in the
 Unknown language.
 2013-07-15 01:05:20.283923 I  AFD: EIA-708 caption service #3 is in the
 Unknown language.
 2013-07-15 01:05:20.283933 I  AFD: EIA-708 caption service #48 is in the
 Unknown language.
 2013-07-15 01:05:20.283942 I  AFD: EIA-608 caption service #1 is in the
 Unknown language.
 2013-07-15 01:05:20.283954 I  AFD: EIA-708 caption service #39 is in the
 Unknown language.
 2013-07-15 01:05:20.283964 I  AFD: EIA-608 caption service #3 is in the
 Unknown language.
 2013-07-15 01:05:20.283974 I  AFD: EIA-608 caption service #1 is in the
 Unknown language.
 2013-07-15 01:05:20.283985 I  AFD: EIA-608 caption service #1 is in the
 Unknown language.
 2013-07-15 01:05:20.283994 I  AFD: EIA-608 caption service #1 is in the
 Unknown language.
 2013-07-15 01:05:20.284012 I  AFD: Using vdpau for video decoding
 2013-07-15 01:05:20.284031 I  AFD: Opened codec 0x95e5460, id(MPEG2VIDEO)
 type(Video)
 2013-07-15 01:05:20.284056 I  AFD: Trying to select audio track (w/lang)
 2013-07-15 01:05:20.285761 I  AFD: Selected track 1: English AC3 5.1ch
 (A/V Stream #1)
 2013-07-15 01:05:20.285779 I  AFD: Audio data is planar
 2013-07-15 01:05:20.285799 I  AFD: Initializing audio parms from audio
 track #1
 2013-07-15 01:05:20.285824 I  AFD: Audio format changed
                         from id(NONE)     -1Hz -1ch -1bps     (profile 0)
 to id( AC3)  48000Hz  6ch 32bps  pt (profile 0)
 2013-07-15 01:05:20.285852 I  AOBase: Setting AC3 passthrough
 2013-07-15 01:05:20.286672 I  SPDIFEncoder: Creating spdif encoder (for
 AC3)
 2013-07-15 01:05:20.286685 I  AOBase: Killing AudioOutputDSP
 2013-07-15 01:05:20.286701 I  AOBase: Original codec was AC3, 32 bit
 floating point, 48 kHz, 6 channels
 2013-07-15 01:05:20.286725 I  AOBase: enc(0), passthru(1), features
 (AC3,DTS) configured_channels(6), 6 channels supported(1) max_channels(6)
 2013-07-15 01:05:20.286746 I  AOBase: Opening audio device
 'plughw:CARD=NVidia,DEV=9' ch 2(6) sr 48000 sf signed 16 bit reenc 0
 2013-07-15 01:05:20.286755 I  ALSA: OpenDevice
 plughw:CARD=NVidia,DEV=9,AES0=6,AES1=0x82,AES2=0x00,AES3=0x01 for
 passthrough
 2013-07-15 01:05:20.286927 I  ALSA: Auto setting passthrough failed (No
 such file or directory), defaulting to main device
 2013-07-15 01:05:20.286934 I  ALSA: OpenDevice plughw:CARD=NVidia,DEV=9
 2013-07-15 01:05:20.289947 I  ALSA: SetParameters(format=2, channels=2,
 rate=48000, buffer_time=500000, period_time=4)
 2013-07-15 01:05:20.290285 I  ALSA: Buffer size range from 64 to 16384
 2013-07-15 01:05:20.290294 I  ALSA: Period size range from 32 to 8192
 2013-07-15 01:05:20.290336 E  ALSA: Requested 500000us got 341333 buffer
 time
 2013-07-15 01:05:20.290426 I  ALSA: Hardware audio buffer cur: 64 need:
 128 max allowed: 32768
 2013-07-15 01:05:20.290453 E  ALSA: Try to manually increase audio buffer
 with: echo 128 | sudo tee /proc/asound/card1/pcm9p/sub0/prealloc
 2013-07-15 01:05:20.290465 I  ALSA: Buffer time = 341333 us
 2013-07-15 01:05:20.290522 I  ALSA: Period time = 4 periods
 2013-07-15 01:05:20.410177 I  ALSA: Buffer size = 16384 | Period size =
 4096
 2013-07-15 01:05:20.506104 I  AOBase: Audio fragment size: 8192
 2013-07-15 01:05:20.506137 I  AOBase: Audio Stretch Factor: 1
 2013-07-15 01:05:20.506192 I  AOBase: Ending Reconfigure()
 2013-07-15 01:05:20.506233 I  Dec: Selected track #1 (type 2) in the
 Unknown language(0)
 2013-07-15 01:05:20.506247 I  Dec: Trying to select track (w/lang &
 forced)
 2013-07-15 01:05:20.506271 I  Dec: Selected track #1 (type 4) in the
 Unknown language(1123328)
 2013-07-15 01:05:20.506281 I  Dec: Trying to select track (w/lang &
 forced)
 2013-07-15 01:05:20.506342 I  Dec: Selected track #1 (type 5) in the
 Unknown language(33255)
 2013-07-15 01:05:20.506344 I  AOBase: kickoffOutputAudioLoop: pid = 27911
 2013-07-15 01:05:20.506366 I  Dec: Resyncing position map. posmapStarted =
 0 livetv(0) watchingRec(0)
 2013-07-15 01:05:20.506372 I  AOBase: OutputAudioLoop: Play Event
 2013-07-15 01:05:20.554693 I  Dec: Position map filled from DB to: 118631
 2013-07-15 01:05:20.558007 I  Dec: Duration map filled from DB to: 118631
 2013-07-15 01:05:20.558408 I  Dec: SyncPositionMap prerecorded, from DB:
 7965 entries
 2013-07-15 01:05:20.558425 I  Dec: SyncPositionMap, new totframes: 118631,
 new length: 3958, posMap size: 7965
 2013-07-15 01:05:20.561400 I  AFD: Position map found
 2013-07-15 01:05:20.561407 I  AFD: Successfully opened decoder for file:
 "/mnt/store2/recordings/6754_20130715055700.mpg". novideo(0)
 2013-07-15 01:05:20.570235 I  VideoOutput: Allowed renderers: vdpau
 2013-07-15 01:05:20.570257 I  VideoOutput: Allowed renderers (filt:
 vdpau): vdpau
 2013-07-15 01:05:20.574128 I  VDP: Accepting: cmp(> 0 0) dec(vdpau)
 cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
 deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto)
 2013-07-15 01:05:20.574148 I  VDP: LoadBestPreferences(2048x2048, 0)
 2013-07-15 01:05:20.574174 I  VDP: LoadBestPreferences(2048x2048, 60)
 2013-07-15 01:05:20.574199 I  VDP: LoadBestPreferences(1920x1080, 60)
 2013-07-15 01:05:20.574221 I  VideoOutput: Preferred renderer: vdpau
 2013-07-15 01:05:20.574236 I  VideoOutput: Trying video renderer: 'vdpau'
 2013-07-15 01:05:20.601200 I  VDP: Accepting: cmp(> 0 0) dec(vdpau)
 cpus(1) skiploop(enabled) rend(vdpau) osd(vdpau) osdfade(enabled)
 deint(vdpauadvanceddoublerate,vdpauadvanced) filt(vdpaucolorspace=auto)
 2013-07-15 01:05:20.601217 I  VDP: LoadBestPreferences(2048x2048, 0)
 2013-07-15 01:05:20.601240 I  VDP: LoadBestPreferences(2048x2048, 60)
 2013-07-15 01:05:20.601292 I  VideoOutWindow::SetPIPState. pip_state: 0]
 2013-07-15 01:05:20.601304 I  VDP: LoadBestPreferences(2048x2048, 29.97)
 2013-07-15 01:05:20.601325 I  Clearing OpenGL painter cache.
 2013-07-15 01:05:20.625250 I  Snapping width to avoid scaling: width:
 1920, left: 0
 2013-07-15 01:05:20.625273 I  Display Rect  left: 0, top: 135, width:
 1920, height: 810, aspect: 1.33333
 2013-07-15 01:05:20.625293 I  Video Rect    left: 0, top: 0, width: 1920,
 height: 1080, aspect: 1.77778
 2013-07-15 01:05:20.625315 I  VDP: LoadBestPreferences(1920x1088, 29.97)
 2013-07-15 01:05:20.625364 I  Snapping width to avoid scaling: width:
 1920, left: 0
 2013-07-15 01:05:20.625378 I  Display Rect  left: 0, top: 135, width:
 1920, height: 810, aspect: 1.33333
 2013-07-15 01:05:20.625390 I  Video Rect    left: 0, top: 0, width: 1920,
 height: 1080, aspect: 1.77778
 2013-07-15 01:05:20.625400 I  VDP: SetVideoRenderer(vdpau)
 2013-07-15 01:05:20.625410 I  VDP: SetVideoRender(vdpau) ==
 GetVideoRenderer()
 2013-07-15 01:05:20.626128 I  VideoOutput: Pixel dimensions: Screen
 1920x1080, window 1920x1080
 2013-07-15 01:05:20.626148 I  VideoOutput: Actual display dimensions:
 650x366 mm  Aspect: 1.77596
 2013-07-15 01:05:20.626160 I  VideoOutput: Estimated window dimensions:
 650x366 mm  Aspect: 1.77596
 2013-07-15 01:05:20.724659 I  VDPAU: Created 2 output surfaces.
 2013-07-15 01:05:20.724687 I  VDPAU: Set colorkey to 0x20202
 2013-07-15 01:05:20.724699 I  VDPAU: Version 1
 2013-07-15 01:05:20.724707 I  VDPAU: Information NVIDIA VDPAU Driver
 Shared Library  304.88  Wed Mar 27 14:49:27 PDT 2013
 2013-07-15 01:05:20.724720 I  VDPAU: HQ scaling level 1 of 9 available.
 2013-07-15 01:05:20.724728 I  VDPAU: MPEG4 hardware acceleration
 supported.
 2013-07-15 01:05:20.724737 I  VDPAU: Created VDPAU render device 1920x1080
 2013-07-15 01:05:20.724759 I  VidOutVDPAU: Created VDPAU osd (1920x1080)
 2013-07-15 01:05:20.750754 I  ColourSpace: PictureAttributes: Brightness,
 Contrast, Colour, Hue, Studio Levels,
 2013-07-15 01:05:20.750763 I  VidOutVDPAU: Using ITU BT.709 colorspace
 2013-07-15 01:05:20.750786 I  Snapping height to avoid scaling: height:
 1080, top: 0
 2013-07-15 01:05:20.750791 I  Snapping width to avoid scaling: width:
 1920, left: 0
 2013-07-15 01:05:20.750801 I  Display Rect  left: 0, top: 0, width: 1920,
 height: 1080, aspect: 1.77778
 2013-07-15 01:05:20.750809 I  Video Rect    left: 0, top: 0, width: 1920,
 height: 1080, aspect: 1.77778
 2013-07-15 01:05:20.750813 I  VidOutVDPAU: Created VDPAU context (GPU
 decode)
 2013-07-15 01:05:20.754441 I  Over/underscan. V: 0, H: 0
 2013-07-15 01:05:20.754456 I  Snapping height to avoid scaling: height:
 1080, top: 0
 2013-07-15 01:05:20.754465 I  Snapping width to avoid scaling: width:
 1920, left: 0
 2013-07-15 01:05:20.754480 I  Display Rect  left: 0, top: 0, width: 1920,
 height: 1080, aspect: 1.77778
 2013-07-15 01:05:20.754493 I  Video Rect    left: 0, top: 0, width: 1920,
 height: 1080, aspect: 1.77778
 2013-07-15 01:05:20.754506 N  Player(0): Forcing decode extra audio option
 on (Video method requires it).
 2013-07-15 01:05:20.754563 I  FilterManager: GetFilterInfo(convert)
 returning: 0x0
 2013-07-15 01:05:20.754639 I  Player(0):
 LoadFilters('vdpaucolorspace=auto'..) -> 0x0
 2013-07-15 01:05:20.758553 I  Player(0): Decoder thread starting.
 2013-07-15 01:05:20.765778 I  OSD: Loaded window osd_message
 2013-07-15 01:05:20.768075 I  OSD: Loaded window osd_input
 2013-07-15 01:05:20.788271 I  OSD: Loaded window program_info
 2013-07-15 01:05:20.791037 I  OSD: Loaded window browse_info
 2013-07-15 01:05:20.793602 I  OSD: Loaded window osd_status
 2013-07-15 01:05:20.796324 I  OSD: Loaded window osd_program_editor
 2013-07-15 01:05:20.801837 I  OSD: Loaded window osd_debug
 2013-07-15 01:05:20.801849 I  OSD: Loaded OSD: size 1920x1080 offset 0+0
 2013-07-15 01:05:20.801907 I  OSD: Created window aa_OSD_SUBTITLES
 2013-07-15 01:05:20.801940 I  Player(0): ClearAfterSeek(0)
 2013-07-15 01:05:20.802658 I  VDP: GetFilteredDeint() : vdpau ->
 'vdpauadvanceddoublerate'
 2013-07-15 01:05:20.803122 I  VidOutVDPAU: Enabled deinterlacing.
 2013-07-15 01:05:20.803156 I  VSYNC: DRMVideoSync: Could not open device
 /dev/dri/card0, No such file or directory
 2013-07-15 01:05:20.803176 E  VSYNC: RTCVideoSync: Could not open
 /dev/rtc:
                         eno: Permission denied (13)
 2013-07-15 01:05:20.803608 I  Player(0): Video timing method: USleep with
 busy wait
 2013-07-15 01:05:20.803621 I  Player(0): Display Refresh Rate: 59.999
 Video Frame Rate: 29.970
 2013-07-15 01:05:20.803627 I  Player(0): SetFrameInterval ps:1 scan:1
 2013-07-15 01:05:20.804386 I  playCtx: StartPlaying(): took 0 ms to start
 player.
 2013-07-15 01:05:20.804403 I  TV: Created player.
 2013-07-15 01:05:20.804425 I  TV: StartPlayer(0, WatchingPreRecorded,
 main) -- end ok
 2013-07-15 01:05:20.804478 I  TV: Changing from None to
 WatchingPreRecorded
 2013-07-15 01:05:20.808411 I  AOBase: OutputAudioLoop: Play Event
 2013-07-15 01:05:20.821257 I  TV: DrawUnusedRects() -- begin
 2013-07-15 01:05:20.821268 I  TV: DrawUnusedRects() -- end
 2013-07-15 01:05:20.821328 I  TV: Main UI disabled.
 2013-07-15 01:05:20.821349 I  TV: HandleStateChange(0) -- end
 2013-07-15 01:05:20.821406 I  TV: tv->Playback() -- end
 2013-07-15 01:05:20.821431 I  TV: Entering main playback loop.
 2013-07-15 01:05:20.829726 I  VidOutVDPAU: Created VDPAU decoder (2 ref
 frames)
 2013-07-15 01:05:20.850447 I  Player(0): Enabled deinterlacing
 2013-07-15 01:05:20.920281 I  VDPAU: Added 2 output surfaces (total 4, max
 4)
 2013-07-15 01:05:21.071406 I  Player(0): Video is 3.4081 frames behind
 audio (too slow), dropping frame to catch up.
 2013-07-15 01:05:21.071418 I  AOBase: Pause 1
 2013-07-15 01:05:21.071485 I  Player(0): Video is 3.73986 frames behind
 audio (too slow), dropping frame to catch up.
 2013-07-15 01:05:21.071490 I  AOBase: Pause 1
 2013-07-15 01:05:21.071533 I  Player(0): Video is 3.74145 frames behind
 audio (too slow), dropping frame to catch up.
 2013-07-15 01:05:21.071537 I  AOBase: Pause 1
 2013-07-15 01:05:21.071580 I  Player(0): Video is 3.49537 frames behind
 audio (too slow), dropping frame to catch up.
 2013-07-15 01:05:21.071585 I  AOBase: Pause 1
 2013-07-15 01:05:21.071627 I  Player(0): Video is 3.05607 frames behind
 audio (too slow), dropping frame to catch up.
 2013-07-15 01:05:21.071632 I  AOBase: Pause 1
 2013-07-15 01:05:21.071671 I  AOBase: Pause 0
 2013-07-15 01:05:21.080384 I  AOBase: OutputAudioLoop: Play Event


 ...

 2013-07-15 01:05:25.538132 I  AOBase: OutputAudioLoop: Play Event
 2013-07-15 01:05:25.792478 I  Player(0): Video is 3.53643 frames ahead of
 audio,
                         doubling video frame interval to slow down.
 2013-07-15 01:05:25.892574 I  Player(0): Video is 3.73872 frames ahead of
 audio,
                         doubling video frame interval to slow down.
 2013-07-15 01:05:25.992670 I  Player(0): Video is 3.36596 frames ahead of
 audio,
                         doubling video frame interval to slow down.
 2013-07-15 01:05:27.072167 E  decoding error
                         eno: Unknown error 541478725 (541478725)
 2013-07-15 01:05:27.088205 I  Player(0): Video is 30 frames behind audio
 (too slow), dropping frame to catch up.
 2013-07-15 01:05:27.088219 I  AOBase: Pause 1
 2013-07-15 01:05:27.088306 I  Player(0): Video is 30 frames behind audio
 (too slow), dropping frame to catch up.
 2013-07-15 01:05:27.088315 I  AOBase: Pause 1

 ...

 2013-07-15 01:05:27.095046 I  Player(0): Video is 30 frames behind audio
 (too slow), dropping frame to catch up.
 2013-07-15 01:05:27.095055 I  AOBase: Pause 1
 2013-07-15 01:05:27.095123 I  AOBase: OutputAudioLoop: audio paused
 2013-07-15 01:05:27.095126 I  Player(0): Video is 30 frames behind audio
 (too slow), dropping frame to catch up.
 2013-07-15 01:05:27.095134 I  AOBase: Pause 1
 2013-07-15 01:05:27.095145 I  AOBase: OutputAudioLoop: audio paused
 2013-07-15 01:05:27.095187 I  Player(0): Video is 30 frames behind audio
 (too slow), dropping frame to catch up.
 2013-07-15 01:05:27.095237 I  Player(0): Video is 30 frames behind audio
 (too slow), dropping frame to catch up.
 }}}

 And then thousands of lines of

 {{{
 2013-07-15 01:06:37.522728 I  Player(1): Video is 30 frames behind audio
 (too slow), dropping frame to catch up.
 2013-07-15 01:06:37.522768 I  Player(1): Video is 30 frames behind audio
 (too slow), dropping frame to catch up.
 2013-07-15 01:06:37.522795 I  Player(1): Video is 30 frames behind audio
 (too slow), dropping frame to catch up.
 2013-07-15 01:06:37.522820 I  Player(1): Video is 30 frames behind audio
 (too slow), dropping frame to catch up.
 2013-07-15 01:06:37.522843 I  Player(1): Video is 30 frames behind audio
 (too slow), dropping frame to catch up.
 2013-07-15 01:06:37.522870 I  Player(1): Video is 30 frames behind audio
 (too slow), dropping frame to catch up.
 }}}


 I never see the entry showing that it is near the end of the recording and
 slowing down to 1x speed as recordings that do not have this problem
 typically have.

 Sorry I do not have any more information.  Do not have a debug build
 running.  I run the frontend with 'mythfrontend -v
 playback,important,audio'.  If I can reproduce, any other verbose options
 that might help?  If you need me to run gdb, I won't have an opportunity
 until at least next Sunday.

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


More information about the mythtv-commits mailing list