Stream start time calculation is off

Hi there,

Earlier today, there was a power trip that caused 2 of my IP Cameras (A and B) to be down for a moment. Once the IP Cameras are back on, Wowza Streaming Engine was able to reconnect to both cameras properly but stream from Camera A seems to have some issue playing for Mpeg-Dash playback (The video playback is intermittent). Checking the error log shows this: MPEGDashWriterHandler.endChunk[live/definst/streamA.stream]: Stream start time calculation is off by 6057ms from original value. I managed to fix the issue by resetting the stream in Incoming Stream using Wowza Streaming Engine Manager.

Still, I am wondering how to interpret this error message and how this actually affects Mpeg-dash playback. I am guessing that since MpegDash relies on start time, this error caused some issue with its playback? Secondly, is there some configurable settings that will allow Wowza to be robust enough to handle this error automatically.

Thanks and Best Regards

Details from Error Log:

ERROR server comment 2016-10-03 14:11:18 - - - - - 245283.349 - - - - - - - - RTPSessionDescriptionDataProviderBasicRTSPWorker.doConnect: Connect org.apache.mina.common.RuntimeIOException: Failed to get the session[ConnectException: Connection refused: no further information].

WARN server comment 2016-10-03 14:11:18 - - - - - 245283.349 - - - - - - - - RTPSessionDescriptionDataProviderBasic.getStreamInfo: RTSP/RTP re-streaming. Cannot connect to server: rtsp://192.168.1.16/stream1

ERROR server comment 2016-10-03 14:11:21 - - - - - 245286.332 - - - - - - - - RTPSessionDescriptionDataProviderBasicRTSPWorker.doConnect: Connect org.apache.mina.common.RuntimeIOException: Failed to get the session[ConnectException: Connection refused: no further information].

WARN server comment 2016-10-03 14:11:21 - - - - - 245286.332 - - - - - - - - RTPSessionDescriptionDataProviderBasic.getStreamInfo: RTSP/RTP re-streaming. Cannot connect to server: rtsp://192.168.1.16/stream1

ERROR server comment 2016-10-03 14:11:24 - - - - - 245289.348 - - - - - - - - RTPSessionDescriptionDataProviderBasicRTSPWorker.doConnect: Connect org.apache.mina.common.RuntimeIOException: Failed to get the session[ConnectException: Connection refused: no further information].

WARN server comment 2016-10-03 14:11:24 - - - - - 245289.348 - - - - - - - - RTPSessionDescriptionDataProviderBasic.getStreamInfo: RTSP/RTP re-streaming. Cannot connect to server: rtsp://192.168.1.16/stream1

ERROR server comment 2016-10-03 14:11:27 - - - - - 245292.332 - - - - - - - - RTPSessionDescriptionDataProviderBasicRTSPWorker.doConnect: Connect org.apache.mina.common.RuntimeIOException: Failed to get the session[ConnectException: Connection refused: no further information].

WARN server comment 2016-10-03 14:11:27 - - - - - 245292.332 - - - - - - - - RTPSessionDescriptionDataProviderBasic.getStreamInfo: RTSP/RTP re-streaming. Cannot connect to server: rtsp://192.168.1.16/stream1

WARN server comment 2016-10-03 14:11:29 - - - - - 245294.849 - - - - - - - - CupertinoPacketHandler.handleHolder[live/definst/streamA.stream]: Invalid video/audio codec combination for iPhone/iPod: video:H264 audio:G711_MULAW

WARN server comment 2016-10-03 14:11:29 - - - - - 245294.849 - - - - - - - - CupertinoPacketHandler.handleHolder[live/definst/streamA.stream]: Invalid audio codec for iPhone/iPod:G711_MULAW

WARN server comment 2016-10-03 14:11:29 - - - - - 245294.977 - - - - - - - - SanJosePacketHandler.handlePacket[live/definst/streamA.stream]: Timecode out of order [audio]: 7971884286:7971884288

WARN server comment 2016-10-03 14:11:29 - - - - - 245294.977 - - - - - - - - CupertinoPacketHandler.handlePacket[live/definst/streamA.stream]: Timecode out of order [audio]: curr:7971884286 last:7971884288 diff:-2 maxTimecodeGap:-1

