wowza retry or repeat connection

Wowza Streaming Engine
1

dear all:

I use wowza 3.0.4. I meet the issue is repeat connection.

From log, I find that sometimes user connection can not fully execute.

If this situation happen, the wowza retry or repeat the connection, Is right?

2012/3/18 12:44:57 TST connect cupertino INFO 200 957590983 -

2012/3/18 12:44:57 TST comment server INFO 200 - EricTest_onStreamCreate;sequence=5785

2012/3/18 12:44:57 TST create stream INFO 200 360142.mp4 -

2012/3/18 12:44:57 TST comment server INFO 200 - EricTest_onHTTPSessionCreate;sequence=5786

2012/3/18 12:44:57 TST comment server INFO 200 - EricTest_onHTTPCupertinoStreamingSessionCreate;sequence=5787

2012/3/18 12:44:57 TST comment server INFO 200 - cupertino_sessionId:957590983

2012/3/18 12:44:57 TST comment server INFO 200 - ipAddress:140.115.130.102

2012/3/18 12:44:57 TST comment server INFO 200 - uri:h264/definst/mp4:360142.mp4/playlist.m3u8

2012/3/18 12:44:57 TST comment server INFO 200 - queryStr:token=1332045895904030315888

2012/3/18 12:44:57 TST comment server INFO 200 - referrer:null

2012/3/18 12:44:57 TST comment server INFO 200 - cookieStr:null

2012/3/18 12:44:57 TST comment server INFO 200 - userAgent:AppleCoreMedia/1.0.0.8C148 (iPad; U; CPU OS 4_2_1 like Mac OS X; zh_cn)

2012/3/18 12:44:57 TST comment server INFO 200 - streamName:360142.mp4

2012/3/18 12:44:57 TST comment server INFO 200 - ApplicationName:h264

2012/3/18 12:44:57 TST comment server INFO 200 - ClientMap.size()=1

2012/3/18 12:44:57 TST comment server INFO 200 - userToken=1332045895904030315888

2012/3/18 12:44:57 TST comment server INFO 200 - remove token start!!

2012/3/18 12:44:57 TST comment server INFO 200 - remove token end!!token=1332045895904030315888

2012/3/18 12:44:57 TST comment server INFO 200 - remove_ClientMap.size()=0

2012/3/18 12:44:57 TST comment server INFO 200 - isPassed=true

2012/3/18 12:44:57 TST comment server INFO 200 - “MediaReaderH264Cupertino.indexFile[h264/definst/360142.mp4][mp4a.40.2]: AAC Audio info: {MediaCodecInfoAudio: codec:AAC, channels:2, frequency:48000, samplesPerFrame:1024, objectType:LC}”

2012/3/18 12:44:57 TST comment server INFO 200 - “MediaReaderH264Cupertino.indexFile[h264/definst/360142.mp4][avc1.77.30]: H.264 (Video may not be playable on older iPhone and iPod touch devices where Baseline/Level 3.0 or lower is required) Video info: {MediaCodecInfoVideo: codec:H264, profile:Main, level:3.0, frameSize:640x360, displaySize:640x360, frameRate:29.970000}”

2012/3/18 12:44:57 TST comment server INFO 200 - EricTest_onHTTPCupertinoEncryptionKeyCreateVOD=5788

2012/3/18 12:44:57 TST comment server INFO 200 - mySharedSecret=enckeysharedsecret

2012/3/18 12:44:57 TST comment server INFO 200 - sessionId=957590983

2012/3/18 12:44:57 TST comment server INFO 200 - appInstance.getApplication().getName()=h264

2012/3/18 12:44:57 TST comment server INFO 200 - appInstance.getName()=definst

2012/3/18 12:44:57 TST comment server INFO 200 - httpCupertinoStreamingSession.getStreamName()=360142.mp4

2012/3/18 12:44:57 TST comment server INFO 200 - ModuleEncryptionHandlerCupertinoStreaming.onHTTPCupertinoEncryptionKeyCreateVOD[h264/definst/360142.mp4]: *6978

2012/3/18 12:44:57 TST comment server INFO 200 - HTTPStreamerCupertinoIndexFile.init[h264/definst/360142.mp4]: Encrypt Cupertino stream: key: *6978

2012/3/18 12:44:57 TST comment server INFO 200 - HTTPStreamerCupertinoIndexFile.init[h264/definst/360142.mp4]: Encrypt Cupertino stream: url: http://61.219.1.121:80/h264/definst/mp4:360142.mp4/key.m3u8key

