09:27:32.955 [main] INFO  [CloudPoolRestApiImpl:<init>:102] - CloudPoolRestApiImpl created
09:27:32.959 [main] INFO  [CloudPoolRestApiImpl:prepareStorageDir:124] - creating storage directory /home/peterg/dev/git/scale.cloudpool/target/checkout/api/target/cloudpool/storage
09:27:32.962 [main] INFO  [CloudPoolServer:restoreConfig:169] - restoring cloudpool config from /home/peterg/dev/git/scale.cloudpool/target/checkout/api/target/cloudpool/storage/config.json
09:27:32.962 [main] INFO  [CloudPoolServer:restoreConfig:171] - no cloud pool configuration found at /home/peterg/dev/git/scale.cloudpool/target/checkout/api/target/cloudpool/storage/config.json. starting without config ...
09:27:33.063 [main] INFO  [Log:initialized:192] - Logging initialized @1141ms to org.eclipse.jetty.util.log.Slf4jLog
09:27:33.198 [main] DEBUG [ServletServerBuilder:createServletHandler:209] - adding servlet 'org.glassfish.jersey.servlet.ServletContainer@3fc5d397' at context path '/' with path spec '/*' and init-params {}
09:27:33.198 [main] DEBUG [ServletServerBuilder:addCrossOriginFilter:116] - enabling CORS support
09:27:33.210 [main] INFO  [Server:doStart:372] - jetty-9.4.0.v20161208
09:27:33.273 [main] INFO  [DefaultSessionIdManager:doStart:354] - DefaultSessionIdManager workerName=node0
09:27:33.274 [main] INFO  [DefaultSessionIdManager:doStart:359] - No SessionScavenger set, using defaults
09:27:33.277 [main] INFO  [HouseKeeper:startScavenging:146] - Scavenging every 660000ms
09:27:33.967 [main] INFO  [ContextHandler:doStart:791] - Started o.e.j.s.ServletContextHandler@5216e43c{/,null,AVAILABLE}
09:27:34.015 [main] INFO  [SslContextFactory:load:288] - x509=X509@a6e0e5f(1,h=[],w=[]) for SslContextFactory@15d6f0e1(file:///home/peterg/dev/git/scale.cloudpool/target/checkout/api/target/test-classes/security/server/server_keystore.p12,file:///home/peterg/dev/git/scale.cloudpool/target/checkout/api/target/test-classes/security/server/server_keystore.p12)
09:27:34.034 [main] INFO  [AbstractConnector:doStart:280] - Started ServerConnector@2fb3c642{SSL,[ssl, http/1.1]}{0.0.0.0:40389}
09:27:34.034 [main] INFO  [Server:doStart:444] - Started @2112ms
09:27:34.143 [main] DEBUG [Http:execute:85] - sending request (GET https://localhost:40389/pool/size HTTP/1.1)
09:27:34.351 [qtp1457005276-26] INFO  [RequestLogFilter:filter:26] - GET https://localhost:40389/pool/size by 127.0.0.1
09:27:34.456 [main] DEBUG [Http:execute:85] - sending request (POST https://localhost:40389/config HTTP/1.1)
09:27:34.465 [qtp1457005276-26] INFO  [RequestLogFilter:filter:26] - POST https://localhost:40389/config by 127.0.0.1
09:27:34.474 [qtp1457005276-26] ERROR [CloudPoolRestApiImpl:setConfig:154] - illegal input: bad config!
java.lang.IllegalArgumentException: bad config!
	at com.elastisys.scale.cloudpool.api.restapi.impl.CloudPoolRestApiImpl.setConfig(CloudPoolRestApiImpl.java:149)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.glassfish.jersey.server.model.internal.ResourceMethodInvocationHandlerFactory$1.invoke(ResourceMethodInvocationHandlerFactory.java:81)
	at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher$1.run(AbstractJavaResourceMethodDispatcher.java:144)
	at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.invoke(AbstractJavaResourceMethodDispatcher.java:161)
	at org.glassfish.jersey.server.model.internal.JavaResourceMethodDispatcherProvider$ResponseOutInvoker.doDispatch(JavaResourceMethodDispatcherProvider.java:160)
	at org.glassfish.jersey.server.model.internal.AbstractJavaResourceMethodDispatcher.dispatch(AbstractJavaResourceMethodDispatcher.java:99)
	at org.glassfish.jersey.server.model.ResourceMethodInvoker.invoke(ResourceMethodInvoker.java:389)
	at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:347)
	at org.glassfish.jersey.server.model.ResourceMethodInvoker.apply(ResourceMethodInvoker.java:102)
	at org.glassfish.jersey.server.ServerRuntime$2.run(ServerRuntime.java:326)
	at org.glassfish.jersey.internal.Errors$1.call(Errors.java:271)
	at org.glassfish.jersey.internal.Errors$1.call(Errors.java:267)
	at org.glassfish.jersey.internal.Errors.process(Errors.java:315)
	at org.glassfish.jersey.internal.Errors.process(Errors.java:297)
	at org.glassfish.jersey.internal.Errors.process(Errors.java:267)
	at org.glassfish.jersey.process.internal.RequestScope.runInScope(RequestScope.java:317)
	at org.glassfish.jersey.server.ServerRuntime.process(ServerRuntime.java:305)
	at org.glassfish.jersey.server.ApplicationHandler.handle(ApplicationHandler.java:1154)
	at org.glassfish.jersey.servlet.WebComponent.serviceImpl(WebComponent.java:473)
	at org.glassfish.jersey.servlet.WebComponent.service(WebComponent.java:427)
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:388)
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:341)
	at org.glassfish.jersey.servlet.ServletContainer.service(ServletContainer.java:228)
	at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:838)
	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1634)
	at org.eclipse.jetty.servlets.CrossOriginFilter.handle(CrossOriginFilter.java:308)
	at org.eclipse.jetty.servlets.CrossOriginFilter.doFilter(CrossOriginFilter.java:262)
	at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1621)
	at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:541)
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
	at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1584)
	at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:188)
	at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1228)
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:168)
	at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:481)
	at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1553)
	at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:166)
	at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1130)
	at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
	at org.eclipse.jetty.server.handler.HandlerList.handle(HandlerList.java:52)
	at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:132)
	at org.eclipse.jetty.server.Server.handle(Server.java:564)
	at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:318)
	at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
	at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:279)
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:112)
	at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:261)
	at org.eclipse.jetty.io.ssl.SslConnection$3.succeeded(SslConnection.java:150)
	at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:112)
	at org.eclipse.jetty.io.ChannelEndPoint$2.run(ChannelEndPoint.java:124)
	at org.eclipse.jetty.util.thread.Invocable.invokePreferred(Invocable.java:122)
	at org.eclipse.jetty.util.thread.strategy.ExecutingExecutionStrategy.invoke(ExecutingExecutionStrategy.java:58)
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:201)
	at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:133)
	at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:672)
	at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:590)
	at java.lang.Thread.run(Thread.java:748)
