[mythtv] [mythtv-commits] Ticket #11686: Frontend OOMs at end of watching a recording
Tom Lichti
tom at redpepperracing.com
Mon Jul 15 13:32:44 UTC 2013
I noticed this as well yesterday after updating to latest trunk build. If
necessary I can rebuild with debug sooner than Sunday.
Tom
On Mon, Jul 15, 2013 at 4:49 AM, <noreply at mythtv.org> wrote:
> #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
> _______________________________________________
> mythtv-commits mailing list
> mythtv-commits at mythtv.org
> http://www.mythtv.org/mailman/listinfo/mythtv-commits
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://www.mythtv.org/pipermail/mythtv-dev/attachments/20130715/c0fcab71/attachment.html>
More information about the mythtv-dev
mailing list