Opened 6 years ago

Closed 6 years ago

Last modified 6 years ago

#11484 closed Bug Report - General (Works for me)

ffmpeg1.1 sync: issue with AAC radio streams

Reported by: warpme@… Owned by:
Priority: minor Milestone: unknown
Component: MythTV - General Version: Master Head
Severity: medium Keywords: ffmpeg radio
Cc: Ticket locked: no

Description

Ref to http://www.gossamer-threads.com/lists/mythtv/dev/542021. Syncing to ffmpeg1.1 causes issue with AAC streaming radio stations with. Fe log attached.

2013-04-13 18:26:48.916104 I  ALSA: OpenDevice front:CARD=NVidia,DEV=0
2013-04-13 18:26:48.925896 I  AOS: Sample rate 44100 is supported
2013-04-13 18:26:48.925983 I  AOS: Sample rate 48000 is supported
2013-04-13 18:26:48.926035 I  AOS: Sample rate 96000 is supported
2013-04-13 18:26:48.926090 I  AOS: Sample rate 192000 is supported
2013-04-13 18:26:48.926161 I  AOS: Format signed 16 bit is supported
2013-04-13 18:26:48.926214 I  AOS: Format signed 24 bit MSB is supported
2013-04-13 18:26:48.926228 I  AOS: Format signed 32 bit is supported
2013-04-13 18:26:48.926330 I  AOS: 2 channel(s) are supported
2013-04-13 18:26:48.926388 I  AOS: 4 channel(s) are supported
2013-04-13 18:26:48.926452 I  AOS: 6 channel(s) are supported
2013-04-13 18:26:48.949354 I  AO: Killing AudioOutputDSP
2013-04-13 18:26:48.949452 I  AO: Original codec was NONE, signed 16 bit, 44 kHz, 2 channels
2013-04-13 18:26:48.949493 I  AO: enc(0), passthru(0), features (LPCM) configured_channels(2), 2 channels supported(1) max_channels(2)
2013-04-13 18:26:48.950533 I  AO: Opening audio device 'front:CARD=NVidia,DEV=0' ch 2(2) sr 44100 sf signed 16 bit reenc 0
2013-04-13 18:26:48.950557 I  ALSA: OpenDevice front:CARD=NVidia,DEV=0
2013-04-13 18:26:48.953286 I  ALSA: SetParameters(format=2, channels=2, rate=44100, buffer_time=500000, period_time=4)
2013-04-13 18:26:48.953749 I  ALSA: Buffer size range from 64 to 131072
2013-04-13 18:26:48.953776 I  ALSA: Period size range from 32 to 65536
2013-04-13 18:26:48.953978 I  ALSA: Buffer time = 499954 us
2013-04-13 18:26:48.954025 I  ALSA: Period time = 13 periods
2013-04-13 18:26:49.009054 I  ALSA: Buffer size = 22048 | Period size = 1696
2013-04-13 18:26:49.011540 I  ALSA: found playback control PCM on mixer device default
2013-04-13 18:26:49.011605 I  ALSA: mixer volume range on mixer device default/PCM - min 0, max 255, range 255
2013-04-13 18:26:49.011618 I  ALSA: mixer device default/PCM set up successfully
2013-04-13 18:26:49.013243 I  ALSA: channel 0 volume set 78 => 199
2013-04-13 18:26:49.013264 I  ALSA: channel 1 volume set 78 => 199
2013-04-13 18:26:49.013279 I  AO: Audio fragment size: 3392
2013-04-13 18:26:49.013370 I  ALSA: get volume channel 0: 78
2013-04-13 18:26:49.013397 I  ALSA: channel 0 volume set 78 => 199
2013-04-13 18:26:49.013412 I  ALSA: channel 1 volume set 78 => 199
2013-04-13 18:26:49.013450 I  AO: Audio Stretch Factor: 1
2013-04-13 18:26:49.013544 I  AO: Ending Reconfigure()
2013-04-13 18:26:49.013943 I  AO: kickoffOutputAudioLoop: pid = 7673
2013-04-13 18:26:49.014006 I  AO: OutputAudioLoop: Play Event
2013-04-13 18:26:49.015195 N  Suspending idle timer
2013-04-13 18:26:51.055256 I  AO: Pause 1
[aac @ 0x7f12795b6ca0] get_buffer() failed
[aac @ 0x7f12795b5140] max_analyze_duration 5000000 reached at 5015510
[aac @ 0x7f12795b5140] Estimating duration from bitrate, this may be inaccurate
[aac @ 0x7f12795b6ca0] channel element 2.12 is not allocated
2013-04-13 18:26:51.066991 I  AO: OutputAudioLoop: audio paused
2013-04-13 18:26:51.077482 I  AO: Killing AudioOutputDSP
2013-04-13 18:26:51.105867 I  AO: OutputAudioLoop: Stop Event
2013-04-13 18:26:51.105899 I  AO: kickoffOutputAudioLoop exiting
2013-04-13 18:26:51.106290 I  AO: Original codec was AAC, 32 bit floating point, 44 kHz, 2 channels
2013-04-13 18:26:51.106327 I  AO: enc(0), passthru(0), features (LPCM) configured_channels(2), 2 channels supported(1) max_channels(2)
2013-04-13 18:26:51.106360 I  AO: Audio processing enabled
2013-04-13 18:26:51.106395 I  AO: Opening audio device 'front:CARD=NVidia,DEV=0' ch 2(2) sr 44100 sf signed 32 bit reenc 0
2013-04-13 18:26:51.106410 I  ALSA: OpenDevice front:CARD=NVidia,DEV=0
2013-04-13 18:26:51.107363 I  ALSA: SetParameters(format=10, channels=2, rate=44100, buffer_time=500000, period_time=4)
2013-04-13 18:26:51.107529 I  ALSA: Buffer size range from 32 to 65536
2013-04-13 18:26:51.107544 I  ALSA: Period size range from 16 to 32768
2013-04-13 18:26:51.107677 I  ALSA: Buffer time = 499954 us
2013-04-13 18:26:51.107768 I  ALSA: Period time = 2 periods
2013-04-13 18:26:51.160973 I  ALSA: Buffer size = 22048 | Period size = 11024
2013-04-13 18:26:51.161585 I  ALSA: found playback control PCM on mixer device default
2013-04-13 18:26:51.161614 I  ALSA: mixer volume range on mixer device default/PCM - min 0, max 255, range 255
2013-04-13 18:26:51.161627 I  ALSA: mixer device default/PCM set up successfully
2013-04-13 18:26:51.161661 I  ALSA: channel 0 volume set 78 => 199
2013-04-13 18:26:51.161677 I  ALSA: channel 1 volume set 78 => 199
2013-04-13 18:26:51.161690 I  AO: Audio fragment size: 44096
2013-04-13 18:26:51.161707 I  ALSA: get volume channel 0: 78
2013-04-13 18:26:51.161723 I  ALSA: channel 0 volume set 78 => 199
2013-04-13 18:26:51.161737 I  ALSA: channel 1 volume set 78 => 199
2013-04-13 18:26:51.161758 I  AO: Audio Stretch Factor: 1
2013-04-13 18:26:51.161821 I  AO: Ending Reconfigure()
2013-04-13 18:26:51.162082 I  AO: kickoffOutputAudioLoop: pid = 7673
2013-04-13 18:26:51.163081 E  AOUtil: audio decode error: Operation not permitted (0)

