So, this behavior is completely reproducible. 1) Shut down all instances. 2) Set min instances to 1 (leave all other sliders at default values). 3) Save settings and wait for instance to spin up. 4) Issue one GET request from browser to a specific API endpoint. This is an API call so it is not multiplexing into additional requests the way, say, an HTML page fetch would (to get css, images, etc). It really is just one call. 5) Note that Google App Engine spins up a new instance to handle the request.
Here are the logs from this; there are no other events within two minutes of this window of time: 2012-07-31 23:08:28.045 /api/game/57002?pretty=true 200 7893ms 11kb Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_4) AppleWebKit/536.25 (KHTML, like Gecko) Version/6.0 Safari/536.25 76.102.149.245 - kris [31/Jul/2012:23:08:28 -0700] "GET /api/game/57002?pretty=true HTTP/1.1" 200 11652 - "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_4) AppleWebKit/536.25 (KHTML, like Gecko) Version/6.0 Safari/536.25" "titan-game-qa.appspot.com" ms=7893 cpu_ms=3520 api_cpu_ms=0 cpm_usd=0.099322 instance=00c61b117c77507e2cfe78a0806d0ca80b52720e 2012-07-31 23:08:27.475 /_ah/warmup 200 5873ms 0kb 0.1.0.3 - - [31/Jul/2012:23:08:27 -0700] "GET /_ah/warmup HTTP/1.1" 200 60 - - "1.360723738856412175.titan-game-qa.appspot.com" ms=5873 cpu_ms=2475 api_cpu_ms=0 cpm_usd=0.068778 loading_request=1 instance=00c61b117cdaae6145945d99c16aeee7cc0f4ad8 I 2012-07-31 23:08:27.466 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. 2012-07-31 23:07:42.842 /_ah/warmup 200 5045ms 0kb 0.1.0.3 - - [31/Jul/2012:23:07:42 -0700] "GET /_ah/warmup HTTP/1.1" 200 60 - - "1.360723738856412175.titan-game-qa.appspot.com" ms=5046 cpu_ms=2475 api_cpu_ms=0 cpm_usd=0.068778 loading_request=1 instance=00c61b117c77507e2cfe78a0806d0ca80b52720e I 2012-07-31 23:07:42.841 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. Spinning up 10 instances doesn't help. I refreshed this GET at somewhat irregular intervals for about thirty seconds, and GAE spun up five new instances, despite not being even close to out of headroom: 2012-07-31 23:17:02.178 /api/game/57002?pretty=true 200 4744ms 11kb Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_4) AppleWebKit/536.25 (KHTML, like Gecko) Version/6.0 Safari/536.25 2012-07-31 23:17:02.177 /api/game/57002?pretty=true 200 5770ms 11kb Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_4) AppleWebKit/536.25 (KHTML, like Gecko) Version/6.0 Safari/536.25 2012-07-31 23:16:59.793 /api/game/57002?pretty=true 200 1311ms 11kb Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_4) AppleWebKit/536.25 (KHTML, like Gecko) Version/6.0 Safari/536.25 2012-07-31 23:16:57.844 /api/game/57002?pretty=true 200 2618ms 11kb Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_4) AppleWebKit/536.25 (KHTML, like Gecko) Version/6.0 Safari/536.25 2012-07-31 23:16:57.843 /api/game/57002?pretty=true 200 5619ms 11kb Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_4) AppleWebKit/536.25 (KHTML, like Gecko) Version/6.0 Safari/536.25 2012-07-31 23:16:54.623 /api/game/57002?pretty=true 200 309ms 11kb Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_4) AppleWebKit/536.25 (KHTML, like Gecko) Version/6.0 Safari/536.25 2012-07-31 23:16:51.911 /_ah/warmup 200 2942ms 0kb I 2012-07-31 23:16:51.911 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 requ 2012-07-31 23:16:51.622 /api/game/57002?pretty=true 200 174ms 11kb Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_4) AppleWebKit/536.25 (KHTML, like Gecko) Version/6.0 Safari/536.25 2012-07-31 23:16:50.915 /api/game/57002?pretty=true 200 2664ms 11kb Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_4) AppleWebKit/536.25 (KHTML, like Gecko) Version/6.0 Safari/536.25 2012-07-31 23:16:47.720 /api/game/57002?pretty=true 200 557ms 11kb Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_4) AppleWebKit/536.25 (KHTML, like Gecko) Version/6.0 Safari/536.25 2012-07-31 23:16:47.286 /_ah/warmup 200 4823ms 0kb I 2012-07-31 23:16:47.285 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 requ 2012-07-31 23:16:46.377 /api/game/57002?pretty=true 200 5360ms 11kb Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_4) AppleWebKit/536.25 (KHTML, like Gecko) Version/6.0 Safari/536.25 2012-07-31 23:16:40.500 /api/game/57002?pretty=true 200 1828ms 11kb Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_4) AppleWebKit/536.25 (KHTML, like Gecko) Version/6.0 Safari/536.25 2012-07-31 23:16:37.969 /api/game/57002?pretty=true 200 512ms 11kb Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_4) AppleWebKit/536.25 (KHTML, like Gecko) Version/6.0 Safari/536.25 2012-07-31 23:16:37.783 /_ah/warmup 200 2523ms 0kb I 2012-07-31 23:16:37.783 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 requ 2012-07-31 23:16:36.264 /api/game/57002?pretty=true 200 1715ms 11kb Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_4) AppleWebKit/536.25 (KHTML, like Gecko) Version/6.0 Safari/536.25 2012-07-31 23:16:05.552 /api/game/57002?pretty=true 200 6351ms 11kb Mozilla/5.0 (Macintosh; Intel Mac OS X 10_7_4) AppleWebKit/536.25 (KHTML, like Gecko) Version/6.0 Safari/536.25 2012-07-31 23:16:04.883 /_ah/warmup 200 4870ms 0kb I 2012-07-31 23:16:04.881 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 requ 2012-07-31 23:15:42.565 /_ah/warmup 200 4354ms 0kb I 2012-07-31 23:15:42.564 This request caused a new process to be started for your application, and thus caused your application code to be loaded f Here's what my instances looked like at the end. <https://lh4.googleusercontent.com/-PA2RZve74tM/UBjLV-CE3EI/AAAAAAAAADI/3xNNorySv6E/s1600/instances-2.png> - Kris -- 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/-/eqE1I46VPS4J. 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.
