Login at Kodi Home

techiejohn · (This post was last modified: 2013-12-23, 15:27 by techiejohn.)

EDIT: After some troubleshooting, the stream retrieved from the URL below constantly buffers on nightly build of XBMC (while on Safari on OSX and iPhone, and "JWPlayer" play fine).

Get stream URL from: http://admin.gamaxmedia.hu/player-inside...serid=mtva
Stream URL will look like: http://cdn1.gamaxmedia.hu/streams/mtva/m...1387775123

ORIGINAL: I put together a Python script which retrieves a stream URL that's dynamically generated by the following web page: http://admin.gamaxmedia.hu/player-inside...serid=mtva

The returning HTML contains a "source" tag with "src" attribute if the User-Agent is IOS based (otherwise it loads some bootleg player with the actual stream source in JavaScript).

The stream source URL looks like: http://cdn1.gamaxmedia.hu/streams/mtva/m...1387775123

This expires after a while (so it will likely not work if you click on it now).

When it's not expired, this URL loads fine in Safari on Mavericks (and begins to play).

However, XBMC gets the following error when I try to play it using "xbmc.Player().play()":

Code:
06:13 T:140735249462032   DEBUG: CPlayerCoreFactory::GetPlayers(http://cdn1.gamaxmedia.hu/streams/mtva/mtv1live.smil/playlist.m3u8?keys=IafPUq1OJW6ne7EC7Lq5ZQ&keyt=1387775123)

06:13 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules

06:13 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules

06:13 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv

06:13 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp

06:13 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout

06:13 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp

06:13 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp

06:13 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams

06:13 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams

06:13 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp

06:13 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2

06:13 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd

06:13 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage

06:13 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf

06:13 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv

06:13 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio

06:13 T:140735249462032   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players

06:13 T:140735249462032   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)

06:13 T:140735249462032   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0

06:13 T:140735249462032   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1

06:13 T:140735249462032   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)

06:13 T:140735249462032   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players

06:13 T:140735249462032  NOTICE: DVDPlayer: Opening: http://cdn1.gamaxmedia.hu/streams/mtva/mtv1live.smil/playlist.m3u8?keys=IafPUq1OJW6ne7EC7Lq5ZQ&keyt=1387775123

06:13 T:140735249462032 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED

06:13 T:140735249462032   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec

06:13 T:140735249462032   DEBUG: LinuxRendererGL: Cleaning up GL resources

06:13 T:140735249462032   DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16

06:13 T:4607967232  NOTICE: Thread CDVDPlayer start, auto delete: false

06:13 T:4607967232  NOTICE: Creating InputStream

06:13 T:4607967232   DEBUG: CFileCache::Open - opening <streams/mtva/mtv1live.smil/playlist.m3u8> using cache

06:13 T:4607967232   DEBUG: CurlFile::Open(0x10417d3f0) http://cdn1.gamaxmedia.hu/streams/mtva/mtv1live.smil/playlist.m3u8?keys=IafPUq1OJW6ne7EC7Lq5ZQ&keyt=1387775123

06:13 T:140735249462032   DEBUG: ------ Window Init (DialogBusy.xml) ------

06:13 T:140735249462032   DEBUG: ------ Window Deinit (Pointer.xml) ------

06:13 T:140735249462032   DEBUG: ------ Window Deinit (DialogProgress.xml) ------

06:14 T:4608503808  NOTICE: Thread CFileCache start, auto delete: false

06:14 T:4608503808    INFO: CFileCache::Process - Hit eof.

06:14 T:4607967232    INFO: Auto-selecting http://cdn1.gamaxmedia.hu/streams/mtva/mtv1live.smil/chunklist_b3000000.m3u8 based on configured bandwidth.

06:14 T:4608503808   DEBUG: Thread CFileCache 4608503808 terminating

06:14 T:4607967232  NOTICE: Creating Demuxer

06:14 T:4607967232   DEBUG: ffmpeg[12A80000]: Using network protocols without global network initialization. Please use avformat_network_init(), this will become mandatory later.

06:14 T:4607967232   DEBUG: Previous line repeats 1 times.

06:14 T:4607967232   DEBUG: Error, could not open file http://cdn1.gamaxmedia.hu/streams/mtva/mtv1live.smil/chunklist_b3000000.m3u8

06:14 T:4607967232   ERROR: OpenDemuxStream - Error creating demuxer

