1 Reply Latest reply on Jun 1, 2011 4:50 AM by Silviu Vergoti

    each media stops at 19 th second

    Maria Sigal

      each media stops at 19 th second

       

      i have video player based on osmf. i have fms 4 on my computer. when i try to stream video files  localy everything works fine.

      i  have trouble streaming videos from  rtmpe://filmotechfvs.cdnetworks.net:1935/filmotechfvs?key=Q0RBVQQCkAC  Hnsv429br66lCDDpuscA4iLHrm-zvaapVd2HCU1oUFhqQziCulq3VgGnlPcwK2423ayDh2  whJ9PC-Zxc-bOYJ0LoDpByq7uWicyx6J61fELaaa6w4yhY05ohm4phy2QoP5XxFZUJpX9g  x3qUwww6Wpb-9wO1mav4RoT15O3k7Nh6B4xoarTGfvB_1yDwki_8=.

      on http

      you can view it here

      http://www.tvinci.com/lior/maria/filmo/

       

      each  media  i try to playback stops at 19th second.

      i got these masseges

       

      Tue  May 31 2011 05:08:01 PM [DEBUG]  [org.osmf.net.NetStreamSwitchManager]  17:08:01 GMT+0300>>>  NetStreamSwitchManager.executeSwitch() -  Switching to index 1 at 900  kbps
      Tue May 31 2011 05:08:11 PM [INFO] [org.osmf.net.NetStreamLoadTrait] NetStream.Play.Failed
      Tue May 31 2011 05:08:11 PM [DEBUG]   [org.osmf.net.NetStreamSwitchManager] 17:08:11 GMT+0300>>>   NetStreamSwitchManager.onNetStatus() -   event.info.code=NetStream.Play.Failed
      Tue May 31 2011 05:08:11 PM [INFO] [org.osmf.net.NetLoader] Stream reconnect: onNetStatus: NetStream.Play.Failed

       

       

      what is intresting is that it  only happens with adaptive bitrate, with simple steaming it reaches tthe end.

      another  intresting fact is that we have old  player  not based on osmf , that  we developed including switching mananger and there it works fine, not  stops at 19 second.

       

      thanks in advance

       

      logs i got:

       

       

      host  name -   rtmpe://filmotechfvs.cdnetworks.net/filmotechfvs/flashstream?key=Q0RB  VQQCkACHnsv429br66lCDDpuscA4iLHrm-zvaapVd2HCU1oUFhqQziCulq3VgGnlPcwK24  23ayDh2whJ9PC-Zxc-bOYJ0LoDpByq7uWicyx6J61fELaaa6w4yhY05ohm4phy2QoP5XxF  ZUJpX9gx3qUwww6Wpb-9wO1mav4RoT15O3k7Nh6B4xoarTGfvB_1yDwki_8=
      stream name -  mp4:Langer_licht_trailer_04.mp4
      host  name -   rtmpe://filmotechfvs.cdnetworks.net/filmotechfvs/flashstream?key=Q0RB  VQQCkACHnsv429br66lCDDpuscA4iLHrm-zvaapVd2HCU1oUFhqQziCulq3VgGnlPcwK24  23ayDh2whJ9PC-Zxc-bOYJ0LoDpByq7uWicyx6J61fELaaa6w4yhY05ohm4phy2QoP5XxF  ZUJpX9gx3qUwww6Wpb-9wO1mav4RoT15O3k7Nh6B4xoarTGfvB_1yDwki_8=
      stream name -  mp4:Langer_licht_trailer_09.mp4
      host  name -   rtmpe://filmotechfvs.cdnetworks.net/filmotechfvs/flashstream?key=Q0RB  VQQCkACHnsv429br66lCDDpuscA4iLHrm-zvaapVd2HCU1oUFhqQziCulq3VgGnlPcwK24  23ayDh2whJ9PC-Zxc-bOYJ0LoDpByq7uWicyx6J61fELaaa6w4yhY05ohm4phy2QoP5XxF  ZUJpX9gx3qUwww6Wpb-9wO1mav4RoT15O3k7Nh6B4xoarTGfvB_1yDwki_8=
      stream name -  mp4:Langer_licht_trailer_19.mp4
      host  name -   rtmpe://filmotechfvs.cdnetworks.net/filmotechfvs/flashstream?key=Q0RB  VQQCkACHnsv429br66lCDDpuscA4iLHrm-zvaapVd2HCU1oUFhqQziCulq3VgGnlPcwK24  23ayDh2whJ9PC-Zxc-bOYJ0LoDpByq7uWicyx6J61fELaaa6w4yhY05ohm4phy2QoP5XxF  ZUJpX9gx3qUwww6Wpb-9wO1mav4RoT15O3k7Nh6B4xoarTGfvB_1yDwki_8=
      stream name -  mp4:Langer_licht_trailer_39.mp4
      Tue  May 31 2011 05:07:40 PM [INFO]  [org.osmf.media.videoClasses.VideoSurface] switchRenderer.  currentVideoRenderer = [object Video]; the new renderer = null
      Tue May 31 2011 05:07:40 PM [INFO] [org.osmf.net.NetStreamLoadTrait] NetConnection.Connect.Closed
      Tue May 31 2011 05:07:40 PM [INFO] [org.osmf.net.NetLoader] Stream reconnect: onNetStatus: NetConnection.Connect.Closed
      Tue May 31 2011 05:07:40 PM [DEBUG] [org.osmf.net.NetLoader] Stream reconnect: connection failed, bufferLength is 0
      state >>  uninitialized
      state >>  loading
      Tue  May 31 2011 05:07:40 PM [INFO] [org.osmf.net.NetNegotiator] Attempting  connection to  rtmpe://filmotechfvs.cdnetworks.net:1935/filmotechfvs?key=Q0RBVQQCkAC  Hnsv429br66lCDDpuscA4iLHrm-zvaapVd2HCU1oUFhqQziCulq3VgGnlPcwK2423ayDh2  whJ9PC-Zxc-bOYJ0LoDpByq7uWicyx6J61fELaaa6w4yhY05ohm4phy2QoP5XxFZUJpX9g  x3qUwww6Wpb-9wO1mav4RoT15O3k7Nh6B4xoarTGfvB_1yDwki_8=
      ======================video url <set>
         <file bitrate="04"  only_fullscreen="false">rtmpe://filmotechfvs.cdnetworks.net/filmotech  fvs/flashstream/mp4:Langer_licht_trailer_04.mp4?key=Q0RBVQQCkACHnsv429  br66lCDDpuscA4iLHrm-zvaapVd2HCU1oUFhqQziCulq3VgGnlPcwK2423ayDh2whJ9PC-  Zxc-bOYJ0LoDpByq7uWicyx6J61fELaaa6w4yhY05ohm4phy2QoP5XxFZUJpX9gx3qUwww  6Wpb-9wO1mav4RoT15O3k7Nh6B4xoarTGfvB_1yDwki_8=</file>
         <file bitrate="09"  only_fullscreen="false">rtmpe://filmotechfvs.cdnetworks.net/filmotech  fvs/flashstream/mp4:Langer_licht_trailer_09.mp4?key=Q0RBVQQCkACHnsv429  br66lCDDpuscA4iLHrm-zvaapVd2HCU1oUFhqQziCulq3VgGnlPcwK2423ayDh2whJ9PC-  Zxc-bOYJ0LoDpByq7uWicyx6J61fELaaa6w4yhY05ohm4phy2QoP5XxFZUJpX9gx3qUwww  6Wpb-9wO1mav4RoT15O3k7Nh6B4xoarTGfvB_1yDwki_8=</file>
         <file bitrate="19"  only_fullscreen="false">rtmpe://filmotechfvs.cdnetworks.net/filmotech  fvs/flashstream/mp4:Langer_licht_trailer_19.mp4?key=Q0RBVQQCkACHnsv429  br66lCDDpuscA4iLHrm-zvaapVd2HCU1oUFhqQziCulq3VgGnlPcwK2423ayDh2whJ9PC-  Zxc-bOYJ0LoDpByq7uWicyx6J61fELaaa6w4yhY05ohm4phy2QoP5XxFZUJpX9gx3qUwww  6Wpb-9wO1mav4RoT15O3k7Nh6B4xoarTGfvB_1yDwki_8=</file>
         <file bitrate="39"  only_fullscreen="false">rtmpe://filmotechfvs.cdnetworks.net/filmotech  fvs/flashstream/mp4:Langer_licht_trailer_39.mp4?key=Q0RBVQQCkACHnsv429  br66lCDDpuscA4iLHrm-zvaapVd2HCU1oUFhqQziCulq3VgGnlPcwK2423ayDh2whJ9PC-  Zxc-bOYJ0LoDpByq7uWicyx6J61fELaaa6w4yhY05ohm4phy2QoP5XxFZUJpX9gx3qUwww  6Wpb-9wO1mav4RoT15O3k7Nh6B4xoarTGfvB_1yDwki_8=</file>
      </set>
      Tue May 31 2011 05:07:40 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] ON EXIT FRAME: BEGIN
      Tue May 31 2011 05:07:40 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] VALIDATING LAYOUT
      Tue May 31 2011 05:07:40 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] layout dimensions before measurement (648, 364)
      Tue May 31 2011 05:07:40 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] layout dimensions after measurement (648, 364)
      Tue  May 31 2011 05:07:40 PM [DEBUG] [org.osmf.layout.LayoutRenderer]  dimensions: (x=0, y=0, w=648, h=364) available: (648, 364), media:  (NaN,NaN) target (null)
      Tue May 31 2011 05:07:40 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] LAYOUT VALIDATED
      Tue May 31 2011 05:07:40 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] ON EXIT FRAME: END
      Tue  May 31 2011 05:07:40 PM [INFO] [org.osmf.net.NetNegotiator] Attempting  connection to  rtmpe://filmotechfvs.cdnetworks.net:443/filmotechfvs?key=Q0RBVQQCkACH  nsv429br66lCDDpuscA4iLHrm-zvaapVd2HCU1oUFhqQziCulq3VgGnlPcwK2423ayDh2w  hJ9PC-Zxc-bOYJ0LoDpByq7uWicyx6J61fELaaa6w4yhY05ohm4phy2QoP5XxFZUJpX9gx  3qUwww6Wpb-9wO1mav4RoT15O3k7Nh6B4xoarTGfvB_1yDwki_8=
      Tue May 31 2011  05:07:40 PM [INFO] [org.osmf.net.NetNegotiator] Attempting connection to  rtmpe://filmotechfvs.cdnetworks.net:80/filmotechfvs?key=Q0RBVQQCkACHn  sv429br66lCDDpuscA4iLHrm-zvaapVd2HCU1oUFhqQziCulq3VgGnlPcwK2423ayDh2wh  J9PC-Zxc-bOYJ0LoDpByq7uWicyx6J61fELaaa6w4yhY05ohm4phy2QoP5XxFZUJpX9gx3  qUwww6Wpb-9wO1mav4RoT15O3k7Nh6B4xoarTGfvB_1yDwki_8=
      Tue May 31 2011  05:07:41 PM [INFO] [org.osmf.net.NetNegotiator] Attempting connection to  rtmpte://filmotechfvs.cdnetworks.net:1935/filmotechfvs?key=Q0RBVQQCkA  CHnsv429br66lCDDpuscA4iLHrm-zvaapVd2HCU1oUFhqQziCulq3VgGnlPcwK2423ayDh  2whJ9PC-Zxc-bOYJ0LoDpByq7uWicyx6J61fELaaa6w4yhY05ohm4phy2QoP5XxFZUJpX9  gx3qUwww6Wpb-9wO1mav4RoT15O3k7Nh6B4xoarTGfvB_1yDwki_8=
      Tue May 31  2011 05:07:42 PM [INFO] [org.osmf.net.NetNegotiator] Attempting  connection to  rtmpte://filmotechfvs.cdnetworks.net:443/filmotechfvs?key=Q0RBVQQCkAC  Hnsv429br66lCDDpuscA4iLHrm-zvaapVd2HCU1oUFhqQziCulq3VgGnlPcwK2423ayDh2  whJ9PC-Zxc-bOYJ0LoDpByq7uWicyx6J61fELaaa6w4yhY05ohm4phy2QoP5XxFZUJpX9g  x3qUwww6Wpb-9wO1mav4RoT15O3k7Nh6B4xoarTGfvB_1yDwki_8=
      Tue May 31 2011  05:07:42 PM [INFO] [org.osmf.net.NetNegotiator] Attempting connection  to rtmpte://filmotechfvs.cdnetworks.net:80/filmotechfvs?key=Q0RBVQQCkACH  nsv429br66lCDDpuscA4iLHrm-zvaapVd2HCU1oUFhqQziCulq3VgGnlPcwK2423ayDh2w  hJ9PC-Zxc-bOYJ0LoDpByq7uWicyx6J61fELaaa6w4yhY05ohm4phy2QoP5XxFZUJpX9gx  3qUwww6Wpb-9wO1mav4RoT15O3k7Nh6B4xoarTGfvB_1yDwki_8=
      Tue May 31 2011 05:07:43 PM [INFO] [org.osmf.net.NetNegotiator] FMS Version: 3,5,5,2004
      Tue  May 31 2011 05:07:43 PM [INFO] [org.osmf.net.NetConnectionFactory]  NetConnection established with:  rtmpe://filmotechfvs.cdnetworks.net:443/filmotechfvs?key=Q0RBVQQCkACH  nsv429br66lCDDpuscA4iLHrm-zvaapVd2HCU1oUFhqQziCulq3VgGnlPcwK2423ayDh2w  hJ9PC-Zxc-bOYJ0LoDpByq7uWicyx6J61fELaaa6w4yhY05ohm4phy2QoP5XxFZUJpX9gx  3qUwww6Wpb-9wO1mav4RoT15O3k7Nh6B4xoarTGfvB_1yDwki_8=
      state >>  ready
      Tue May 31 2011 05:07:43 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] ON EXIT FRAME: BEGIN
      Tue May 31 2011 05:07:43 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] VALIDATING LAYOUT
      Tue May 31 2011 05:07:43 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] layout dimensions before measurement (648, 364)
      Tue  May 31 2011 05:07:43 PM [INFO]  [org.osmf.media.videoClasses.VideoSurface] switchRenderer.  currentVideoRenderer = null; the new renderer = [object Video]
      Tue May 31 2011 05:07:43 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] layout dimensions after measurement (648, 364)
      Tue  May 31 2011 05:07:43 PM [DEBUG] [org.osmf.layout.LayoutRenderer]  dimensions: (x=0, y=0, w=648, h=364) available: (648, 364), media:  (NaN,NaN) target ([object VideoSurface])
      Tue May 31 2011 05:07:43 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] LAYOUT VALIDATED
      Tue May 31 2011 05:07:43 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] ON EXIT FRAME: END
      Tue May 31 2011 05:07:44 PM [INFO] [org.osmf.net.NetStreamLoadTrait] NetStream.Play.Reset
      Tue  May 31 2011 05:07:44 PM [DEBUG] [org.osmf.net.NetStreamSwitchManager]  17:07:44 GMT+0300>>> NetStreamSwitchManager.onNetStatus() -  event.info.code=NetStream.Play.Reset
      Tue May 31 2011 05:07:44 PM [INFO] [org.osmf.net.NetLoader] Stream reconnect: onNetStatus: NetStream.Play.Reset
      Tue May 31 2011 05:07:44 PM [INFO] [org.osmf.net.NetStreamLoadTrait] NetStream.Play.Start
      Tue  May 31 2011 05:07:44 PM [DEBUG] [org.osmf.net.NetStreamSwitchManager]  17:07:44 GMT+0300>>> NetStreamSwitchManager.onNetStatus() -  event.info.code=NetStream.Play.Start
      Tue May 31 2011 05:07:44 PM  [DEBUG] [org.osmf.net.NetStreamSwitchManager] 17:07:44  GMT+0300>>> NetStreamSwitchManager.prepareForSwitching() -  Starting with stream index 0 at 400 kbps
      Tue May 31 2011 05:07:44 PM [INFO] [org.osmf.net.NetLoader] Stream reconnect: onNetStatus: NetStream.Play.Start
      state >>  buffering
      Tue May 31 2011 05:07:53 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] layout dimensions before measurement (648, 364)
      Tue May 31 2011 05:07:53 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] layout dimensions after measurement (648, 364)
      Tue  May 31 2011 05:07:53 PM [DEBUG] [org.osmf.layout.LayoutRenderer]  dimensions: (x=0, y=11, w=648, h=342) available: (648, 364), media:  (576,304) target ([object VideoSurface])
      Tue May 31 2011 05:07:53 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] ON EXIT FRAME: BEGIN
      Tue May 31 2011 05:07:53 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] VALIDATING LAYOUT
      Tue May 31 2011 05:07:53 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] layout dimensions before measurement (648, 364)
      Tue May 31 2011 05:07:53 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] layout dimensions after measurement (648, 364)
      Tue  May 31 2011 05:07:53 PM [DEBUG] [org.osmf.layout.LayoutRenderer]  dimensions: (x=0, y=11, w=648, h=342) available: (648, 364), media:  (576,304) target ([object VideoSurface])
      Tue May 31 2011 05:07:53 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] LAYOUT VALIDATED
      Tue May 31 2011 05:07:53 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] ON EXIT FRAME: END
      Tue May 31 2011 05:07:53 PM [INFO] [org.osmf.net.NetStreamLoadTrait] NetStream.Buffer.Full
      Tue  May 31 2011 05:07:53 PM [DEBUG] [org.osmf.net.NetStreamSwitchManager]  17:07:53 GMT+0300>>> NetStreamSwitchManager.onNetStatus() -  event.info.code=NetStream.Buffer.Full
      Tue May 31 2011 05:07:53 PM [INFO] [org.osmf.net.NetLoader] Stream reconnect: onNetStatus: NetStream.Buffer.Full
      state >>  playing
      report play
      Tue  May 31 2011 05:07:53 PM [DEBUG]  [org.osmf.net.rtmpstreaming.SufficientBandwidthRule] Move up since avg  dropped FPS 0 < 2 and bufferLength > 7
      Tue May 31 2011 05:07:53  PM [DEBUG] [org.osmf.net.rtmpstreaming.SufficientBandwidthRule]  getNewIndex() - about to return: 1, detail=null
      Tue May 31 2011  05:07:53 PM [DEBUG] [org.osmf.net.NetStreamSwitchManager] 17:07:53  GMT+0300>>> NetStreamSwitchManager.checkRules() - Calling for  switch to 1 at 900 kbps
      Tue May 31 2011 05:07:53 PM [DEBUG]  [org.osmf.net.NetStreamSwitchManager] 17:07:53 GMT+0300>>>  NetStreamSwitchManager.executeSwitch() - Switching to index 1 at 900  kbps
      Tue May 31 2011 05:08:01 PM [INFO] [org.osmf.net.NetStreamLoadTrait] NetStream.Play.Transition
      Tue  May 31 2011 05:08:01 PM [DEBUG] [org.osmf.net.NetStreamSwitchManager]  17:08:01 GMT+0300>>> NetStreamSwitchManager.onNetStatus() -  event.info.code=NetStream.Play.Transition
      Tue May 31 2011 05:08:01 PM [INFO] [org.osmf.net.NetLoader] Stream reconnect: onNetStatus: NetStream.Play.Transition
      Tue  May 31 2011 05:08:01 PM [DEBUG]  [org.osmf.net.rtmpstreaming.SufficientBandwidthRule] Move up since avg  dropped FPS 0 < 2 and bufferLength > 7
      Tue May 31 2011 05:08:01  PM [DEBUG] [org.osmf.net.rtmpstreaming.SufficientBandwidthRule]  getNewIndex() - about to return: 1, detail=null
      Tue May 31 2011  05:08:01 PM [DEBUG] [org.osmf.net.NetStreamSwitchManager] 17:08:01  GMT+0300>>> NetStreamSwitchManager.prepareForSwitching() -  Starting with stream index 0 at 400 kbps
      Tue May 31 2011 05:08:01 PM  [DEBUG] [org.osmf.net.NetStreamSwitchManager] 17:08:01  GMT+0300>>> NetStreamSwitchManager.checkRules() - Calling for  switch to 1 at 900 kbps
      Tue May 31 2011 05:08:01 PM [DEBUG]  [org.osmf.net.NetStreamSwitchManager] 17:08:01 GMT+0300>>>  NetStreamSwitchManager.executeSwitch() - Switching to index 1 at 900  kbps
      Tue May 31 2011 05:08:11 PM [INFO] [org.osmf.net.NetStreamLoadTrait] NetStream.Play.Failed
      Tue  May 31 2011 05:08:11 PM [DEBUG] [org.osmf.net.NetStreamSwitchManager]  17:08:11 GMT+0300>>> NetStreamSwitchManager.onNetStatus() -  event.info.code=NetStream.Play.Failed
      Tue May 31 2011 05:08:11 PM [INFO] [org.osmf.net.NetLoader] Stream reconnect: onNetStatus: NetStream.Play.Failed
      state >>  playbackError
      Tue May 31 2011 05:08:11 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] layout dimensions before measurement (648, 364)
      Tue May 31 2011 05:08:11 PM [DEBUG] [org.osmf.layout.LayoutRendererBase] layout dimensions after measurement (648, 364)
      Tue  May 31 2011 05:08:11 PM [DEBUG] [org.osmf.layout.LayoutRenderer]  dimensions: (x=0, y=11, w=648, h=342) available: (648, 364), media:  (576,304) target ([object VideoSurface])
      Tue May 31 2011 05:08:11 PM [INFO] [org.osmf.net.NetStreamLoadTrait] NetStream.Pause.Notify
      Tue  May 31 2011 05:08:11 PM [DEBUG] [org.osmf.net.NetStreamSwitchManager]  17:08:11 GMT+0300>>> NetStreamSwitchManager.onNetStatus() -  event.info.code=NetStream.Pause.Notify
      Tue May 31 2011 05:08:11 PM [INFO] [org.osmf.net.NetLoader] Stream reconnect: onNetStatus: NetStream.Pause.Notify
      Tue May 31 2011 05:08:11 PM [INFO] [org.osmf.net.NetStreamLoadTrait] NetStream.Buffer.Flush
      Tue  May 31 2011 05:08:11 PM [DEBUG] [org.osmf.net.NetStreamSwitchManager]  17:08:11 GMT+0300>>> NetStreamSwitchManager.onNetStatus() -  event.info.code=NetStream.Buffer.Flush
      Tue May 31 2011 05:08:11 PM [INFO] [org.osmf.net.NetLoader] Stream reconnect: onNetStatus: NetStream.Buffer.Flush

        • 1. Re: each media stops at 19 th second
          Silviu Vergoti Adobe Employee

          Hi Maria,

           

          I see from your log that the switch does not take place correctly.

           

          Here are some questions that could lead you to the source of the problem:

           

          Can you play each stream individually (especially the stream that does not play) ?

          Did you provide the correct bitrate (kbps) in each dynamic streaming media item?

          Can you verify if the same problem appears if you do not protect the streams?

          Do the streams have synchronized keyframes (e.g, GOP forced to 2 seconds)?

          Does the problem happen on FMS 4? your cdn seems to use FMS 3.5.5.

           

          S.