09:27:34.486 [main] DEBUG [Http:execute:85] - sending request (GET https://localhost:40389/pool HTTP/1.1)
09:27:34.494 [qtp1457005276-18] INFO  [RequestLogFilter:filter:26] - GET https://localhost:40389/pool by 127.0.0.1
09:27:34.503 [main] DEBUG [Http:execute:85] - sending request (POST https://localhost:40389/config HTTP/1.1)
09:27:34.509 [qtp1457005276-20] INFO  [RequestLogFilter:filter:26] - POST https://localhost:40389/config by 127.0.0.1
09:27:34.526 [main] DEBUG [Http:execute:85] - sending request (POST https://localhost:40389/pool/i-X/terminate HTTP/1.1)
09:27:34.532 [qtp1457005276-14] INFO  [RequestLogFilter:filter:26] - POST https://localhost:40389/pool/i-X/terminate by 127.0.0.1
09:27:34.537 [main] DEBUG [Http:execute:85] - sending request (POST https://localhost:40389/pool/i-X/detach HTTP/1.1)
09:27:34.543 [qtp1457005276-16] INFO  [RequestLogFilter:filter:26] - POST https://localhost:40389/pool/i-X/detach by 127.0.0.1
09:27:34.549 [main] DEBUG [Http:execute:85] - sending request (POST https://localhost:40389/pool/i-1/membershipStatus HTTP/1.1)
09:27:34.554 [qtp1457005276-26] INFO  [RequestLogFilter:filter:26] - POST https://localhost:40389/pool/i-1/membershipStatus by 127.0.0.1
09:27:34.558 [main] DEBUG [Http:execute:85] - sending request (GET https://localhost:40389/config HTTP/1.1)
09:27:34.563 [qtp1457005276-25] INFO  [RequestLogFilter:filter:26] - GET https://localhost:40389/config by 127.0.0.1
09:27:34.567 [main] DEBUG [Http:execute:85] - sending request (POST https://localhost:40389/pool/i-X/serviceState HTTP/1.1)
09:27:34.572 [qtp1457005276-25] INFO  [RequestLogFilter:filter:26] - POST https://localhost:40389/pool/i-X/serviceState by 127.0.0.1
09:27:34.577 [main] DEBUG [Http:execute:85] - sending request (POST https://localhost:40389/stop HTTP/1.1)
09:27:34.582 [qtp1457005276-14] INFO  [RequestLogFilter:filter:26] - POST https://localhost:40389/stop by 127.0.0.1
09:27:34.585 [main] DEBUG [Http:execute:85] - sending request (POST https://localhost:40389/pool/i-X/membershipStatus HTTP/1.1)
09:27:34.589 [qtp1457005276-18] INFO  [RequestLogFilter:filter:26] - POST https://localhost:40389/pool/i-X/membershipStatus by 127.0.0.1
09:27:34.594 [main] DEBUG [Http:execute:85] - sending request (POST https://localhost:40389/pool/i-1/attach HTTP/1.1)
09:27:34.599 [qtp1457005276-14] INFO  [RequestLogFilter:filter:26] - POST https://localhost:40389/pool/i-1/attach by 127.0.0.1
09:27:34.603 [main] DEBUG [Http:execute:85] - sending request (POST https://localhost:40389/start HTTP/1.1)
09:27:34.607 [qtp1457005276-26] INFO  [RequestLogFilter:filter:26] - POST https://localhost:40389/start by 127.0.0.1
09:27:34.610 [main] DEBUG [Http:execute:85] - sending request (POST https://localhost:40389/pool/i-1/serviceState HTTP/1.1)
09:27:34.614 [qtp1457005276-18] INFO  [RequestLogFilter:filter:26] - POST https://localhost:40389/pool/i-1/serviceState by 127.0.0.1
09:27:34.617 [main] DEBUG [Http:execute:85] - sending request (GET https://localhost:40389/config HTTP/1.1)
09:27:34.621 [qtp1457005276-28] INFO  [RequestLogFilter:filter:26] - GET https://localhost:40389/config by 127.0.0.1
09:27:34.625 [main] DEBUG [Http:execute:85] - sending request (GET https://localhost:40389/status HTTP/1.1)
09:27:34.630 [qtp1457005276-18] INFO  [RequestLogFilter:filter:26] - GET https://localhost:40389/status by 127.0.0.1
09:27:34.633 [main] DEBUG [Http:execute:85] - sending request (POST https://localhost:40389/pool/i-X/attach HTTP/1.1)
09:27:34.638 [qtp1457005276-26] INFO  [RequestLogFilter:filter:26] - POST https://localhost:40389/pool/i-X/attach by 127.0.0.1
09:27:34.643 [main] DEBUG [Http:execute:85] - sending request (POST https://localhost:40389/pool/i-1/terminate HTTP/1.1)
09:27:34.646 [qtp1457005276-18] INFO  [RequestLogFilter:filter:26] - POST https://localhost:40389/pool/i-1/terminate by 127.0.0.1
09:27:34.651 [main] DEBUG [Http:execute:85] - sending request (POST https://localhost:40389/pool/size HTTP/1.1)
09:27:34.654 [qtp1457005276-25] INFO  [RequestLogFilter:filter:26] - POST https://localhost:40389/pool/size by 127.0.0.1
09:27:34.659 [main] DEBUG [Http:execute:85] - sending request (POST https://localhost:40389/pool/i-1/detach HTTP/1.1)
09:27:34.662 [qtp1457005276-28] INFO  [RequestLogFilter:filter:26] - POST https://localhost:40389/pool/i-1/detach by 127.0.0.1
09:27:34.666 [main] DEBUG [Http:execute:85] - sending request (POST https://localhost:40389/pool/size HTTP/1.1)
09:27:34.670 [qtp1457005276-26] INFO  [RequestLogFilter:filter:26] - POST https://localhost:40389/pool/size by 127.0.0.1
09:27:34.674 [main] DEBUG [Http:execute:85] - sending request (POST https://localhost:40389/start HTTP/1.1)
09:27:34.678 [qtp1457005276-26] INFO  [RequestLogFilter:filter:26] - POST https://localhost:40389/start by 127.0.0.1
09:27:34.678 [qtp1457005276-26] ERROR [CloudPoolRestApiImpl:start:169] - no config set!
09:27:34.684 [main] INFO  [AbstractConnector:doStop:320] - Stopped ServerConnector@2fb3c642{SSL,[ssl, http/1.1]}{0.0.0.0:40389}
09:27:34.684 [main] INFO  [HouseKeeper:stopScavenging:164] - Stopped scavenging
09:27:34.687 [main] INFO  [ContextHandler:doStop:932] - Stopped o.e.j.s.ServletContextHandler@5216e43c{/,null,UNAVAILABLE}
