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 > 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.api.DeadlineExceededException: This request > (0000000000000000) started at 2011/11/23 19:41:16.162 UTC and was still > executing at 2011/11/23 19:42:18.626 UTC. > at > org.springframework.context.annotation.ClassPathScanningCandidateComponentProvider.findCandidateComponents(ClassPathScanningCandidateComponentProvider.java:255) > at > org.springframework.context.annotation.ClassPathBeanDefinitionScanner.doScan(ClassPathBeanDefinitionScanner.java:218) > at > org.springframework.context.annotation.ComponentScanBeanDefinitionParser.parse(ComponentScanBeanDefinitionParser.java:84) > at > org.springframework.beans.factory.xml.NamespaceHandlerSupport.parse(NamespaceHandlerSupport.java:73) > at > org.springframework.beans.factory.xml.BeanDefinitionParserDelegate.parseCustomElement(BeanDefinitionParserDelegate.java:1419) > at > org.springframework.beans.factory.xml.BeanDefinitionParserDelegate.parseCustomElement(BeanDefinitionParserDelegate.java:1409) > at > org.springframework.beans.factory.xml.DefaultBeanDefinitionDocumentReader.parseBeanDefinitions(DefaultBeanDefinitionDocumentReader.java:184) > at > org.springframework.beans.factory.xml.DefaultBeanDefinitionDocumentReader.doRegisterBeanDefinitions(DefaultBeanDefinitionDocumentReader.java:140 > > com.google.apphosting.api.DeadlineExceededException: This request > (0000000000000000) started at 2011/11/23 19:45:41.941 UTC and was still > executing at 2011/11/23 19:46:43.436 UTC. > at > org.springframework.context.annotation.ClassPathScanningCandidateComponentProvider.findCandidateComponents(ClassPathScanningCandidateComponentProvider.java:255) > at > org.springframework.context.annotation.ClassPathBeanDefinitionScanner.doScan(ClassPathBeanDefinitionScanner.java:218) > at > org.springframework.context.annotation.ComponentScanBeanDefinitionParser.parse(ComponentScanBeanDefinitionParser.java:84) > at > org.springframework.beans.factory.xml.NamespaceHandlerSupport.parse(NamespaceHandlerSupport.java:73) > at > org.springframework.beans.factory.xml.BeanDefinitionParserDelegate.parseCustomElement(BeanDefinitionParserDelegate.java:1419) > at > org.springframework.beans.factory.xml.BeanDefinitionParserDelegate.parseCustomElement(BeanDefinitionParserDelegate.java:1409)
-- 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.
