The VideoLAN Forums

While playing a mms Stream from a Windows Media Server 2003 my VLC-Client (v 0.8.6e) delays about 5 Minutes to start playing the audio stream.
I
mms://e4.nc3.biz:80/fritz.montag.2

Whats wrong with this setup? Is there anything which could be improved at the Streaming Server?


this is the complete log from start to play

main debug: creating new input thread
main debug: waiting for thread completion
main debug: thread 4864 (input) created at priority 1 (input/input.c:265)
main debug: `mms://e4.nc3.biz:80/fritz.montag.2' gives access `mms' demux `' path `e4.nc3.biz:80/fritz.montag.2'
main debug: creating demux: access='mms' demux='' path='e4.nc3.biz:80/fritz.montag.2'
main debug: looking for access_demux module: 0 candidates
main warning: no access_demux module matched "mms"
main debug: creating access 'mms' path='e4.nc3.biz:80/fritz.montag.2'
main debug: looking for access2 module: 6 candidates
access_mms debug: waiting for connection...
main debug: net: connecting to e4.nc3.biz port 80
main debug: connection in progress
access_mms debug: connection(tcp) with "e4.nc3.biz:80" successful
access_mms debug: generated guid: babac001-f8e1-84fd-b5a5d05d12d29acd
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: failed to receive command (aborting)
access_mms debug: Connection closed
access_mms error: failed to send command
access_mms debug: waiting for connection...
main debug: net: connecting to e4.nc3.biz port 80
main debug: connection in progress
access_mms debug: connection(tcp) with "e4.nc3.biz:80" successful
main debug: net: connecting to '[]:0@[192.168.155.106]:7000'
main debug: looking for network module: 1 candidate
ipv6 debug: 192.168.155.106: Host or service not found
main debug: using network module "ipv6"
main debug: removing module "ipv6"
main debug: looking for network module: 1 candidate
ipv4 debug: resolving 192.168.155.106:7000...
ipv4 debug: resolving :0...
main debug: using network module "ipv4"
main debug: removing module "ipv4"
access_mms debug: connection(udp) at "192.168.155.106:7000" successful
access_mms debug: generated guid: babac001-3c20-37b6-3627bea0e06b7df4
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: cannot fill buffer
access_mms warning: failed to receive command (aborting)
access_mms debug: Connection closed
access_mms error: failed to send command
access_mms error: cannot connect to server
main debug: net: connecting to e4.nc3.biz port 80
main debug: connection in progress
access_mms debug: HTTP reply 'HTTP/1.0 200 OK'
access_mms debug: stream type = broadcast
access_mms error: cannot read data
access_mms debug: complete header size=487
access_mms debug: packet count=68834 packet size=1069
access_mms debug: starting stream
main debug: net: connecting to e4.nc3.biz port 80
main debug: connection in progress
access_mms debug: HTTP reply 'HTTP/1.0 200 OK'
access_mms debug: Content-Type: application/x-mms-framed
access_mms debug: Server: Cougar/9.01.01.3841
access_mms debug: Date: Mon, 14 Apr 2008 14:56:49 GMT
access_mms debug: Pragma: no-cache, client-id=3944332214, xResetStrm=1, features="broadcast,playlist", AccelBW=0, AccelDuration=0, Speed=1.000
access_mms debug: Cache-Control: no-cache
access_mms debug: Last-Modified: Mon, 14 Apr 2008 14:56:49 GMT
access_mms debug: Supported: com.microsoft.wm.srvppair, com.microsoft.wm.sswitch, com.microsoft.wm.predstrm, com.microsoft.wm.fastcache, com.microsoft.wm.startupprofile
access_mms debug: Connection: keep-alive
main debug: using access2 module "access_mms"
main debug: pre-buffering...
access_mms warning: packet lost ? (0 != 1)
access_mms warning: packet lost ? (12720 != 1)
main debug: received first data for our buffer
main debug: pre-buffering done 12828 bytes in 1s - 12 kbytes/s
main debug: creating demux: access='mms' demux='' path='e4.nc3.biz:80/fritz.montag.2'
main debug: looking for demux2 module: 45 candidates
asf debug: found object guid: 0x75b22630-0x668e-0x11cf-0xa6d900aa0062ce6c size:437
asf debug: read "header object" subobj:4, reserved1:1, reserved2:2
asf debug: found object guid: 0x8cabdca1-0xa947-0x11cf-0x8ee400c00c205365 size:104
asf debug: read "file properties object" file_id:0x0-0x0-0x0-0x0000000000000000 file_size:73584033 creation_date:0 data_packets_count:68834 play_duration:72002400000 send_duration:71999537992 preroll:204 flags:9 min_data_packet_size:1069 max_data_packet_size:1069 max_bitrate:81760
asf debug: found object guid: 0x5fbf03b5-0xa92e-0x11cf-0x8ee300c00c205365 size:46
asf debug: read "header extension object" reserved1:0xabd3d211-0xa9ba-0x11cf-0x8ee600c00c205365 reserved2:6 header_extension_size:0
asf debug: found object guid: 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 size:117
asf debug: read "stream Properties object" stream_type:0xf8699e40-0x5b4d-0x11cf-0xa8fd00805f5c442b error_correction_type:0x49f1a440-0x4ece-0x11d0-0xa3ac00a0c90348f6 time_offset:0 type_specific_data_length:30 error_correction_data_length:9 flags:0x1 stream_number:1
asf debug: found object guid: 0x86d15240-0x311d-0x11d0-0xa3a400a0c90348f6 size:140
asf debug: read "codec list object" reserved_guid:0x86d15241-0x311d-0x11d0-0xa3a400a0c90348f6 codec_entries_count:1
asf debug: - codec[0] audio name:"MPEG Audio Layer-3" description:"80 kbps, 44 kHz, stereo" information_length:2
asf debug: found object guid: 0x75b22636-0x668e-0x11cf-0xa6d900aa0062ce6c size:73583596
asf debug: read "data object" file_id:0x0-0x0-0x0-0x0000000000000000 total data packet:68834 reserved:257
asf debug: + 'Unknown' GUID 0x0-0x0-0x0-0x0000000000000000 size:0pos:0
asf debug: + 'Header' GUID 0x75b22630-0x668e-0x11cf-0xa6d900aa0062ce6c size:437pos:0
asf debug: | + 'File Properties' GUID 0x8cabdca1-0xa947-0x11cf-0x8ee400c00c205365 size:104pos:30
asf debug: | + 'Header Extension' GUID 0x5fbf03b5-0xa92e-0x11cf-0x8ee300c00c205365 size:46pos:134
asf debug: | + 'Stream Properties' GUID 0xb7dc0791-0xa9b7-0x11cf-0x8ee600c00c205365 size:117pos:180
asf debug: | + 'Codec List' GUID 0x86d15240-0x311d-0x11d0-0xa3a400a0c90348f6 size:140pos:297
asf debug: + 'Data' GUID 0x75b22636-0x668e-0x11cf-0xa6d900aa0062ce6c size:73583596pos:437
asf debug: found 1 streams
main debug: selecting program id=0
asf debug: added new audio stream(codec:0x55,ID:1)
main debug: using demux2 module "asf"
main debug: looking for a subtitle file in C:\Dokumente und Einstellungen\rv005\Eigene Dateien\Mucke\Ph.D\1981 Ph.D\
main debug: looking for decoder module: 28 candidates
main debug: using decoder module "mpeg_audio"
main debug: thread 4796 (decoder) created at priority 2 (input/decoder.c:159)
access_mms warning: unimplemented query in control
main debug: `mms://e4.nc3.biz:80/fritz.montag.2' successfully opened
mpeg_audio debug: MPGA channels:2 samplerate:44100 bitrate:80
main debug: looking for audio output module: 3 candidates
aout_directx debug: OpenAudio
aout_directx debug:
aout_directx debug: found device: SoundMAX Digital Audio
aout_directx debug: device supports 2 channels
aout_directx debug: device supports 1 channel
aout_directx debug: creating DirectSoundThread
main debug: thread 4788 (DirectSound Notification Thread) created at priority 15 (directx.c:372)
aout_directx debug: DirectSoundThread ready
main debug: using audio output module "aout_directx"
main debug: output 's16l' 44100 Hz Stereo frame=1 samples/4 bytes
main debug: mixer 'fl32' 44100 Hz Stereo frame=1 samples/8 bytes
main debug: filter(s) 'fl32'->'s16l' 44100 Hz->44100 Hz Stereo->Stereo
main debug: looking for audio filter module: 24 candidates
main debug: using audio filter module "float32tos16"
main debug: found a filter for the whole conversion
main debug: looking for audio mixer module: 3 candidates
main debug: using audio mixer module "float32_mixer"
main debug: input 'mpga' 44100 Hz Stereo frame=1152 samples/1053 bytes
main debug: filter(s) 'mpga'->'fl32' 44100 Hz->44100 Hz Stereo->Stereo
main debug: looking for audio filter module: 24 candidates
main debug: using audio filter module "mpgatofixed32"
main debug: found a filter for the whole conversion
main debug: filter(s) 'fl32'->'fl32' 48510 Hz->44100 Hz Stereo->Stereo
main debug: looking for audio filter module: 24 candidates
main debug: using audio filter module "bandlimited_resampler"
main debug: found a filter for the whole conversion
mpgatofixed32 debug: libmad error: bad main_data_begin pointer
mpgatofixed32 debug: libmad error: bad main_data_begin pointer
mpgatofixed32 debug: libmad error: bad main_data_begin pointer