Attachments (3)

diskless.log (48.7 KB) - added by warpme@… 6 years ago.
Log from ION1 FE (problematic AAC playback)
combined.log (51.5 KB) - added by warpme@… 6 years ago.
Log from combined FE/BE (plays OK)
mythfrontend.20130509164144.8046.log (80.0 KB) - added by warpme@… 6 years ago.
Full LOG from ION1 FE

Download all attachments as: .zip

Change History (11)

comment:1 Changed 6 years ago by paulh

Plays OK here using either http://87.98.163.6:8500/ or http://87.98.163.6:8200/. It does highlight a problem with the downloading of the pls or m3u for that station but using the stream URL directly works so it's not a big problem.

I think the 'Operation not permitted' error is a red-herring since aac_decode_frame() can return -1 which gets interpreted as EPERM.

comment:2 Changed 6 years ago by warpme@…

Yeah, interesting. When I launch FE on the same host running BE - it plays OK. It not plays OK on my diskless FE. What are major differences: disk less FE are 64bit binaries cross-compiled on 32-bit host. Combined BE/FE are 64bit binaries compiled on this 64bit host. I managed to use as much as possible the same configure params for both compilations. It makes no difference - so my hypothesis is that issue is cross-compilation related...

comment:3 Changed 6 years ago by paulh

