If you're new to the group, you may not yet know to ignore Tapir. >From what I've seen myself and read from others, this isn't normal behaviour. Have you looked into whether you get similar behaviour locally? Can you time how long it takes to start the JVM and your app and respond to a request? Maybe write a script that runs the "runserver" ant task and then calls wget to retrieve a page once it sees :8080 in the output?
I know that people using lots of areas of the Spring framework and also JDO/JPA see much longer startup times than those who use lightweight frameworks. Favourites seems to be Objectify, Guice, Freemarker, Cambridge templates, Resteasy, Htmleasy. (These are mostly frameworks to handle just part of the task that Spring does). Mat. On 24 November 2011 07:53, Tapir <tapir....@gmail.com> wrote: > Although GAE team always says the free quotas are enough for small > apps, > the facts deny this conclusion from time and time. > > I think the main problem is the instance scheduler. > The instance scheduler is very not smart. > > On Nov 24, 3:46 pm, Tapir <tapir....@gmail.com> wrote: > > Pay (to get more idle instances) or go. > > > > On Nov 24, 4:05 am, Scott Murphy <sc...@pixoto.com> wrote: > > > > > > > > > > > > > > > > > Can someone please explain why the startup times of my app are HIGHLY > > > inconsistent. The only access to the datastore I see is when it > creates a > > > session. > > > > > What would cause such a high variance??? > > > > > 1. > > > 1. 2011-11-23 11:59:24.042 /_ah/warmup 200 53918ms 6kb > > > 2. > > > 1. 2011-11-23 11:58:24.403 /_ah/warmup 500 68224ms 0kb > > > 3. > > > 1. 2011-11-23 11:58:08.254 /_ah/warmup 200 41931ms 6kb > > > 4. > > > 1. 2011-11-23 11:58:04.296 /_ah/warmup 200 38301ms 6kb > > > 5. > > > 1. 2011-11-23 11:57:31.280 /_ah/warmup 200 39769ms 6kb > > > 6. > > > 1. 2011-11-23 11:56:27.292 /_ah/warmup 200 39129ms 6kb > > > 7. > > > 1. 2011-11-23 11:55:09.358 /_ah/warmup 200 40067ms 6kb > > > 8. > > > 1. 2011-11-23 11:54:19.186 /_ah/warmup 200 41484ms 6kb > > > 9. > > > 1. 2011-11-23 11:53:10.643 /_ah/warmup 200 43649ms 6kb > > > 10. > > > 1. 2011-11-23 11:52:26.666 /_ah/warmup 200 35097ms 6kb > > > 11. > > > 1. 2011-11-23 11:52:21.543 /_ah/warmup 200 41004ms 6kb > > > 12. > > > 1. 2011-11-23 11:50:59.197 /_ah/warmup 200 50842ms 6kb > > > 13. > > > 1. 2011-11-23 11:50:38.411 /_ah/warmup 200 54322ms 6kb > > > 14. > > > 1. 2011-11-23 11:50:05.128 /_ah/warmup 500 61197ms 0kb > > > 15. > > > 1. 2011-11-23 11:49:49.509 /_ah/warmup 200 33466ms 6kb > > > 16. > > > 1. 2011-11-23 11:49:28.185 /_ah/warmup 200 43114ms 6kb > > > 17. > > > 1. 2011-11-23 11:49:04.039 /_ah/warmup 200 48665ms 6kb > > > 18. > > > 1. 2011-11-23 11:48:45.516 /_ah/warmup 200 56083ms 6kb > > > 19. > > > 1. 2011-11-23 11:48:38.776 /_ah/warmup 200 34770ms 6kb > > > 20. > > > 1. 2011-11-23 11:48:37.808 /_ah/warmup 200 55666ms 6kb > > > 21. > > > 1. 2011-11-23 11:48:16.366 /_ah/warmup 200 56648ms 6kb > > > 22. > > > 1. 2011-11-23 11:48:01.073 /_ah/warmup 200 51638ms 6kb > > > 23. > > > 1. 2011-11-23 11:47:29.635 /_ah/warmup 200 51231ms 6kb > > > 24. > > > 1. 2011-11-23 11:46:44.020 /_ah/warmup 500 63135ms 0kb > > > 25. > > > 1. 2011-11-23 11:46:25.135 /_ah/warmup 500 63424ms 0kb > > > 26. > > > 1. 2011-11-23 11:45:51.898 /_ah/warmup 200 48358ms 6kb > > > 27. > > > 1. 2011-11-23 11:45:04.530 /_ah/warmup 200 44475ms 6kb > > > 28. > > > 1. 2011-11-23 11:44:55.071 /_ah/warmup 200 45317ms 6kb > > > 29. > > > 1. 2011-11-23 11:42:54.513 /_ah/warmup 200 59274ms 6kb > > > 30. > > > 1. 2011-11-23 11:42:52.294 /_ah/warmup 200 53660ms 6kb > > > 31. > > > 1. 2011-11-23 11:42:47.333 /_ah/warmup 200 41321ms 6kb > > > 32. > > > 1. 2011-11-23 11:42:19.248 /_ah/warmup 500 63798ms 0kb > > > 33. > > > 1. 2011-11-23 11:41:27.386 /_ah/warmup 200 44808ms 6kb > > > 34. > > > 1. 2011-11-23 11:40:39.237 /_ah/warmup 200 56062ms 6kb > > > 35. > > > 1. 2011-11-23 11:40:07.117 /_ah/warmup 200 49035ms 6kb > > > 36. > > > 1. 2011-11-23 11:39:36.750 /_ah/warmup 200 56603ms 6kb > > > 37. > > > 1. 2011-11-23 11:39:24.583 /_ah/warmup 200 38206ms 6kb > > > 38. > > > 1. 2011-11-23 11:39:08.247 /_ah/warmup 200 48777ms 6kb > > > 39. > > > 1. 2011-11-23 11:38:37.122 /_ah/warmup 200 32621ms 6kb > > > 40. > > > 1. 2011-11-23 11:38:29.123 /_ah/warmup 200 43760ms 6kb > > > 41. > > > 1. 2011-11-23 11:38:11.801 /_ah/warmup 200 49921ms 6kb > > > 42. > > > 1. 2011-11-23 11:37:33.279 /_ah/warmup 200 32509ms 6kb > > > 43. > > > 1. 2011-11-23 11:37:01.625 /_ah/warmup 200 50572ms 6kb > > > 44. > > > 1. 2011-11-23 11:36:11.510 /_ah/warmup 200 46396ms 6kb > > > 45. > > > 1. 2011-11-23 11:36:00.549 /_ah/warmup 200 58911ms 6kb > > > 46. > > > 1. 2011-11-23 11:35:18.891 /_ah/warmup 200 39333ms 6kb > > > 47. > > > 1. 2011-11-23 11:34:52.073 /_ah/warmup 200 45786ms 6kb > > > 48. > > > 1. 2011-11-23 11:34:51.864 /_ah/warmup 200 49792ms 6kb > > > 49. > > > 1. 2011-11-23 11:34:42.803 /_ah/warmup 200 47192ms 6kb > > > 50. > > > 1. 2011-11-23 11:33:59.085 /_ah/warmup 200 40240ms 6kb > > > > > 1. > > > 1. 2011-11-23 11:33:42.011 /_ah/warmup 200 45390ms 6kb > > > 2. > > > 1. 2011-11-23 11:33:35.179 /_ah/warmup 200 43507ms 6kb > > > 3. > > > 1. 2011-11-23 11:33:02.652 /_ah/warmup 200 42903ms 6kb > > > 4. > > > 1. 2011-11-23 11:31:24.780 /_ah/warmup 200 42572ms 6kb > > > 5. > > > 1. 2011-11-23 11:30:48.566 /_ah/warmup 200 46982ms 6kb > > > 6. > > > 1. 2011-11-23 11:29:40.832 /_ah/warmup 200 41064ms 6kb > > > 7. > > > 1. 2011-11-23 11:29:28.806 /_ah/warmup 200 51422ms 6kb > > > 8. > > > 1. 2011-11-23 11:28:53.777 /_ah/warmup 500 64380ms 0kb > > > 9. > > > 1. 2011-11-23 11:28:35.153 /_ah/warmup 200 37536ms 6kb > > > 10. > > > 1. 2011-11-23 11:28:09.723 /_ah/warmup 200 33384ms 6kb > > > 11. > > > 1. 2011-11-23 11:27:47.256 /_ah/warmup 200 44638ms 6kb > > > 12. > > > 1. 2011-11-23 11:27:36.003 /_ah/warmup 200 40285ms 6kb > > > 13. > > > 1. 2011-11-23 11:27:15.601 /_ah/warmup 200 39266ms 6kb > > > 14. > > > 1. 2011-11-23 11:26:55.195 /_ah/warmup 200 40467ms 6kb > > > 15. > > > 1. 2011-11-23 11:26:15.706 /_ah/warmup 200 42753ms 6kb > > > 16. > > > 1. 2011-11-23 11:25:37.349 /_ah/warmup 500 66337ms 0kb > > > 17. > > > 1. 2011-11-23 11:25:03.982 /_ah/warmup 200 44072ms 6kb > > > 18. > > > 1. 2011-11-23 11:23:55.924 /_ah/warmup 200 41352ms 6kb > > > 19. > > > 1. 2011-11-23 11:23:23.377 /_ah/warmup 200 42378ms 6kb > > > 20. > > > 1. 2011-11-23 11:23:14.026 /_ah/warmup 500 67308ms 0kb > > > 21. > > > 1. 2011-11-23 11:23:08.225 /_ah/warmup 200 50944ms 6kb > > > 22. > > > 1. 2011-11-23 11:22:23.616 /_ah/warmup 200 40849ms 6kb > > > 23. > > > 1. 2011-11-23 11:21:56.962 /_ah/warmup 200 49801ms 6kb > > > 24. > > > 1. 2011-11-23 11:21:38.655 /_ah/warmup 200 44521ms 6kb > > > 25. > > > 1. 2011-11-23 11:21:25.505 /_ah/warmup 200 42200ms 6kb > > > 26. > > > 1. 2011-11-23 11:21:15.049 /_ah/warmup 500 65036ms 0kb > > > 27. > > > 1. 2011-11-23 11:20:40.072 /_ah/warmup 200 46367ms 6kb > > > 28. > > > 1. 2011-11-23 11:20:17.965 /_ah/warmup 200 50689ms 6kb > > > 29. > > > 1. 2011-11-23 11:18:42.068 /_ah/warmup 200 40070ms 6kb > > > 30. > > > 1. 2011-11-23 11:18:38.184 /_ah/warmup 500 64767ms 0kb > > > 31. > > > 1. 2011-11-23 11:18:19.399 /_ah/warmup 200 49889ms 6kb > > > 32. > > > 1. 2011-11-23 11:17:17.542 /_ah/warmup 200 51572ms 6kb > > > 33. > > > 1. 2011-11-23 11:17:16.802 /_ah/warmup 200 38413ms 6kb > > > 34. > > > 1. 2011-11-23 11:17:10.677 /_ah/warmup 200 44681ms 6kb > > > 35. > > > 1. 2011-11-23 11:16:36.748 /_ah/warmup 500 67379ms 0kb > > > 36. > > > 1. 2011-11-23 11:16:22.101 /_ah/warmup 200 54775ms 6kb > > > 37. > > > 1. 2011-11-23 11:16:13.976 /_ah/warmup 200 45289ms 6kb > > > 38. > > > 1. 2011-11-23 11:16:12.017 /_ah/warmup 200 44738ms 6kb > > > 39. > > > 1. 2011-11-23 11:14:56.273 /_ah/warmup 200 49807ms 6kb > > > 40. > > > 1. 2011-11-23 11:14:13.660 /_ah/warmup 200 53409ms 6kb > > > 41. > > > 1. 2011-11-23 11:14:09.277 /_ah/warmup 500 66529ms 0kb > > > 42. > > > 1. 2011-11-23 11:12:54.104 /_ah/warmup 200 49611ms 6kb > > > 43. > > > 1. 2011-11-23 11:12:50.963 /_ah/warmup 200 41096ms 6kb > > > 44. > > > 1. 2011-11-23 11:12:45.862 /_ah/warmup 500 64073ms 0kb > > > 45. > > > 1. 2011-11-23 11:12:01.185 /_ah/warmup 200 41636ms 6kb > > > 46. > > > 1. 2011-11-23 11:11:30.454 /_ah/warmup 500 67930ms 0kb > > > 47. > > > 1. 2011-11-23 11:10:49.242 /_ah/warmup 200 43514ms 6kb > > > 48. > > > 1. 2011-11-23 11:10:48.000 /_ah/warmup 200 57239ms 6kb > > > 49. > > > 1. 2011-11-23 11:10:01.809 /_ah/warmup 200 43016ms 6kb > > > 50. > > > 1. 2011-11-23 11:09:58.637 /_ah/warmup 200 42384ms 6kb > > > > > Here are some of the 500s: > > > > > com.google.apphosting.runtime.HardDeadlineExceededError: This request > (0000000000000000) started at 2011/11/23 19:57:18.919 UTC and was still > executing at 2011/11/23 19:58:24.178 UTC. > > > at > com.google.appengine.runtime.Request.process-0000000000000000(Request.java) > > > at java.io.FileInputStream.open(Native Method) > > > at java.io.FileInputStream.<init>(FileInputStream.java:139) > > > at java.lang.Class.forName(Class.java:133) > > > at > org.datanucleus.JDOClassLoaderResolver.classOrNull(JDOClassLoaderResolver.java:553) > > > at > org.datanucleus.JDOClassLoaderResolver.classForName(JDOClassLoaderResolver.java:204) > > > at > org.datanucleus.JDOClassLoaderResolver.classForName(JDOClassLoaderResolver.java:415) > > > at > org.datanucleus.metadata.MetaDataManager.loadPersistenceUnit(MetaDataManager.java:767) > > > at > org.datanucleus.jpa.EntityManagerFactoryImpl.initialisePMF(EntityManagerFactoryImpl.java:488) > > > at > org.datanucleus.jpa.EntityManagerFactoryImpl.<init>(EntityManagerFactoryImpl.java:355) > > > at > org.datanucleus.store.appengine.jpa.DatastoreEntityManagerFactory.<init>(DatastoreEntityManagerFactory.java:63) > > > > > com.google.apphosting.runtime.HardDeadlineExceededError: This request > (0000000000000000) started at 2011/11/23 19:49:04.661 UTC and was still > executing at 2011/11/23 19:50:05.080 UTC. > > > at > com.google.appengine.runtime.Request.process-0000000000000000(Request.java) > > > at java.io.FileInputStream.open(Native Method) > > > at java.io.FileInputStream.<init>(FileInputStream.java:139) > > > at java.lang.Class.forName(Class.java:133) > > > at > org.datanucleus.JDOClassLoaderResolver.classOrNull(JDOClassLoaderResolver.java:553) > > > at > org.datanucleus.JDOClassLoaderResolver.classForName(JDOClassLoaderResolver.java:204) > > > at > > > > ... > > > > read more ยป > > -- > You received this message because you are subscribed to the Google Groups > "Google App Engine for Java" group. > To post to this group, send email to > google-appengine-java@googlegroups.com. > To unsubscribe from this group, send email to > google-appengine-java+unsubscr...@googlegroups.com. > For more options, visit this group at > http://groups.google.com/group/google-appengine-java?hl=en. > > -- You received this message because you are subscribed to the Google Groups "Google App Engine for Java" group. To post to this group, send email to google-appengine-java@googlegroups.com. To unsubscribe from this group, send email to google-appengine-java+unsubscr...@googlegroups.com. For more options, visit this group at http://groups.google.com/group/google-appengine-java?hl=en.
