Mediacache returning 404

Hi,

I am seeing that mediacache is returning a 404 error. 404 is returned when media is not found in the cache. Isn’t the purpose of the mediacache to fetch from source if media is not present in the cache?

Or

does the metadata think that the media is in the cache but the media cannot be found in the cache. Don’t understand why or how this could happen.

2014-04-01 19:41:40 UTC comment server WARN 404 http/uploads/test/5330f2c650233dbe7e000001/test.smil open: java.io.IOException: MediaCacheRandomAccessReader.open: Item not in cache: http/uploads/test/5330f2c650233dbe7e000001/test.smil?signature=4e3aeb5ff031742114493bae22b81657c560becf&access_token=1ILqMXqHQlreGp2-3F8rR1bKDY0%3DWq5TceIh1e6824e30f6cb9bb758f8bf719b96d58c39dfd241bec4b83c063506c98474af4a986c80256e950a4ce82e400549c28d5f7a09ee8a98b6142c305360eae70211a&referer=https://test.test.com/&test_code=abcd - - - 125.731 - - - - - - - - - -

This seems to happen randomly, if I click the play button again after the 404 error, it sometimes works after 3 or 4 attempts.

Is there a way to make mediacache send the request to the source instead of returning a 404.

Is there any document I could read on how the cache index is generated, what part of the request is used as an index to look up on subsequent media requests?

Thank you.

Hi,

The 404 response here will be returned if the request to the source resulted in a non successful response.

I noted in your other forum post, Mediacache - caching is not useful, that you have implemented a custom MediaCacheRandomAccessReader class and are adding the variables to the url in the init method of that class.

When your class calls init for the main MediaCacheRandomAccessReader, the Media Cache should send a HEAD request to the source and is expecting a 200 response along with the file size. If it doesn’t receive a valid response, it determines that it cannot access the content and a 404 message is logged and returned to the player.

Roger.

Hi,

Looking at your log output, the mediaCache is trying to locate the item here,

http://boxer:3030/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4

Is this where you are expecting it to be looking?

I can see that you have multiple sources configured. It is going through each of the sources in turn to see if it can handle the request. It looks at the beginning of the url (http/) to match the prefix set for the source.

MediaCacheSourceBasic.canHandle[dvrorigin]: false
. . . 
MediaCacheSourceBasic.canHandle[abcdef-boxer]: true

Once if finds a source that can handle the request, it won’t try other sources. It then tries to make a connection to the source and do a HEAD request on the file and get the file length.