2012/3/18 12:44:57 TST connect cupertino INFO 200 1427989923 -

2012/3/18 12:44:57 TST comment server INFO 200 - EricTest_onStreamCreate;sequence=5789

2012/3/18 12:44:57 TST create stream INFO 200 360142.mp4 -

2012/3/18 12:44:57 TST comment server INFO 200 - EricTest_onHTTPSessionCreate;sequence=5790

2012/3/18 12:44:57 TST comment server INFO 200 - EricTest_onHTTPCupertinoStreamingSessionCreate;sequence=5791

2012/3/18 12:44:57 TST comment server INFO 200 - cupertino_sessionId:1427989923

2012/3/18 12:44:57 TST comment server INFO 200 - ipAddress:140.115.130.102

2012/3/18 12:44:57 TST comment server INFO 200 - uri:h264/definst/mp4:360142.mp4/playlist.m3u8

2012/3/18 12:44:57 TST comment server INFO 200 - queryStr:token=1332045895904030315888

2012/3/18 12:44:57 TST comment server INFO 200 - referrer:null

2012/3/18 12:44:57 TST comment server INFO 200 - cookieStr:null

2012/3/18 12:44:57 TST comment server INFO 200 - userAgent:AppleCoreMedia/1.0.0.8C148 (iPad; U; CPU OS 4_2_1 like Mac OS X; zh_cn)

2012/3/18 12:44:57 TST comment server INFO 200 - streamName:360142.mp4

2012/3/18 12:44:57 TST comment server INFO 200 - ApplicationName:h264

2012/3/18 12:44:57 TST comment server INFO 200 - ClientMap.size()=1

2012/3/18 12:44:57 TST comment server INFO 200 - userToken=1332045895904030315888

2012/3/18 12:44:57 TST comment server INFO 200 - noPassed!!

2012/3/18 12:44:57 TST comment server INFO 200 - remove token start!!

2012/3/18 12:44:57 TST comment server INFO 200 - remove token end!!token=1332045895904030315888

2012/3/18 12:44:57 TST comment server INFO 200 - remove_ClientMap.size()=0

2012/3/18 12:44:57 TST comment server INFO 200 - isPassed=false

2012/3/18 12:44:57 TST comment server WARN 200 - HTTPStreamerAdapterCupertinoStreamer.onPlaylist[h264/definst/mp4:360142.mp4/playlist.m3u8?token=1332045895904030315888]: Session not accepted[1427989923]

2012/3/18 12:44:57 TST connect cupertino INFO 200 281042974 -

2012/3/18 12:44:57 TST comment server INFO 200 - EricTest_onStreamCreate;sequence=5792

2012/3/18 12:44:57 TST create stream INFO 200 360142.mp4 -

2012/3/18 12:44:57 TST comment server INFO 200 - EricTest_onHTTPSessionCreate;sequence=5793

2012/3/18 12:44:57 TST comment server INFO 200 - EricTest_onHTTPCupertinoStreamingSessionCreate;sequence=5794

2012/3/18 12:44:57 TST comment server INFO 200 - cupertino_sessionId:281042974

2012/3/18 12:44:57 TST comment server INFO 200 - ipAddress:140.115.130.102

2012/3/18 12:44:57 TST comment server INFO 200 - uri:h264/definst/mp4:360142.mp4/playlist.m3u8

2012/3/18 12:44:57 TST comment server INFO 200 - queryStr:token=1332045895904030315888

2012/3/18 12:44:57 TST comment server INFO 200 - referrer:null

2012/3/18 12:44:57 TST comment server INFO 200 - cookieStr:null

2012/3/18 12:44:57 TST comment server INFO 200 - userAgent:AppleCoreMedia/1.0.0.8C148 (iPad; U; CPU OS 4_2_1 like Mac OS X; zh_cn)

2012/3/18 12:44:57 TST comment server INFO 200 - streamName:360142.mp4

2012/3/18 12:44:57 TST comment server INFO 200 - ApplicationName:h264

2012/3/18 12:44:57 TST comment server INFO 200 - ClientMap.size()=1

2012/3/18 12:44:57 TST comment server INFO 200 - userToken=1332045895904030315888

2012/3/18 12:44:57 TST comment server INFO 200 - noPassed!!

2012/3/18 12:44:57 TST comment server INFO 200 - remove token start!!