Have you tried nightly builds?
http://nightlies.videolan.org/

See viewtopic.php?f=14&t=55896#p185275

As far as I can tell this is a problem with win32 builds 9.8a, 9.9 and current nightly builds.

Arne

All,

Note, trac has been updated. http://trac.videolan.org/vlc/ticket/2533

Changed 4 days ago by VLC_help

It isn't general network issue. Playback starts almost immediately in Windows Media Player, but not in VLC. Tested also with VLC 0.9.9 and VLC 1.0.0-pre2-20090428-2204.

I have reduced the default mms-cache value of 5200 (? from memory) ms to 300, but this seems to have no effect in 0.9.x w32 builds

Just some further testing:
Tested with 0.8.6c win32 - no problem
Tested with 0.9.9a macos - no problem
Tested with 0.9.8a win32 - problem
Tested with 0.9.9 win32 - problem
Tested with vlc-1.0.0-pre2-20090423-2203-win32 - problem

Havn't tested with linux yet but will do so today on debian - post results later. Will try to narrow down the differences between platforms.

Arne

BTW here are some mms streams you can test with.

<station name="Sydney Nova 96.9" state="NSW" custom="True" mt="audio/mpeg" id="switch1005" url="http://www.nova969.com.au/dmgcustom/Nova969.asx" />
<station name="Sydney Vega 95.3" state="NSW" custom="True" mt="audio/mpeg" id="switch1006" url="http://www.vega953.com.au/dmgcustom/vega953.asx" />
<station name="Sydney Triple M 104.9" state="NSW" custom="True" mt="audio/mpeg" id="switch1007" url="http://resources.triplem.com.au/listenf ... stream.asx" />
<station name="2day FM 104.1" state="NSW" custom="True" mt="audio/mpeg" id="switch1008" url="http://resources.2dayfm.com.au/listenfeed/2dayfm.asx" />
<station name="The Edge 96.1" state="NSW" custom="True" mt="audio/mpeg" id="switch1009" url="http://www.961.com.au/961.asx" />
<station name="Bondi FM" state="NSW" custom="True" mt="audio/mpeg" id="switch1010" url="mms://media4.shiftreload.com.au/bondifm" />
<station name="WSFM Classic Hits 101.7" state="NSW" custom="True" mt="audio/mpeg" id="switch1011" url="http://208.96.14.149/wsfm" />
<station name="2UE" state="NSW" custom="True" mt="audio/mpeg" id="switch1012" url="http://streaming.mytalk.com.au/2ue" />
<station name="2SM" state="NSW" custom="True" mt="audio/mpeg" id="switch1013" url="http://s2.viastreaming.net/7500/listen.asx" />
<station name="2KY" state="NSW" custom="True" mt="audio/mpeg" id="switch1014" url="http://www.2ky.com.au/media/2ky.asx" />
<station name="fbi 94.5 FM" state="NSW" custom="True" mt="audio/mpeg" id="switch1015" url="mms://wm9.streaming.telstra.com/UCS-wh_fbi0live1" />
<station name="3MP" state="VIC" custom="True" mt="audio/mpeg" id="switch1018" url="http://3mpstream.soundaudiosolutions.com.au/3mp.asx" />
<station name="PBS 106.7 FM" state="VIC" custom="True" mt="audio/mpeg" id="switch1019" url="http://www.pbsfm.org.au/public/ASX/3pbsfm.asx" />

