Why the Cache on Demand is slow, analyzed with the log file

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.

posted @ 2012-07-20 12:00  你不认识我  阅读(285)  评论(1编辑  收藏  举报