WARN server comment 2016-10-03 14:11:30 - - - - - 245295.666 - - - - - - - - CupertinoPacketHandler.handleHolder[live/definst/streamA.stream_160p]: Invalid video/audio codec combination for iPhone/iPod: video:H264 audio:G711_MULAW

WARN server comment 2016-10-03 14:11:30 - - - - - 245295.667 - - - - - - - - CupertinoPacketHandler.handleHolder[live/definst/streamA.stream_160p]: Invalid audio codec for iPhone/iPod:G711_MULAW

WARN server comment 2016-10-03 14:11:30 - - - - - 245295.67 - - - - - - - - CupertinoPacketHandler.handleHolder[live/definst/streamA.stream_720p]: Invalid video/audio codec combination for iPhone/iPod: video:H264 audio:G711_MULAW

WARN server comment 2016-10-03 14:11:30 - - - - - 245295.671 - - - - - - - - CupertinoPacketHandler.handleHolder[live/definst/streamA.stream_240p]: Invalid video/audio codec combination for iPhone/iPod: video:H264 audio:G711_MULAW

WARN server comment 2016-10-03 14:11:30 - - - - - 245295.671 - - - - - - - - CupertinoPacketHandler.handleHolder[live/definst/streamA.stream_240p]: Invalid audio codec for iPhone/iPod:G711_MULAW

WARN server comment 2016-10-03 14:11:30 - - - - - 245295.671 - - - - - - - - CupertinoPacketHandler.handleHolder[live/definst/streamA.stream_720p]: Invalid audio codec for iPhone/iPod:G711_MULAW

WARN server comment 2016-10-03 14:11:30 - - - - - 245295.672 - - - - - - - - CupertinoPacketHandler.handleHolder[live/definst/streamA.stream_360p]: Invalid video/audio codec combination for iPhone/iPod: video:H264 audio:G711_MULAW

WARN server comment 2016-10-03 14:11:30 - - - - - 245295.673 - - - - - - - - CupertinoPacketHandler.handleHolder[live/definst/streamA.stream_360p]: Invalid audio codec for iPhone/iPod:G711_MULAW

WARN server comment 2016-10-03 14:11:30 - - - - - 245295.778 - - - - - - - - SanJosePacketHandler.handlePacket[live/definst/streamA.stream_720p]: Timecode out of order [audio]: 7971884286:7971884288

WARN server comment 2016-10-03 14:11:30 - - - - - 245295.779 - - - - - - - - CupertinoPacketHandler.handlePacket[live/definst/streamA.stream_240p]: Timecode out of order [audio]: curr:7971884286 last:7971884288 diff:-2 maxTimecodeGap:-1

WARN server comment 2016-10-03 14:11:30 - - - - - 245295.779 - - - - - - - - SanJosePacketHandler.handlePacket[live/definst/streamA.stream_240p]: Timecode out of order [audio]: 7971884286:7971884288

WARN server comment 2016-10-03 14:11:30 - - - - - 245295.779 - - - - - - - - CupertinoPacketHandler.handlePacket[live/definst/streamA.stream_360p]: Timecode out of order [audio]: curr:7971884286 last:7971884288 diff:-2 maxTimecodeGap:-1

WARN server comment 2016-10-03 14:11:30 - - - - - 245295.779 - - - - - - - - SanJosePacketHandler.handlePacket[live/definst/streamA.stream_360p]: Timecode out of order [audio]: 7971884286:7971884288

WARN server comment 2016-10-03 14:11:30 - - - - - 245295.779 - - - - - - - - CupertinoPacketHandler.handlePacket[live/definst/streamA.stream_160p]: Timecode out of order [audio]: curr:7971884286 last:7971884288 diff:-2 maxTimecodeGap:-1

WARN server comment 2016-10-03 14:11:30 - - - - - 245295.779 - - - - - - - - SanJosePacketHandler.handlePacket[live/definst/streamA.stream_160p]: Timecode out of order [audio]: 7971884286:7971884288