MediaCacheItemHTTPImpl.init hostId:boxer url:http://boxer:3030/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?...
MediaCacheItemHTTPImpl.getItemInfo[boxer/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?...

It is receiving a HTTP 500 response from this request.

It tries 3 or 4 times before giving up.

The ELB in front of Wowza shouldn’t cause an issue. A 500 response comes from the web server that the Media cache is talking to. It has received the request but for what ever reason, it cannot handle the request. It is normally due to a misconfigured web server or some coding error in the web server application that handles the request. It could also happen if one of the values that you are sending is not the right type, i.e. it is expecting a number and you are sending a string value.

If you look at the web server error logs, it may give some indication as to what went wrong. If there is also an ELB in front of the web server, I guess that could also cause issues if the ELB thinks it cannot handle the request from the Media Cache, especially, if it is sending it out to the wrong server.

Note: I wrapped your logging output with code tags so that it is easier to read.

Roger.

In you Media Cache source, if you add the following property, it will add debug information that shows the HTTP requests from the MediaCache to the source.

	<Property>
		<Name>debugHTTPRequests</Name>
		<Value>true</Value>
		<Type>Boolean</Type>
	</Property>

Roger.

Hi,

The property needs to be in the Media Cache Source properties section. When it is enabled, you should see every request and response from the Media Cache to the source.

If you are running Wowza Streaming Engine 4.0 or later, you can enable debug logging for the MediaCache without having to enable full debug logging in the log4j.properties. Edit MediaCache.xml and set Root/MediaCache/DebugLog to true. You will have to restart Wowza after making the change.

Roger.

Hi Roger:

Looks like the mediacache is returning a 404 without querying the source. I did not receive any request at the source (no GET or HEAD request). I am cutting and pasting the access log file here. Apologize for the inline log file. Please let me know if there is a better way to get a log file to you.

The 404 is at line 102.

Thankyou

014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	HTTPStreamerAdapterMPEGDashStreamer.canHandle[mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8]: false	-	-	255.64	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	INFO	200	-	HTTPStreamerAdapterSmoothStreamer.canHandle[mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8]: false	-	-	255.641	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	HTTPStreamerAdapterCupertinoStreamer.canHandle[mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8]: true	-	-	255.641	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	HTTPStreamerAdapterCupertinoStreamer.service: mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683	-	-	-	255.645	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	INFO	200	-	HTTPStreamerAdapterCupertinoStreamer.onPlaylist: mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683	-	-	-	255.65	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	Loading Application.xml: file:///usr/local/WowzaStreamingEngine/conf/mediacache/Application.xml	-	-	-	255.697	-	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	load module: name:base class:com.wowza.wms.module.ModuleCore	-	-	-	255.713	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): closeStream	-	-	-	255.715	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): FCPublish	-	-	-	255.716	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): FCUnPublish	-	-	-	255.717	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): FCUnpublish	-	-	-	255.717	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): pauseRaw	-	-	-	255.717	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): FCSubscribe	-	-	-	255.718	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): FCUnsubscribe	-	-	-	255.718	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): FCUnSubscribe	-	-	-	255.719	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): startTransmit	-	-	-	255.719	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): stopTransmit	-	-	-	255.719	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): releaseStream	-	-	-	255.72	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): setBandwidthLimit	-	-	-	255.72	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): play2	-	-	-	255.72	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): publish	-	-	-	255.721	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): getVersion	-	-	-	255.721	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): play	-	-	-	255.721	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): getRepeaterOriginUrl	-	-	-	255.721	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): setRepeaterOriginUrl	-	-	-	255.722	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): createStream	-	-	-	255.722	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): initStream	-	-	-	255.722	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): getLastStreamId	-	-	-	255.723	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): getStreamLength	-	-	-	255.723	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): getStreamBitrate	-	-	-	255.723	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): getClientID	-	-	-	255.724	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): getLiveStreamPacketizer	-	-	-	255.724	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): setLiveStreamPacketizer	-	-	-	255.724	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): initLiveStreamRepeating	-	-	-	255.725	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): receiveAudio	-	-	-	255.725	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): receiveVideo	-	-	-	255.725	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): setBufferTime	-	-	-	255.726	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): getStreamType	-	-	-	255.726	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): setStreamType	-	-	-	255.726	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): getReferrer	-	-	-	255.727	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): getPageUrl	-	-	-	255.727	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): pause	-	-	-	255.727	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): deleteStream	-	-	-	255.728	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): seek	-	-	-	255.728	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	load module: name:logging class:com.wowza.wms.module.ModuleClientLogging	-	-	255.729	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): logInfo	-	-	-	255.73	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): logDebug	-	-	-	255.73	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): logWarn	-	-	-	255.73	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): logError	-	-	-	255.731	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	load module: name:flvplayback class:com.wowza.wms.module.ModuleFLVPlayback	-	-	255.731	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (method): checkBandwidth	-	-	-	255.732	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (event): onDisconnect	-	-	-	255.732	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (event): onConnect	-	-	-	255.732	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (event): onConnectReject	-	-	-	255.733	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	  add method (event): onConnectAccept	-	-	-	255.733	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	SharedObjectReadAccess: *	-	-	-	255.762	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	SharedObjectWriteAccess: *	-	-	-	255.763	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	StreamReadAccess: *	-	-	-	255.763	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	StreamWriteAccess: 	-	-	-	255.763	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	StreamAudioSampleAccess: 	-	-	-	255.764	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	StreamVideoSampleAccess: 	-	-	-	255.764	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	explodeStorageDir: ${com.wowza.wms.context.VHostConfigHome}/content=/usr/local/WowzaStreamingEngine/content	-	-	-	255.768	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	validateStoragePath: start: /usr/local/WowzaStreamingEngine/content	-	-	-	255.769	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	explodeStorageDir: ${com.wowza.wms.context.VHostConfigHome}/keys=/usr/local/WowzaStreamingEngine/keys	-	-	-	255.774	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	validateStoragePath: start: /usr/local/WowzaStreamingEngine/keys	-	-	-	255.775	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	explodeStorageDir: ${com.wowza.wms.context.VHostConfigHome}/applications/${com.wowza.wms.context.Application}/sharedobjects/${com.wowza.wms.context.ApplicationInstance}=/usr/local/WowzaStreamingEngine/applications/mediacache/sharedobjects/_definst_	-	-	255.776	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	validateStoragePath: start: /usr/local/WowzaStreamingEngine/applications/mediacache/sharedobjects/_definst_	-	-	-	255.777	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	INFO	200	-	MediaCasterStreamValidator.init[mediacache/_definst_]: Started	-	-	-	255.801	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	app-start	application	INFO	200	_definst_	mediacache/_definst_	-	-	-	255.875	-	-	-
2014-04-02	15:19:09	UTC	connect	cupertino	INFO	200	1446793164	-	_defaultVHost_	mediacache	_definst_	0.208	test.abcdef.com	1935	[url]http://test.abcdef.com:1935/mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683[/url]	10.242.65.241	http (cupertino)	[url]https://test.abcdef.com/[/url]	Mozilla/5.0 (Macintosh; Intel Mac OS X 10_9_2) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/33.0.1750.152 Safari/537.36	1446793164	0	http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4	signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683	-	-	-	-	[url]http://test.abcdef.com:1935/mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683[/url]	[url]http://test.abcdef.com:1935/mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8[/url]	signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683	[url]http://test.abcdef.com:1935/mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8[/url]	signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683
2014-04-02	15:19:09	UTC	create	stream	INFO	200	http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4	-	_defaultVHostmediacache	_definst_	0.003	test.abcdef.com	1935	[url]http://test.abcdef.com:1935/mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683[/url]	10.242.65.241	http (cupertino)	[url]https://test.abcdef.com/[/url]	Mozilla/5.0 (Macintosh; Intel Mac OS X 10_9_2) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/33.0.1750.152 Safari/537.36	1446793164	0	0	1	0	0	0	http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4	signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683	-	-	-	-	[url]http://test.abcdef.com:1935/mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683[/url]	[url]http://test.abcdef.com:1935/mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8[/url]	signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683	[url]http://test.abcdef.com:1935/mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8[/url]	signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	MediaReaderH264.mp4ReadAheadSize: 65536	-	-	-	0.224	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	MediaReaderH264.mp4ReadAheadTrigger: 32768	-	-	-	0.224	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	MediaReaderH264.mp4IndexBufferSize: 16384	-	-	-	0.225	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	MediaReaderH264.randomAccessReaderClass: com.abcdef.wowza.abcdefMediaCacheRandomAccessReade0.225	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	MediaReaderH264.randomAccessReaderOptimizerClass: com.wowza.io.RandomAccessReadOptimizer	0.225	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	MediaCacheSourceBasic.canHandle[dvrorigin]: false mediaName:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4 prefix:dvrorigin/	0.231	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	MediaCacheSourceBasic.canHandle[abcdef-boxer]: true mediaName:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4 prefix:http/	-	0.232	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	MediaCache.acquireReader[http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4]: createNew	-	-	-	0.232	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	MediaCacheSourceBasic.canHandle[dvrorigin]: false mediaName:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4 prefix:dvrorigin/	0.233	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	MediaCacheSourceBasic.canHandle[abcdef-boxer]: true mediaName:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4 prefix:http/	-	0.233	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:09	UTC	comment	server	DEBUG	200	-	MediaCacheItemHTTPImpl.init hostId:boxer url:[url]http://boxer:3030/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4[/url]	-	-	-	0.238	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:10	UTC	comment	server	WARN	200	-	MediaCacheItemHTTPImpl.getItemInfo[boxer/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4]: HTTP response: 500	-	-	-	0.5	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:10	UTC	comment	server	DEBUG	200	-	MediaCacheItemHTTPImpl.getItemInfo length: 1991	-	-	-	0.501	-	-
2014-04-02	15:19:10	UTC	comment	server	WARN	200	-	MediaCacheItemBase.init: Item does not exist: http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4	-	-	-	0.502-
2014-04-02	15:19:10	UTC	comment	server	DEBUG	200	-	MediaCacheSourceBasic.canHandle[dvrorigin]: false mediaName:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4 prefix:dvrorigin/	0.504	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:10	UTC	comment	server	DEBUG	200	-	MediaCacheSourceBasic.canHandle[abcdef-boxer]: true mediaName:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4 prefix:http/	-	0.504	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:10	UTC	comment	server	DEBUG	200	-	MediaCache.acquireReader[http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4]: createNew	-	-	-	0.505	-	-
2014-04-02	15:19:10	UTC	comment	server	DEBUG	200	-	MediaCacheSourceBasic.canHandle[dvrorigin]: false mediaName:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4 prefix:dvrorigin/	0.505	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:10	UTC	comment	server	DEBUG	200	-	MediaCacheSourceBasic.canHandle[abcdef-boxer]: true mediaName:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4 prefix:http/	-	0.506	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:10	UTC	comment	server	DEBUG	200	-	MediaCacheItemHTTPImpl.init hostId:boxer url:[url]http://boxer:3030/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4[/url]	-	-	-	0.506	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:10	UTC	comment	server	WARN	200	-	MediaCacheItemHTTPImpl.getItemInfo[boxer/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4]: HTTP response: 500	-	-	-	0.676	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:10	UTC	comment	server	DEBUG	200	-	MediaCacheItemHTTPImpl.getItemInfo length: 1991	-	-	-	0.676	-	-
2014-04-02	15:19:10	UTC	comment	server	WARN	200	-	MediaCacheItemBase.init: Item does not exist: http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4	-	-	-	0.676-
2014-04-02	15:19:10	UTC	comment	server	DEBUG	200	-	MediaCache.acquireReader[http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4]: createNew	-	-	-	0.677	-	-
2014-04-02	15:19:10	UTC	comment	server	DEBUG	200	-	MediaCacheSourceBasic.canHandle[dvrorigin]: false mediaName:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4 prefix:dvrorigin/	0.677	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:10	UTC	comment	server	DEBUG	200	-	MediaCacheSourceBasic.canHandle[abcdef-boxer]: true mediaName:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4 prefix:http/	-	0.678	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:10	UTC	comment	server	DEBUG	200	-	MediaCacheItemHTTPImpl.init hostId:boxer url:[url]http://boxer:3030/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4[/url]	-	-	-	0.678	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:10	UTC	comment	server	WARN	200	-	MediaCacheItemHTTPImpl.getItemInfo[boxer/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4]: HTTP response: 500	-	-	-	0.8	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:10	UTC	comment	server	DEBUG	200	-	MediaCacheItemHTTPImpl.getItemInfo length: 1991	-	-	-	0.801	-	-
2014-04-02	15:19:10	UTC	comment	server	WARN	200	-	MediaCacheItemBase.init: Item does not exist: http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd.mp4	-	-	-	0.801-
2014-04-02	15:19:10	UTC	comment	server	WARN	404	http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4	MediaReaderH264.open[1]: java.io.IOException: MediaCacheRandomAccessReader.open: Item not in cache: http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd: java.io.IOException: MediaCacheRandomAccessReader.open: Item not in cache: http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683&access_token=4FgOOjL_ETYyzkS3XMZw5rXdXz4%3DOoc4ISi29d1013fb647cfe6cbde68c882af4e31d5b278ddb8e14ce2c29f9ecd5dda2d7679b6a55a0293d93b324dd6905cf092ea44267e23e8bd018dcdc5aaff1cbbd7ef3&referer=https://test.abcdef.com/&test_code=abcd|at com.wowza.wms.mediacache.impl.MediaCacheRandomAccessReader.open(MediaCacheRandomAccessReader.java:81)|at com.wowza.wms.mediacache.impl.MediaCacheRandomAccessReaderFilter.open(MediaCacheRandomAccessReaderFilter.java:75)|at com.abcdef.wowza.abcdefMediaCacheRandomAccessReader.open(abcdefMediaCacheRandomAccessReader.java:139)|at com.wowza.wms.mediareader.h264.MediaReaderH264.open(MediaReaderH264.java:250)|at com.wowza.wms.httpstreamer.model.HTTPStreamerAdapterBase.doesFileExist(HTTPStreamerAdapterBase.java:1423)|	0.804	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:42	UTC	comment	server	INFO	200	-	killUnidentifiedSessions: Killing unidentified session: address:/10.242.65.241:44727	-	-	288.899	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:42	UTC	comment	server	INFO	200	-	killUnidentifiedSessions: Killing unidentified session: address:/10.242.65.241:44728	-	-	288.899	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:44	UTC	destroy	stream	INFO	200	http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4	-	_defaultVHost_	mediacache	_definst_	35.01	test.abcdef.com	1935	[url]http://test.abcdef.com:1935/mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683[/url]	10.242.65.241	http (cupertino)	[url]https://test.abcdef.com/[/url]	Mozilla/5.0 (Macintosh; Intel Mac OS X 10_9_2) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/33.0.1750.152 Safari/537.36	1446793164	0	0	1	0	0	0	http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4	signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683	-	-	-	-	[url]http://test.abcdef.com:1935/mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683[/url]	[url]http://test.abcdef.com:1935/mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8[/url]	signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683	[url]http://test.abcdef.com:1935/mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8[/url]	signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683
2014-04-02	15:19:44	UTC	disconnect	cupertino	INFO	200	1446793164	-	_defaultVHost_	mediacache	_definst_	35.217	test.abcdef.com	1935	[url]http://test.abcdef.com:1935/mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683[/url]	10.242.65.241	http (cupertino)	[url]https://test.abcdef.com/[/url]	Mozilla/5.0 (Macintosh; Intel Mac OS X 10_9_2) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/33.0.1750.152 Safari/537.36	1446793164	0	0	-	0	-	-	http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4	signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683	-	-	-	-	[url]http://test.abcdef.com:1935/mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8?signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683[/url]	[url]http://test.abcdef.com:1935/mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8[/url]	signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683	[url]http://test.abcdef.com:1935/mediacache/_definst_/mp4:http/uploads/abcdefstaging/52faf83d1be8bdb24d000001/_OneMintest.mp4/manifest.m3u8[/url]	signature=aa0d0c46dc1f6cf4221435522be4efbf9f931683
2014-04-02	15:19:57	UTC	comment	server	INFO	200	-	killUnidentifiedSessions: Killing unidentified session: address:/10.242.65.241:44732	-	-	303.911	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-
2014-04-02	15:19:57	UTC	comment	server	INFO	200	-	killUnidentifiedSessions: Killing unidentified session: address:/10.242.65.241:44733	-	-	303.912	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-	-

Quick update.

We have an Amazon elastic load balancer in front of the wowza server. I think the LB is not happy with some of the requests and is responding with a 500. I am in the process of turning on the LB logs and seeing what is happening. Will keep you posted. Please let me know if there are any known issues with using Amazon Elastic LB.

Hi Roger - As per your suggestion I added the debug property, the property was already there but was set to false, I set it to true. I restarted wowza. I see no difference in the access log file. Is there any thing else I have to do to see the HTTP requests.

Thanks,

Hi Roger - Found the root cause. It was a problem at the source web-server. We fixed that and it works now. Debugging this problem helped me understand Wowza better. Thanks for your help.

Hi,

Can you confirm that you are running the latest release of Wowza Streaming Engine and that you don’t have any custom modules enabled? Also does this occur with any media sources or is it specific to certain ones? For example, can you replicate this when using the provided sample.mp4 as your source?

If this is reproducible then I’d suggest opening a support ticket and include a zip of your /conf and /logs. Please also reference this thread when opening.

Regards,

Paul

Hi

I am facing same issue. I am using S3 buckets to store videos and to fetch videos from bucket I am using Cloudfront. I have already configured cloudfront url as base url while creating mediacache. Majority of times its working fine, but some times its producing the error 404 even though video is there in the said path.

This is very intermittent error and I am not able to debug it even though I have added following line at proper location. I see the added logs but nothing is conclusive there.

debugHTTPRequests

true

Boolean

There is no authentication condition over cloudfront or S3 bucket.

Following is the JAVA exception that I got in wowza logs.

channelvideos/QA.SERVER%2F93%2F37%23%23%235accf2e17ba82ab4414b875e70ee1b4ba27a1b9763e84371ec51ceb0ff15904e.mp4 MediaReaderH264.open[2]: java.lang.NullPointerException: java.lang.NullPointerException|at com.wowza.wms.mediareader.h264.MediaReaderH264.open(MediaReaderH264.java:277)|at com.wowza.wms.stream.publish.PublishingProviderMediaReader.a(PublishingProviderMediaReader.java:96)|at com.wowza.wms.stream.publish.PublishingProviderMediaReader.(PublishingProviderMediaReader.java:71)|at com.perennialsys.channel.thread.Channel.readFromVOD(Channel.java:301)|at com.perennialsys.channel.thread.ContinuousChannel.readFromVOD(ContinuousChannel.java:44)