Why the Cache on Demand is slow, analyzed with the log file-优快云博客

本文探讨了ArcGIS for Server 10.1版本中SampleWorldCities服务的缓存机制，详细记录了缓存请求处理过程的日志信息，并分析了每次请求处理的时间，指出无论视图范围如何变化，处理时间保持一致的现象，揭示了按超瓦片进行缓存的工作机制。

SampleWorldCities is the basic map service for the fresh installed ArcGIS for Server 10.1. This service consists of 3 layers: Cities, Continent and World with one class breaks renderer and two simple fill renderers for each. You may find this map is kinda similar with the former "World", while there is no SampleWorldCities.mxd but SampleWorldCities.msd attached with ArcGIS for Server 10.1.

Cache on Demand works fine with previous versions of ArcGIS Server, when it comes to 10.1, it takes nearly 10secs to generate the requested tiles. That is unbearable! Started with the log files, I found these:

级别	时间	消息	源
FINE	2012-7-19 下午2:54:04	REST request received. Request size is 34 characters.	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 0	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 2	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 1	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 0	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 2	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 1	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 1	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 2	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 1	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 0	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 2	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 1	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 0	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 2	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 1	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 0	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 2	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 1	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 1	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 2	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 1	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 2	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 2	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 1	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 1	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 2	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 1	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 0	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 2	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 1	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 0	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 2	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 1	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 0	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 2	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 1	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 0	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Cities	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 2	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: Continent	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 1	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Data Access	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbolizing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Execute Query	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of layer draw: World	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	End of labeling phase (labeling and label draw)	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Number of features drawn: 1	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Symbol Drawing	SampleWorldCities.MapServer
VERBOSE	2012-7-19 下午2:54:04	Beginning of labeling phase (labeling and label draw)	SampleWorldCities.MapServer

The final operation ended at 2:54:12 and the total process time was 9.5sec approximately. Soon after I had noticed that no matter what the extent was, the average process time for each kept the same and the tiles round the visible extent could be loaded immediately without waiting. This phenomenon indicated the machanism of CACHING. Every time a SOC instance receives a request to cache the specific extent to tiles, it locates this extent to one or more supertiles(4096*4096 or 2048*2048 antialiazed) which is the atomic package for the SOC instance. That is to say, On Demand Caching carrys on supertiles to each request. Therefor, you must make a appropriate plan in determining areas to be cached staticly or dynamically.

转载于:https://www.cnblogs.com/fortoday/archive/2012/07/20/2599739.html