06:14 T:4607967232  NOTICE: CDVDPlayer::OnExit()

06:14 T:4607967232  NOTICE: CDVDPlayer::OnExit() deleting input stream

06:14 T:4607967232   DEBUG: Thread CDVDPlayer 4607967232 terminating

06:14 T:140735249462032   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc

06:14 T:140735249462032   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop

06:14 T:4603514880    INFO: Scriptresult: Success

06:14 T:4603514880    INFO: Python script stopped

I'm getting the same behavior on Windows (12.2. Frodo) or Mac (with the monthly build).

Any ideas how I can get this stream to play?

techiejohn · (This post was last modified: 2013-12-23, 04:09 by techiejohn.)

UPDATE... I tried the nightly build (20131221-61b60ce and 20131220-fa344b7).

This started playing it, but it stops after a few seconds, buffers for a long time, starts, buffers etc.

Below is the log. Any suggestions on how to get it to play consistently?

Code:
38:50 T:140735249462032   DEBUG: CPlayerCoreFactory::GetPlayers(http://cdn1.gamaxmedia.hu/streams/mtva/mtv1live.smil/playlist.m3u8?keys=nql_QZ4AMmdI-29kXUElyA&keyt=1387777080)

38:50 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules

38:50 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules

38:50 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtv

38:50 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/myth/mms/udp

38:50 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout

38:50 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp

38:50 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp

38:50 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams

38:50 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams

38:50 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp

38:50 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2

38:50 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd

38:50 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage

38:50 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf

38:50 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv

38:50 T:140735249462032   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio

38:50 T:140735249462032   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players

38:50 T:140735249462032   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)

38:50 T:140735249462032   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0

38:50 T:140735249462032   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1

38:50 T:140735249462032   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)

38:50 T:140735249462032   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players

38:50 T:140735249462032  NOTICE: DVDPlayer: Opening: http://cdn1.gamaxmedia.hu/streams/mtva/mtv1live.smil/playlist.m3u8?keys=nql_QZ4AMmdI-29kXUElyA&keyt=1387777080

38:50 T:140735249462032 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED

38:50 T:140735249462032   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec

38:50 T:140735249462032   DEBUG: LinuxRendererGL: Cleaning up GL resources

38:50 T:140735249462032   DEBUG: CLinuxRendererGL::PreInit - precision of luminance 16 is 16

38:50 T:4672008192  NOTICE: Thread DVDPlayer start, auto delete: false

38:50 T:4672008192  NOTICE: Creating InputStream

38:50 T:4672008192   DEBUG: CFileCache::Open - opening <streams/mtva/mtv1live.smil/playlist.m3u8> using cache

38:50 T:4672008192   DEBUG: CurlFile::Open(0x1162d4980) http://cdn1.gamaxmedia.hu/streams/mtva/mtv1live.smil/playlist.m3u8?keys=nql_QZ4AMmdI-29kXUElyA&keyt=1387777080

38:51 T:140735249462032   DEBUG: ------ Window Init (DialogBusy.xml) ------

38:51 T:140735249462032   DEBUG: Window DialogBusy.xml was already loaded

38:51 T:140735249462032   DEBUG: Alloc resources: 0.03m

38:51 T:4705816576  NOTICE: Thread FileCache start, auto delete: false

38:51 T:4705816576    INFO: CFileCache::Process - Hit eof.

38:51 T:4672008192    INFO: Auto-selecting http://cdn1.gamaxmedia.hu/streams/mtva/mtv1live.smil/chunklist_b3000000.m3u8 based on configured bandwidth.

38:51 T:4705816576   DEBUG: Thread FileCache 4705816576 terminating

38:51 T:4672008192  NOTICE: Creating Demuxer

38:53 T:4672008192   DEBUG: ffmpeg[16793000]: [mpegts] parser not found for codec none, packets or times may be invalid.

38:53 T:140735249462032   DEBUG: ------ Window Deinit (Pointer.xml) ------

38:54 T:4672008192   DEBUG: ffmpeg[16793000]: [h264] Current profile doesn't provide more RBSP data in PPS, skipping

38:58 T:140735249462032   DEBUG: ------ Window Init (Pointer.xml) ------

38:58 T:140735249462032   DEBUG: ------ Window Deinit (Pointer.xml) ------

39:00 T:140735249462032   DEBUG: ------ Window Init (Pointer.xml) ------

39:00 T:140735249462032   DEBUG: ------ Window Deinit (Pointer.xml) ------

