Startdelay while Playing mms Streams

[Frederik_Berlin]

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

[VLC_help]

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

[ahansen]

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

[ahansen]

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

[ahansen]

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" />

[Jean-Baptiste Kempf]

Please post those URL to the trac.

[VLC_help]

I updated the ticket.

[ahansen]

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?

[carver]

i Has The some problem, freebsd and win32 VLC wait approх two minutes, before start play stream from WMSEncoder.

Code: Select all

[0x287070c0] main input debug: creating demux: access='http' demux='' path='192.168.9.33:8081' [0x2871d1e0] main demux debug: looking for access_demux module: 0 candidates [0x2871d1e0] main demux debug: no access_demux module matched "http" [0x2871d1e0] main demux debug: TIMER module_need() : 0.221 ms - Total 0.221 ms / 1 intvls (Avg 0.221 ms) [0x287070c0] main input debug: creating access 'http' path='192.168.9.33:8081' [0x28713150] main access debug: looking for access module: 2 candidates [0x28713150] access_http access debug: http: server='192.168.9.33' port=8081 file='(null) [0x28713150] main access debug: net: connecting to 192.168.9.33 port 8081 [0x28713150] main access debug: connection: Operation now in progress [0x28713150] main access debug: connection succeeded (socket = 5) [0x28713150] access_http access debug: protocol 'HTTP' answer code 400 [0x28713150] access_http access error: error: HTTP/1.0 400 Bad Request [0x28713150] access_http access debug: switching to HTTP version 1.0 [0x28713150] main access debug: net: connecting to 192.168.9.33 port 8081 [0x28713150] main access debug: connection: Operation now in progress [0x28713150] main access debug: connection succeeded (socket = 5) [0x28713150] access_http access debug: protocol 'HTTP' answer code 400 [0x28713150] access_http access error: error: HTTP/1.0 400 Bad Request [0x28713150] main access debug: net: connecting to 192.168.9.33 port 8081 [0x28713150] main access debug: connection: Operation now in progress [0x28713150] main access debug: connection succeeded (socket = 5) [0x28713150] access_mms access debug: HTTP reply 'HTTP/1.0 200 OK' [0x28713150] access_mms access debug: stream type = broadcast

wait two minutes, and start play

Code: Select all

[0x28713150] access_mms access error: cannot read data 2 [0x28713150] access_mms access debug: complete header size=5416 [0x28713150] access_mms access debug: packet count=4294967295 packet size=6100 [0x28713150] access_mms access debug: starting stream [0x28713150] main access debug: net: connecting to 192.168.9.33 port 8081 [0x28713150] main access debug: connection: Operation now in progress [0x28713150] main access debug: connection succeeded (socket = 5) [0x28713150] access_mms access debug: HTTP reply 'HTTP/1.0 200 OK' [0x28713150] access_mms access debug: Server: Rex/11.0.5721.5145 [0x28713150] access_mms access debug: Cache-Control: no-cache [0x28713150] access_mms access debug: Pragma: no-cache [0x28713150] access_mms access debug: Pragma: client-id=2334038997 [0x28713150] access_mms access debug: Pragma: features="broadcast,playlist" [0x28713150] access_mms access debug: Content-Type: application/x-mms-framed [0x28713150] main access debug: using access module "access_mms"

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.

[manolamancha]

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

[kearygriffin]

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

[VLC_help]

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

[littleyellowspoon]

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?

[VLC_help]

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

IIRC already done.

[Nintendo Maniac 64]

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