Hi,
In January, I began to notice short periods (about an hour or so) of
increased latency.
New instances take about a minute to load. The pages only load simple
django patterns. No database operations. Normally, new instances take about
4 seconds to load. So the minute load time looks completely unexplainable.
But today I see even more weird load time. Here are just a few such entries
from my log:
1.
0.0.0.0 - - [19/Jan/2012:12:27:28 -0800] "GET / HTTP/1.1" 500 0 -
2.
"Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.10)
Gecko/20100914 Firefox/3.6.10 ( .NET CLR 3.5.30729)" "example.com"
3.
ms=*680991* cpu_ms=537 api_cpu_ms=0 cpm_usd=0.014935
loading_request=1 pending_ms=4399 exit_code=203
instance=00c61b117c003720d56f8afc2e1e5cbfb8b5ab
4.
This request caused a new process to be started for your application, and
thus caused your application code to be loaded for the first time. This request
may thus take longer and use more CPU than a typical request for your
application.
5.
The process handling this request unexpectedly died. This is likely to cause
a new process to be used for the next request to your application. (Error code
203)
1.
0.0.0.0 - - [19/Jan/2012:12:26:57 -0800] "GET / HTTP/1.1" 500 0 -
2.
"Mozilla/5.0 (Windows; U; Windows NT 5.1; en-GB; rv:1.9.2.25)
Gecko/20111212 Firefox/3.6.25 ( .NET CLR 3.5.30729)" "example.com"
3.
ms=*681098* cpu_ms=490 api_cpu_ms=0 cpm_usd=0.013652 loading_request=1
pending_ms=4898 exit_code=203 instance=00c61b117c383910c3d83c3e33e4b2f8511562
4.
This request caused a new process to be started for your application, and
thus caused your application code to be loaded for the first time. This request
may thus take longer and use more CPU than a typical request for your
application.
5.
The process handling this request unexpectedly died. This is likely to cause
a new process to be used for the next request to your application. (Error code
203)
1.
0.0.0.0 - - [19/Jan/2012:12:21:23 -0800] "GET / HTTP/1.1" *200* 5367 -
2.
"Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.9.2.10)
Gecko/20100914 Firefox/3.6.10 ( .NET CLR 3.5.30729)" "example.com"
3.
ms=*555699* cpu_ms=653 api_cpu_ms=0 cpm_usd=0.018776
loading_request=1 pending_ms=4014
instance=00c61b117ca9c15eb45f2a94e3585a92dab6ce00
4.
This request caused a new process to be started for your application, and
thus caused your application code to be loaded for the first time. This request
may thus take longer and use more CPU than a typical request for your
application.
Note, the last entry is for successful request (response code 200) that took
* 555699 *ms! (9 minutes?)
My app settings:
Max Idle Instances: ( 1 )
Min Pending Latency: ( Automatic )
(I'm trying to operate using only free quota)
My app normally has 0.2 requests/second and 1 active instance. Most
requests complete within 1 second, the rest within 10 seconds.
During the weird latency periods I can see the following stats (right
now):
0.1 requests/second and *21*(!) active instances (during the peak) -
what's wrong?
What can contribute to these 10+ minutes load times? How is this possible?
--
You received this message because you are subscribed to the Google Groups
"Google App Engine" group.
To view this discussion on the web visit
https://groups.google.com/d/msg/google-appengine/-/J8jrl_lU16wJ.
To post to this group, send email to google-appengine@googlegroups.com.
To unsubscribe from this group, send email to
google-appengine+unsubscr...@googlegroups.com.
For more options, visit this group at
http://groups.google.com/group/google-appengine?hl=en.
