Opened 11 years ago
Closed 11 years ago
Last modified 11 years ago
#12242 closed Bug Report - General (fixed)
After FFmpeg sync to 2.3.1 some radio stations aren't playing
| Reported by: | Owned by: | paulh | |
|---|---|---|---|
| Priority: | minor | Milestone: | 0.28 |
| Component: | Plugin - MythMusic | Version: | Master Head |
| Severity: | medium | Keywords: | AAC FFMpeg |
| Cc: | Ticket locked: | no |
Description (last modified by )
After FFmpeg sync to 2.3.1 some radio stations stopped playing in MythMusic stream. Example is http://87.98.180.164:8500/
FE log looks following:
2014-08-11 15:31:12.562085 I Setup Interrupt handler 2014-08-11 15:31:12.562230 I Setup Terminated handler 2014-08-11 15:31:12.562260 I Setup Segmentation fault handler 2014-08-11 15:31:12.562289 I Setup Aborted handler 2014-08-11 15:31:12.562318 I Setup Bus error handler 2014-08-11 15:31:12.562348 I Setup Floating point exception handler 2014-08-11 15:31:12.562378 I Setup Illegal instruction handler 2014-08-11 15:31:12.562412 I Setup Real-time signal 0 handler 2014-08-11 15:31:12.562449 I Setup User defined signal 1 handler 2014-08-11 15:31:12.562477 I Setup User defined signal 2 handler 2014-08-11 15:31:12.563095 C mythfrontend version: master [v0.28-pre-2107-g554bcd6-v7.0] www.mythtv.org 2014-08-11 15:31:12.563133 C Qt version: compile: 4.8.5, runtime: 4.8.5 2014-08-11 15:31:12.563148 N Enabled verbose msgs: general media playback audio libav 2014-08-11 15:31:12.563221 N Setting Log Level to LOG_INFO 2014-08-11 15:31:12.564479 N Using runtime prefix = /usr 2014-08-11 15:31:12.564503 N Using configuration directory = /home/minimyth/.mythtv 2014-08-11 15:31:12.564905 I Assumed character encoding: en_US.UTF-8 2014-08-11 15:31:12.565025 I Added logging to the console 2014-08-11 15:31:12.567635 N Empty LocalHostName. 2014-08-11 15:31:12.567678 I Using localhost value of FE-Devel 2014-08-11 15:31:12.567813 I Testing network connectivity to \'192.168.1.221\' 2014-08-11 15:31:12.568759 I Starting IO manager (read) 2014-08-11 15:31:12.568985 I Starting process signal handler 2014-08-11 15:31:12.569098 I Starting IO manager (write) 2014-08-11 15:31:12.569035 I Starting process manager 2014-08-11 15:31:12.726114 N Setting QT default locale to pl_US 2014-08-11 15:31:12.726194 I Current locale pl_US 2014-08-11 15:31:12.726393 E No locale defaults file for pl_US, skipping 2014-08-11 15:31:12.780338 I ScreenSaverX11Private: XScreenSaver support enabled 2014-08-11 15:31:12.782026 I ScreenSaverX11Private: DPMS is disabled. 2014-08-11 15:31:12.806652 N Desktop video mode: 1280x1024 60.020 Hz 2014-08-11 15:31:12.908750 I max_width: 1400 max_height: 1050 2014-08-11 15:31:12.925351 I Listening on TCP 127.0.0.1:6547 2014-08-11 15:31:12.925922 I Listening on TCP 192.168.1.130:6547 2014-08-11 15:31:14.081686 I Loading pl translation for module mythfrontend 2014-08-11 15:31:14.100584 I LIRC: Successfully initialized \'/dev/lircd\' using \'/home/minimyth/.mythtv/lircrc\' config 2014-08-11 15:31:14.100727 I UDPListener: Enabling 2014-08-11 15:31:14.102452 I Binding to UDP 127.0.0.1:6948 2014-08-11 15:31:14.102619 I Binding to UDP 192.168.1.130:6948 2014-08-11 15:31:14.102741 I Binding to UDP 192.168.1.255:6948 2014-08-11 15:31:14.174203 I Using Frameless Window 2014-08-11 15:31:14.174220 I Using Full Screen Window 2014-08-11 15:31:14.181990 I UI Screen Resolution: 1280 x 1024 2014-08-11 15:31:14.282701 I OpenGL: Sync to VBlank is enabled (good!) 2014-08-11 15:31:14.394848 I Trying the OpenGL 1.2 render 2014-08-11 15:31:14.411785 I OpenGL1: Fragment program support available 2014-08-11 15:31:14.411938 I OpenGL: OpenGL vendor : NVIDIA Corporation 2014-08-11 15:31:14.411953 I OpenGL: OpenGL renderer: ION/integrated/SSE2 2014-08-11 15:31:14.411966 I OpenGL: OpenGL version : 3.3.0 NVIDIA 340.24 2014-08-11 15:31:14.411987 I OpenGL: Max texture size: 8192 x 8192 2014-08-11 15:31:14.412001 I OpenGL: Max texture units: 4 2014-08-11 15:31:14.412089 I OpenGL: Direct rendering: Yes 2014-08-11 15:31:14.412101 I OpenGL: PixelBufferObject support available 2014-08-11 15:31:14.412112 I OpenGL: Initialised MythRenderOpenGL 2014-08-11 15:31:16.778986 I MythCoreContext: Connecting to backend server: 192.168.1.221:6543 (try 1 of 1) 2014-08-11 15:31:16.786972 I Using protocol version 83 2014-08-11 15:31:17.133861 I MythUIWebBrowser: Loading css from - file:///usr/share/mythtv/themes/default/htmls/mythbrowser.css 2014-08-11 15:31:17.137071 I MythUIWebBrowser: enabling plugins 2014-08-11 15:31:17.201906 I RAOP Device: Created RAOP device objects. 2014-08-11 15:31:17.203470 I AirPlay: Created airplay objects. 2014-08-11 15:31:17.203530 I Listening on TCP 127.0.0.1:5000 2014-08-11 15:31:17.203764 I Listening on TCP 192.168.1.130:5000 2014-08-11 15:31:17.203831 I RAOP Device: Listening for connections on port 5000 2014-08-11 15:31:17.203930 I Listening on TCP 127.0.0.1:5100 2014-08-11 15:31:17.204272 I Listening on TCP 192.168.1.130:5100 2014-08-11 15:31:17.208904 I Registering service 3a4b52674d6b@MythTV on FE-Devel._raop._tcp port 5000 TXT tp=UDPsm=falsesv=falseek=1et=0,1cn=0,1ch=2ss=16sr=44100pw=falsevn=3 txtvers=1md=0,1,2vs=115.2da=trueam=MythTV,1 2014-08-11 15:31:17.210951 I Current MythTV Schema Version (DBSchemaVer): 1328 2014-08-11 15:31:17.213156 I VDP: decoder<->render support: ffmpeg null opengl opengl-lite vdpau nullvdpau nullvaapi openglvaapi 2014-08-11 15:31:17.213195 I VDP: decoder<->render support: vdpau vdpau nullvdpau 2014-08-11 15:31:17.213215 I VDP: decoder<->render support: vaapi nullvaapi openglvaapi 2014-08-11 15:31:17.219479 N VDP: Ignoring profile item 710 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi)) 2014-08-11 15:31:17.219645 N VDP: Ignoring profile item 711 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi)) 2014-08-11 15:31:17.219775 N VDP: Ignoring profile item 712 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi)) 2014-08-11 15:31:17.219900 N VDP: Ignoring profile item 713 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi)) 2014-08-11 15:31:17.223954 N VDP: Ignoring profile item 714 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi)) 2014-08-11 15:31:17.224091 N VDP: Ignoring profile item 715 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi)) 2014-08-11 15:31:17.224219 N VDP: Ignoring profile item 716 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi)) 2014-08-11 15:31:17.224345 N VDP: Ignoring profile item 717 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi)) 2014-08-11 15:31:17.231210 N VDP: Ignoring profile item 718 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi)) 2014-08-11 15:31:17.231346 N VDP: Ignoring profile item 719 (renderer xv-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi)) 2014-08-11 15:31:17.231473 N VDP: Ignoring profile item 720 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi)) 2014-08-11 15:31:17.231596 N VDP: Ignoring profile item 721 (renderer quartz-blit is not supported w/decoder ffmpeg (supported: null,opengl,opengl-lite,vdpau,nullvdpau,nullvaapi,openglvaapi)) 2014-08-11 15:31:17.485446 W Key Alt+V is already bound to a jump point. 2014-08-11 15:31:17.645148 N Registering Internal as a media playback plugin. 2014-08-11 15:31:17.646854 N Creating MediaMonitor 2014-08-11 15:31:17.646870 I IgnoreDevices= 2014-08-11 15:31:17.708198 I MMUnix::AddDevice() - Added /dev/sr0 2014-08-11 15:31:17.709528 I Initial device list... /dev/sr0 (Slimtype DVD A DS8A3S) 2014-08-11 15:31:17.709573 I Registering \'MythDVD DVD Media Handler\' as a media handler for MEDIATYPE_DVD 2014-08-11 15:31:17.723261 N Registering WebBrowser as a media playback plugin. 2014-08-11 15:31:17.723502 I Loading pl translation for module mythbrowser 2014-08-11 15:31:17.760958 I Registering \'MythGallery Media Handler 1/2\' as a media handler for MEDIATYPE_DATA 2014-08-11 15:31:17.761216 I Registering \'MythGallery Media Handler 2/2\' as a media handler for MEDIATYPE_MGALLERY, ext(bmp,gif,ico,jpeg,jpg,mng,pbm,pgm,png,ppm,svg,svgz,tga,tif,tiff,xbm,xpm) 2014-08-11 15:31:17.761462 I Loading pl translation for module mythgallery 2014-08-11 15:31:17.773638 I Loading pl translation for module mythgame 2014-08-11 15:31:17.789691 I Current MythMusic Schema Version (MusicDBSchemaVer): 1022 2014-08-11 15:31:17.816984 I Registering \'MythMusic Media Handler 1/2\' as a media handler for MEDIATYPE_MIXED 2014-08-11 15:31:17.817016 I Registering \'MythMusic Media Handler 2/2\' as a media handler for MEDIATYPE_MMUSIC, ext(mp3,mp2,ogg,oga,flac,wma,wav,ac3,oma,omg,atp,ra,dts,aac,m4a,aa3,tta,mka,aiff,swa,wv) 2014-08-11 15:31:17.826163 I Loading pl translation for module mythmusic 2014-08-11 15:31:17.831833 I Loading pl translation for module mythnetvision 2014-08-11 15:31:17.839647 I Loading pl translation for module mythnews 2014-08-11 15:31:17.849562 I Loading pl translation for module mythweather 2014-08-11 15:31:17.855809 I Loading pl translation for module mythzoneminder 2014-08-11 15:31:17.856262 N Starting MediaMonitor 2014-08-11 15:31:17.859011 I Listening on TCP 127.0.0.1:6546 2014-08-11 15:31:17.859293 I Listening on TCP 192.168.1.130:6546 2014-08-11 15:31:18.015442 N Found mainmenu.xml for theme \'Default\' 2014-08-11 15:31:18.018800 I Registering HouseKeeperTask \'HardwareProfiler\'. 2014-08-11 15:31:18.030334 I Starting HouseKeeper. 2014-08-11 15:31:18.105269 I Bonjour: Service registration complete: name \'MythTV on FE-Devel\' type \'_airplay._tcp.\' domain: \'local.\' 2014-08-11 15:31:18.116383 I Bonjour: Service registration complete: name \'3a4b52674d6b@MythTV on FE-Devel\' type \'_raop._tcp.\' domain: \'local.\' 2014-08-11 15:31:20.380465 I ScreenSaverX11Private: Calling xscreensaver-command -deactivate 2014-08-11 15:31:20.472813 I Bonjour: Service registration complete: name \'Mythfrontend on FE-Devel\' type \'_mythfrontend._tcp.\' domain: \'local.\' 2014-08-11 15:31:27.784269 I ALSA: OpenDevice sysdefault:CARD=NVidia 2014-08-11 15:31:27.801258 I AOS: Sample rate 48000 is supported 2014-08-11 15:31:27.801338 I AOS: Format signed 24 bit MSB is supported 2014-08-11 15:31:27.801367 I AOS: Format signed 32 bit is supported 2014-08-11 15:31:27.801432 I AOS: 2 channel(s) are supported 2014-08-11 15:31:27.832383 I AOBase: Killing AudioOutputDSP 2014-08-11 15:31:27.832520 I AOBase: Original codec was NONE, signed 16 bit, 44 kHz, 2 channels 2014-08-11 15:31:27.832565 I AOBase: enc(0), passthru(0), features () configured_channels(2), 2 channels supported(1) max_channels(2) 2014-08-11 15:31:27.833736 I AOBase: Resampling from 44 kHz to 48 kHz with quality medium 2014-08-11 15:31:27.833999 I AOBase: Resampler allocating 17840 2014-08-11 15:31:27.834057 I AOBase: Audio processing enabled 2014-08-11 15:31:27.834097 I AOBase: Opening audio device \'sysdefault:CARD=NVidia\' ch 2(2) sr 48000 sf signed 32 bit reenc 0 2014-08-11 15:31:27.834115 I ALSA: OpenDevice sysdefault:CARD=NVidia 2014-08-11 15:31:27.842677 I ALSA: SetParameters(format=10, channels=2, rate=48000, buffer_time=500000, period_time=4) 2014-08-11 15:31:27.843436 I ALSA: Buffer size range from 2048 to 16384 2014-08-11 15:31:27.843457 I ALSA: Period size range from 1024 to 1024 2014-08-11 15:31:27.843568 W ALSA: Requested 500000us got 341333 buffer time 2014-08-11 15:31:27.843859 I ALSA: Hardware audio buffer cur: 512 need: 768 max allowed: 32768 2014-08-11 15:31:27.843935 W ALSA: Try to manually increase audio buffer with: echo 768 | sudo tee /proc/asound/card0/pcm0p/sub0/prealloc 2014-08-11 15:31:27.843980 I ALSA: Buffer time = 341333 us 2014-08-11 15:31:27.844033 I ALSA: Period time = 16 periods 2014-08-11 15:31:27.844281 I ALSA: Buffer size = 16384 | Period size = 1024 2014-08-11 15:31:27.846651 I ALSA: found playback control PCM on mixer device default 2014-08-11 15:31:27.846758 I ALSA: mixer volume range on mixer device default/PCM - min 0, max 255, range 255 2014-08-11 15:31:27.846775 I ALSA: mixer device default/PCM set up successfully 2014-08-11 15:31:27.847936 I ALSA: channel 0 volume set 80 => 204 2014-08-11 15:31:27.847960 I ALSA: channel 1 volume set 80 => 204 2014-08-11 15:31:27.847978 I AOBase: Audio fragment size: 4096 2014-08-11 15:31:27.848083 I ALSA: get volume channel 0: 80 2014-08-11 15:31:27.848115 I ALSA: channel 0 volume set 80 => 204 2014-08-11 15:31:27.848133 I ALSA: channel 1 volume set 80 => 204 2014-08-11 15:31:27.848173 I AOBase: Audio Stretch Factor: 1 2014-08-11 15:31:27.848282 I AOBase: Ending Reconfigure() 2014-08-11 15:31:27.849190 I AOBase: kickoffOutputAudioLoop: pid = 8367 2014-08-11 15:31:27.849230 I AOBase: OutputAudioLoop: Play Event 2014-08-11 15:31:28.174109 I Track 0 = http://204.45.15.82:10204/ 2014-08-11 15:31:28.174200 I Now playing \'http://204.45.15.82:10204/\' 2014-08-11 15:31:28.174261 I DecoderIOFactoryShoutCast http://204.45.15.82:10204/ 2014-08-11 15:31:28.174532 I ShoutCast changed state to RESOLVING 2014-08-11 15:31:28.174751 I ShoutCast changed state to CONNECTING 2014-08-11 15:31:28.176742 N Suspending idle timer 2014-08-11 15:31:28.338514 I ShoutCast changed state to CONNECTED 2014-08-11 15:31:28.338863 I ShoutCast changed state to READING_HEADER 2014-08-11 15:31:28.499211 I ShoutCast changed state to STOPPED 2014-08-11 15:31:28.499262 I ShoutCast changed state to STOPPED 2014-08-11 15:31:30.595714 I DecoderHandler: Stopping decoder 2014-08-11 15:31:30.595999 I Track 0 = http://87.98.180.164:8500/ 2014-08-11 15:31:30.596047 I Now playing \'http://87.98.180.164:8500/\' 2014-08-11 15:31:30.596089 I DecoderIOFactoryShoutCast http://87.98.180.164:8500/ 2014-08-11 15:31:30.596210 I ShoutCast changed state to RESOLVING 2014-08-11 15:31:30.596356 I ShoutCast changed state to CONNECTING 2014-08-11 15:31:30.596611 N Suspending idle timer 2014-08-11 15:31:30.628362 I ShoutCast changed state to CONNECTED 2014-08-11 15:31:30.628490 I ShoutCast changed state to READING_HEADER 2014-08-11 15:31:30.673957 I Playing 4CLUBBERS (lounge chillout nu jazz smooth) at 32 kbps 2014-08-11 15:31:30.673981 I ShoutCast changed state to PLAYING 2014-08-11 15:31:30.674080 I ShoutCast changed state to STREAMING 2014-08-11 15:31:31.433428 I contents \'audio/aacp\' 2014-08-11 15:31:31.433925 I decoder handler is ready, decoding http://87.98.180.164:8500/ 2014-08-11 15:31:31.434511 I AOBase: Pause 1 2014-08-11 15:31:31.434920 I ShoutCast changed state to STREAMING_META 2014-08-11 15:31:31.443074 E Cannot initialise decoder for stream 2014-08-11 15:31:31.443202 E Decoder Error: Could not identify the stream type in avfDecoder::initialize 2014-08-11 15:31:31.443419 I AOBase: OutputAudioLoop: audio paused 2014-08-11 15:31:31.443446 I DecoderHandler: Stopping decoder 2014-08-11 15:31:31.653884 I Track 0 = http://204.45.15.82:10204/ 2014-08-11 15:31:31.653940 I Now playing \'http://204.45.15.82:10204/\' 2014-08-11 15:31:31.653980 I DecoderIOFactoryShoutCast http://204.45.15.82:10204/ 2014-08-11 15:31:31.654115 I ShoutCast changed state to RESOLVING 2014-08-11 15:31:31.654251 I ShoutCast changed state to CONNECTING 2014-08-11 15:31:31.660084 N Suspending idle timer 2014-08-11 15:31:31.814766 I ShoutCast changed state to CONNECTED 2014-08-11 15:31:31.814889 I ShoutCast changed state to READING_HEADER 2014-08-11 15:31:31.986252 I ShoutCast changed state to STOPPED 2014-08-11 15:31:31.986302 I ShoutCast changed state to STOPPED 2014-08-11 15:31:36.252736 I DecoderHandler: Stopping decoder 2014-08-11 15:31:36.252916 I Track 0 = http://87.98.180.164:8500/ 2014-08-11 15:31:36.252963 I Now playing \'http://87.98.180.164:8500/\' 2014-08-11 15:31:36.253006 I DecoderIOFactoryShoutCast http://87.98.180.164:8500/ 2014-08-11 15:31:36.253129 I ShoutCast changed state to RESOLVING 2014-08-11 15:31:36.253276 I ShoutCast changed state to CONNECTING 2014-08-11 15:31:36.253578 N Suspending idle timer 2014-08-11 15:31:36.284473 I ShoutCast changed state to CONNECTED 2014-08-11 15:31:36.284603 I ShoutCast changed state to READING_HEADER 2014-08-11 15:31:36.331779 I Playing 4CLUBBERS (lounge chillout nu jazz smooth) at 32 kbps 2014-08-11 15:31:36.331807 I ShoutCast changed state to PLAYING 2014-08-11 15:31:36.331868 I ShoutCast changed state to STREAMING 2014-08-11 15:31:37.090116 I contents \'audio/aacp\' 2014-08-11 15:31:37.090625 I decoder handler is ready, decoding http://87.98.180.164:8500/ 2014-08-11 15:31:37.090673 I AOBase: Pause 1 2014-08-11 15:31:37.090861 I ShoutCast changed state to STREAMING_META 2014-08-11 15:31:37.097592 E Cannot initialise decoder for stream 2014-08-11 15:31:37.097723 E Decoder Error: Could not identify the stream type in avfDecoder::initialize 2014-08-11 15:31:37.097846 I DecoderHandler: Stopping decoder 2014-08-11 15:31:37.100362 I AOBase: OutputAudioLoop: audio paused 2014-08-11 15:31:37.310739 I Track 0 = http://204.45.15.82:10204/ 2014-08-11 15:31:37.310803 I Now playing \'http://204.45.15.82:10204/\' 2014-08-11 15:31:37.310847 I DecoderIOFactoryShoutCast http://204.45.15.82:10204/ 2014-08-11 15:31:37.311008 I ShoutCast changed state to RESOLVING 2014-08-11 15:31:37.311167 I ShoutCast changed state to CONNECTING 2014-08-11 15:31:37.316717 N Suspending idle timer 2014-08-11 15:31:37.471699 I ShoutCast changed state to CONNECTED 2014-08-11 15:31:37.471818 I ShoutCast changed state to READING_HEADER 2014-08-11 15:31:37.642550 I ShoutCast changed state to STOPPED 2014-08-11 15:31:37.642600 I ShoutCast changed state to STOPPED
Change History (8)
comment:1 Changed 11 years ago by
| Component: | MythTV - General → Plugin - MythMusic |
|---|---|
| Description: | modified (diff) |
| Owner: | set to paulh |
comment:2 Changed 11 years ago by
comment:3 Changed 11 years ago by
When you had this stream working was there a long delay, 20 or 30 seconds sometimes, after a track change for the metadata to change?
Just experimenting using ffmpeg's protocol handling for ice/shoutcast streams and this stream plays fine but the metadata is slow to update.
comment:4 Changed 11 years ago by
Before ffmpeg sync to 2.3.1 all was working as expected (no any delay between new track playback and new corresponding metadata change). If You believe it will be helpful - I can test alternative (ice/shoutcast) handling for this radio on my system.
comment:5 Changed 11 years ago by
| Resolution: | → fixed |
|---|---|
| Status: | new → closed |
comment:6 Changed 11 years ago by
| Milestone: | unknown → 0.28 |
|---|
Note: See
TracTickets for help on using
tickets.
I can also reproduce this with some radio stations. It looks like probing for the format fails most of the time for some stations but does succeed occasionally.
I might see if using the ffmpeg protocol handling for everything improves things since it now handles shoutcast natively and we would get support for stations using MMS (Microsoft Media Server) protocol over TCP and HTTP for free.