2012/3/18 12:44:57 TST comment server INFO 200 - remove token end!!token=1332045895904030315888

2012/3/18 12:44:57 TST comment server INFO 200 - remove_ClientMap.size()=0

2012/3/18 12:44:57 TST comment server INFO 200 - isPassed=false

2012/3/18 12:44:57 TST comment server WARN 200 - HTTPStreamerAdapterCupertinoStreamer.onPlaylist[h264/definst/mp4:360142.mp4/playlist.m3u8?token=1332045895904030315888]: Session not accepted[281042974]

2012/3/18 12:44:57 TST connect cupertino INFO 200 1867574217 -

2012/3/18 12:44:57 TST comment server INFO 200 - EricTest_onStreamCreate;sequence=5795

2012/3/18 12:44:57 TST create stream INFO 200 360142.mp4 -

2012/3/18 12:44:57 TST comment server INFO 200 - EricTest_onHTTPSessionCreate;sequence=5796

2012/3/18 12:44:57 TST comment server INFO 200 - EricTest_onHTTPCupertinoStreamingSessionCreate;sequence=5797

2012/3/18 12:44:57 TST comment server INFO 200 - cupertino_sessionId:1867574217

2012/3/18 12:44:57 TST comment server INFO 200 - ipAddress:140.115.130.102

2012/3/18 12:44:57 TST comment server INFO 200 - uri:h264/definst/mp4:360142.mp4/playlist.m3u8

2012/3/18 12:44:57 TST comment server INFO 200 - queryStr:token=1332045895904030315888

2012/3/18 12:44:57 TST comment server INFO 200 - referrer:null

2012/3/18 12:44:57 TST comment server INFO 200 - cookieStr:null

2012/3/18 12:44:57 TST comment server INFO 200 - userAgent:AppleCoreMedia/1.0.0.8C148 (iPad; U; CPU OS 4_2_1 like Mac OS X; zh_cn)

2012/3/18 12:44:57 TST comment server INFO 200 - streamName:360142.mp4

2012/3/18 12:44:57 TST comment server INFO 200 - ApplicationName:h264

2012/3/18 12:44:57 TST comment server INFO 200 - ClientMap.size()=1

2012/3/18 12:44:57 TST comment server INFO 200 - userToken=1332045895904030315888

2012/3/18 12:44:57 TST comment server INFO 200 - noPassed!!

2012/3/18 12:44:57 TST comment server INFO 200 - remove token start!!

2012/3/18 12:44:57 TST comment server INFO 200 - remove token end!!token=1332045895904030315888

2012/3/18 12:44:57 TST comment server INFO 200 - remove_ClientMap.size()=0

2012/3/18 12:44:57 TST comment server INFO 200 - isPassed=false

2012/3/18 12:44:57 TST comment server WARN 200 - HTTPStreamerAdapterCupertinoStreamer.onPlaylist[h264/definst/mp4:360142.mp4/playlist.m3u8?token=1332045895904030315888]: Session not accepted[1867574217]

2012/3/18 12:44:57 TST connect cupertino INFO 200 967143390 -

2012/3/18 12:44:57 TST comment server INFO 200 - EricTest_onStreamCreate;sequence=5798

2012/3/18 12:44:57 TST create stream INFO 200 360142.mp4 -

2012/3/18 12:44:57 TST comment server INFO 200 - EricTest_onHTTPSessionCreate;sequence=5799

2012/3/18 12:44:57 TST comment server INFO 200 - EricTest_onHTTPCupertinoStreamingSessionCreate;sequence=5800

2012/3/18 12:44:57 TST comment server INFO 200 - cupertino_sessionId:967143390

2012/3/18 12:44:57 TST comment server INFO 200 - ipAddress:140.115.130.102

2012/3/18 12:44:57 TST comment server INFO 200 - uri:h264/definst/mp4:360142.mp4/playlist.m3u8

2012/3/18 12:44:57 TST comment server INFO 200 - queryStr:token=1332045895904030315888

2012/3/18 12:44:57 TST comment server INFO 200 - referrer:null

2012/3/18 12:44:57 TST comment server INFO 200 - cookieStr:null

2012/3/18 12:44:57 TST comment server INFO 200 - userAgent:AppleCoreMedia/1.0.0.8C148 (iPad; U; CPU OS 4_2_1 like Mac OS X; zh_cn)