WARN server comment 2016-10-03 14:11:30 - - - - - 245295.778 - - - - - - - - CupertinoPacketHandler.handlePacket[live/definst/streamA.stream_720p]: Timecode out of order [audio]: curr:7971884286 last:7971884288 diff:-2 maxTimecodeGap:-1

WARN server comment 2016-10-03 14:11:32 - - - - - 245297.884 - - - - - - - - TranscoderStreamNameGroup.validateMembers[defaultVHost:live/definst/streamA.stream]: StreamNameGroup[720p] audio codec is not valid for name group: G711_MULAW (g711.mulaw)

WARN server comment 2016-10-03 14:11:32 - - - - - 245297.889 - - - - - - - - TranscoderStreamNameGroup.validateMembers[defaultVHost:live/definst/streamA.stream]: StreamNameGroup[360p] audio codec is not valid for name group: G711_MULAW (g711.mulaw)

WARN server comment 2016-10-03 14:11:32 - - - - - 245297.889 - - - - - - - - TranscoderStreamNameGroup.validateMembers[defaultVHost:live/definst/streamA.stream]: StreamNameGroup[240p] audio codec is not valid for name group: G711_MULAW (g711.mulaw)

WARN server comment 2016-10-03 14:11:32 - - - - - 245297.889 - - - - - - - - TranscoderStreamNameGroup.validateMembers[defaultVHost:live/definst/streamA.stream]: StreamNameGroup[160p] audio codec is not valid for name group: G711_MULAW (g711.mulaw)

WARN server comment 2016-10-03 14:11:32 - - - - - 245297.889 - - - - - - - - TranscoderStreamNameGroup.addInternal[defaultVHost:live/definst/streamA.stream]: Name group was not added, it is empty: all

WARN server comment 2016-10-03 14:11:32 - - - - - 245297.89 - - - - - - - - TranscoderStreamNameGroup.validateMembers[defaultVHost:live/definst/streamA.stream]: StreamNameGroup[240p] audio codec is not valid for name group: G711_MULAW (g711.mulaw)

WARN server comment 2016-10-03 14:11:32 - - - - - 245297.89 - - - - - - - - TranscoderStreamNameGroup.validateMembers[defaultVHost:live/definst/streamA.stream]: StreamNameGroup[160p] audio codec is not valid for name group: G711_MULAW (g711.mulaw)

WARN server comment 2016-10-03 14:11:32 - - - - - 245297.89 - - - - - - - - TranscoderStreamNameGroup.addInternal[defaultVHost:live/definst/streamA.stream]: Name group was not added, it is empty: mobile

WARN server comment 2016-10-03 14:11:37 - - - - - 245302.615 - - - - - - - - MPEGDashWriterHandler.endChunk[live/definst/streamA.stream]: Stream start time calculation is off by 6057ms from original value.

WARN server comment 2016-10-03 14:11:38 - - - - - 245303.827 - - - - - - - - MPEGDashWriterHandler.endChunk[live/definst/streamA.stream_160p]: Stream start time calculation is off by 5587ms from original value.

WARN server comment 2016-10-03 14:11:38 - - - - - 245303.827 - - - - - - - - MPEGDashWriterHandler.endChunk[live/definst/streamA.stream_720p]: Stream start time calculation is off by 5588ms from original value.

WARN server comment 2016-10-03 14:11:38 - - - - - 245303.827 - - - - - - - - MPEGDashWriterHandler.endChunk[live/definst/streamA.stream_240p]: Stream start time calculation is off by 5588ms from original value.

WARN server comment 2016-10-03 14:11:38 - - - - - 245303.827 - - - - - - - - MPEGDashWriterHandler.endChunk[live/definst/streamA.stream_360p]: Stream start time calculation is off by 5588ms from original value.

WARN server comment 2016-10-03 14:11:40 - - - - - 245305.715 - - - - - - - - MPEGDashWriterHandler.endChunk[live/definst/streamA.stream_720p]: Stream start time calculation is off by 1644ms from original value.

Hi,

These messages are just logging that the source timecodes are changing. It’s usually nothing to be concerned about. If you haven’t already you may also want to ensure you’ve applied the latest update (currently 4.5.0.03) from the My Downloads tab of your customer portal. There have been a number of updates to MPEG-DASH since the last full release.

Regards,

Paul