39:02 T:4672008192   DEBUG: ffmpeg[16793000]: [h264] Current profile doesn't provide more RBSP data in PPS, skipping

39:15 T:140735249462032   DEBUG: ------ Window Init (Pointer.xml) ------

39:15 T:140735249462032   DEBUG: ------ Window Deinit (Pointer.xml) ------

39:21 T:4672008192   DEBUG: ffmpeg[16793000]: [mpegts] PES packet size mismatch

39:21 T:4672008192   DEBUG: ffmpeg[16793000]: [mpegts] Could not find codec parameters for stream 0 (Unknown: none ([21][0][0][0] / 0x0015)): unknown codec

39:21 T:4672008192   DEBUG: ffmpeg[16793000]: [mpegts] Consider increasing the value for the 'analyzeduration' and 'probesize' options

39:21 T:4672008192   DEBUG: ffmpeg[16793000]: [mpegts] Estimating duration from bitrate, this may be inaccurate

39:21 T:4672008192   DEBUG: Open - avformat_find_stream_info starting

39:21 T:4672008192   DEBUG: ffmpeg[16793000]: [hls,applehttp] Could not find codec parameters for stream 0 (Unknown: none ([21][0][0][0] / 0x0015)): unknown codec

39:21 T:4672008192   DEBUG: ffmpeg[16793000]: [hls,applehttp] Consider increasing the value for the 'analyzeduration' and 'probesize' options

39:21 T:4672008192   DEBUG: ffmpeg[16793000]: [hls,applehttp] Estimating duration from bitrate, this may be inaccurate

39:21 T:4672008192   DEBUG: Open - av_find_stream_info finished

39:21 T:4672008192    INFO: ffmpeg[16793000]: Input #0, hls,applehttp, from 'http://cdn1.gamaxmedia.hu/streams/mtva/mtv1live.smil/chunklist_b3000000.m3u8':

39:21 T:4672008192    INFO: ffmpeg[16793000]:   Duration: 00:00:00.04, bitrate: 47 kb/s

39:21 T:4672008192    INFO: ffmpeg[16793000]:   Program 0

39:21 T:4672008192    INFO: ffmpeg[16793000]:     Metadata:

39:21 T:4672008192    INFO: ffmpeg[16793000]:       variant_bitrate : 0

39:21 T:4672008192    INFO: ffmpeg[16793000]:     Stream #0:0: Unknown: none ([21][0][0][0] / 0x0015)

39:21 T:4672008192    INFO: ffmpeg[16793000]:     Stream #0:1: Video: h264 (Constrained Baseline) ([27][0][0][0] / 0x001B), yuv420p, 1280x720 [SAR 1:1 DAR 16:9], 25 tbr, 90k tbn, 50 tbc

39:21 T:4672008192    INFO: ffmpeg[16793000]:     Stream #0:2: Audio: aac ([15][0][0][0] / 0x000F), 32000 Hz, stereo, fltp, 47 kb/s

39:21 T:4672008192   DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0

39:21 T:4672008192   DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1

39:21 T:4672008192   DEBUG: CDVDDemuxFFmpeg::AddStream(2, ...) -> 2

39:21 T:4672008192  NOTICE: Opening video stream: 1 source: 256

39:21 T:4672008192  NOTICE: Creating video codec with codec id: 28

39:21 T:4672008192   DEBUG: CDVDFactoryCodec: compiled in hardware support: VDADecoder:yes VideoToolBoxDecoder:no CrystalHD:yes AMCodec:no MediaCodec:no OpenMax:no libstagefright:no

39:21 T:4672008192   DEBUG: FactoryCodec - Video: vda- - Opening

39:21 T:4672008192    INFO: CBitstreamConverter::Open annexb to bitstream init

39:21 T:4672008192   DEBUG: FactoryCodec - Video: vda-h264 - Opened

39:21 T:4672008192  NOTICE: Creating video thread

39:21 T:4652859392  NOTICE: Thread DVDPlayerVideo start, auto delete: false

39:21 T:4652859392  NOTICE: running thread: video_thread

39:21 T:4672008192  NOTICE: Opening audio stream: 2 source: 256

39:21 T:4672008192  NOTICE: Finding audio codec for: 86018

39:21 T:4652859392   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE

39:21 T:4672008192   DEBUG: FactoryCodec - Audio: passthrough - Opening

39:21 T:4672008192   DEBUG: FactoryCodec - Audio: passthrough - Failed