Please post those URL to the trac.

I updated the ticket.

I have tested win32 build 1.0.0_RC1 today - same problem applies - approx 7-8 second delay before buffering commences. Also, it seems like the MMS Buffer in preferences has no impact on the actual buffer size - ie the buffer seems to take 5.7 seconds to fill even though I have set it to 1 second, for example.

J-B, don't know if this second issue should be treated as a separate ticket?

i Has The some problem, freebsd and win32 VLC wait approх two minutes, before start play stream from WMSEncoder.
wait two minutes, and start play so, i make tcpdump trace for cvlc -vvv http://192.168.9.33:8081 (WMS Encoder)

VLC -> WMSE GET / HTTP/1.0..Host: WMSencoder..
VLC -> WMSE Accept: */*..User-Agent: NSPlayer/7.10.0.3059..Pragma: no-cache,rate=1.000000,stream-time=0,stream-offset=0:0,request-context=1,max-duration=0..Pragma: xClientGUID={babac001-9fb8-f91c-daf83df5f86fee0a}..Connection: Close....
WMSE -> VLC HTTP/1.0 200 OK..Server: Rex/11.0.5721.5145..Cache-Control: no-cache..Pragma: no-cache..Pragma: client-id=2342587066..Pragma: features="broadcast,playlist"..Content-Type: application/vnd.ms.wms-hdr.asfv1..Content-Length: 5428..Connection: Keep-Alive....

recieve stream info, like name and codecs, and, wait two minutes before next request

VLC -> WMSE GET / HTTP/1.0..Host: WMSencoder..
VLC -> WMSE Accept: */*..User-Agent: NSPlayer/7.10.0.3059..Pragma: no-cache,rate=1.000000,request-context=2..Pragma: xPlayStrm=1..Pragma: xClientGUID={babac001-9fb8-f91c-daf83df5f86fee0a}..Pragma: stream-switch-count=2..Pragma: stream-switch-entry=ffff:1:0 ffff:2:0 ..Connection: Close....
WMSE -> VLC HTTP/1.0 200 OK..Server: Rex/11.0.5721.5145..Cache-Control: no-cache..Pragma: no-cache..Pragma: client-id=2334038997..Pragma: features="broadcast,playlist"..Content-Type: application/x-mms-framed....

and start play

so, can i use any VLC parameter for change playing string ? i marked it bold.

thanks.

Old topic I know, but I wanted to add another test URL to the bug trac list. I just downloaded version 1.0.5 for Win32 and it takes a couple of minutes to load this stream and start playing. Thanks!
mms://203.45.34.236:7011

I think I've come up a with a patch that fixes this issue (at least in my case...)

I was having the same problem, and based on the information in this thread I assumed the issue was VLC not honoring Content-Length & keep-alive. Fixing this worked for me.

http://techblog.unwiredappeal.com/2010/ ... patch.html

Thanks. I will test this on weekend if nobody tries it before.

i have just tested mms://203.45.34.236:7011 with VLC 1.0.5 goldeneye. it took me a good 4 minutes for the stream to start . Other Streams like mms://6ix.gohosting.com.au/6ix are quicker to load but still take 30 or 40 seconds. I have not tried the patch yet. Will it be submitted and rolled into the main the trunk of VLC?

Will it be submitted and rolled into the main the trunk of VLC?

IIRC already done.

I'm still getting the same issue with VLC 1.1.0 (specifically VLC Portable 1.1.0 Rev 2)

Here's the stream I'm getting the issue with:
http://202.171.158.28:8080/ch1