2012/3/18 12:44:57 TST comment server INFO 200 - streamName:360142.mp4

2012/3/18 12:44:57 TST comment server INFO 200 - ApplicationName:h264

2012/3/18 12:44:57 TST comment server INFO 200 - ClientMap.size()=1

2012/3/18 12:44:57 TST comment server INFO 200 - userToken=1332045895904030315888

2012/3/18 12:44:57 TST comment server INFO 200 - noPassed!!

2012/3/18 12:44:57 TST comment server INFO 200 - remove token start!!

2012/3/18 12:44:57 TST comment server INFO 200 - remove token end!!token=1332045895904030315888

2012/3/18 12:44:57 TST comment server INFO 200 - remove_ClientMap.size()=0

2012/3/18 12:44:57 TST comment server INFO 200 - isPassed=false

2012/3/18 12:44:57 TST comment server WARN 200 - HTTPStreamerAdapterCupertinoStreamer.onPlaylist[h264/definst/mp4:360142.mp4/playlist.m3u8?token=1332045895904030315888]: Session not accepted[967143390]

…

Thanks.

Eric

2

It could repeat if the client that was rejected tries again and again. Otherwise, no, Wowza will not try again.

Richard

3

Eric,

If it is not the user, then it must be the device.

There is also that iOS device will create two sessions (one empty) when a m3u8 url is opened in a iOS browser directly instead of opening a HTML5 page with video tag for the m3u8 URL. Is that what you are seeing?

Richard

4

It could repeat if the client that was rejected tries again and again. Otherwise, no, Wowza will not try again.

Richard

dear Richard:

From logs, it could not be that user retry by himself, because it retry over 10 times during one second. Nobody can do this.

So your meanings is that does the client device(ipad or iphone) do this ?

I find the issue begin at wowza not fully execute.

If wowza fully execute, it is logs as below, it will appear play stream state.

.

.

2012/3/18 13:24:11 TST comment server INFO 200 - ModuleEncryptionHandlerCupertinoStreaming.onHTTPCupertinoEncryptionKeyCreateVOD[h264/definst/360152.mp4]: *3e7e

2012/3/18 13:24:11 TST comment server INFO 200 - HTTPStreamerCupertinoIndexFile.init[h264/definst/360152.mp4]: Encrypt Cupertino stream: key: *3e7e

2012/3/18 13:24:11 TST comment server INFO 200 - HTTPStreamerCupertinoIndexFile.init[h264/definst/360152.mp4]: Encrypt Cupertino stream: url: http://61.219.1.121:80/h264/definst/mp4:360152.mp4/key.m3u8key

2012/3/18 13:24:11 TST comment server INFO 200 - EricTest:onHTTPCupertinoEncryptionKeyRequest=7599

2012/3/18 13:24:11 TST comment server INFO 200 - ModuleEncryptionHandlerCupertinoStreaming.onHTTPCupertinoEncryptionKeyRequest[h264/definst/360152.mp4]: accept:true

2012/3/18 13:24:12 TST play stream INFO 200 360152.mp4 -

.

.

But if it can not fully execute, its log as below, it can not execute to play stream state.

2012/3/18 12:44:57 TST comment server INFO 200 - ModuleEncryptionHandlerCupertinoStreaming.onHTTPCu pertinoEncryptionKeyCreateVOD[h264/definst/360142.mp4]: *6978

2012/3/18 12:44:57 TST comment server INFO 200 - HTTPStreamerCupertinoIndexFile.init[h264/definst/360142.mp4]: Encrypt Cupertino stream: key: *6978

2012/3/18 12:44:57 TST comment server INFO 200 - HTTPStreamerCupertinoIndexFile.init[h264/definst/360142.mp4]: Encrypt Cupertino stream: url: http://61.219.1.121:80/h264/_definst…p4/key.m3u8key

If it can not fully execute, repeat connection is appear.

Is it normal? Is this wowza mechanism or iOS retry mechanism?

Thanks.

Eric

5

There is also that iOS device will create two sessions (one empty) when a m3u8 url is opened in a iOS browser directly instead of opening a HTML5 page with video tag for the m3u8 URL. Is that what you are seeing?

dear Richard:

No, this is HTML5 page. And it is not always to repeat every user. And it repeat more 10 times during one second.

From logs, it execute every state, but not play state. So it repeat.

I can’t to reappear the situation. Because it is not always to repeat.

Could you tell me what is happen?

Thanks.

Eric