39:21 T:4672008192   DEBUG: FactoryCodec - Audio: FFmpeg - Opening

39:21 T:4672008192   DEBUG: FactoryCodec - Audio: FFmpeg - Opened

39:21 T:4672008192  NOTICE: Creating audio thread

39:21 T:4691468288  NOTICE: Thread DVDPlayerAudio start, auto delete: false

39:21 T:4691468288  NOTICE: running thread: CDVDPlayerAudio::Process()

39:21 T:4672008192   DEBUG: OnPlayBackStarted : play state was 1, starting 1

39:21 T:4672008192   DEBUG: CDVDPlayer::SetCaching - caching state 3

39:21 T:4691468288   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(22676318311.111111, 1)

39:21 T:4691468288  NOTICE: Creating audio stream (codec id: 86018, channels: 2, sample rate: 32000, no pass-through)

39:21 T:4652859392   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(22676382311.111111, 0)

39:21 T:4691468288    INFO: CCoreAudioAE::MakeStream - AE_FMT_FLOAT, 32000, 32000, FL,FR

39:21 T:4652859392    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback

39:21 T:140735249462032   DEBUG: PlayFile : OpenFile succeed, play state 2

39:21 T:140735249462032   DEBUG: OnPlayBackStarted : play state was 2, starting 0

39:21 T:4481863680    INFO: CPythonInvoker(2, /Users/.../Documents/Technical/XBMC/MTV1.py): script successfully run

39:21 T:4652859392  NOTICE:  fps: 25.000000, pwidth: 1280, pheight: 720, dwidth: 1280, dheight: 720

39:21 T:4652859392   DEBUG: OutputPicture - change configuration. 1280x720. framerate: 25.00. format: YV12

39:21 T:4652859392  NOTICE: Display resolution DESKTOP : 1440x900@ 60.00 - Full Screen (16)

39:21 T:4652859392   DEBUG: CXBMCRenderManager::Configure - 3

39:21 T:4481863680    INFO: Python script stopped

39:21 T:4481863680   DEBUG: Thread LanguageInvoker 4481863680 terminating

39:21 T:140735249462032  NOTICE: Using GL_TEXTURE_2D

39:21 T:140735249462032   DEBUG: GL: Requested render method: 0

39:21 T:140735249462032   DEBUG: GL: BaseYUV2RGBGLSLShader: defines:

                                            #define XBMC_texture_rectangle 0

                                            #define XBMC_texture_rectangle_hack 0

                                            #define XBMC_STRETCH 0

                                            #define XBMC_YV12

39:21 T:140735249462032  NOTICE: GL: Selecting Single Pass YUV 2 RGB shader

39:21 T:140735249462032   DEBUG: GL: Vertex Shader compilation log:

39:21 T:140735249462032   DEBUG: GL: Vertex Shader compiled successfully

39:21 T:140735249462032   DEBUG: GL: Pixel Shader compilation log:

39:21 T:140735249462032   DEBUG: GL: Fragment Shader compiled successfully

39:21 T:140735249462032   DEBUG: GL: Vertex Shader compilation log:

39:21 T:140735249462032   DEBUG: GL: Vertex Shader compiled successfully

39:21 T:140735249462032   DEBUG: GL: Pixel Shader compilation log:

39:21 T:140735249462032   DEBUG: GL: Fragment Shader compiled successfully

39:21 T:140735249462032  NOTICE: GL: NPOT texture support detected

39:21 T:140735249462032  NOTICE: GL: Using GL_ARB_pixel_buffer_object