Resolution: Works for me
Status: newclosed

warpme, I'm going to close this as works for me since it appears to be something unique to your setup. Please re-open the ticket if you find more information and you think there is something we need to fix.

comment:4 Changed 6 years ago by warpme@…

Paul, I can successfully play this radio stream with mythffmpeg on system in question. In MythMusic on this system it gives no audio - but rest of MythMusic pretend like all is OK. Do You have idea where issue might be ?

comment:5 Changed 6 years ago by paulh

No idea sorry.

Can you update to current clean master and get a log using mythfrontend -v libav,network,file,playback,audio --loglevel=debug maybe that will show something.

comment:6 Changed 6 years ago by warpme@…

Paul, thx You still bother Your time with this ticket. I'm scratching my head why AAC playback issue is single, post ffmpeg1.1, issue I have and only on subset of my frontends (minimyth running on ION1). All rest multimedia functions I tested on those IONs works OK. I'm attaching logs from: ION1 (broken AAC), combined FE/BE (plays OK) built with current master and launched with "-v libav,network,file,playback,audio --loglevel=debug". I also attached --logpath output for ION1 FE. Generally ION1 "hangs" on

2013-05-09 18:41:56.718117 E [8046/8103] avfDecoder audio/audiooutpututil.cpp:712 (DecodeAudio) - AOUtil: audio decode error: Operation not permitted (0)
2013-05-09 18:42:45.569511 I [8046/8046] CoreContext screensaver-x11.cpp:200 (ResetScreenSaver) - ScreenSaverX11Private: Calling xscreensaver-command -deactivate
2013-05-09 18:43:35.570273 I [8046/8046] CoreContext screensaver-x11.cpp:200 (ResetScreenSaver) - ScreenSaverX11Private: Calling xscreensaver-command -deactivate

while combined FE/BE (which plays AAC OK) continues with:

2013-05-09 18:27:20.360608 E  AOUtil: audio decode error: Operation not permitted (0)
2013-05-09 18:27:20.368236 I  AO: Pause 0
2013-05-09 18:27:20.374612 I  AO: OutputAudioLoop: Play Event
2013-05-09 18:27:26.931090 I  ShoutCast changed state to STREAMING
2013-05-09 18:27:26.931164 I  ShoutCastIODevice:  227 kb in buffer, btnm=16384/32768 state=STREAMING, len=16384
2013-05-09 18:27:31.553149 I  ShoutCastIODevice:  227 kb in buffer, btnm=   0/32768 state=STREAMING_META, len=16384

Changed 6 years ago by warpme@…

Attachment: diskless.log added

Log from ION1 FE (problematic AAC playback)

Changed 6 years ago by warpme@…

Attachment: combined.log added

Log from combined FE/BE (plays OK)

Changed 6 years ago by warpme@…

Full LOG from ION1 FE

comment:7 Changed 6 years ago by paulh

warpme, Do all AAC stations fail to play or just this one?

You can find a few AAC ones to test here http://dir.xiph.org/by_format/AAC and a few AAC+ here http://dir.xiph.org/by_format/AAC+

comment:8 Changed 6 years ago by warpme@…

Paul, for testing I tried following stations: http://kxleamfm.com:8000/kxlefm.aac http://radio-gfm.net:8000/metal_low.aac http://67-228-77-18.webnow.net.br:80/radiorock.aac All them plays ok with mythffmpeg but not playing on mythmusic.

Note: See TracTickets for help on using tickets.