39:21 T:140735249462032   DEBUG: CGUIInfoManager::SetCurrentMovie(http://cdn1.gamaxmedia.hu/streams/mtva/mtv1live.smil/playlist.m3u8?keys=nql_QZ4AMmdI-29kXUElyA&keyt=1387777080)

39:21 T:4726583296  NOTICE: Thread JobWorker start, auto delete: true

39:21 T:140735249462032   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc

39:21 T:140735249462032   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay

39:21 T:140735249462032   DEBUG: Activating window ID: 12005

39:21 T:140735249462032   DEBUG: ------ Window Deinit (Home.xml) ------

39:21 T:140735249462032   DEBUG: ------ Window Init (VideoFullScreen.xml) ------

39:21 T:140735249462032    INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY

39:21 T:140735249462032   DEBUG: Load VideoFullScreen.xml: 16.07ms

39:21 T:140735249462032   DEBUG: Alloc resources: 16.97ms  (16.13 ms skin load)

39:21 T:140735249462032   DEBUG: GL: Vertex Shader compilation log:

39:21 T:140735249462032   DEBUG: GL: Vertex Shader compiled successfully

39:21 T:140735249462032   DEBUG: GL: Pixel Shader compilation log:

39:21 T:140735249462032   DEBUG: GL: Fragment Shader compiled successfully

39:21 T:140735249462032    INFO: CheckIdle - Closing session to http://cdn1.gamaxmedia.hu (easy=0x108936e00, multi=0x1163243b0)

39:21 T:4691468288   DEBUG: CCoreAudioHardware::ResetAudioDevices resetting our devices to LPCM

39:21 T:4691468288   DEBUG: CCoreAudioHardware::ResetAudioDevices 1 streams for device AirPlay

39:21 T:4691468288   DEBUG: CCoreAudioStream::Open: Opened stream 0x0028.

39:21 T:4691468288   DEBUG: CCoreAudioStream::Close: Closed stream 0x0028.

39:21 T:4691468288   DEBUG: CCoreAudioHardware::ResetAudioDevices 1 streams for device Built-in Output

39:21 T:4691468288   DEBUG: CCoreAudioStream::Open: Opened stream 0x0030.

39:21 T:4691468288   DEBUG: CCoreAudioStream::Close: Closed stream 0x0030.

39:21 T:4691468288   DEBUG: CCoreAudioHardware::FindAudioDevice: Returning default device [0x002f].

39:21 T:4691468288   ERROR: CCoreAudioDevice::SupportsMixing: Device mixing support : 'No'.

39:21 T:4691468288   DEBUG: CCoreAudioGraph::CreateMixMap: Source Stream Layout: [Left] [Right]

39:21 T:4691468288   DEBUG: CCoreAudioGraph::CreateMixMap: User-Configured Speaker Layout: [Left] [Right]

39:21 T:4691468288   DEBUG: CCoreAudioGraph::CreateMixMap: Output Device Layout: [Unknown] [Unknown]

39:21 T:4691468288   DEBUG: CCoreAudioMixMap::CreateMap: No pre-defined mapping from 2 to 2 channels, building diagonal matrix.

39:21 T:4691468288   DEBUG: CCoreAudioMixMap::Rebuild 0 = 0.000000

39:21 T:4691468288   DEBUG: CCoreAudioMixMap::Rebuild 1 = 0.000000

39:21 T:4691468288   DEBUG: CCoreAudioMixMap::SetMixingMatrix channel 0 = [1, 0, ]

39:21 T:4691468288   DEBUG: CCoreAudioMixMap::SetMixingMatrix channel 1 = [0, 1, ]

39:21 T:4691468288   DEBUG: CCoreAudioGraph::Open: Mixer Output Format: 2 channels, 32.0 kHz, 32 bits, 4 bytes per frame

39:21 T:4691468288   DEBUG: CCoreAudioGraph::Open: audioUnit, Input Stream Format  [lpcm] Mixable Non-Interleaved 2 Channel 32-bit Floating Point LE (32000Hz)

39:21 T:4691468288   DEBUG: CCoreAudioGraph::Open: audioUnit, Output Stream Format [lpcm] Mixable Non-Interleaved 2 Channel 32-bit Floating Point LE (44100Hz)

39:21 T:4691468288   DEBUG: CCoreAudioGraph::Open: mixerUnit, Input Stream Format  [lpcm] Mixable Non-Interleaved 2 Channel 32-bit Floating Point LE (32000Hz)

39:21 T:4691468288   DEBUG: CCoreAudioGraph::Open: mixerUnit, Output Stream Format [lpcm] Mixable Non-Interleaved 2 Channel 32-bit Floating Point LE (32000Hz)

39:21 T:4691468288   DEBUG: CCoreAudioGraph::Open: inputUnit, Input Stream Format  [lpcm] Mixable Interleaved 2 Channel 32-bit Floating Point LE (32000Hz)

39:21 T:4691468288   DEBUG: CCoreAudioGraph::Open: inputUnit, Output Stream Format [lpcm] Mixable Non-Interleaved 2 Channel 32-bit Floating Point LE (32000Hz)

39:21 T:4691468288    INFO: CCoreAudioAE::Initialize:

39:21 T:4691468288    INFO:   Output Device : default

39:21 T:4691468288    INFO:   Sample Rate   : 32000

39:21 T:4691468288    INFO:   Sample Format : AE_FMT_FLOAT

39:21 T:4691468288    INFO:   Channel Count : 2

39:21 T:4691468288    INFO:   Channel Layout: FL,FR

39:21 T:4691468288    INFO:   Frame Size    : 8

39:21 T:4691468288    INFO:   Volume Level  : 1.000000

39:21 T:4691468288    INFO:   Passthrough   : 0

39:21 T:4691468288    INFO:   Transcode     : 0

39:21 T:4691468288   DEBUG: AERemap: Downmix normalization is enabled

39:21 T:4691468288   DEBUG: Previous line repeats 3 times.

39:21 T:4691468288   DEBUG: CCoreAudioMixMap::SetMixingMatrix channel 0 = [1, 0, ]

39:21 T:4691468288   DEBUG: CCoreAudioMixMap::SetMixingMatrix channel 1 = [0, 1, ]

39:21 T:4691468288   DEBUG: CCoreAudioGraph::Open: Mixer Output Format: 2 channels, 32.0 kHz, 32 bits, 4 bytes per frame

39:21 T:4691468288   DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback

39:21 T:140735249462032   DEBUG: ------ Window Deinit (DialogBusy.xml) ------

39:22 T:4672008192   DEBUG: ffmpeg[16793000]: skipping 2 segments ahead, expired from playlists

39:22 T:4672008192   DEBUG: CDVDPlayer::SetCaching - caching state 0

39:22 T:4691468288   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:22676318565.725109, should be:22676350311.111111, error:31745.386002

39:22 T:4672008192   DEBUG: CDVDPlayer::HandleMessages - player started 2

39:22 T:4672008192   DEBUG: CDVDPlayer::HandleMessages - player started 1

39:22 T:4672008192   DEBUG: CDVDPlayer::CheckContinuity - resync forward :1, prev:22680414311.111111, curr:22706366311.111115, diff:25920000.000004

39:23 T:4672008192   DEBUG: ffmpeg[16793000]: [NULL] Current profile doesn't provide more RBSP data in PPS, skipping

39:23 T:4672008192   DEBUG: CheckPlayerInit - too far to decode before finishing seek

39:23 T:4672008192   DEBUG: ffmpeg[16793000]: [NULL] Current profile doesn't provide more RBSP data in PPS, skipping

39:24 T:140735249462032   DEBUG: ------ Window Init (VideoOSD.xml) ------

39:24 T:140735249462032    INFO: Loading skin file: VideoOSD.xml, load type: KEEP_IN_MEMORY

39:24 T:140735249462032   DEBUG: Load VideoOSD.xml: 23.48ms

39:24 T:140735249462032   DEBUG: Alloc resources: 27.82ms  (23.53 ms skin load)

39:24 T:140735249462032   DEBUG: ------ Window Init (Pointer.xml) ------

techiejohn · (This post was last modified: 2013-12-23, 04:05 by techiejohn.)

One other thing I noticed is that if I load this stream in Safari the quality is rather low. However the nightly build of XBMC appears much better (I'm not sure if this is a result of scaling). I'm wondering if the pauses are bandwidth related (there are no pauses when playing in Safari). If so, what is XBMC doing to obtain a higher quality stream and is there a way to force lower resolution?

techiejohn · (This post was last modified: 2013-12-23, 05:15 by techiejohn.)

More troubleshooting (and learning as I go)... so the playlist.m3u8 returns the different available bit rates:

Code:
#EXTM3U

#EXT-X-VERSION:3

#EXT-X-STREAM-INF:PROGRAM-ID=1,BANDWIDTH=400000

chunklist_b400000.m3u8

#EXT-X-STREAM-INF:PROGRAM-ID=1,BANDWIDTH=800000

chunklist_b800000.m3u8

#EXT-X-STREAM-INF:PROGRAM-ID=1,BANDWIDTH=1200000

chunklist_b1200000.m3u8

#EXT-X-STREAM-INF:PROGRAM-ID=1,BANDWIDTH=1600000

chunklist_b1600000.m3u8

#EXT-X-STREAM-INF:PROGRAM-ID=1,BANDWIDTH=3000000

chunklist_b3000000.m3u8

But even if I specifically use the lowest rate ("chunklist_b400000.m3u8") XBMC will stop to buffer, while Safari (QuickTime) and the bootleg "JWPlayer" will not.

techiejohn · 2013-12-23, 15:16

Here's the log of what happens just before and after buffering...

Code:
13:33 T:4674822144   DEBUG: CPullupCorrection: pattern lost on diff 0.000000

13:34 T:4675813376 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

13:34 T:4674822144 WARNING: Previous line repeats 2 times.

13:34 T:4674822144 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available

13:35 T:4675813376 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

13:35 T:4675813376 WARNING: Previous line repeats 3 times.

13:35 T:4675813376   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:141947866.645116, should be:141744000.111112, error:-203866.534004

13:35 T:4675813376 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

13:36 T:4453261312   DEBUG: CDVDPlayer::SetCaching - caching state 1

13:36 T:4453261312   DEBUG: CDVDPlayer::HandleMessages - player started 2

13:36 T:4453261312   DEBUG: CDVDPlayer::HandleMessages - player started 1

13:36 T:140735249462032   DEBUG: ------ Window Init (DialogSeekBar.xml) ------

13:36 T:140735249462032   DEBUG: Window DialogSeekBar.xml was already loaded

13:36 T:140735249462032   DEBUG: Alloc resources: 0.17m

13:37 T:4453261312   DEBUG: ffmpeg[96F6000]: [NULL] Current profile doesn't provide more RBSP data in PPS, skipping

13:54 T:4453261312   DEBUG: Previous line repeats 3 times.

13:54 T:4453261312   DEBUG: CDVDPlayer::SetCaching - caching state 4

13:54 T:4675813376   DEBUG: CDVDPlayerAudio:: Discontinuity1 - was:142123133.576112, should be:141816972.878109, error:-306160.698003

13:54 T:4453261312   DEBUG: CDVDPlayer::SetCaching - caching state 0

13:55 T:140735249462032   DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------

13:56 T:4453261312   DEBUG: ffmpeg[96F6000]: [http] Invalid cookie found, no value, path or domain specified

13:56 T:4453261312   DEBUG: Previous line repeats 1 times.

13:56 T:4453261312   DEBUG: ffmpeg[96F6000]: skipping 1 segments ahead, expired from playlists

13:56 T:4453261312   DEBUG: ffmpeg[96F6000]: [http] Invalid cookie found, no value, path or domain specified

13:56 T:4453261312   DEBUG: Previous line repeats 1 times.

13:56 T:4453261312   DEBUG: CDVDPlayer::CheckContinuity - resync forward :1, prev:150064000.111111, curr:160080000.111115, diff:9976000.000003

13:57 T:4453261312   DEBUG: ffmpeg[96F6000]: [NULL] Current profile doesn't provide more RBSP data in PPS, skipping

13:57 T:4674822144   DEBUG: CPullupCorrection: detected pattern of length 1: 40000.00, frameduration: 40000.000000

14:02 T:4453261312   DEBUG: ffmpeg[96F6000]: [NULL] Current profile doesn't provide more RBSP data in PPS, skipping

14:02 T:4674822144   DEBUG: CPullupCorrection: pattern lost on diff -9736000.000005

14:06 T:4675813376 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

14:06 T:4453261312   DEBUG: ffmpeg[96F6000]: [NULL] Current profile doesn't provide more RBSP data in PPS, skipping

14:06 T:4675813376 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

14:07 T:4674822144 WARNING: Previous line repeats 1 times.

14:07 T:4674822144 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available

14:07 T:4675813376 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

14:07 T:4674822144 WARNING: Previous line repeats 1 times.

14:07 T:4674822144 WARNING: CDVDMessageQueue(video)::Get - asked for new data packet, with nothing available

14:07 T:4675813376 WARNING: CDVDMessageQueue(audio)::Get - asked for new data packet, with nothing available

14:07 T:4674822144    INFO: CDVDPlayerVideo - Stillframe detected, switching to forced 25.000000 fps

14:07 T:4453261312   DEBUG: CDVDPlayer::SetCaching - caching state 1

14:07 T:140735249462032   DEBUG: ------ Window Init (DialogSeekBar.xml) ------

14:07 T:140735249462032   DEBUG: Window DialogSeekBar.xml was already loaded

14:07 T:140735249462032   DEBUG: Alloc resources: 0.13m

14:08 T:4453261312   DEBUG: CDVDPlayer::HandleMessages - player started 1

14:08 T:4453261312   DEBUG: CDVDPlayer::HandleMessages - player started 2

Anyone, any suggestions?